builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0153 starttime: 1464282280.8 results: success (0) buildid: 20160526080550 builduid: 9a8897d3cefa42198723b081555703be revision: 56e5195bef5bf352d37181195dc6043ac3d198ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.804903) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.805430) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.805722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.827546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.827956) ========= 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.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-26 10:04:40-- 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% 7.74M=0.001s 2016-05-26 10:04:40 (7.74 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122535 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.970368) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:40.970706) ========= 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.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.020120 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:41.021074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:41.021442) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56e5195bef5bf352d37181195dc6043ac3d198ad --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 56e5195bef5bf352d37181195dc6043ac3d198ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-26 10:04:41,088 truncating revision to first 12 chars 2016-05-26 10:04:41,088 Setting DEBUG logging. 2016-05-26 10:04:41,088 attempt 1/10 2016-05-26 10:04:41,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56e5195bef5b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 10:04:41,813 unpacking tar archive at: mozilla-central-56e5195bef5b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.979795 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:42.019953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:42.020368) ========= script_repo_revision: 56e5195bef5bf352d37181195dc6043ac3d198ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:42.020826) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:42.021137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 10:04:42.036867) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 10:04:42.037297) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:04:42 INFO - MultiFileLogger online at 20160526 10:04:42 in /builds/slave/test 10:04:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:04:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:04:42 INFO - {'append_to_log': False, 10:04:42 INFO - 'base_work_dir': '/builds/slave/test', 10:04:42 INFO - 'blob_upload_branch': 'Firefox', 10:04:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:04:42 INFO - 'branch': 'Firefox', 10:04:42 INFO - 'buildbot_json_path': 'buildprops.json', 10:04:42 INFO - 'config_files': ('talos/mac_config.py',), 10:04:42 INFO - 'default_actions': ('clobber', 10:04:42 INFO - 'read-buildbot-config', 10:04:42 INFO - 'download-and-extract', 10:04:42 INFO - 'populate-webroot', 10:04:42 INFO - 'create-virtualenv', 10:04:42 INFO - 'install', 10:04:42 INFO - 'run-tests'), 10:04:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:04:42 INFO - 'download_minidump_stackwalk': True, 10:04:42 INFO - 'download_symbols': 'ondemand', 10:04:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:04:42 INFO - 'tooltool.py': '/tools/tooltool.py', 10:04:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:04:42 INFO - '/tools/misc-python/virtualenv.py')}, 10:04:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:04:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:04:42 INFO - 'installer_path': 'installer.exe', 10:04:42 INFO - 'log_level': 'info', 10:04:42 INFO - 'log_name': 'talos', 10:04:42 INFO - 'log_to_console': True, 10:04:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:04:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:04:42 INFO - 'opt_config_files': (), 10:04:42 INFO - 'pip_index': False, 10:04:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:42 INFO - 'cmd': ('bash', 10:04:42 INFO - '-c', 10:04:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:42 INFO - 'enabled': True, 10:04:42 INFO - 'halt_on_failure': False, 10:04:42 INFO - 'name': 'check_screen_resolution'},), 10:04:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:04:42 INFO - 'cmd': ('bash', 10:04:42 INFO - '-c', 10:04:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:04:42 INFO - 'enabled': True, 10:04:42 INFO - 'halt_on_failure': False, 10:04:42 INFO - 'name': 'check_screen_resolution'},), 10:04:42 INFO - 'run_cmd_checks_enabled': True, 10:04:42 INFO - 'sps_profile': False, 10:04:42 INFO - 'sps_profile_interval': 0, 10:04:42 INFO - 'suite': 'dromaeojs-e10s', 10:04:42 INFO - 'system_bits': '32', 10:04:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:04:42 INFO - 'title': 't-yosemite-r7-0153', 10:04:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:04:42 INFO - 'use_talos_json': True, 10:04:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:04:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:04:42 INFO - 'work_dir': 'build'} 10:04:42 INFO - ##### 10:04:42 INFO - ##### Running clobber step. 10:04:42 INFO - ##### 10:04:42 INFO - Running pre-action listener: _resource_record_pre_action 10:04:42 INFO - Running main action method: clobber 10:04:42 INFO - rmtree: /builds/slave/test/build 10:04:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:04:43 INFO - Running post-action listener: _resource_record_post_action 10:04:43 INFO - ##### 10:04:43 INFO - ##### Finished clobber step (success) 10:04:43 INFO - ##### 10:04:43 INFO - ##### 10:04:43 INFO - ##### Running read-buildbot-config step. 10:04:43 INFO - ##### 10:04:43 INFO - Running pre-action listener: _resource_record_pre_action 10:04:43 INFO - Running main action method: read_buildbot_config 10:04:43 INFO - Using buildbot properties: 10:04:43 INFO - { 10:04:43 INFO - "project": "", 10:04:43 INFO - "product": "firefox", 10:04:43 INFO - "script_repo_revision": "production", 10:04:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:04:43 INFO - "repository": "", 10:04:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:04:43 INFO - "buildid": "20160526080550", 10:04:43 INFO - "pgo_build": "False", 10:04:43 INFO - "basedir": "/builds/slave/test", 10:04:43 INFO - "buildnumber": 16, 10:04:43 INFO - "slavename": "t-yosemite-r7-0153", 10:04:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:04:43 INFO - "platform": "yosemite_r7", 10:04:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:04:43 INFO - "branch": "mozilla-central", 10:04:43 INFO - "repo_path": "mozilla-central", 10:04:43 INFO - "revision": "56e5195bef5bf352d37181195dc6043ac3d198ad", 10:04:43 INFO - "stage_platform": "macosx64", 10:04:43 INFO - "builduid": "9a8897d3cefa42198723b081555703be", 10:04:43 INFO - "slavebuilddir": "test" 10:04:43 INFO - } 10:04:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:04:43 INFO - Running post-action listener: _resource_record_post_action 10:04:43 INFO - ##### 10:04:43 INFO - ##### Finished read-buildbot-config step (success) 10:04:43 INFO - ##### 10:04:43 INFO - ##### 10:04:43 INFO - ##### Running download-and-extract step. 10:04:43 INFO - ##### 10:04:43 INFO - Running pre-action listener: _resource_record_pre_action 10:04:43 INFO - Running main action method: download_and_extract 10:04:43 INFO - mkdir: /builds/slave/test/build/tests 10:04: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')]} 10:04:43 INFO - https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:04:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:04:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:04:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:44 INFO - Downloaded 1199 bytes. 10:04:44 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:04:44 INFO - Using the following test package requirements: 10:04:44 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:04:44 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:04:44 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:04:44 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'jsshell-mac.zip'], 10:04:44 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:04:44 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:04:44 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:04:44 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:04:44 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:04:44 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:04:44 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:04:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:04:44 INFO - https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:04:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:04:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:04:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:45 INFO - Downloaded 18622085 bytes. 10:04:45 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'] 10:04:45 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 10:04:49 INFO - Return code: 0 10:04:49 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 10:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:04:49 INFO - https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:04:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:04:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:04:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:50 INFO - Downloaded 18622085 bytes. 10:04:50 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'] 10:04:50 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 10:04:53 INFO - Return code: 0 10:04:53 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')]} 10:04:53 INFO - https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:04:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:04:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:04:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:04:56 INFO - Downloaded 10834034 bytes. 10:04:56 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'] 10:04:56 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 10:04:56 INFO - Return code: 0 10:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:04:56 INFO - https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:04:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:04:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:04:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:04:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TnHT3JeURAOQmrJyjKvp_w/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 10:05:00 INFO - Downloaded 110500538 bytes. 10:05:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:05:00 INFO - mkdir: /builds/slave/test/properties 10:05:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:00 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:00 INFO - Contents: 10:05:00 INFO - build_url:https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:05:01 INFO - Running post-action listener: _resource_record_post_action 10:05:01 INFO - Running post-action listener: set_extra_try_arguments 10:05:01 INFO - ##### 10:05:01 INFO - ##### Finished download-and-extract step (success) 10:05:01 INFO - ##### 10:05:01 INFO - ##### 10:05:01 INFO - ##### Running populate-webroot step. 10:05:01 INFO - ##### 10:05:01 INFO - Running pre-action listener: _resource_record_pre_action 10:05:01 INFO - Running main action method: populate_webroot 10:05:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:01 INFO - '--tppagecycles', 10:05:01 INFO - '7'], 10:05:01 INFO - 'tests': ['tsvgm']}}, 10:05:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['damp', 'tps']}, 10:05:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'tests': ['damp', 'tps']}, 10:05:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['dromaeo_dom']}, 10:05:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['a11yr', 10:05:01 INFO - 'ts_paint', 10:05:01 INFO - 'tpaint', 10:05:01 INFO - 'sessionrestore', 10:05:01 INFO - 'sessionrestore_no_auto_restore', 10:05:01 INFO - 'tabpaint']}, 10:05:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['tsvgx', 10:05:01 INFO - 'tsvgr_opacity', 10:05:01 INFO - 'tart', 10:05:01 INFO - 'tscrollx', 10:05:01 INFO - 'cart']}, 10:05:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:01 INFO - 'tsvgr_opacity', 10:05:01 INFO - 'tart', 10:05:01 INFO - 'tscrollx', 10:05:01 INFO - 'cart']}, 10:05:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'talos_options': ['--disable-e10s'], 10:05:01 INFO - 'tests': ['tp5o']}, 10:05:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'tests': ['tp5o']}, 10:05:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'talos_options': ['--disable-e10s', 10:05:01 INFO - '--xperf_path', 10:05:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:01 INFO - 'tests': ['tp5n']}, 10:05:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:01 INFO - 'talos_options': ['--xperf_path', 10:05:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:01 INFO - 'tests': ['tp5n']}}, 10:05:01 INFO - 'talos.zip': {'path': '', 10:05:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:01 INFO - Running post-action listener: _resource_record_post_action 10:05:01 INFO - ##### 10:05:01 INFO - ##### Finished populate-webroot step (success) 10:05:01 INFO - ##### 10:05:01 INFO - ##### 10:05:01 INFO - ##### Running create-virtualenv step. 10:05:01 INFO - ##### 10:05:01 INFO - Running pre-action listener: _resource_record_pre_action 10:05:01 INFO - Running main action method: create_virtualenv 10:05:01 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:01 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 10:05:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:01 INFO - Using real prefix '/tools/python27' 10:05:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:02 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:05 INFO - Installing pip.................done. 10:05:05 INFO - Return code: 0 10:05:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:05 INFO - 'HOME': '/Users/cltbld', 10:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:05 INFO - 'LOGNAME': 'cltbld', 10:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:05 INFO - 'MOZ_NO_REMOTE': '1', 10:05:05 INFO - 'NO_EM_RESTART': '1', 10:05:05 INFO - 'PAGER': '/bin/cat', 10:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:05 INFO - 'PWD': '/builds/slave/test', 10:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:05 INFO - 'SHELL': '/bin/bash', 10:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:05 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:05 INFO - 'USER': 'cltbld', 10:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:05 INFO - 'XPC_FLAGS': '0x0', 10:05:05 INFO - 'XPC_SERVICE_NAME': '0', 10:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:05 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 10:05:05 INFO - Cleaning up... 10:05:05 INFO - Return code: 0 10:05:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:05:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:05 INFO - 'HOME': '/Users/cltbld', 10:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:05 INFO - 'LOGNAME': 'cltbld', 10:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:05 INFO - 'MOZ_NO_REMOTE': '1', 10:05:05 INFO - 'NO_EM_RESTART': '1', 10:05:05 INFO - 'PAGER': '/bin/cat', 10:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:05 INFO - 'PWD': '/builds/slave/test', 10:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:05 INFO - 'SHELL': '/bin/bash', 10:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:05 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:05 INFO - 'USER': 'cltbld', 10:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:05 INFO - 'XPC_FLAGS': '0x0', 10:05:05 INFO - 'XPC_SERVICE_NAME': '0', 10:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:05 INFO - Downloading/unpacking psutil>=3.1.1 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:08 INFO - Installing collected packages: psutil 10:05:08 INFO - Running setup.py install for psutil 10:05:08 INFO - building 'psutil._psutil_osx' extension 10:05:08 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 10:05:08 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 10:05:08 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 10:05:09 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 10:05:09 INFO - building 'psutil._psutil_posix' extension 10:05:09 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 10:05:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:05:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:05:09 INFO - ^ 10:05:09 INFO - 1 warning generated. 10:05:09 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 10:05:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:09 INFO - Successfully installed psutil 10:05:09 INFO - Cleaning up... 10:05:09 INFO - Return code: 0 10:05:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:05:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:05:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:09 INFO - 'HOME': '/Users/cltbld', 10:05:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:09 INFO - 'LOGNAME': 'cltbld', 10:05:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:09 INFO - 'MOZ_NO_REMOTE': '1', 10:05:09 INFO - 'NO_EM_RESTART': '1', 10:05:09 INFO - 'PAGER': '/bin/cat', 10:05:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:09 INFO - 'PWD': '/builds/slave/test', 10:05:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:09 INFO - 'SHELL': '/bin/bash', 10:05:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:09 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:09 INFO - 'USER': 'cltbld', 10:05:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:09 INFO - 'XPC_FLAGS': '0x0', 10:05:09 INFO - 'XPC_SERVICE_NAME': '0', 10:05:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:09 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 10:05:09 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 10:05:09 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 10:05:09 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 10:05:09 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 10:05:09 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 10:05:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:12 INFO - Installing collected packages: mozsystemmonitor 10:05:12 INFO - Running setup.py install for mozsystemmonitor 10:05:12 INFO - Successfully installed mozsystemmonitor 10:05:12 INFO - Cleaning up... 10:05:12 INFO - Return code: 0 10:05:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:12 INFO - 'HOME': '/Users/cltbld', 10:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:12 INFO - 'LOGNAME': 'cltbld', 10:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:12 INFO - 'MOZ_NO_REMOTE': '1', 10:05:12 INFO - 'NO_EM_RESTART': '1', 10:05:12 INFO - 'PAGER': '/bin/cat', 10:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:12 INFO - 'PWD': '/builds/slave/test', 10:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:12 INFO - 'SHELL': '/bin/bash', 10:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:12 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:12 INFO - 'USER': 'cltbld', 10:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:12 INFO - 'XPC_FLAGS': '0x0', 10:05:12 INFO - 'XPC_SERVICE_NAME': '0', 10:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:12 INFO - Downloading/unpacking blobuploader==1.2.4 10:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:15 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:16 INFO - Downloading docopt-0.6.1.tar.gz 10:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:16 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:16 INFO - Running setup.py install for blobuploader 10:05:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:16 INFO - Running setup.py install for requests 10:05:17 INFO - Running setup.py install for docopt 10:05:17 INFO - Successfully installed blobuploader requests docopt 10:05:17 INFO - Cleaning up... 10:05:17 INFO - Return code: 0 10:05:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:17 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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:17 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 10:05:17 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 10:05:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:17 INFO - 'HOME': '/Users/cltbld', 10:05:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:17 INFO - 'LOGNAME': 'cltbld', 10:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:17 INFO - 'MOZ_NO_REMOTE': '1', 10:05:17 INFO - 'NO_EM_RESTART': '1', 10:05:17 INFO - 'PAGER': '/bin/cat', 10:05:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:17 INFO - 'PWD': '/builds/slave/test', 10:05:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:17 INFO - 'SHELL': '/bin/bash', 10:05:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:17 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:17 INFO - 'USER': 'cltbld', 10:05:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:17 INFO - 'XPC_FLAGS': '0x0', 10:05:17 INFO - 'XPC_SERVICE_NAME': '0', 10:05:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:17 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Teoq3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:17 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-kEgq0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:17 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-k7_o0r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:17 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-XznWTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-kZeYGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-_CeDuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Ggyx7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-QU_Flz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-suxWey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UeoT0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-_Mi4MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-QUI8HG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-hnmjSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:18 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-hsV1Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:19 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-QyQI64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:19 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-J74l7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:19 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UpTm9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:19 INFO - Running setup.py install for manifestparser 10:05:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:19 INFO - Running setup.py install for mozcrash 10:05:19 INFO - Running setup.py install for mozdebug 10:05:19 INFO - Running setup.py install for mozdevice 10:05:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:05:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:19 INFO - Running setup.py install for mozfile 10:05:20 INFO - Running setup.py install for mozhttpd 10:05:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Running setup.py install for mozinfo 10:05:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Running setup.py install for mozInstall 10:05:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Running setup.py install for mozleak 10:05:20 INFO - Running setup.py install for mozlog 10:05:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:20 INFO - Running setup.py install for moznetwork 10:05:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Running setup.py install for mozprocess 10:05:21 INFO - Running setup.py install for mozprofile 10:05:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Running setup.py install for mozrunner 10:05:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Running setup.py install for mozscreenshot 10:05:21 INFO - Running setup.py install for moztest 10:05:21 INFO - Running setup.py install for mozversion 10:05:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:21 INFO - Cleaning up... 10:05:21 INFO - Return code: 0 10:05:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05: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')]} 10:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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')]} 10:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:21 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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:21 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 10:05:21 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 10:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:21 INFO - 'HOME': '/Users/cltbld', 10:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:21 INFO - 'LOGNAME': 'cltbld', 10:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:21 INFO - 'MOZ_NO_REMOTE': '1', 10:05:21 INFO - 'NO_EM_RESTART': '1', 10:05:21 INFO - 'PAGER': '/bin/cat', 10:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:21 INFO - 'PWD': '/builds/slave/test', 10:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:21 INFO - 'SHELL': '/bin/bash', 10:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:21 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:21 INFO - 'USER': 'cltbld', 10:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:21 INFO - 'XPC_FLAGS': '0x0', 10:05:21 INFO - 'XPC_SERVICE_NAME': '0', 10:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-TNGu_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-23inC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MtRwZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-c_sMqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ijxOMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-zSsWkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-mQYHxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:22 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)) 10:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:22 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-EWsIGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-fl7ljt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2eWDnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-sUxBP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-qpt83T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-s0i1c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-FS66dK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-u1fIED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-usbGm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:23 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)) 10:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:23 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-icZIHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:24 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)) 10:05:24 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)) 10:05:24 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)) 10:05:24 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)) 10:05:24 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)) 10:05:24 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)) 10:05:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05:26 INFO - Downloading blessings-1.6.tar.gz 10:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:26 INFO - Installing collected packages: blessings 10:05:26 INFO - Running setup.py install for blessings 10:05:26 INFO - Successfully installed blessings 10:05:26 INFO - Cleaning up... 10:05:26 INFO - Return code: 0 10:05:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:27 INFO - Reading from file tmpfile_stdout 10:05:27 INFO - Current package versions: 10:05:27 INFO - blessings == 1.6 10:05:27 INFO - blobuploader == 1.2.4 10:05:27 INFO - docopt == 0.6.1 10:05:27 INFO - manifestparser == 1.1 10:05:27 INFO - mozInstall == 1.12 10:05:27 INFO - mozcrash == 0.17 10:05:27 INFO - mozdebug == 0.1 10:05:27 INFO - mozdevice == 0.48 10:05:27 INFO - mozfile == 1.2 10:05:27 INFO - mozhttpd == 0.7 10:05:27 INFO - mozinfo == 0.9 10:05:27 INFO - mozleak == 0.1 10:05:27 INFO - mozlog == 3.2 10:05:27 INFO - moznetwork == 0.27 10:05:27 INFO - mozprocess == 0.22 10:05:27 INFO - mozprofile == 0.28 10:05:27 INFO - mozrunner == 6.11 10:05:27 INFO - mozscreenshot == 0.1 10:05:27 INFO - mozsystemmonitor == 0.3 10:05:27 INFO - moztest == 0.7 10:05:27 INFO - mozversion == 1.4 10:05:27 INFO - psutil == 3.1.1 10:05:27 INFO - requests == 1.2.3 10:05:27 INFO - wsgiref == 0.1.2 10:05:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:27 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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:27 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 10:05:27 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 10:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:27 INFO - 'HOME': '/Users/cltbld', 10:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:27 INFO - 'LOGNAME': 'cltbld', 10:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:27 INFO - 'MOZ_NO_REMOTE': '1', 10:05:27 INFO - 'NO_EM_RESTART': '1', 10:05:27 INFO - 'PAGER': '/bin/cat', 10:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:27 INFO - 'PWD': '/builds/slave/test', 10:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:27 INFO - 'SHELL': '/bin/bash', 10:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:27 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:27 INFO - 'USER': 'cltbld', 10:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:27 INFO - 'XPC_FLAGS': '0x0', 10:05:27 INFO - 'XPC_SERVICE_NAME': '0', 10:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 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)) 10:05:27 INFO - Cleaning up... 10:05:27 INFO - Return code: 0 10:05:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:27 INFO - 'HOME': '/Users/cltbld', 10:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:27 INFO - 'LOGNAME': 'cltbld', 10:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:27 INFO - 'MOZ_NO_REMOTE': '1', 10:05:27 INFO - 'NO_EM_RESTART': '1', 10:05:27 INFO - 'PAGER': '/bin/cat', 10:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:27 INFO - 'PWD': '/builds/slave/test', 10:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:27 INFO - 'SHELL': '/bin/bash', 10:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:27 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:27 INFO - 'USER': 'cltbld', 10:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:27 INFO - 'XPC_FLAGS': '0x0', 10:05:27 INFO - 'XPC_SERVICE_NAME': '0', 10:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:27 INFO - Downloading/unpacking jsonschema 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:27 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 10:05:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:30 INFO - Downloading/unpacking functools32 (from jsonschema) 10:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:30 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:30 INFO - warning: no files found matching '*.txt' 10:05:30 INFO - no previously-included directories found matching 'build' 10:05:30 INFO - no previously-included directories found matching 'dist' 10:05:30 INFO - no previously-included directories found matching '.git*' 10:05:30 INFO - Installing collected packages: jsonschema, functools32 10:05:30 INFO - Running setup.py install for functools32 10:05:30 INFO - warning: no files found matching '*.txt' 10:05:30 INFO - no previously-included directories found matching 'build' 10:05:30 INFO - no previously-included directories found matching 'dist' 10:05:30 INFO - no previously-included directories found matching '.git*' 10:05:30 INFO - Successfully installed jsonschema functools32 10:05:30 INFO - Cleaning up... 10:05:30 INFO - Return code: 0 10:05:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:30 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 0x10bfd5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfdb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f991359c5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, '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.UnVzSB0csI/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.zbwO4xEBAj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:05:30 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 10:05:30 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 10:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:30 INFO - 'HOME': '/Users/cltbld', 10:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:30 INFO - 'LOGNAME': 'cltbld', 10:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:30 INFO - 'MOZ_NO_REMOTE': '1', 10:05:30 INFO - 'NO_EM_RESTART': '1', 10:05:30 INFO - 'PAGER': '/bin/cat', 10:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:30 INFO - 'PWD': '/builds/slave/test', 10:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:30 INFO - 'SHELL': '/bin/bash', 10:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:05:30 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:30 INFO - 'USER': 'cltbld', 10:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:30 INFO - 'XPC_FLAGS': '0x0', 10:05:30 INFO - 'XPC_SERVICE_NAME': '0', 10:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:31 INFO - Downloading/unpacking flake8 10:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:05:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:05:33 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 10:05:33 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 10:05:33 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 10:05:33 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 10:05:33 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 10:05:33 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 10:05:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:05:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:34 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 10:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:05:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:05:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:05:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:05:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:05:35 INFO - no previously-included directories found matching 'docs/_build' 10:05:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:05:35 INFO - Running setup.py install for pep8 10:05:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:05:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:05:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:05:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:05:35 INFO - no previously-included directories found matching 'docs/_build' 10:05:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:05:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Running post-action listener: _resource_record_post_action 10:05:35 INFO - Running post-action listener: _start_resource_monitoring 10:05:35 INFO - Starting resource monitoring. 10:05:35 INFO - ##### 10:05:35 INFO - ##### Finished create-virtualenv step (success) 10:05:35 INFO - ##### 10:05:35 INFO - ##### 10:05:35 INFO - ##### Running install step. 10:05:35 INFO - ##### 10:05:35 INFO - Running pre-action listener: _resource_record_pre_action 10:05:35 INFO - Running main action method: install 10:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:35 INFO - Reading from file tmpfile_stdout 10:05:35 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:35 INFO - Reading from file tmpfile_stdout 10:05:35 INFO - Output received: 10:05:35 INFO - Usage: mozinstall [options] installer 10:05:35 INFO - Options: 10:05:35 INFO - -h, --help show this help message and exit 10:05:35 INFO - -d DEST, --destination=DEST 10:05:35 INFO - Directory to install application into. [default: 10:05:35 INFO - "/builds/slave/test"] 10:05:35 INFO - --app=APP Application being installed. [default: firefox] 10:05:35 INFO - mkdir: /builds/slave/test/build/application 10:05:35 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'] 10:05:35 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 10:06:09 INFO - Reading from file tmpfile_stdout 10:06:09 INFO - Output received: 10:06:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:06:09 INFO - Running post-action listener: _resource_record_post_action 10:06:09 INFO - ##### 10:06:09 INFO - ##### Finished install step (success) 10:06:09 INFO - ##### 10:06:09 INFO - ##### 10:06:09 INFO - ##### Running run-tests step. 10:06:09 INFO - ##### 10:06:09 INFO - Running pre-action listener: _resource_record_pre_action 10:06:09 INFO - Running main action method: run_tests 10:06:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:09 INFO - 2016-05-26 10:06:09.432 screenresolution[1926:25768] starting screenresolution argv=screenresolution get 10:06:09 INFO - 2016-05-26 10:06:09.434 screenresolution[1926:25768] Display 0: 1600x1200x32@60 10:06:09 INFO - 2016-05-26 10:06:09.440 screenresolution[1927:25771] starting screenresolution argv=screenresolution list 10:06:09 INFO - Available Modes on Display 0 10:06:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:09 INFO - Intel Iris: 10:06:09 INFO - Chipset Model: Intel Iris 10:06:09 INFO - Type: GPU 10:06:09 INFO - Bus: Built-In 10:06:09 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:09 INFO - Vendor: Intel (0x8086) 10:06:09 INFO - Device ID: 0x0a2e 10:06:09 INFO - Revision ID: 0x0009 10:06:09 INFO - Displays: 10:06:09 INFO - Display: 10:06:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:09 INFO - Display Serial Number: 1600x1200 60 10:06:09 INFO - Main Display: Yes 10:06:09 INFO - Mirror: Off 10:06:09 INFO - Online: Yes 10:06:09 INFO - Rotation: Supported 10:06:09 INFO - Return code: 0 10:06:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:09 INFO - Python 2.7.3 10:06:09 INFO - Return code: 0 10:06:09 INFO - grabbing minidump binary from tooltool 10:06:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:09 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 0x10be79d30>, '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 0x10bc9ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10be70b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:09 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 10:06:09 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 10:06:09 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 10:06:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:09 INFO - Return code: 0 10:06:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:06:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:06:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:06:10 INFO - Return code: 0 10:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UnVzSB0csI/Render', 10:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:10 INFO - 'HOME': '/Users/cltbld', 10:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:10 INFO - 'LOGNAME': 'cltbld', 10:06:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:10 INFO - 'MOZ_NO_REMOTE': '1', 10:06:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:10 INFO - 'NO_EM_RESTART': '1', 10:06:10 INFO - 'PAGER': '/bin/cat', 10:06:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:10 INFO - 'PWD': '/builds/slave/test', 10:06:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:10 INFO - 'SHELL': '/bin/bash', 10:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zbwO4xEBAj/Listeners', 10:06:10 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:10 INFO - 'USER': 'cltbld', 10:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:10 INFO - 'XPC_FLAGS': '0x0', 10:06:10 INFO - 'XPC_SERVICE_NAME': '0', 10:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:06:11 INFO - mozversion application_buildid: 20160526080550 10:06:11 INFO - mozversion application_changeset: 56e5195bef5bf352d37181195dc6043ac3d198ad 10:06:11 INFO - mozversion application_display_name: Nightly 10:06:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:11 INFO - mozversion application_name: Firefox 10:06:11 INFO - mozversion application_remotingname: firefox 10:06:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:06:11 INFO - mozversion application_vendor: Mozilla 10:06:11 INFO - mozversion application_version: 49.0a1 10:06:11 INFO - mozversion platform_buildid: 20160526080550 10:06:11 INFO - mozversion platform_changeset: 56e5195bef5bf352d37181195dc6043ac3d198ad 10:06:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:06:11 INFO - mozversion platform_version: 49.0a1 10:06:11 INFO - using testdate: 1464282371 10:06:11 INFO - actual date: 1464282371 10:06:11 INFO - starting webserver on 'localhost:52751' 10:06:11 INFO - SUITE-START | Running 2 tests 10:06:11 INFO - TEST-START | dromaeo_css 10:06:11 INFO - operating with platform_type : mac_ 10:06:11 INFO - Initialising browser for dromaeo_css test... 10:06:11 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpXWbSVL/profile http://localhost:52751/getInfo.html) 10:06:14 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 10:06:14 INFO - PROCESS | 1945 | colorDepth:24 10:06:14 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 10:06:14 INFO - PROCESS | 1945 | __metrics 10:06:14 ERROR - PROCESS | 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 10:06:14 INFO - TEST-INFO | 1945: exit 0 10:06:14 INFO - Browser initialized. 10:06:14 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:14 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpXWbSVL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:26 INFO - PROCESS | 1948 | RSS: Main: 252395520 10:06:26 INFO - PROCESS | 1948 | 10:07:16 INFO - PROCESS | 1948 | 1464282436622 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] 10:09:27 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52751/tests/dromaeo/cssquery-ext.html) 10:09:29 INFO - PROCESS | 1948 | RSS: Main: 264151040 10:09:29 INFO - PROCESS | 1948 | 10:12:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-ext.html (next: http://localhost:52751/tests/dromaeo/cssquery-jquery.html) 10:12:31 INFO - PROCESS | 1948 | RSS: Main: 275959808 10:12:31 INFO - PROCESS | 1948 | 10:15:33 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52751/tests/dromaeo/cssquery-mootools.html) 10:15:34 INFO - PROCESS | 1948 | RSS: Main: 277868544 10:15:34 INFO - PROCESS | 1948 | 10:18:36 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52751/tests/dromaeo/cssquery-prototype.html) 10:18:36 INFO - PROCESS | 1948 | RSS: Main: 279490560 10:18:36 INFO - PROCESS | 1948 | 10:21:39 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52751/tests/dromaeo/cssquery-yui.html) 10:21:39 INFO - PROCESS | 1948 | RSS: Main: 282345472 10:21:39 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52751/tests/dromaeo/cssquery-yui.html (next: http://localhost:52751/tests/dromaeo/cssquery-dojo.html) 10:24:42 INFO - PROCESS | 1948 | RSS: Main: 283561984 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | __start_tp_report 10:24:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 10:24:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 10:24:42 INFO - PROCESS | 1948 | |i|pagename|runs| 10:24:42 INFO - PROCESS | 1948 | |0;dojo.html;2628.5794272916323;3466.7919924804505;3502.247016891368;3519.2433626770257;3523.4362501999676;4931.260310953358;5805.071188609823;5869.677167755777;5900.0854867495555;5955.612885162462;4891.339669144664;5795.884082318351;5927.140564618127;5979.222701048858;6029.397060293968;5213.113770658978;5217.791288348471;5252.579793616502;5360.785568577252;5407.107827208506;5399.406065332811;5441.047816632094;5483.75323110459;5494.725263736817;5495.368032676253;4479.76806378247;4721.457032441279;5222.060029194762;5243.816466423695;5248.737563121828;5212.947870521322;5249.370075590915;5271.130263506517;5316.893662126736;5332.440093790174;5227.320448341729;5300.496452836975;5320.547753440962;5334.306540149794;5353.598480114007;5622.8313150605545;5768.596198266123;5827.504662103726;5868.853278668024;5878.764849406019;5236.371635403776;6159.3224745277985;6175.814725558241;6195.287541932646;6215.7513699451965;6120.904292401164;6171.537134714914;6271.717772700194;6283.214598175228;6300.5589608727405;5095.286659867622;5972.462478376923;6071;6083.361247069039;6111.480524155452;6377.96811015942;6399.776007839703;6401.583897046677;6430.228372595318;6441.581297215666;5348.652337598043;6107.552954153613;6145.525073982212;6230.376962303733;6232.781852635136;4837.346958160603;5270.341207349082;5301.54936830366;6271.153394291712;6302.275238347557;5082.923756143661;5204.739763011834;5927.347991720907;6169.691515424301;6199.690015499207;5335.706536140549;6121.5139442231075;6145.955187618027;6163.414475624809;6198.783042593487;5069.163588008011;5070.949290507121;5089.51649593288;5098.643094983389;5122.154844450699;4563.0873825235085;4589.816407343736;4592.173408786451;4601.148787474328;4656.998745269715;5909.143174239797;5931.169636250928;5997.100434934795;6020.886136064842;6087.421694938974;6235.251769787655;6365.172527571479;6367.840803979938;6378.170837791057;6417.39034791694;6055.757769689251;6069.119977603223;6076.756929722762;6083.665398403042;6121.4490695837585;5443.482869127427;5462.945370546323;5492.505674489315;5591;5637.548996080303;6531.967340163269;6535.0850880876715;6562.212534495891;6564.9671751640935;6577.14497115372;6170.352113028247;6277.686115694289;6318.968405157945;6326.367363263636;6350.7142178603;6322.715477803418;6422.807315780534;6458.257300410513;6468.450181734605;6540.149780528501;6189.195404597373;6202.596831205957;6220.346863579259;6233.189685340877;6267.749290028348;6016.2479690038745;6294.590851594632;6296.212973378328;6297.496200304056;6308.369163083838;6068.544859135494;6158.938410615837;6172.012478003678;6208.689565521796;6243.531735119794;6025.246844144482;6169.475594574512;6195.721192546256;6207.6585787782105;6219.378062193744;5385.488378604026;5671.886562268649;6109.480694141048;6231.657258850807;6253.312135664983;6141.332587233824;6153.9845925421805;6168.907466387918;6215.06773983906;6223.626582405071;6144.723487443165;6145;6146.416090471398;6172.290186628596;6198.969005154945 10:24:42 INFO - PROCESS | 1948 | |1;ext.html;481.22282513740316;498.9850304490864;515.1474310016921;528.723133632278;567.4042255631591;38666.226675466474;39419;39461.802690986544;39532.01169970749;41593.584064159346;33967;42663.57336426643;43677.56322436775;43707;43779.562204377944;8573.95713021434;9337.926138494058;9595;9618.182454491342;9625.229981601455;9582.185514231263;9678.516074196263;9706.951465242664;9714.805703885953;9741.366811157288;9631.325807193463;9681;9706.466144362057;9721.125098741111;9730.65942692006;9593.232541396672;9672.56473458696;9693.660721874701;9701.90298097024;9706.223502119814;9593.616255349778;9623.230141588727;9672.90327096727;9679.854802177973;9708.834939807268;32143.83928080368;39300.60699393027;39344.80327598372;39811.20377592432;39924.60075399238;2584.9224523264334;2944.9852750736322;2958.2456473599095;3005.459017376871;3197.9926323208115;23957.760422395902;28975.85512072426;29366.853165734035;29804.701952980628;29979;2745.574435962429;2755.435135797157;2762.778977681781;2773.7087605801503;2901.637295338083;539652;548198;548857;552429;554503;67048.6647566759;67246.32753672401;67743.32256677469;67758.66120669365;67839;35559.82220088883;36103.27793444116;36526.45210321847;38358.041048974;72081;17373.915130184458;17544.29822807099;17566.824331756776;17601;17646.911765441346;11340;13203.273819939996;13347.733045339233;13350.666233344244;13427.865721342661;16133.515994520183;20632.69050964237;20852.1659133636;20960.475988100116;21000.894995525232;8447.324214062983;8496.575171241537;8496.915030849612;8498.617562209593;8545.572721364031;784.5253621558988;905.7418635688797;952.8999455057258;1000.5297510170209;1065.7708592652616;28609.284767880552;29536.409271814442;29750.958716445253;29838.25404364865;30268.697313027027;1036.4247842447364;1058.25921854702;1123.9550417983353;1125.4147843121532;1226.4910062324036;15350.846491535165;15421.922890385476;15489.148096854557;15658.295376708076;15833.762493562606;694657;739543;750681;751906;756791;62599.37400625936;63426.68286658629;63666.68166659137;63863;64703.35296647067;1415.497498388078;1430.106183635228;1451.796748455217;1452.7530319845653;1457.2786470697072;28417.857910710314;29492.410151797274;29624.703752963058;29740.405191896472;30132.84933575318;54724.726376367864;54949.725251373486;55215.72392138014;55393.1691024643;55402.445975539726;621687;640930.9534523162;644887;648011.7599411972;648633;23150.768492314863;27166.456670866868;27304.771285291812;27593;27651.723482764915;47486.287705685136;47598.28602570895;48106.27840582324;48396.5160348392;49083;39036.80481597574;39593.81218563448;39710.80144599258;40464;40464.59535404608;32741.672583274816;37736;38810.41784373293;38814.029649257864;39890.40164397594;37488.25023499569;38370.23259534738;38671.03322416962;39108;41531.37702934519;31509.684903150675;31999.200019999687;34715.9585212444;34851.825740871136;34952.65047349596;24027.879860601286;25061;25578.10476633309;25738.613920790827;25842.966281349287 10:24:42 INFO - PROCESS | 1948 | |2;jquery.html;385.4930766042655;391.27223364541953;391.8843941037394;392.72705469698565;410.1284769864039;19581.902090489544;20246.595068098628;20849.166033358666;20879.956002199906;21097;20029.399118026446;20334.08496617652;21098;21101.577968440622;21148.471288217803;5498.285222921015;5894.734736936848;5957.344692083867;5979.282486101674;6004.429579189992;5663.4336566343245;5878.235829342179;5963;5969.850753731148;5978.342382337939;5089.872753181163;5094.592432605401;5865.325487568942;5878.823635290927;5892.351841297454;5412.891742165135;5784.4504772046585;5851.093080572497;5905.055191169414;5916.26046744157;5406.864828379283;5638.2670252866865;5646.802361917355;5652.943470565283;5653.1520271959125;13199.010074244374;13200.405981730844;13208.009399294995;13240.933795331059;13246.40391182399;1900.1734245603207;1919.2418994497234;1932.1015727686552;2132.1897678881924;2184.3774524260666;9278.907210927871;9325.953370233174;9616.51917404127;9676.225901927828;9815.116639502408;1738.5827401423694;1750.3523696232348;1773.2286455391945;1789.7046987247088;1819.3898399916038;113409;118226;122949;123293;125054;11336.546538138382;11597.246178998337;11617.244879082833;11839.112066594867;12039.217450865666;14393.92803035999;14517.491887784088;14539.854601454063;14560.907931905149;14584.48954286595;8598.05421403645;8662;8667.393282470293;8668.696595619123;8702.912970870337;7250.927490725131;8500.362472814564;8608.78478038054;8709.172628600272;8761.167689069529;3967.778044295204;4317.784110794448;4580.175568397721;4584.816607335736;4614.653900957442;1711.5293294344156;1941.718465812557;1949.9805001950083;1974.6544354737864;1976.4762337980446;850.953197574127;917.7063339731355;923.9815203696023;938.8357037518407;939.1031564855547;17273.65452690939;19494.805051949297;19504.609907801765;19672.803271967383;19724;903.9954800225956;934.0519372836576;950.1686024728363;955.9522023898777;1001.3927646128005;10236.897631023743;10653.467326633638;10707.464626768631;10746.408947507962;10749.925007499187;561706;618327;620699;643000;645702;112580.43709781562;121256;125816.37091814665;144355;148986;882.3029806452959;1029.2383636109225;1039.1894322428518;1079.9244052916379;1084.5770149641646;18035.639287214446;18219.180136894138;18247.0876456174;18251.269949201884;18353.082345882387;55533.1670024958;57011;57037;57608.135877961024;59180.70409647924;80277.59861200656;81043.78434323608;81911;82007;82236.58881705793;6804.6257455840405;7793.142754297136;7876.582215201319;7943.801404964922;8255.504669719836;5334.706591137525;10017.949910250401;10497.47512624381;12451.377431128227;12519.436625351698;9681.66114186;10006.599736010477;10911.072558832271;11119.72200694989;11324.490397931948;10388.37669739818;10741.462926853783;10828.729281768019;11245.044171245208;11350.978411942964;9124.635014599342;11178.385188814367;11200.551977920792;11351.602693905674;11599;7253.8186545336785;7316.9634151828905;7501.774946751606;7561.9243807563425;7589.772306830804;10628.149748020294;11018.283811552223;11056.226064175431;11143.888561114285;11365.54537818511 10:24:42 INFO - PROCESS | 1948 | |3;mootools.html;1464.926753662317;1584.144561936554;1835.1191428114505;1949.6880499120134;1982.593568318493;8078.353731701464;8518.659253629856;8622.310215182786;9054.185123338906;9058.320625953054;8707.25988290996;8772.464818656725;8797.736067917973;8869.822603547926;8892.599833007514;2346.9178578749747;2376.5128148729555;2388.617821148608;2390.055927908475;2399.9040038398443;2351.1183306260154;2376.7266764322153;2385.510970251103;2388.426777573379;2396.2930935374106;2372.905083796646;2375.560521303564;2382.7498112698177;2386.558486679961;2389.8924548395275;2331.405491599648;2369.786719195272;2371.9563392107502;2372.217168334454;2374.228375777879;2316;2322.988385058081;2354.411397150712;2355.62310030396;2357.26924653358;7548.169701332849;8828.073052329477;8841.646334146626;8885.555722213929;8899.37704360695;5223.73881305937;5384.434634363413;5389.83830485086;5390.272313237741;5403.810866619689;3372.578427696538;3500.8774692885627;3554.6445355464507;3562.750607457479;3573.6783689468075;853.6500034985688;1008.6268080810074;1025.9487025648689;1040.8386700061503;1041.401194313273;11212.76659567447;11426.428678566122;11566.190366674336;11696.824547631792;11700.122490813137;4492.595666390041;4551.271796512542;4619.214733495314;4630.050839577879;4652.278896771006;9137.177654011171;9140.360174787702;9168.633254669738;9208.769780755401;9261.351705380559;5080.49195080489;5097.057044446789;5144.382674079135;5172.896542069213;5251.4485978972425;3681.208540163824;4722.362481065013;4864.075825593126;4886.853394398103;4901.142300097468;3802.676772474371;3823.5602905666206;3879.301725689403;3917.941230881539;3922.3920292354733;998.7453199434098;1193.6359410379744;1196.4675719304826;1198.6823491774687;1207.7161866961258;767.6277005652254;839.8446287436843;893.3032234856693;923.3167456082585;951.2437612098398;5701.657900525899;5776.682282474504;5948.226730525091;5960.821175364746;6010.939890601126;426.67146297350865;431.5835219013667;436.4435344935233;438.401581840785;442.6060805882767;3528.2590655962535;3893.8831835044994;3915.314819906505;4011.719179657396;4062.756234625932;8875.778105547413;10308.948455257676;10388.844167337496;10750.78498430027;10783.83824242637;10502.737431564272;10513.737156570995;10526.894731052744;10705.678829635124;10714.46427678613;6713.429358504484;8078.474899131656;8222.876656850152;8257.339412846957;8264.8347033059;5803.419658034163;5816.127580862735;5866.2373891393845;5890.852728681817;5923.318818335946;9899.703008909744;9942.652007179713;9987.750306242402;10072.294939354175;10106.242031847494;9847.704568862946;9888.258380621626;10117.59529618807;10201.489925503844;10278.023587759151;6531.11829902957;6816.2502124767225;7032.648367581703;7074.3633073023675;7074.823129421806;8391.202835730595;8393.412461127662;8435.746927592181;8483.10927352619;8487.490750554987;7236.2401947796825;7376.6311684414495;7462.888056679263;7581.772546823604;7599.848003040019;7201.027861238876;7338.046054012944;7410.184879663125;7421.146568144733;7424.480286380118;7009.579425234502;7040.542364746275;7067.434605231466;7095.290470952863;7100.0414943981905;4799.928001080057;4836.637252206028;4947.82682606107;4952.331435256338;5158.587313015025;4924.532169443897;4998.900241946766;5046.293518907282;5087.771050302671;5211.973940130275 10:24:42 INFO - PROCESS | 1948 | |4;prototype.html;286.9283227146607;306.44992238931104;314.658595423965;324.39824126245827;326.7598315238299;4562.6121779648765;5216.374035115787;5472.398036216023;5697.4017728138515;5717.912961768956;5553.305836770403;5599.592175364592;5608.887822243552;5622.184783206432;5642.548596112311;3611.620779818121;4231.11715286696;4233.343831706083;4247.065645557972;4280.880963922863;3771.264603402338;4107.589241075899;4226.45056142701;4229.661627069842;4248.638865696419;3672.869735553386;3830.1190726133004;3840.9039774005732;4017.830371599333;4077.55146933837;4221.535631080578;4227.577242275778;4244.363345498169;4266.914661706748;4285.657147428229;3685.907852303687;3692.2430901665093;3707.8331475083687;3710.517632707758;3720.386136287509;4137.648299894513;5298.814541491068;5417.26866872971;5419.783208671688;5421.728913554307;4359.38968544404;4572.817087316504;4660.813567457331;4782.187028205213;4854.587360074398;5242.580593552507;5665.490105890449;5687.402822703598;5903.675297858616;5912.334352701309;4021.336479480882;4045.1707399982934;4046.615571520701;4057.2899742545233;4062;5888.558358123158;6251.937480625181;6259.843503912393;6262.624242545462;6276.5292603054495;3625.8549658013912;3946.3685810270217;4016.8393264269102;4090.854560723002;4094.6928980326593;3569.5359603251413;3932.3511620582854;4146.958530414658;4149.2738770715005;4167.99968007682;4601.125786100697;4768.737719425396;4838.669365924399;4888.682235654671;4919.032387045139;5651.547876169897;5972.700510303477;5989.520838332923;5990.221271234712;6068.9696551517845;1389.3539504130426;1423.7223741370333;1424.480064776349;1426.4222989689192;1432.9140251584938;848.3891598049395;879.5487445714622;879.7052987249343;881.5592203898051;884.8097659003345;4656.906861862743;4658.254679251304;4661.764632372424;4665.836695715634;4690.2327626699725;4717.113154738064;4718.01843225846;4824.517548245218;4862;5194.246834209094;4368.291659171334;4416.315471101984;4588.2888152336445;4655.37152484417;4665.227213658788;2379.2386436340553;2580.574205256149;2666.2267942296517;2676.1703871799805;2694.9461010779673;4903.8528844134735;5116.616253780981;5191.8182863599595;5286.788528458896;5402.702851343135;4714.858554243378;4921.089598424232;5106.78272688204;5272.6309158358545;5302.409277216897;4425.3626158320985;4757.8096876125255;4983.651144419929;5042.747862606929;5353.250544923714;4953.504649535018;5120.871978200575;5149.918517111449;5187.98834227322;5192.688438693691;4157.688173386948;4656.67403281767;5089.414717307557;5285.947140528546;6134.8159555213415;6122.632642041492;6453.257875344396;6454.290028097;6465.319017055505;6491.967540162269;4017.77902215381;4032.677385809187;4063.959360406358;4064.9106678924823;4094.5905409458815;5473.768402109494;5937.554683398676;6205.844853878689;6215.81352559424;6249.468795152281;5070.746462676778;5441.673499590037;5551.7779288828;5685.943140568541;5825.621334613236;5404.810831621032;5487.759189973645;5991.580589358703;6041.580228646352;6079.118527813531;5014.448410674896;5261.421903267448;5491.368492623399;5810.244668193108;5873.823785286449;4646.65150113736;5616.932782771424;6077.574569780073;6126.295476020315;6140.938590614215;5605.421506107236;5927.733252003756;5945.494632956247;5955.047192449188;5990.3710110439515 10:24:42 INFO - PROCESS | 1948 | |5;yui.html;642.9742810287589;675.3482889012103;711.1856923822221;727.6943683652869;768.0514564512825;11525.36610486423;14973.25133743314;15071.77392339113;15255.771163432557;15313.234338283082;13694.52069177579;13939.163650180997;13980.650483737887;14228.928855355709;14325.92837035816;750.2497502497503;906.1527471813843;923.4551614547415;934.6401635370397;953.704351650987;918.8483900156466;920.5213289089669;927.5872236854602;933.8132373525289;934.3693007220133;964.4261664309724;991.6578780320814;994.3983889746708;996.5515518016886;1000.5097502223895;943.528235882059;953.1231267234161;953.9618415263382;983.2871168402922;987.1757082835841;736.9815754606124;737.1449119021962;737.3842841227581;741.5365396627108;741.5884184277778;14876.404943802236;14895.851041489555;15007.174605396698;15048.172350520717;15070.09579425238;12857.935710321482;12866.328122670802;12926.54757083507;12985.896198823017;13182.340882955814;10021.398716076987;10293.330933489375;10334.483275836254;10675.893241067568;10793.83809242862;3492.3189977954057;3534.3991521441417;3534.9116272093147;3578.6421357864265;3659.70722342212;87247.69128463078;88132;88374;89664.55167724185;90079.54960225223;7880.763577092696;9464.810703785886;9489.90510094904;9520.381175223589;9537.475438850792;22953.885230574073;23496.295111146694;23568.29295121149;23629.409264768517;23680.40798980039;6409.289400555739;7313.780586582411;7520.473566850268;7546;7629.3114151623195;4016.377461493473;4044.797760111983;4114.814833332507;4115.1975364803575;4126.154138401621;3394.762366634312;3505.9123521911647;3608.729345299113;3613.9700185447023;3622.7464077514846;635.9109724638554;639.7185238495048;642.2389468479773;651.1402375969236;651.9217693876765;465.9387822009366;532.8028629407066;546.7211722021798;582.7406803972192;583.130857341206;7868.527888326605;8111.1888811118415;8134.145914678992;8338.583070846433;8399.286060684788;568.4287291272327;575.4648177195165;590.5551018782245;595.8480587450259;616.5745635511482;3734.738568300193;3786.261678972574;3797.5442946846556;3847.961520384816;3937.251922134785;76362;82452.58773706094;84301;88034;88094.55952720196;65530.68938621201;76561.61719191368;76894.615526922;77114.22885771183;78149;69100.30899691039;71174.22064448327;71281;71310;71424.64287678528;8217.096119426978;8281.627326770189;8300.168852450919;8300.750977470685;8339;37395;43624.345634816134;43942.340864887694;43989.560104398544;45168;55333.723331384695;55717.721411394305;58544;61797.38202617916;62430;10703.625373111903;10779.299345542515;11125.944370278097;11277.984981351718;11281.605143819927;11748.647540573455;11816.291022538331;11823.11326650522;11969.521219151136;12216.26702397859;11129.499172537417;11187.608433704781;11337.943310283727;11417.543298267976;11553.24903881245;8949.418287811272;11402.885971140515;11420;11551.826722599335;11715.531378744754;9099.454032758056;10530.736731581464;11087.55649774;11297.209195356574;11749;8621.956890215759;9807.754806129904;9970.102690757576;9977.950110249401;10134.847977280198;9616.471094089613;9627.518624068629;9740.853887191555;9767.658131965347;9811.803763924825 10:24:42 INFO - PROCESS | 1948 | __end_tp_report 10:24:42 INFO - PROCESS | 1948 | __start_cc_report 10:24:42 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,418 10:24:42 INFO - PROCESS | 1948 | __end_cc_report 10:24:42 INFO - PROCESS | 1948 | __startTimestamp1464283482851__endTimestamp 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | ------- Summary: start ------- 10:24:42 INFO - PROCESS | 1948 | Number of tests: 6 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5778.10 Median:6071.00 stddev:672.46 (11.1%) stddev-sans-first:627.49 10:24:42 INFO - PROCESS | 1948 | Values: 2628.6 3466.8 3502.2 3519.2 3523.4 4931.3 5805.1 5869.7 5900.1 5955.6 4891.3 5795.9 5927.1 5979.2 6029.4 5213.1 5217.8 5252.6 5360.8 5407.1 5399.4 5441.0 5483.8 5494.7 5495.4 4479.8 4721.5 5222.1 5243.8 5248.7 5212.9 5249.4 5271.1 5316.9 5332.4 5227.3 5300.5 5320.5 5334.3 5353.6 5622.8 5768.6 5827.5 5868.9 5878.8 5236.4 6159.3 6175.8 6195.3 6215.8 6120.9 6171.5 6271.7 6283.2 6300.6 5095.3 5972.5 6071.0 6083.4 6111.5 6378.0 6399.8 6401.6 6430.2 6441.6 5348.7 6107.6 6145.5 6230.4 6232.8 4837.3 5270.3 5301.5 6271.2 6302.3 5082.9 5204.7 5927.3 6169.7 6199.7 5335.7 6121.5 6146.0 6163.4 6198.8 5069.2 5070.9 5089.5 5098.6 5122.2 4563.1 4589.8 4592.2 4601.1 4657.0 5909.1 5931.2 5997.1 6020.9 6087.4 6235.3 6365.2 6367.8 6378.2 6417.4 6055.8 6069.1 6076.8 6083.7 6121.4 5443.5 5462.9 5492.5 5591.0 5637.5 6532.0 6535.1 6562.2 6565.0 6577.1 6170.4 6277.7 6319.0 6326.4 6350.7 6322.7 6422.8 6458.3 6468.5 6540.1 6189.2 6202.6 6220.3 6233.2 6267.7 6016.2 6294.6 6296.2 6297.5 6308.4 6068.5 6158.9 6172.0 6208.7 6243.5 6025.2 6169.5 6195.7 6207.7 6219.4 5385.5 5671.9 6109.5 6231.7 6253.3 6141.3 6154.0 6168.9 6215.1 6223.6 6144.7 6145.0 6146.4 6172.3 6199.0 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:76319.63 Median:39440.40 stddev:173730.82 (440.5%) stddev-sans-first:174124.87 10:24:42 INFO - PROCESS | 1948 | Values: 481.2 499.0 515.1 528.7 567.4 38666.2 39419.0 39461.8 39532.0 41593.6 33967.0 42663.6 43677.6 43707.0 43779.6 8574.0 9337.9 9595.0 9618.2 9625.2 9582.2 9678.5 9707.0 9714.8 9741.4 9631.3 9681.0 9706.5 9721.1 9730.7 9593.2 9672.6 9693.7 9701.9 9706.2 9593.6 9623.2 9672.9 9679.9 9708.8 32143.8 39300.6 39344.8 39811.2 39924.6 2584.9 2945.0 2958.2 3005.5 3198.0 23957.8 28975.9 29366.9 29804.7 29979.0 2745.6 2755.4 2762.8 2773.7 2901.6 539652.0 548198.0 548857.0 552429.0 554503.0 67048.7 67246.3 67743.3 67758.7 67839.0 35559.8 36103.3 36526.5 38358.0 72081.0 17373.9 17544.3 17566.8 17601.0 17646.9 11340.0 13203.3 13347.7 13350.7 13427.9 16133.5 20632.7 20852.2 20960.5 21000.9 8447.3 8496.6 8496.9 8498.6 8545.6 784.5 905.7 952.9 1000.5 1065.8 28609.3 29536.4 29751.0 29838.3 30268.7 1036.4 1058.3 1124.0 1125.4 1226.5 15350.8 15421.9 15489.1 15658.3 15833.8 694657.0 739543.0 750681.0 751906.0 756791.0 62599.4 63426.7 63666.7 63863.0 64703.4 1415.5 1430.1 1451.8 1452.8 1457.3 28417.9 29492.4 29624.7 29740.4 30132.8 54724.7 54949.7 55215.7 55393.2 55402.4 621687.0 640931.0 644887.0 648011.8 648633.0 23150.8 27166.5 27304.8 27593.0 27651.7 47486.3 47598.3 48106.3 48396.5 49083.0 39036.8 39593.8 39710.8 40464.0 40464.6 32741.7 37736.0 38810.4 38814.0 39890.4 37488.3 38370.2 38671.0 39108.0 41531.4 31509.7 31999.2 34716.0 34851.8 34952.7 24027.9 25061.0 25578.1 25738.6 25843.0 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:35387.92 Median:2158.28 stddev:103387.64 (4790.3%) stddev-sans-first:103644.26 10:24:42 INFO - PROCESS | 1948 | Values: 385.5 391.3 391.9 392.7 410.1 19581.9 20246.6 20849.2 20880.0 21097.0 20029.4 20334.1 21098.0 21101.6 21148.5 5498.3 5894.7 5957.3 5979.3 6004.4 5663.4 5878.2 5963.0 5969.9 5978.3 5089.9 5094.6 5865.3 5878.8 5892.4 5412.9 5784.5 5851.1 5905.1 5916.3 5406.9 5638.3 5646.8 5652.9 5653.2 13199.0 13200.4 13208.0 13240.9 13246.4 1900.2 1919.2 1932.1 2132.2 2184.4 9278.9 9326.0 9616.5 9676.2 9815.1 1738.6 1750.4 1773.2 1789.7 1819.4 113409.0 118226.0 122949.0 123293.0 125054.0 11336.5 11597.2 11617.2 11839.1 12039.2 14393.9 14517.5 14539.9 14560.9 14584.5 8598.1 8662.0 8667.4 8668.7 8702.9 7250.9 8500.4 8608.8 8709.2 8761.2 3967.8 4317.8 4580.2 4584.8 4614.7 1711.5 1941.7 1950.0 1974.7 1976.5 851.0 917.7 924.0 938.8 939.1 17273.7 19494.8 19504.6 19672.8 19724.0 904.0 934.1 950.2 956.0 1001.4 10236.9 10653.5 10707.5 10746.4 10749.9 561706.0 618327.0 620699.0 643000.0 645702.0 112580.4 121256.0 125816.4 144355.0 148986.0 882.3 1029.2 1039.2 1079.9 1084.6 18035.6 18219.2 18247.1 18251.3 18353.1 55533.2 57011.0 57037.0 57608.1 59180.7 80277.6 81043.8 81911.0 82007.0 82236.6 6804.6 7793.1 7876.6 7943.8 8255.5 5334.7 10017.9 10497.5 12451.4 12519.4 9681.7 10006.6 10911.1 11119.7 11324.5 10388.4 10741.5 10828.7 11245.0 11351.0 9124.6 11178.4 11200.6 11351.6 11599.0 7253.8 7317.0 7501.8 7561.9 7589.8 10628.1 11018.3 11056.2 11143.9 11365.5 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5626.78 Median:4950.08 stddev:3162.29 (63.9%) stddev-sans-first:3155.70 10:24:42 INFO - PROCESS | 1948 | Values: 1464.9 1584.1 1835.1 1949.7 1982.6 8078.4 8518.7 8622.3 9054.2 9058.3 8707.3 8772.5 8797.7 8869.8 8892.6 2346.9 2376.5 2388.6 2390.1 2399.9 2351.1 2376.7 2385.5 2388.4 2396.3 2372.9 2375.6 2382.7 2386.6 2389.9 2331.4 2369.8 2372.0 2372.2 2374.2 2316.0 2323.0 2354.4 2355.6 2357.3 7548.2 8828.1 8841.6 8885.6 8899.4 5223.7 5384.4 5389.8 5390.3 5403.8 3372.6 3500.9 3554.6 3562.8 3573.7 853.7 1008.6 1025.9 1040.8 1041.4 11212.8 11426.4 11566.2 11696.8 11700.1 4492.6 4551.3 4619.2 4630.1 4652.3 9137.2 9140.4 9168.6 9208.8 9261.4 5080.5 5097.1 5144.4 5172.9 5251.4 3681.2 4722.4 4864.1 4886.9 4901.1 3802.7 3823.6 3879.3 3917.9 3922.4 998.7 1193.6 1196.5 1198.7 1207.7 767.6 839.8 893.3 923.3 951.2 5701.7 5776.7 5948.2 5960.8 6010.9 426.7 431.6 436.4 438.4 442.6 3528.3 3893.9 3915.3 4011.7 4062.8 8875.8 10308.9 10388.8 10750.8 10783.8 10502.7 10513.7 10526.9 10705.7 10714.5 6713.4 8078.5 8222.9 8257.3 8264.8 5803.4 5816.1 5866.2 5890.9 5923.3 9899.7 9942.7 9987.8 10072.3 10106.2 9847.7 9888.3 10117.6 10201.5 10278.0 6531.1 6816.3 7032.6 7074.4 7074.8 8391.2 8393.4 8435.7 8483.1 8487.5 7236.2 7376.6 7462.9 7581.8 7599.8 7201.0 7338.0 7410.2 7421.1 7424.5 7009.6 7040.5 7067.4 7095.3 7100.0 4799.9 4836.6 4947.8 4952.3 5158.6 4924.5 4998.9 5046.3 5087.8 5212.0 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4580.62 Median:4896.27 stddev:1414.36 (28.9%) stddev-sans-first:1381.12 10:24:42 INFO - PROCESS | 1948 | Values: 286.9 306.4 314.7 324.4 326.8 4562.6 5216.4 5472.4 5697.4 5717.9 5553.3 5599.6 5608.9 5622.2 5642.5 3611.6 4231.1 4233.3 4247.1 4280.9 3771.3 4107.6 4226.5 4229.7 4248.6 3672.9 3830.1 3840.9 4017.8 4077.6 4221.5 4227.6 4244.4 4266.9 4285.7 3685.9 3692.2 3707.8 3710.5 3720.4 4137.6 5298.8 5417.3 5419.8 5421.7 4359.4 4572.8 4660.8 4782.2 4854.6 5242.6 5665.5 5687.4 5903.7 5912.3 4021.3 4045.2 4046.6 4057.3 4062.0 5888.6 6251.9 6259.8 6262.6 6276.5 3625.9 3946.4 4016.8 4090.9 4094.7 3569.5 3932.4 4147.0 4149.3 4168.0 4601.1 4768.7 4838.7 4888.7 4919.0 5651.5 5972.7 5989.5 5990.2 6069.0 1389.4 1423.7 1424.5 1426.4 1432.9 848.4 879.5 879.7 881.6 884.8 4656.9 4658.3 4661.8 4665.8 4690.2 4717.1 4718.0 4824.5 4862.0 5194.2 4368.3 4416.3 4588.3 4655.4 4665.2 2379.2 2580.6 2666.2 2676.2 2694.9 4903.9 5116.6 5191.8 5286.8 5402.7 4714.9 4921.1 5106.8 5272.6 5302.4 4425.4 4757.8 4983.7 5042.7 5353.3 4953.5 5120.9 5149.9 5188.0 5192.7 4157.7 4656.7 5089.4 5285.9 6134.8 6122.6 6453.3 6454.3 6465.3 6492.0 4017.8 4032.7 4064.0 4064.9 4094.6 5473.8 5937.6 6205.8 6215.8 6249.5 5070.7 5441.7 5551.8 5685.9 5825.6 5404.8 5487.8 5991.6 6041.6 6079.1 5014.4 5261.4 5491.4 5810.2 5873.8 4646.7 5616.9 6077.6 6126.3 6140.9 5605.4 5927.7 5945.5 5955.0 5990.4 10:24:42 INFO - PROCESS | 1948 | 10:24:42 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:17792.15 Median:593.20 stddev:24838.02 (4187.1%) stddev-sans-first:24874.32 10:24:42 INFO - PROCESS | 1948 | Values: 643.0 675.3 711.2 727.7 768.1 11525.4 14973.3 15071.8 15255.8 15313.2 13694.5 13939.2 13980.7 14228.9 14325.9 750.2 906.2 923.5 934.6 953.7 918.8 920.5 927.6 933.8 934.4 964.4 991.7 994.4 996.6 1000.5 943.5 953.1 954.0 983.3 987.2 737.0 737.1 737.4 741.5 741.6 14876.4 14895.9 15007.2 15048.2 15070.1 12857.9 12866.3 12926.5 12985.9 13182.3 10021.4 10293.3 10334.5 10675.9 10793.8 3492.3 3534.4 3534.9 3578.6 3659.7 87247.7 88132.0 88374.0 89664.6 90079.5 7880.8 9464.8 9489.9 9520.4 9537.5 22953.9 23496.3 23568.3 23629.4 23680.4 6409.3 7313.8 7520.5 7546.0 7629.3 4016.4 4044.8 4114.8 4115.2 4126.2 3394.8 3505.9 3608.7 3614.0 3622.7 635.9 639.7 642.2 651.1 651.9 465.9 532.8 546.7 582.7 583.1 7868.5 8111.2 8134.1 8338.6 8399.3 568.4 575.5 590.6 595.8 616.6 3734.7 3786.3 3797.5 3848.0 3937.3 76362.0 82452.6 84301.0 88034.0 88094.6 65530.7 76561.6 76894.6 77114.2 78149.0 69100.3 71174.2 71281.0 71310.0 71424.6 8217.1 8281.6 8300.2 8300.8 8339.0 37395.0 43624.3 43942.3 43989.6 45168.0 55333.7 55717.7 58544.0 61797.4 62430.0 10703.6 10779.3 11125.9 11278.0 11281.6 11748.6 11816.3 11823.1 11969.5 12216.3 11129.5 11187.6 11337.9 11417.5 11553.2 8949.4 11402.9 11420.0 11551.8 11715.5 9099.5 10530.7 11087.6 11297.2 11749.0 8622.0 9807.8 9970.1 9978.0 10134.8 9616.5 9627.5 9740.9 9767.7 9811.8 10:24:42 INFO - PROCESS | 1948 | -------- Summary: end -------- 10:24:42 INFO - PROCESS | 1948 | 10:24:43 INFO - TEST-INFO | 1948: exit 0 10:24:43 INFO - TEST-OK | dromaeo_css | took 1112334ms 10:24:43 INFO - TEST-START | kraken 10:24:43 INFO - operating with platform_type : mac_ 10:24:43 INFO - Initialising browser for kraken test... 10:24:43 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpUsgyzE/profile http://localhost:52751/getInfo.html) 10:24:46 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 10:24:46 INFO - PROCESS | 1953 | colorDepth:24 10:24:46 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 10:24:46 INFO - PROCESS | 1953 | __metrics 10:24:46 ERROR - PROCESS | 1953 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 10:24:47 INFO - TEST-INFO | 1953: exit 0 10:24:47 INFO - Browser initialized. 10:24:47 INFO - Running cycle 1/1 for kraken test... 10:24:47 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpUsgyzE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:59 INFO - PROCESS | 1955 | RSS: Main: 252375040 10:24:59 INFO - PROCESS | 1955 | 10:25:01 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52751/tests/kraken/driver.html?testName=audio-beat-detection) 10:25:02 INFO - PROCESS | 1955 | RSS: Main: 264323072 10:25:02 INFO - PROCESS | 1955 | 10:25:05 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52751/tests/kraken/driver.html?testName=audio-dft) 10:25:06 INFO - PROCESS | 1955 | RSS: Main: 269037568 10:25:06 INFO - PROCESS | 1955 | 10:25:09 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52751/tests/kraken/driver.html?testName=audio-fft) 10:25:10 INFO - PROCESS | 1955 | RSS: Main: 269537280 10:25:10 INFO - PROCESS | 1955 | 10:25:12 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52751/tests/kraken/driver.html?testName=audio-oscillator) 10:25:12 INFO - PROCESS | 1955 | RSS: Main: 270163968 10:25:12 INFO - PROCESS | 1955 | 10:25:14 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:15 INFO - PROCESS | 1955 | RSS: Main: 270819328 10:25:15 INFO - PROCESS | 1955 | 10:25:18 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52751/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:18 INFO - PROCESS | 1955 | RSS: Main: 271527936 10:25:18 INFO - PROCESS | 1955 | 10:25:21 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52751/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:22 INFO - PROCESS | 1955 | RSS: Main: 271491072 10:25:22 INFO - PROCESS | 1955 | 10:25:25 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52751/tests/kraken/driver.html?testName=json-parse-financial) 10:25:26 INFO - PROCESS | 1955 | RSS: Main: 271953920 10:25:26 INFO - PROCESS | 1955 | 10:25:27 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:25:28 INFO - PROCESS | 1955 | RSS: Main: 271265792 10:25:28 INFO - PROCESS | 1955 | 10:25:30 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:25:31 INFO - PROCESS | 1955 | RSS: Main: 271343616 10:25:31 INFO - PROCESS | 1955 | 10:25:33 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:25:34 INFO - PROCESS | 1955 | RSS: Main: 271380480 10:25:34 INFO - PROCESS | 1955 | 10:25:37 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:25:37 INFO - PROCESS | 1955 | RSS: Main: 271720448 10:25:37 INFO - PROCESS | 1955 | 10:25:40 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:25:41 INFO - PROCESS | 1955 | RSS: Main: 271241216 10:25:41 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:52751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52751/tests/kraken/driver.html?testName=ai-astar) 10:25:43 INFO - PROCESS | 1955 | RSS: Main: 273260544 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | __start_tp_report 10:25:43 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 10:25:43 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 10:25:43 INFO - PROCESS | 1955 | |i|pagename|runs| 10:25:43 INFO - PROCESS | 1955 | |0;ai-astar;116;112;117;105;127;114;116;114;116;132 10:25:43 INFO - PROCESS | 1955 | |1;audio-beat-detection;160;142;175;187;151;164;164;123;173;119 10:25:43 INFO - PROCESS | 1955 | |2;audio-dft;197;201;200;239;195;200;199;194;202;194 10:25:43 INFO - PROCESS | 1955 | |3;audio-fft;69;84;74;151;57;57;58;57;133;58 10:25:43 INFO - PROCESS | 1955 | |4;audio-oscillator;73;82;124;71;72;77;117;72;74;124 10:25:43 INFO - PROCESS | 1955 | |5;imaging-gaussian-blur;91;89;90;88;91;87;92;88;90;87 10:25:43 INFO - PROCESS | 1955 | |6;imaging-darkroom;98;107;106;152;150;95;97;96;110;128 10:25:43 INFO - PROCESS | 1955 | |7;imaging-desaturate;92;95;90;92;116;148;101;149;131;90 10:25:43 INFO - PROCESS | 1955 | |8;json-parse-financial;58;60;58;58;60;61;61;71;59;58 10:25:43 INFO - PROCESS | 1955 | |9;json-stringify-tinderbox;65;66;68;77;76;72;68;88;107;105 10:25:43 INFO - PROCESS | 1955 | |10;stanford-crypto-aes;87;88;93;109;108;81;103;108;80;97 10:25:43 INFO - PROCESS | 1955 | |11;stanford-crypto-ccm;156;143;148;126;158;134;160;154;191;160 10:25:43 INFO - PROCESS | 1955 | |12;stanford-crypto-pbkdf2;189;231;191;167;165;169;166;168;163;166 10:25:43 INFO - PROCESS | 1955 | |13;stanford-crypto-sha256-iterative;64;66;83;83;83;81;84;74;65;67 10:25:43 INFO - PROCESS | 1955 | __end_tp_report 10:25:43 INFO - PROCESS | 1955 | __start_cc_report 10:25:43 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1087 10:25:43 INFO - PROCESS | 1955 | __end_cc_report 10:25:43 INFO - PROCESS | 1955 | __startTimestamp1464283543262__endTimestamp 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | ------- Summary: start ------- 10:25:43 INFO - PROCESS | 1955 | Number of tests: 14 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#0] ai-astar Cycles:10 Average:116.90 Median:116.00 stddev:7.56 (6.5%) stddev-sans-first:8.02 10:25:43 INFO - PROCESS | 1955 | Values: 116.0 112.0 117.0 105.0 127.0 114.0 116.0 114.0 116.0 132.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#1] audio-beat-detection Cycles:10 Average:155.80 Median:164.00 stddev:22.20 (13.5%) stddev-sans-first:23.49 10:25:43 INFO - PROCESS | 1955 | Values: 160.0 142.0 175.0 187.0 151.0 164.0 164.0 123.0 173.0 119.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#2] audio-dft Cycles:10 Average:202.10 Median:200.00 stddev:13.29 (6.6%) stddev-sans-first:13.96 10:25:43 INFO - PROCESS | 1955 | Values: 197.0 201.0 200.0 239.0 195.0 200.0 199.0 194.0 202.0 194.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#3] audio-fft Cycles:10 Average:79.80 Median:58.00 stddev:34.28 (59.1%) stddev-sans-first:36.14 10:25:43 INFO - PROCESS | 1955 | Values: 69.0 84.0 74.0 151.0 57.0 57.0 58.0 57.0 133.0 58.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:72.50 stddev:23.11 (31.9%) stddev-sans-first:23.82 10:25:43 INFO - PROCESS | 1955 | Values: 73.0 82.0 124.0 71.0 72.0 77.0 117.0 72.0 74.0 124.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:1.77 (2.0%) stddev-sans-first:1.76 10:25:43 INFO - PROCESS | 1955 | Values: 91.0 89.0 90.0 88.0 91.0 87.0 92.0 88.0 90.0 87.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:123.50 stddev:21.85 (17.7%) stddev-sans-first:22.40 10:25:43 INFO - PROCESS | 1955 | Values: 98.0 107.0 106.0 152.0 150.0 95.0 97.0 96.0 110.0 128.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:90.00 stddev:24.02 (26.7%) stddev-sans-first:24.54 10:25:43 INFO - PROCESS | 1955 | Values: 92.0 95.0 90.0 92.0 116.0 148.0 101.0 149.0 131.0 90.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.00 stddev:3.92 (6.5%) stddev-sans-first:4.06 10:25:43 INFO - PROCESS | 1955 | Values: 58.0 60.0 58.0 58.0 60.0 61.0 61.0 71.0 59.0 58.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:70.00 stddev:15.67 (22.4%) stddev-sans-first:15.75 10:25:43 INFO - PROCESS | 1955 | Values: 65.0 66.0 68.0 77.0 76.0 72.0 68.0 88.0 107.0 105.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:84.00 stddev:11.25 (13.4%) stddev-sans-first:11.51 10:25:43 INFO - PROCESS | 1955 | Values: 87.0 88.0 93.0 109.0 108.0 81.0 103.0 108.0 80.0 97.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:157.00 stddev:17.61 (11.2%) stddev-sans-first:18.65 10:25:43 INFO - PROCESS | 1955 | Values: 156.0 143.0 148.0 126.0 158.0 134.0 160.0 154.0 191.0 160.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:168.50 stddev:21.29 (12.6%) stddev-sans-first:22.17 10:25:43 INFO - PROCESS | 1955 | Values: 189.0 231.0 191.0 167.0 165.0 169.0 166.0 168.0 163.0 166.0 10:25:43 INFO - PROCESS | 1955 | 10:25:43 INFO - PROCESS | 1955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:82.00 stddev:8.67 (10.6%) stddev-sans-first:8.23 10:25:43 INFO - PROCESS | 1955 | Values: 64.0 66.0 83.0 83.0 83.0 81.0 84.0 74.0 65.0 67.0 10:25:43 INFO - PROCESS | 1955 | -------- Summary: end -------- 10:25:43 INFO - PROCESS | 1955 | 10:25:44 INFO - TEST-INFO | 1955: exit 0 10:25:44 INFO - TEST-OK | kraken | took 60624ms 10:25:44 INFO - SUITE-END | took 1172s 10:25:44 INFO - Completed test suite (00:19:33) 10:25:44 INFO - javascript benchmark 10:25:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7155.26180772408, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2628.5794272916323, 3466.7919924804505, 3502.247016891368, 3519.2433626770257, 3523.4362501999676, 4931.260310953358, 5805.071188609823, 5869.677167755777, 5900.0854867495555, 5955.612885162462, 4891.339669144664, 5795.884082318351, 5927.140564618127, 5979.222701048858, 6029.397060293968, 5213.113770658978, 5217.791288348471, 5252.579793616502, 5360.785568577252, 5407.107827208506, 5399.406065332811, 5441.047816632094, 5483.75323110459, 5494.725263736817, 5495.368032676253, 4479.76806378247, 4721.457032441279, 5222.060029194762, 5243.816466423695, 5248.737563121828, 5212.947870521322, 5249.370075590915, 5271.130263506517, 5316.893662126736, 5332.440093790174, 5227.320448341729, 5300.496452836975, 5320.547753440962, 5334.306540149794, 5353.598480114007, 5622.8313150605545, 5768.596198266123, 5827.504662103726, 5868.853278668024, 5878.764849406019, 5236.371635403776, 6159.3224745277985, 6175.814725558241, 6195.287541932646, 6215.7513699451965, 6120.904292401164, 6171.537134714914, 6271.717772700194, 6283.214598175228, 6300.5589608727405, 5095.286659867622, 5972.462478376923, 6071.0, 6083.361247069039, 6111.480524155452, 6377.96811015942, 6399.776007839703, 6401.583897046677, 6430.228372595318, 6441.581297215666, 5348.652337598043, 6107.552954153613, 6145.525073982212, 6230.376962303733, 6232.781852635136, 4837.346958160603, 5270.341207349082, 5301.54936830366, 6271.153394291712, 6302.275238347557, 5082.923756143661, 5204.739763011834, 5927.347991720907, 6169.691515424301, 6199.690015499207, 5335.706536140549, 6121.5139442231075, 6145.955187618027, 6163.414475624809, 6198.783042593487, 5069.163588008011, 5070.949290507121, 5089.51649593288, 5098.643094983389, 5122.154844450699, 4563.0873825235085, 4589.816407343736, 4592.173408786451, 4601.148787474328, 4656.998745269715, 5909.143174239797, 5931.169636250928, 5997.100434934795, 6020.886136064842, 6087.421694938974, 6235.251769787655, 6365.172527571479, 6367.840803979938, 6378.170837791057, 6417.39034791694, 6055.757769689251, 6069.119977603223, 6076.756929722762, 6083.665398403042, 6121.4490695837585, 5443.482869127427, 5462.945370546323, 5492.505674489315, 5591.0, 5637.548996080303, 6531.967340163269, 6535.0850880876715, 6562.212534495891, 6564.9671751640935, 6577.14497115372, 6170.352113028247, 6277.686115694289, 6318.968405157945, 6326.367363263636, 6350.7142178603, 6322.715477803418, 6422.807315780534, 6458.257300410513, 6468.450181734605, 6540.149780528501, 6189.195404597373, 6202.596831205957, 6220.346863579259, 6233.189685340877, 6267.749290028348, 6016.2479690038745, 6294.590851594632, 6296.212973378328, 6297.496200304056, 6308.369163083838, 6068.544859135494, 6158.938410615837, 6172.012478003678, 6208.689565521796, 6243.531735119794, 6025.246844144482, 6169.475594574512, 6195.721192546256, 6207.6585787782105, 6219.378062193744, 5385.488378604026, 5671.886562268649, 6109.480694141048, 6231.657258850807, 6253.312135664983, 6141.332587233824, 6153.9845925421805, 6168.907466387918, 6215.06773983906, 6223.626582405071, 6144.723487443165, 6145.0, 6146.416090471398, 6172.290186628596, 6198.969005154945], "value": 5737.1212233308825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.22282513740316, 498.9850304490864, 515.1474310016921, 528.723133632278, 567.4042255631591, 38666.226675466474, 39419.0, 39461.802690986544, 39532.01169970749, 41593.584064159346, 33967.0, 42663.57336426643, 43677.56322436775, 43707.0, 43779.562204377944, 8573.95713021434, 9337.926138494058, 9595.0, 9618.182454491342, 9625.229981601455, 9582.185514231263, 9678.516074196263, 9706.951465242664, 9714.805703885953, 9741.366811157288, 9631.325807193463, 9681.0, 9706.466144362057, 9721.125098741111, 9730.65942692006, 9593.232541396672, 9672.56473458696, 9693.660721874701, 9701.90298097024, 9706.223502119814, 9593.616255349778, 9623.230141588727, 9672.90327096727, 9679.854802177973, 9708.834939807268, 32143.83928080368, 39300.60699393027, 39344.80327598372, 39811.20377592432, 39924.60075399238, 2584.9224523264334, 2944.9852750736322, 2958.2456473599095, 3005.459017376871, 3197.9926323208115, 23957.760422395902, 28975.85512072426, 29366.853165734035, 29804.701952980628, 29979.0, 2745.574435962429, 2755.435135797157, 2762.778977681781, 2773.7087605801503, 2901.637295338083, 539652.0, 548198.0, 548857.0, 552429.0, 554503.0, 67048.6647566759, 67246.32753672401, 67743.32256677469, 67758.66120669365, 67839.0, 35559.82220088883, 36103.27793444116, 36526.45210321847, 38358.041048974, 72081.0, 17373.915130184458, 17544.29822807099, 17566.824331756776, 17601.0, 17646.911765441346, 11340.0, 13203.273819939996, 13347.733045339233, 13350.666233344244, 13427.865721342661, 16133.515994520183, 20632.69050964237, 20852.1659133636, 20960.475988100116, 21000.894995525232, 8447.324214062983, 8496.575171241537, 8496.915030849612, 8498.617562209593, 8545.572721364031, 784.5253621558988, 905.7418635688797, 952.8999455057258, 1000.5297510170209, 1065.7708592652616, 28609.284767880552, 29536.409271814442, 29750.958716445253, 29838.25404364865, 30268.697313027027, 1036.4247842447364, 1058.25921854702, 1123.9550417983353, 1125.4147843121532, 1226.4910062324036, 15350.846491535165, 15421.922890385476, 15489.148096854557, 15658.295376708076, 15833.762493562606, 694657.0, 739543.0, 750681.0, 751906.0, 756791.0, 62599.37400625936, 63426.68286658629, 63666.68166659137, 63863.0, 64703.35296647067, 1415.497498388078, 1430.106183635228, 1451.796748455217, 1452.7530319845653, 1457.2786470697072, 28417.857910710314, 29492.410151797274, 29624.703752963058, 29740.405191896472, 30132.84933575318, 54724.726376367864, 54949.725251373486, 55215.72392138014, 55393.1691024643, 55402.445975539726, 621687.0, 640930.9534523162, 644887.0, 648011.7599411972, 648633.0, 23150.768492314863, 27166.456670866868, 27304.771285291812, 27593.0, 27651.723482764915, 47486.287705685136, 47598.28602570895, 48106.27840582324, 48396.5160348392, 49083.0, 39036.80481597574, 39593.81218563448, 39710.80144599258, 40464.0, 40464.59535404608, 32741.672583274816, 37736.0, 38810.41784373293, 38814.029649257864, 39890.40164397594, 37488.25023499569, 38370.23259534738, 38671.03322416962, 39108.0, 41531.37702934519, 31509.684903150675, 31999.200019999687, 34715.9585212444, 34851.825740871136, 34952.65047349596, 24027.879860601286, 25061.0, 25578.10476633309, 25738.613920790827, 25842.966281349287], "value": 20113.621424440993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4930766042655, 391.27223364541953, 391.8843941037394, 392.72705469698565, 410.1284769864039, 19581.902090489544, 20246.595068098628, 20849.166033358666, 20879.956002199906, 21097.0, 20029.399118026446, 20334.08496617652, 21098.0, 21101.577968440622, 21148.471288217803, 5498.285222921015, 5894.734736936848, 5957.344692083867, 5979.282486101674, 6004.429579189992, 5663.4336566343245, 5878.235829342179, 5963.0, 5969.850753731148, 5978.342382337939, 5089.872753181163, 5094.592432605401, 5865.325487568942, 5878.823635290927, 5892.351841297454, 5412.891742165135, 5784.4504772046585, 5851.093080572497, 5905.055191169414, 5916.26046744157, 5406.864828379283, 5638.2670252866865, 5646.802361917355, 5652.943470565283, 5653.1520271959125, 13199.010074244374, 13200.405981730844, 13208.009399294995, 13240.933795331059, 13246.40391182399, 1900.1734245603207, 1919.2418994497234, 1932.1015727686552, 2132.1897678881924, 2184.3774524260666, 9278.907210927871, 9325.953370233174, 9616.51917404127, 9676.225901927828, 9815.116639502408, 1738.5827401423694, 1750.3523696232348, 1773.2286455391945, 1789.7046987247088, 1819.3898399916038, 113409.0, 118226.0, 122949.0, 123293.0, 125054.0, 11336.546538138382, 11597.246178998337, 11617.244879082833, 11839.112066594867, 12039.217450865666, 14393.92803035999, 14517.491887784088, 14539.854601454063, 14560.907931905149, 14584.48954286595, 8598.05421403645, 8662.0, 8667.393282470293, 8668.696595619123, 8702.912970870337, 7250.927490725131, 8500.362472814564, 8608.78478038054, 8709.172628600272, 8761.167689069529, 3967.778044295204, 4317.784110794448, 4580.175568397721, 4584.816607335736, 4614.653900957442, 1711.5293294344156, 1941.718465812557, 1949.9805001950083, 1974.6544354737864, 1976.4762337980446, 850.953197574127, 917.7063339731355, 923.9815203696023, 938.8357037518407, 939.1031564855547, 17273.65452690939, 19494.805051949297, 19504.609907801765, 19672.803271967383, 19724.0, 903.9954800225956, 934.0519372836576, 950.1686024728363, 955.9522023898777, 1001.3927646128005, 10236.897631023743, 10653.467326633638, 10707.464626768631, 10746.408947507962, 10749.925007499187, 561706.0, 618327.0, 620699.0, 643000.0, 645702.0, 112580.43709781562, 121256.0, 125816.37091814665, 144355.0, 148986.0, 882.3029806452959, 1029.2383636109225, 1039.1894322428518, 1079.9244052916379, 1084.5770149641646, 18035.639287214446, 18219.180136894138, 18247.0876456174, 18251.269949201884, 18353.082345882387, 55533.1670024958, 57011.0, 57037.0, 57608.135877961024, 59180.70409647924, 80277.59861200656, 81043.78434323608, 81911.0, 82007.0, 82236.58881705793, 6804.6257455840405, 7793.142754297136, 7876.582215201319, 7943.801404964922, 8255.504669719836, 5334.706591137525, 10017.949910250401, 10497.47512624381, 12451.377431128227, 12519.436625351698, 9681.66114186, 10006.599736010477, 10911.072558832271, 11119.72200694989, 11324.490397931948, 10388.37669739818, 10741.462926853783, 10828.729281768019, 11245.044171245208, 11350.978411942964, 9124.635014599342, 11178.385188814367, 11200.551977920792, 11351.602693905674, 11599.0, 7253.8186545336785, 7316.9634151828905, 7501.774946751606, 7561.9243807563425, 7589.772306830804, 10628.149748020294, 11018.283811552223, 11056.226064175431, 11143.888561114285, 11365.54537818511], "value": 9246.819178136222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.926753662317, 1584.144561936554, 1835.1191428114505, 1949.6880499120134, 1982.593568318493, 8078.353731701464, 8518.659253629856, 8622.310215182786, 9054.185123338906, 9058.320625953054, 8707.25988290996, 8772.464818656725, 8797.736067917973, 8869.822603547926, 8892.599833007514, 2346.9178578749747, 2376.5128148729555, 2388.617821148608, 2390.055927908475, 2399.9040038398443, 2351.1183306260154, 2376.7266764322153, 2385.510970251103, 2388.426777573379, 2396.2930935374106, 2372.905083796646, 2375.560521303564, 2382.7498112698177, 2386.558486679961, 2389.8924548395275, 2331.405491599648, 2369.786719195272, 2371.9563392107502, 2372.217168334454, 2374.228375777879, 2316.0, 2322.988385058081, 2354.411397150712, 2355.62310030396, 2357.26924653358, 7548.169701332849, 8828.073052329477, 8841.646334146626, 8885.555722213929, 8899.37704360695, 5223.73881305937, 5384.434634363413, 5389.83830485086, 5390.272313237741, 5403.810866619689, 3372.578427696538, 3500.8774692885627, 3554.6445355464507, 3562.750607457479, 3573.6783689468075, 853.6500034985688, 1008.6268080810074, 1025.9487025648689, 1040.8386700061503, 1041.401194313273, 11212.76659567447, 11426.428678566122, 11566.190366674336, 11696.824547631792, 11700.122490813137, 4492.595666390041, 4551.271796512542, 4619.214733495314, 4630.050839577879, 4652.278896771006, 9137.177654011171, 9140.360174787702, 9168.633254669738, 9208.769780755401, 9261.351705380559, 5080.49195080489, 5097.057044446789, 5144.382674079135, 5172.896542069213, 5251.4485978972425, 3681.208540163824, 4722.362481065013, 4864.075825593126, 4886.853394398103, 4901.142300097468, 3802.676772474371, 3823.5602905666206, 3879.301725689403, 3917.941230881539, 3922.3920292354733, 998.7453199434098, 1193.6359410379744, 1196.4675719304826, 1198.6823491774687, 1207.7161866961258, 767.6277005652254, 839.8446287436843, 893.3032234856693, 923.3167456082585, 951.2437612098398, 5701.657900525899, 5776.682282474504, 5948.226730525091, 5960.821175364746, 6010.939890601126, 426.67146297350865, 431.5835219013667, 436.4435344935233, 438.401581840785, 442.6060805882767, 3528.2590655962535, 3893.8831835044994, 3915.314819906505, 4011.719179657396, 4062.756234625932, 8875.778105547413, 10308.948455257676, 10388.844167337496, 10750.78498430027, 10783.83824242637, 10502.737431564272, 10513.737156570995, 10526.894731052744, 10705.678829635124, 10714.46427678613, 6713.429358504484, 8078.474899131656, 8222.876656850152, 8257.339412846957, 8264.8347033059, 5803.419658034163, 5816.127580862735, 5866.2373891393845, 5890.852728681817, 5923.318818335946, 9899.703008909744, 9942.652007179713, 9987.750306242402, 10072.294939354175, 10106.242031847494, 9847.704568862946, 9888.258380621626, 10117.59529618807, 10201.489925503844, 10278.023587759151, 6531.11829902957, 6816.2502124767225, 7032.648367581703, 7074.3633073023675, 7074.823129421806, 8391.202835730595, 8393.412461127662, 8435.746927592181, 8483.10927352619, 8487.490750554987, 7236.2401947796825, 7376.6311684414495, 7462.888056679263, 7581.772546823604, 7599.848003040019, 7201.027861238876, 7338.046054012944, 7410.184879663125, 7421.146568144733, 7424.480286380118, 7009.579425234502, 7040.542364746275, 7067.434605231466, 7095.290470952863, 7100.0414943981905, 4799.928001080057, 4836.637252206028, 4947.82682606107, 4952.331435256338, 5158.587313015025, 4924.532169443897, 4998.900241946766, 5046.293518907282, 5087.771050302671, 5211.973940130275], "value": 4443.264793626469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.9283227146607, 306.44992238931104, 314.658595423965, 324.39824126245827, 326.7598315238299, 4562.6121779648765, 5216.374035115787, 5472.398036216023, 5697.4017728138515, 5717.912961768956, 5553.305836770403, 5599.592175364592, 5608.887822243552, 5622.184783206432, 5642.548596112311, 3611.620779818121, 4231.11715286696, 4233.343831706083, 4247.065645557972, 4280.880963922863, 3771.264603402338, 4107.589241075899, 4226.45056142701, 4229.661627069842, 4248.638865696419, 3672.869735553386, 3830.1190726133004, 3840.9039774005732, 4017.830371599333, 4077.55146933837, 4221.535631080578, 4227.577242275778, 4244.363345498169, 4266.914661706748, 4285.657147428229, 3685.907852303687, 3692.2430901665093, 3707.8331475083687, 3710.517632707758, 3720.386136287509, 4137.648299894513, 5298.814541491068, 5417.26866872971, 5419.783208671688, 5421.728913554307, 4359.38968544404, 4572.817087316504, 4660.813567457331, 4782.187028205213, 4854.587360074398, 5242.580593552507, 5665.490105890449, 5687.402822703598, 5903.675297858616, 5912.334352701309, 4021.336479480882, 4045.1707399982934, 4046.615571520701, 4057.2899742545233, 4062.0, 5888.558358123158, 6251.937480625181, 6259.843503912393, 6262.624242545462, 6276.5292603054495, 3625.8549658013912, 3946.3685810270217, 4016.8393264269102, 4090.854560723002, 4094.6928980326593, 3569.5359603251413, 3932.3511620582854, 4146.958530414658, 4149.2738770715005, 4167.99968007682, 4601.125786100697, 4768.737719425396, 4838.669365924399, 4888.682235654671, 4919.032387045139, 5651.547876169897, 5972.700510303477, 5989.520838332923, 5990.221271234712, 6068.9696551517845, 1389.3539504130426, 1423.7223741370333, 1424.480064776349, 1426.4222989689192, 1432.9140251584938, 848.3891598049395, 879.5487445714622, 879.7052987249343, 881.5592203898051, 884.8097659003345, 4656.906861862743, 4658.254679251304, 4661.764632372424, 4665.836695715634, 4690.2327626699725, 4717.113154738064, 4718.01843225846, 4824.517548245218, 4862.0, 5194.246834209094, 4368.291659171334, 4416.315471101984, 4588.2888152336445, 4655.37152484417, 4665.227213658788, 2379.2386436340553, 2580.574205256149, 2666.2267942296517, 2676.1703871799805, 2694.9461010779673, 4903.8528844134735, 5116.616253780981, 5191.8182863599595, 5286.788528458896, 5402.702851343135, 4714.858554243378, 4921.089598424232, 5106.78272688204, 5272.6309158358545, 5302.409277216897, 4425.3626158320985, 4757.8096876125255, 4983.651144419929, 5042.747862606929, 5353.250544923714, 4953.504649535018, 5120.871978200575, 5149.918517111449, 5187.98834227322, 5192.688438693691, 4157.688173386948, 4656.67403281767, 5089.414717307557, 5285.947140528546, 6134.8159555213415, 6122.632642041492, 6453.257875344396, 6454.290028097, 6465.319017055505, 6491.967540162269, 4017.77902215381, 4032.677385809187, 4063.959360406358, 4064.9106678924823, 4094.5905409458815, 5473.768402109494, 5937.554683398676, 6205.844853878689, 6215.81352559424, 6249.468795152281, 5070.746462676778, 5441.673499590037, 5551.7779288828, 5685.943140568541, 5825.621334613236, 5404.810831621032, 5487.759189973645, 5991.580589358703, 6041.580228646352, 6079.118527813531, 5014.448410674896, 5261.421903267448, 5491.368492623399, 5810.244668193108, 5873.823785286449, 4646.65150113736, 5616.932782771424, 6077.574569780073, 6126.295476020315, 6140.938590614215, 5605.421506107236, 5927.733252003756, 5945.494632956247, 5955.047192449188, 5990.3710110439515], "value": 4132.2222874220615, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.9742810287589, 675.3482889012103, 711.1856923822221, 727.6943683652869, 768.0514564512825, 11525.36610486423, 14973.25133743314, 15071.77392339113, 15255.771163432557, 15313.234338283082, 13694.52069177579, 13939.163650180997, 13980.650483737887, 14228.928855355709, 14325.92837035816, 750.2497502497503, 906.1527471813843, 923.4551614547415, 934.6401635370397, 953.704351650987, 918.8483900156466, 920.5213289089669, 927.5872236854602, 933.8132373525289, 934.3693007220133, 964.4261664309724, 991.6578780320814, 994.3983889746708, 996.5515518016886, 1000.5097502223895, 943.528235882059, 953.1231267234161, 953.9618415263382, 983.2871168402922, 987.1757082835841, 736.9815754606124, 737.1449119021962, 737.3842841227581, 741.5365396627108, 741.5884184277778, 14876.404943802236, 14895.851041489555, 15007.174605396698, 15048.172350520717, 15070.09579425238, 12857.935710321482, 12866.328122670802, 12926.54757083507, 12985.896198823017, 13182.340882955814, 10021.398716076987, 10293.330933489375, 10334.483275836254, 10675.893241067568, 10793.83809242862, 3492.3189977954057, 3534.3991521441417, 3534.9116272093147, 3578.6421357864265, 3659.70722342212, 87247.69128463078, 88132.0, 88374.0, 89664.55167724185, 90079.54960225223, 7880.763577092696, 9464.810703785886, 9489.90510094904, 9520.381175223589, 9537.475438850792, 22953.885230574073, 23496.295111146694, 23568.29295121149, 23629.409264768517, 23680.40798980039, 6409.289400555739, 7313.780586582411, 7520.473566850268, 7546.0, 7629.3114151623195, 4016.377461493473, 4044.797760111983, 4114.814833332507, 4115.1975364803575, 4126.154138401621, 3394.762366634312, 3505.9123521911647, 3608.729345299113, 3613.9700185447023, 3622.7464077514846, 635.9109724638554, 639.7185238495048, 642.2389468479773, 651.1402375969236, 651.9217693876765, 465.9387822009366, 532.8028629407066, 546.7211722021798, 582.7406803972192, 583.130857341206, 7868.527888326605, 8111.1888811118415, 8134.145914678992, 8338.583070846433, 8399.286060684788, 568.4287291272327, 575.4648177195165, 590.5551018782245, 595.8480587450259, 616.5745635511482, 3734.738568300193, 3786.261678972574, 3797.5442946846556, 3847.961520384816, 3937.251922134785, 76362.0, 82452.58773706094, 84301.0, 88034.0, 88094.55952720196, 65530.68938621201, 76561.61719191368, 76894.615526922, 77114.22885771183, 78149.0, 69100.30899691039, 71174.22064448327, 71281.0, 71310.0, 71424.64287678528, 8217.096119426978, 8281.627326770189, 8300.168852450919, 8300.750977470685, 8339.0, 37395.0, 43624.345634816134, 43942.340864887694, 43989.560104398544, 45168.0, 55333.723331384695, 55717.721411394305, 58544.0, 61797.38202617916, 62430.0, 10703.625373111903, 10779.299345542515, 11125.944370278097, 11277.984981351718, 11281.605143819927, 11748.647540573455, 11816.291022538331, 11823.11326650522, 11969.521219151136, 12216.26702397859, 11129.499172537417, 11187.608433704781, 11337.943310283727, 11417.543298267976, 11553.24903881245, 8949.418287811272, 11402.885971140515, 11420.0, 11551.826722599335, 11715.531378744754, 9099.454032758056, 10530.736731581464, 11087.55649774, 11297.209195356574, 11749.0, 8621.956890215759, 9807.754806129904, 9970.102690757576, 9977.950110249401, 10134.847977280198, 9616.471094089613, 9627.518624068629, 9740.853887191555, 9767.658131965347, 9811.803763924825], "value": 6849.2705282633415, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 117.0, 105.0, 127.0, 114.0, 116.0, 114.0, 116.0, 132.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 142.0, 175.0, 187.0, 151.0, 164.0, 164.0, 123.0, 173.0, 119.0], "value": 155.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 200.0, 239.0, 195.0, 200.0, 199.0, 194.0, 202.0, 194.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 84.0, 74.0, 151.0, 57.0, 57.0, 58.0, 57.0, 133.0, 58.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 124.0, 71.0, 72.0, 77.0, 117.0, 72.0, 74.0, 124.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 88.0, 91.0, 87.0, 92.0, 88.0, 90.0, 87.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 106.0, 152.0, 150.0, 95.0, 97.0, 96.0, 110.0, 128.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 90.0, 92.0, 116.0, 148.0, 101.0, 149.0, 131.0, 90.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 58.0, 58.0, 60.0, 61.0, 61.0, 71.0, 59.0, 58.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 68.0, 77.0, 76.0, 72.0, 68.0, 88.0, 107.0, 105.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 93.0, 109.0, 108.0, 81.0, 103.0, 108.0, 80.0, 97.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 143.0, 148.0, 126.0, 158.0, 134.0, 160.0, 154.0, 191.0, 160.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 231.0, 191.0, 167.0, 165.0, 169.0, 166.0, 168.0, 163.0, 166.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 83.0, 83.0, 83.0, 81.0, 84.0, 74.0, 65.0, 67.0], "value": 75.0, "unit": "score"}]}]} 10:25:44 INFO - Return code: 0 10:25:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:25:44 ERROR - # TBPL SUCCESS # 10:25:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:25:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:25:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:25:44 INFO - 2016-05-26 10:25:44.570 screenresolution[1960:28323] starting screenresolution argv=screenresolution get 10:25:44 INFO - 2016-05-26 10:25:44.573 screenresolution[1960:28323] Display 0: 1600x1200x32@60 10:25:44 INFO - 2016-05-26 10:25:44.579 screenresolution[1961:28326] starting screenresolution argv=screenresolution list 10:25:44 INFO - Available Modes on Display 0 10:25:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:25:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:25:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:25:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:25:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:25:44 INFO - Intel Iris: 10:25:44 INFO - Chipset Model: Intel Iris 10:25:44 INFO - Type: GPU 10:25:44 INFO - Bus: Built-In 10:25:44 INFO - VRAM (Dynamic, Max): 1536 MB 10:25:44 INFO - Vendor: Intel (0x8086) 10:25:44 INFO - Device ID: 0x0a2e 10:25:44 INFO - Revision ID: 0x0009 10:25:44 INFO - Displays: 10:25:44 INFO - Display: 10:25:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:25:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:25:44 INFO - Display Serial Number: 1600x1200 60 10:25:44 INFO - Main Display: Yes 10:25:44 INFO - Mirror: Off 10:25:44 INFO - Online: Yes 10:25:44 INFO - Rotation: Supported 10:25:44 INFO - Return code: 0 10:25:44 INFO - Running post-action listener: _resource_record_post_action 10:25:44 INFO - ##### 10:25:44 INFO - ##### Finished run-tests step (success) 10:25:44 INFO - ##### 10:25:44 INFO - Running post-run listener: _resource_record_post_run 10:25:44 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 991232; Write bytes: 696877056; Read time: 10; Write time: 1944 10:25:44 INFO - TinderboxPrint: CPU usage
54.9% 10:25:44 INFO - TinderboxPrint: I/O read bytes / time
991,232 / 10 10:25:44 INFO - TinderboxPrint: I/O write bytes / time
696,877,056 / 1,944 10:25:44 INFO - TinderboxPrint: CPU idle
2,181.8 (45.1%) 10:25:44 INFO - TinderboxPrint: CPU system
976.3 (20.2%) 10:25:44 INFO - TinderboxPrint: CPU user
1,675.5 (34.7%) 10:25:44 INFO - TinderboxPrint: Swap in / out
397,811,712 / 0 10:25:44 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380510720; Write bytes: 374978048; Read time: 28217; Write time: 711 10:25:44 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 991232; Write bytes: 296892928; Read time: 10; Write time: 1183 10:25:45 INFO - Running post-run listener: _upload_blobber_files 10:25:45 INFO - Blob upload gear active. 10:25:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:45 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 10:25:45 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'] 10:25:45 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 10:25:45 INFO - (blobuploader) - INFO - Open directory for files ... 10:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:46 INFO - (blobuploader) - INFO - Done attempting. 10:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:47 INFO - (blobuploader) - INFO - Done attempting. 10:25:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:25:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:50 INFO - (blobuploader) - INFO - Done attempting. 10:25:50 INFO - (blobuploader) - INFO - Iteration through files over. 10:25:50 INFO - Return code: 0 10:25:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:25:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:25:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15fdc818f89c7b1b48b0236835bbdd3b48944e88e3b874f59a1fad78b7295a650a41ced8bebe4bd82d0d1fd1cf35fd432c8e6d962aa0bd2147ab07e6878a3945", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e3206410b756bae4fc363afcc9bc433e0147139f00ce9139ff23ff3dc12c6fa0729fce0e8f0b668fd08a883899b3c68c3b34f4f16407574547ba4e03a28614", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a384a67e9ffce3dbe09e491acecc5dec88452f069e2c639753b7b3e75b99fc6ec7adaf31807e86f1eafb9685db9f77de696acebfd43c3b56172e524feb2b93dc"} 10:25:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:25:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:25:50 INFO - Contents: 10:25:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15fdc818f89c7b1b48b0236835bbdd3b48944e88e3b874f59a1fad78b7295a650a41ced8bebe4bd82d0d1fd1cf35fd432c8e6d962aa0bd2147ab07e6878a3945", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e3206410b756bae4fc363afcc9bc433e0147139f00ce9139ff23ff3dc12c6fa0729fce0e8f0b668fd08a883899b3c68c3b34f4f16407574547ba4e03a28614", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a384a67e9ffce3dbe09e491acecc5dec88452f069e2c639753b7b3e75b99fc6ec7adaf31807e86f1eafb9685db9f77de696acebfd43c3b56172e524feb2b93dc"} 10:25:50 INFO - Running post-run listener: copy_logs_to_upload_dir 10:25:50 INFO - Copying logs to upload dir... 10:25:50 INFO - mkdir: /builds/slave/test/build/upload/logs 10:25:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.323010 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 10:25:50.397878) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 10:25:50.401470) ========= 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.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15fdc818f89c7b1b48b0236835bbdd3b48944e88e3b874f59a1fad78b7295a650a41ced8bebe4bd82d0d1fd1cf35fd432c8e6d962aa0bd2147ab07e6878a3945", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e3206410b756bae4fc363afcc9bc433e0147139f00ce9139ff23ff3dc12c6fa0729fce0e8f0b668fd08a883899b3c68c3b34f4f16407574547ba4e03a28614", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a384a67e9ffce3dbe09e491acecc5dec88452f069e2c639753b7b3e75b99fc6ec7adaf31807e86f1eafb9685db9f77de696acebfd43c3b56172e524feb2b93dc"} build_url:https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/TnHT3JeURAOQmrJyjKvp_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15fdc818f89c7b1b48b0236835bbdd3b48944e88e3b874f59a1fad78b7295a650a41ced8bebe4bd82d0d1fd1cf35fd432c8e6d962aa0bd2147ab07e6878a3945", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01e3206410b756bae4fc363afcc9bc433e0147139f00ce9139ff23ff3dc12c6fa0729fce0e8f0b668fd08a883899b3c68c3b34f4f16407574547ba4e03a28614", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a384a67e9ffce3dbe09e491acecc5dec88452f069e2c639753b7b3e75b99fc6ec7adaf31807e86f1eafb9685db9f77de696acebfd43c3b56172e524feb2b93dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 10:25:50.432614) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:25:50.433031) ========= 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.UnVzSB0csI/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.zbwO4xEBAj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.005316 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:25:50.482623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 10:25:50.482951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 10:25:50.483384) ========= ========= Total master_lag: 0.17 =========