builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1463573651.92 results: success (0) buildid: 20160518030234 builduid: f769d09b003647779edb2f3bf3073c37 revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:11.919171) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:11.919633) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:11.919933) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:11.937512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:11.937812) ========= 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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-18 05:14:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.3M=0.001s 2016-05-18 05:14:12 (12.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:12.048317) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:12.048644) ========= 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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.021664 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:12.097181) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 05:14:12.097511) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --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 f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-18 05:14:12,181 truncating revision to first 12 chars 2016-05-18 05:14:12,181 Setting DEBUG logging. 2016-05-18 05:14:12,181 attempt 1/10 2016-05-18 05:14:12,181 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3f2fa1d7eed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 05:14:14,239 unpacking tar archive at: mozilla-central-f3f2fa1d7eed/testing/mozharness/ program finished with exit code 0 elapsedTime=2.310235 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-18 05:14:14.428727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:14.429056) ========= script_repo_revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:14.429455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:14.432784) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 05:14:14.444389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-18 05:14:14.444687) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:14:14 INFO - MultiFileLogger online at 20160518 05:14:14 in /builds/slave/test 05:14:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:14:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:14 INFO - {'append_to_log': False, 05:14:14 INFO - 'base_work_dir': '/builds/slave/test', 05:14:14 INFO - 'blob_upload_branch': 'Firefox', 05:14:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:14 INFO - 'branch': 'Firefox', 05:14:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:14 INFO - 'config_files': ('talos/mac_config.py',), 05:14:14 INFO - 'default_actions': ('clobber', 05:14:14 INFO - 'read-buildbot-config', 05:14:14 INFO - 'download-and-extract', 05:14:14 INFO - 'populate-webroot', 05:14:14 INFO - 'create-virtualenv', 05:14:14 INFO - 'install', 05:14:14 INFO - 'run-tests'), 05:14:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:14 INFO - 'download_minidump_stackwalk': True, 05:14:14 INFO - 'download_symbols': 'ondemand', 05:14:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:14 INFO - 'installer_path': 'installer.exe', 05:14:14 INFO - 'log_level': 'info', 05:14:14 INFO - 'log_name': 'talos', 05:14:14 INFO - 'log_to_console': True, 05:14:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:14 INFO - 'opt_config_files': (), 05:14:14 INFO - 'pip_index': False, 05:14:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:14 INFO - 'cmd': ('bash', 05:14:14 INFO - '-c', 05:14:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:14 INFO - 'enabled': True, 05:14:14 INFO - 'halt_on_failure': False, 05:14:14 INFO - 'name': 'check_screen_resolution'},), 05:14:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:14 INFO - 'cmd': ('bash', 05:14:14 INFO - '-c', 05:14:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:14 INFO - 'enabled': True, 05:14:14 INFO - 'halt_on_failure': False, 05:14:14 INFO - 'name': 'check_screen_resolution'},), 05:14:14 INFO - 'run_cmd_checks_enabled': True, 05:14:14 INFO - 'sps_profile': False, 05:14:14 INFO - 'sps_profile_interval': 0, 05:14:14 INFO - 'suite': 'dromaeojs', 05:14:14 INFO - 'system_bits': '32', 05:14:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:14 INFO - 'title': 't-yosemite-r7-0077', 05:14:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:14 INFO - 'use_talos_json': True, 05:14:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:14 INFO - 'work_dir': 'build'} 05:14:14 INFO - ##### 05:14:14 INFO - ##### Running clobber step. 05:14:14 INFO - ##### 05:14:14 INFO - Running pre-action listener: _resource_record_pre_action 05:14:14 INFO - Running main action method: clobber 05:14:14 INFO - rmtree: /builds/slave/test/build 05:14:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:15 INFO - Running post-action listener: _resource_record_post_action 05:14:15 INFO - ##### 05:14:15 INFO - ##### Finished clobber step (success) 05:14:15 INFO - ##### 05:14:15 INFO - ##### 05:14:15 INFO - ##### Running read-buildbot-config step. 05:14:15 INFO - ##### 05:14:15 INFO - Running pre-action listener: _resource_record_pre_action 05:14:15 INFO - Running main action method: read_buildbot_config 05:14:15 INFO - Using buildbot properties: 05:14:15 INFO - { 05:14:15 INFO - "project": "", 05:14:15 INFO - "product": "firefox", 05:14:15 INFO - "script_repo_revision": "production", 05:14:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:14:15 INFO - "repository": "", 05:14:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:14:15 INFO - "buildid": "20160518030234", 05:14:15 INFO - "stage_platform": "macosx64", 05:14:15 INFO - "basedir": "/builds/slave/test", 05:14:15 INFO - "nightly_build": "True", 05:14:15 INFO - "buildnumber": 2, 05:14:15 INFO - "slavename": "t-yosemite-r7-0077", 05:14:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:14:15 INFO - "platform": "yosemite_r7", 05:14:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:14:15 INFO - "branch": "mozilla-central", 05:14:15 INFO - "repo_path": "mozilla-central", 05:14:15 INFO - "revision": "f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e", 05:14:15 INFO - "pgo_build": "False", 05:14:15 INFO - "builduid": "f769d09b003647779edb2f3bf3073c37", 05:14:15 INFO - "slavebuilddir": "test" 05:14:15 INFO - } 05:14:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:14:15 INFO - Running post-action listener: _resource_record_post_action 05:14:15 INFO - ##### 05:14:15 INFO - ##### Finished read-buildbot-config step (success) 05:14:15 INFO - ##### 05:14:15 INFO - ##### 05:14:15 INFO - ##### Running download-and-extract step. 05:14:15 INFO - ##### 05:14:15 INFO - Running pre-action listener: _resource_record_pre_action 05:14:15 INFO - Running main action method: download_and_extract 05:14:15 INFO - mkdir: /builds/slave/test/build/tests 05:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:15 INFO - https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:14:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:14:16 INFO - Downloaded 1199 bytes. 05:14:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:14:16 INFO - Using the following test package requirements: 05:14:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:14:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:14:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:14:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'jsshell-mac.zip'], 05:14:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:14:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:14:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:14:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:14:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:14:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:14:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:14:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:16 INFO - https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:17 INFO - Downloaded 18267915 bytes. 05:14:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:20 INFO - Return code: 0 05:14:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:20 INFO - https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:21 INFO - Downloaded 18267915 bytes. 05:14:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:25 INFO - Return code: 0 05:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:25 INFO - https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:14:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:14:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:14:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:14:26 INFO - Downloaded 10840194 bytes. 05:14:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:14:26 INFO - Return code: 0 05:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:26 INFO - https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:14:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:14:29 INFO - Downloaded 110263094 bytes. 05:14:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:29 INFO - mkdir: /builds/slave/test/properties 05:14:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:29 INFO - Contents: 05:14:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:14:30 INFO - Running post-action listener: _resource_record_post_action 05:14:30 INFO - Running post-action listener: set_extra_try_arguments 05:14:30 INFO - ##### 05:14:30 INFO - ##### Finished download-and-extract step (success) 05:14:30 INFO - ##### 05:14:30 INFO - ##### 05:14:30 INFO - ##### Running populate-webroot step. 05:14:30 INFO - ##### 05:14:30 INFO - Running pre-action listener: _resource_record_pre_action 05:14:30 INFO - Running main action method: populate_webroot 05:14:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:30 INFO - '--tppagecycles', 05:14:30 INFO - '7'], 05:14:30 INFO - 'tests': ['tsvgm']}}, 05:14:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:14:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:14:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:14:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['damp', 'tps']}, 05:14:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'tests': ['damp', 'tps']}, 05:14:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['dromaeo_dom']}, 05:14:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['a11yr', 05:14:30 INFO - 'ts_paint', 05:14:30 INFO - 'tpaint', 05:14:30 INFO - 'sessionrestore', 05:14:30 INFO - 'sessionrestore_no_auto_restore', 05:14:30 INFO - 'tabpaint']}, 05:14:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['tsvgx', 05:14:30 INFO - 'tsvgr_opacity', 05:14:30 INFO - 'tart', 05:14:30 INFO - 'tscrollx', 05:14:30 INFO - 'cart']}, 05:14:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:14:30 INFO - 'tsvgr_opacity', 05:14:30 INFO - 'tart', 05:14:30 INFO - 'tscrollx', 05:14:30 INFO - 'cart']}, 05:14:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'talos_options': ['--disable-e10s'], 05:14:30 INFO - 'tests': ['tp5o']}, 05:14:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'tests': ['tp5o']}, 05:14:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'talos_options': ['--disable-e10s', 05:14:30 INFO - '--xperf_path', 05:14:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:30 INFO - 'tests': ['tp5n']}, 05:14:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:30 INFO - 'talos_options': ['--xperf_path', 05:14:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:30 INFO - 'tests': ['tp5n']}}, 05:14:30 INFO - 'talos.zip': {'path': '', 05:14:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:30 INFO - Running post-action listener: _resource_record_post_action 05:14:30 INFO - ##### 05:14:30 INFO - ##### Finished populate-webroot step (success) 05:14:30 INFO - ##### 05:14:30 INFO - ##### 05:14:30 INFO - ##### Running create-virtualenv step. 05:14:30 INFO - ##### 05:14:30 INFO - Running pre-action listener: _resource_record_pre_action 05:14:30 INFO - Running main action method: create_virtualenv 05:14:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:14:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:30 INFO - Using real prefix '/tools/python27' 05:14:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:34 INFO - Installing pip.................done. 05:14:34 INFO - Return code: 0 05:14:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:34 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:34 INFO - 'HOME': '/Users/cltbld', 05:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:34 INFO - 'LOGNAME': 'cltbld', 05:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:34 INFO - 'MOZ_NO_REMOTE': '1', 05:14:34 INFO - 'NO_EM_RESTART': '1', 05:14:34 INFO - 'PAGER': '/bin/cat', 05:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:34 INFO - 'PWD': '/builds/slave/test', 05:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:34 INFO - 'SHELL': '/bin/bash', 05:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:34 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:34 INFO - 'USER': 'cltbld', 05:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:34 INFO - 'XPC_FLAGS': '0x0', 05:14:34 INFO - 'XPC_SERVICE_NAME': '0', 05:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:14:34 INFO - Cleaning up... 05:14:34 INFO - Return code: 0 05:14:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:34 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:34 INFO - 'HOME': '/Users/cltbld', 05:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:34 INFO - 'LOGNAME': 'cltbld', 05:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:34 INFO - 'MOZ_NO_REMOTE': '1', 05:14:34 INFO - 'NO_EM_RESTART': '1', 05:14:34 INFO - 'PAGER': '/bin/cat', 05:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:34 INFO - 'PWD': '/builds/slave/test', 05:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:34 INFO - 'SHELL': '/bin/bash', 05:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:34 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:34 INFO - 'USER': 'cltbld', 05:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:34 INFO - 'XPC_FLAGS': '0x0', 05:14:34 INFO - 'XPC_SERVICE_NAME': '0', 05:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:34 INFO - Downloading/unpacking psutil>=3.1.1 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:37 INFO - Installing collected packages: psutil 05:14:37 INFO - Running setup.py install for psutil 05:14:37 INFO - building 'psutil._psutil_osx' extension 05:14:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:14:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:14:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:14:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:14:37 INFO - building 'psutil._psutil_posix' extension 05:14:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:14:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:37 INFO - ^ 05:14:37 INFO - 1 warning generated. 05:14:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:14:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:38 INFO - Successfully installed psutil 05:14:38 INFO - Cleaning up... 05:14:38 INFO - Return code: 0 05:14:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:38 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:38 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 05:14:38 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 05:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:38 INFO - 'HOME': '/Users/cltbld', 05:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:38 INFO - 'LOGNAME': 'cltbld', 05:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:38 INFO - 'MOZ_NO_REMOTE': '1', 05:14:38 INFO - 'NO_EM_RESTART': '1', 05:14:38 INFO - 'PAGER': '/bin/cat', 05:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:38 INFO - 'PWD': '/builds/slave/test', 05:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:38 INFO - 'SHELL': '/bin/bash', 05:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:38 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:38 INFO - 'USER': 'cltbld', 05:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:38 INFO - 'XPC_FLAGS': '0x0', 05:14:38 INFO - 'XPC_SERVICE_NAME': '0', 05:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:14:40 INFO - Installing collected packages: mozsystemmonitor 05:14:40 INFO - Running setup.py install for mozsystemmonitor 05:14:41 INFO - Successfully installed mozsystemmonitor 05:14:41 INFO - Cleaning up... 05:14:41 INFO - Return code: 0 05:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:41 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:41 INFO - 'HOME': '/Users/cltbld', 05:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:41 INFO - 'LOGNAME': 'cltbld', 05:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:41 INFO - 'MOZ_NO_REMOTE': '1', 05:14:41 INFO - 'NO_EM_RESTART': '1', 05:14:41 INFO - 'PAGER': '/bin/cat', 05:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:41 INFO - 'PWD': '/builds/slave/test', 05:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:41 INFO - 'SHELL': '/bin/bash', 05:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:41 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:41 INFO - 'USER': 'cltbld', 05:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:41 INFO - 'XPC_FLAGS': '0x0', 05:14:41 INFO - 'XPC_SERVICE_NAME': '0', 05:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:41 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:43 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:44 INFO - Downloading docopt-0.6.1.tar.gz 05:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:45 INFO - Running setup.py install for blobuploader 05:14:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:45 INFO - Running setup.py install for requests 05:14:45 INFO - Running setup.py install for docopt 05:14:45 INFO - Successfully installed blobuploader requests docopt 05:14:45 INFO - Cleaning up... 05:14:45 INFO - Return code: 0 05:14:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:45 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:45 INFO - 'HOME': '/Users/cltbld', 05:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:45 INFO - 'LOGNAME': 'cltbld', 05:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:45 INFO - 'MOZ_NO_REMOTE': '1', 05:14:45 INFO - 'NO_EM_RESTART': '1', 05:14:45 INFO - 'PAGER': '/bin/cat', 05:14:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:45 INFO - 'PWD': '/builds/slave/test', 05:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:45 INFO - 'SHELL': '/bin/bash', 05:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:45 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:45 INFO - 'USER': 'cltbld', 05:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:45 INFO - 'XPC_FLAGS': '0x0', 05:14:45 INFO - 'XPC_SERVICE_NAME': '0', 05:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-RCtoEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HY3vSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-LcrXgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yrg10s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-y1ndvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2jtYCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Kso3Qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:46 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-oSuAGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-xVpQh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-X7C6sY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_HYt3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-oBNOX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2JhkQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-j29lMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-9AbWXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zZo2g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:47 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-mmeVMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:47 INFO - Running setup.py install for manifestparser 05:14:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Running setup.py install for mozcrash 05:14:48 INFO - Running setup.py install for mozdebug 05:14:48 INFO - Running setup.py install for mozdevice 05:14:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Running setup.py install for mozfile 05:14:48 INFO - Running setup.py install for mozhttpd 05:14:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:48 INFO - Running setup.py install for mozinfo 05:14:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Running setup.py install for mozInstall 05:14:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Running setup.py install for mozleak 05:14:49 INFO - Running setup.py install for mozlog 05:14:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Running setup.py install for moznetwork 05:14:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Running setup.py install for mozprocess 05:14:49 INFO - Running setup.py install for mozprofile 05:14:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:49 INFO - Running setup.py install for mozrunner 05:14:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:50 INFO - Running setup.py install for mozscreenshot 05:14:50 INFO - Running setup.py install for moztest 05:14:50 INFO - Running setup.py install for mozversion 05:14:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:50 INFO - Cleaning up... 05:14:50 INFO - Return code: 0 05:14:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:50 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:50 INFO - 'HOME': '/Users/cltbld', 05:14:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:50 INFO - 'LOGNAME': 'cltbld', 05:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:50 INFO - 'MOZ_NO_REMOTE': '1', 05:14:50 INFO - 'NO_EM_RESTART': '1', 05:14:50 INFO - 'PAGER': '/bin/cat', 05:14:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:50 INFO - 'PWD': '/builds/slave/test', 05:14:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:50 INFO - 'SHELL': '/bin/bash', 05:14:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:50 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:50 INFO - 'USER': 'cltbld', 05:14:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:50 INFO - 'XPC_FLAGS': '0x0', 05:14:50 INFO - 'XPC_SERVICE_NAME': '0', 05:14:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-M3tqWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fJieD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3QCYIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-6pujBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-aUyR_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BVnwRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_nO2FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DRLrSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fguzmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HLJRFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-WjbCCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-cRiO0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-z1pFsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-LWY0pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-MUYdTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-9fpdEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:14:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:52 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-OgeYjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:54 INFO - Downloading blessings-1.6.tar.gz 05:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:55 INFO - Installing collected packages: blessings 05:14:55 INFO - Running setup.py install for blessings 05:14:55 INFO - Successfully installed blessings 05:14:55 INFO - Cleaning up... 05:14:55 INFO - Return code: 0 05:14:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:55 INFO - Reading from file tmpfile_stdout 05:14:55 INFO - Current package versions: 05:14:55 INFO - blessings == 1.6 05:14:55 INFO - blobuploader == 1.2.4 05:14:55 INFO - docopt == 0.6.1 05:14:55 INFO - manifestparser == 1.1 05:14:55 INFO - mozInstall == 1.12 05:14:55 INFO - mozcrash == 0.17 05:14:55 INFO - mozdebug == 0.1 05:14:55 INFO - mozdevice == 0.48 05:14:55 INFO - mozfile == 1.2 05:14:55 INFO - mozhttpd == 0.7 05:14:55 INFO - mozinfo == 0.9 05:14:55 INFO - mozleak == 0.1 05:14:55 INFO - mozlog == 3.2 05:14:55 INFO - moznetwork == 0.27 05:14:55 INFO - mozprocess == 0.22 05:14:55 INFO - mozprofile == 0.28 05:14:55 INFO - mozrunner == 6.11 05:14:55 INFO - mozscreenshot == 0.1 05:14:55 INFO - mozsystemmonitor == 0.3 05:14:55 INFO - moztest == 0.7 05:14:55 INFO - mozversion == 1.4 05:14:55 INFO - psutil == 3.1.1 05:14:55 INFO - requests == 1.2.3 05:14:55 INFO - wsgiref == 0.1.2 05:14:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:55 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:55 INFO - 'HOME': '/Users/cltbld', 05:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:55 INFO - 'LOGNAME': 'cltbld', 05:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:55 INFO - 'MOZ_NO_REMOTE': '1', 05:14:55 INFO - 'NO_EM_RESTART': '1', 05:14:55 INFO - 'PAGER': '/bin/cat', 05:14:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:55 INFO - 'PWD': '/builds/slave/test', 05:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:55 INFO - 'SHELL': '/bin/bash', 05:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:55 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:55 INFO - 'USER': 'cltbld', 05:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:55 INFO - 'XPC_FLAGS': '0x0', 05:14:55 INFO - 'XPC_SERVICE_NAME': '0', 05:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:55 INFO - Cleaning up... 05:14:55 INFO - Return code: 0 05:14:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:55 INFO - 'HOME': '/Users/cltbld', 05:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:55 INFO - 'LOGNAME': 'cltbld', 05:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:55 INFO - 'MOZ_NO_REMOTE': '1', 05:14:55 INFO - 'NO_EM_RESTART': '1', 05:14:55 INFO - 'PAGER': '/bin/cat', 05:14:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:55 INFO - 'PWD': '/builds/slave/test', 05:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:55 INFO - 'SHELL': '/bin/bash', 05:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:55 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:55 INFO - 'USER': 'cltbld', 05:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:55 INFO - 'XPC_FLAGS': '0x0', 05:14:55 INFO - 'XPC_SERVICE_NAME': '0', 05:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:56 INFO - Downloading/unpacking jsonschema 05:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:58 INFO - Downloading/unpacking functools32 (from jsonschema) 05:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:59 INFO - warning: no files found matching '*.txt' 05:14:59 INFO - no previously-included directories found matching 'build' 05:14:59 INFO - no previously-included directories found matching 'dist' 05:14:59 INFO - no previously-included directories found matching '.git*' 05:14:59 INFO - Installing collected packages: jsonschema, functools32 05:14:59 INFO - Running setup.py install for functools32 05:14:59 INFO - warning: no files found matching '*.txt' 05:14:59 INFO - no previously-included directories found matching 'build' 05:14:59 INFO - no previously-included directories found matching 'dist' 05:14:59 INFO - no previously-included directories found matching '.git*' 05:14:59 INFO - Successfully installed jsonschema functools32 05:14:59 INFO - Cleaning up... 05:14:59 INFO - Return code: 0 05:14:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5d9c68940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:14:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:14:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:59 INFO - 'HOME': '/Users/cltbld', 05:14:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:59 INFO - 'LOGNAME': 'cltbld', 05:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:59 INFO - 'MOZ_NO_REMOTE': '1', 05:14:59 INFO - 'NO_EM_RESTART': '1', 05:14:59 INFO - 'PAGER': '/bin/cat', 05:14:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:59 INFO - 'PWD': '/builds/slave/test', 05:14:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:59 INFO - 'SHELL': '/bin/bash', 05:14:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:14:59 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:14:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:59 INFO - 'USER': 'cltbld', 05:14:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:59 INFO - 'XPC_FLAGS': '0x0', 05:14:59 INFO - 'XPC_SERVICE_NAME': '0', 05:14:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:59 INFO - Downloading/unpacking flake8 05:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:15:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:15:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:15:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:03 INFO - no previously-included directories found matching 'docs/_build' 05:15:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:15:03 INFO - Running setup.py install for pep8 05:15:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:03 INFO - no previously-included directories found matching 'docs/_build' 05:15:03 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:15:03 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:15:03 INFO - Cleaning up... 05:15:03 INFO - Return code: 0 05:15:03 INFO - Running post-action listener: _resource_record_post_action 05:15:03 INFO - Running post-action listener: _start_resource_monitoring 05:15:03 INFO - Starting resource monitoring. 05:15:03 INFO - ##### 05:15:03 INFO - ##### Finished create-virtualenv step (success) 05:15:03 INFO - ##### 05:15:03 INFO - ##### 05:15:03 INFO - ##### Running install step. 05:15:03 INFO - ##### 05:15:03 INFO - Running pre-action listener: _resource_record_pre_action 05:15:03 INFO - Running main action method: install 05:15:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:04 INFO - Reading from file tmpfile_stdout 05:15:04 INFO - Detecting whether we're running mozinstall >=1.0... 05:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:15:04 INFO - Reading from file tmpfile_stdout 05:15:04 INFO - Output received: 05:15:04 INFO - Usage: mozinstall [options] installer 05:15:04 INFO - Options: 05:15:04 INFO - -h, --help show this help message and exit 05:15:04 INFO - -d DEST, --destination=DEST 05:15:04 INFO - Directory to install application into. [default: 05:15:04 INFO - "/builds/slave/test"] 05:15:04 INFO - --app=APP Application being installed. [default: firefox] 05:15:04 INFO - mkdir: /builds/slave/test/build/application 05:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:15:37 INFO - Reading from file tmpfile_stdout 05:15:37 INFO - Output received: 05:15:37 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:15:37 INFO - Running post-action listener: _resource_record_post_action 05:15:37 INFO - ##### 05:15:37 INFO - ##### Finished install step (success) 05:15:37 INFO - ##### 05:15:37 INFO - ##### 05:15:37 INFO - ##### Running run-tests step. 05:15:37 INFO - ##### 05:15:37 INFO - Running pre-action listener: _resource_record_pre_action 05:15:37 INFO - Running main action method: run_tests 05:15:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:37 INFO - 2016-05-18 05:15:37.890 screenresolution[6529:105944] starting screenresolution argv=screenresolution get 05:15:37 INFO - 2016-05-18 05:15:37.893 screenresolution[6529:105944] Display 0: 1600x1200x32@60 05:15:37 INFO - 2016-05-18 05:15:37.899 screenresolution[6530:105946] starting screenresolution argv=screenresolution list 05:15:37 INFO - Available Modes on Display 0 05:15:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:38 INFO - Intel Iris: 05:15:38 INFO - Chipset Model: Intel Iris 05:15:38 INFO - Type: GPU 05:15:38 INFO - Bus: Built-In 05:15:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:38 INFO - Vendor: Intel (0x8086) 05:15:38 INFO - Device ID: 0x0a2e 05:15:38 INFO - Revision ID: 0x0009 05:15:38 INFO - Displays: 05:15:38 INFO - Display: 05:15:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:38 INFO - Display Serial Number: 1600x1200 60 05:15:38 INFO - Main Display: Yes 05:15:38 INFO - Mirror: Off 05:15:38 INFO - Online: Yes 05:15:38 INFO - Rotation: Supported 05:15:38 INFO - Return code: 0 05:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:38 INFO - Python 2.7.3 05:15:38 INFO - Return code: 0 05:15:38 INFO - grabbing minidump binary from tooltool 05:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:38 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 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:15:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:15:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:15:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:38 INFO - Return code: 0 05:15:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:15:39 INFO - Return code: 0 05:15:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:15:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.44nUD2vlu8/Render', 05:15:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:39 INFO - 'HOME': '/Users/cltbld', 05:15:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:39 INFO - 'LOGNAME': 'cltbld', 05:15:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:39 INFO - 'MOZ_NO_REMOTE': '1', 05:15:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:39 INFO - 'NO_EM_RESTART': '1', 05:15:39 INFO - 'PAGER': '/bin/cat', 05:15:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:39 INFO - 'PWD': '/builds/slave/test', 05:15:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:39 INFO - 'SHELL': '/bin/bash', 05:15:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d8O0Ka70Xt/Listeners', 05:15:39 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 05:15:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:39 INFO - 'USER': 'cltbld', 05:15:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:39 INFO - 'XPC_FLAGS': '0x0', 05:15:39 INFO - 'XPC_SERVICE_NAME': '0', 05:15:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:15:39 INFO - mozversion application_buildid: 20160518030234 05:15:39 INFO - mozversion application_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 05:15:39 INFO - mozversion application_display_name: Nightly 05:15:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:39 INFO - mozversion application_name: Firefox 05:15:39 INFO - mozversion application_remotingname: firefox 05:15:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:39 INFO - mozversion application_vendor: Mozilla 05:15:39 INFO - mozversion application_version: 49.0a1 05:15:39 INFO - mozversion platform_buildid: 20160518030234 05:15:39 INFO - mozversion platform_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 05:15:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:39 INFO - mozversion platform_version: 49.0a1 05:15:39 INFO - using testdate: 1463573739 05:15:39 INFO - actual date: 1463573739 05:15:39 INFO - starting webserver on 'localhost:57391' 05:15:39 INFO - SUITE-START | Running 2 tests 05:15:39 INFO - TEST-START | dromaeo_css 05:15:39 INFO - operating with platform_type : mac_ 05:15:39 INFO - Initialising browser for dromaeo_css test... 05:15:39 INFO - TEST-INFO | started process 6548 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpLr8rBM/profile http://localhost:57391/getInfo.html) 05:15:42 INFO - PROCESS | 6548 | __metrics Screen width/height:1600/1200 05:15:42 INFO - PROCESS | 6548 | colorDepth:24 05:15:42 INFO - PROCESS | 6548 | Browser inner width/height: 1024/689 05:15:42 INFO - PROCESS | 6548 | __metrics 05:15:42 INFO - PROCESS | 6548 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:15:42 ERROR - PROCESS | 6548 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:15:42 INFO - TEST-INFO | 6548: exit 0 05:15:42 INFO - Browser initialized. 05:15:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:15:42 INFO - TEST-INFO | started process 6551 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpLr8rBM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:54 INFO - PROCESS | 6551 | RSS: Main: 238374912 05:15:54 INFO - PROCESS | 6551 | 05:16:45 INFO - PROCESS | 6551 | 1463573805660 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:18:56 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57391/tests/dromaeo/cssquery-ext.html) 05:18:58 INFO - PROCESS | 6551 | RSS: Main: 340635648 05:18:58 INFO - PROCESS | 6551 | 05:22:01 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-ext.html (next: http://localhost:57391/tests/dromaeo/cssquery-jquery.html) 05:22:01 INFO - PROCESS | 6551 | RSS: Main: 375459840 05:22:01 INFO - PROCESS | 6551 | 05:25:04 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57391/tests/dromaeo/cssquery-mootools.html) 05:25:04 INFO - PROCESS | 6551 | RSS: Main: 369049600 05:25:04 INFO - PROCESS | 6551 | 05:28:06 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57391/tests/dromaeo/cssquery-prototype.html) 05:28:07 INFO - PROCESS | 6551 | RSS: Main: 369586176 05:28:07 INFO - PROCESS | 6551 | 05:31:10 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57391/tests/dromaeo/cssquery-yui.html) 05:31:11 INFO - PROCESS | 6551 | RSS: Main: 343523328 05:31:11 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-yui.html (next: http://localhost:57391/tests/dromaeo/cssquery-dojo.html) 05:34:14 INFO - PROCESS | 6551 | RSS: Main: 396693504 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | __start_tp_report 05:34:14 INFO - PROCESS | 6551 | _x_x_mozilla_page_load 05:34:14 INFO - PROCESS | 6551 | _x_x_mozilla_page_load_details 05:34:14 INFO - PROCESS | 6551 | |i|pagename|runs| 05:34:14 INFO - PROCESS | 6551 | |0;dojo.html;2994.5208766597348;3399.507071474635;3414.9584376765083;3423.503591979165;3453.689167974882;5704.3154821421485;5713.314402271728;5737.282839645044;5841.853953651155;5902.645841249528;5738.483536481715;5829.47534721876;5873.618214816034;5888.528917686585;5946.672932988684;4193.706440549163;5150.381954165506;5151.381834179885;5217.217417387385;5238.083335416306;5288.048151332759;5288.6562373445795;5313.442088580701;5332.173513105475;5361.276227709268;5119.308893299394;5182.97408512955;5189.299444574972;5217.426083130853;5246.5802735781235;5250.028744682208;5252.527272545451;5256.474352564751;5260.763265653055;5270.235815806686;5144.4083930348115;5221.503957124067;5222.451642577513;5231.790728370861;5242.056429842627;5644.604877658525;5678.290213723285;5683.090705487102;5694.715264236772;5698.544116470673;5965.836661850941;6143.877122457526;6172.783952561684;6205.472534834545;6219.87560248797;6088.939110608926;6123.663198524079;6178.84552886177;6180.351063138396;6288.182536270301;5968.910466342965;5989.3112292086535;5995.370486098984;5998.980173370537;6006.849828754316;6270.435660790551;6311.715972781236;6324.3991820776955;6333.936660633335;6336.746530138835;6077.058056001327;6090.299615544269;6106.248323414603;6107.595253091814;6204.689765511706;6163.599366041193;6189.96905015472;6193.4425901669065;6199.28708198563;6229.844253893689;6169.198004259418;6172.722227499773;6179.073139029181;6192.349803270592;6211.068339748984;6070.392960703983;6122.08258527833;6141.157652704171;6173.475254603409;6181.227346581677;4266.893327666833;4897.069556784199;4929.901401971941;4948.678335908155;4988.8752781180765;4449.710768800018;4460.487043989918;4465.508794032654;4484.708493947883;4516.299973504112;5503.009458297545;5544.805931792368;5570.108782594765;5599.832005039855;5845.473907348359;5391.865203369869;6157.291911430153;6160.168377269011;6164.229471316085;6169.96915015422;5640.435956404327;5666.518345940642;5668.773249070074;5676.659400435987;5677.432256774289;5274.182501712241;5333.120035194227;5369.328833895763;5428.37573679032;5451.1005684062475;6422.3577642235405;6449.5485316027425;6454.064160696673;6459.354064593503;6492.967535162294;6228.937710622836;6248.875022499616;6252.218472690914;6253.593516421418;6257.499400047895;6154.7690461906905;6254.593451425642;6302.590331628429;6330.715117819801;6455.741770329134;6199.504039676905;6201.255849297932;6205.193324867739;6205.2553693557065;6225.875482490234;5214.921776173284;6195.380461953949;6199;6205.906911396242;6227.626342419469;6072.544559158169;6114.602550834182;6143;6161.630302181883;6227.408396202353;6165.938340616536;6168.938310616836;6183.96908015457;6199.690015499297;6203.193584833943;5118.667286626357;5852.853678658068;6071.817845464643;6084.421979911901;6107.144999700135;6097.494431794971;6168.1672974149715;6177.073438984188;6218.9378106218355;6221.3156552778255;6098.390160983866;6109.327973922954;6115.6330620162935;6126.172966649446;6154.384561543989 05:34:14 INFO - PROCESS | 6551 | |1;ext.html;497.91037613229616;499.3982251387079;511.21273239211615;527.4777969809887;578.8118861370053;30544.54183187254;43091.13817723643;43182;45953.77023114888;46022.8494287643;44306.113877722506;44778.10443791122;44861.327080093826;44906.77546612262;44913;8650.65397384104;8825.735227943173;9574.234061275116;9757;9806.068423499755;11757.882421175764;11995.820062699066;12023.098267629919;12150.270983741004;12203.877961220363;11985.580504682337;12017.278963262188;12025.278483290987;12032.458539365705;12058.09564282678;8674.86987695185;8839.823203535892;8858.778530536723;11932.22440541362;11938.462769175365;8638.870416943751;11659.475323610457;11930.582429614922;11934.462949167308;12079.094067944852;34985.12537186565;41736.582634173574;41986.950326241786;42009.94975125616;42121.57878421207;2761.765249953756;3238.6437491875877;3300.372929143455;3329.51722000311;3896.415537669344;29080.127596172148;29090.563641545396;29460.9688660898;29884.10347689573;29976.400471990655;2536.771690547841;2727.5090483712925;2732.4535092981287;2734.5898115282866;2780.31882188863;514835;518689;518891;519166;519782;61150;61758.382416175264;61827.07259391113;61863.381366185764;61894;37325.813370933516;37693.05767355783;37798.62201378006;38907.44370225216;38917.41623875644;14913.850861491463;17149.828501715074;17163.141842907804;17433.82566174322;17482.388116416118;12083.45624446902;12188.878111218952;12196.024318054535;12397.380130993415;12505.187162834387;17753.911230443766;18515.139338643672;20657;20697.482562936046;20725.378238652866;6822.829429264209;8069.152738962442;8079.919200807916;8123.3095186908595;8134.471259368122;957.482959202037;1034.3276870034388;1146.696125526736;1171.1275100050516;1173.1611897493162;30017.549736753965;30592.84703576468;30737.2315692105;30763.69236307653;30936.845315773728;1103.4096758234307;1109.97327472088;1115.893990070942;1126.1610100475034;1177.4407156600546;15093;15231.923840380727;15323.310451029507;15570;15712.214389280718;712189;712668;716993;717933;718474;59773;60066.69966650139;60221.795564088476;60225.09662355068;60488.79022419527;1333;1383.6471699716508;1455.8325792533994;1479.408236705305;1512.8940974131706;29531;29978.85010574933;30106.84946575253;30315.93894213692;30574.84712576423;51915.96168076693;52638;52696.47303527069;52766.47233527616;52857.47142528526;550258;574378;574695;574807;574807;23288;23769.881150594138;25976.480470390863;26747;27168.320791980357;42772.35841462318;44115.77942110269;45813.85465363393;46283.53716462792;46358.768206158755;36765.26469470649;38440;38580.228395432496;39328;39654.206915860945;36846.078848029014;36963.81518092392;37251.81374093221;38177;38186.42720359141;34982.47526287159;35270.29459410849;38417.4237386445;38920.80539597284;39005.219895602495;26544;31057;33484.832575837936;33796.49305260374;33917;24737.628935565623;24891.87986540574;24956.7850041467;25292.62061069122;25367.6194857081 05:34:14 INFO - PROCESS | 6551 | |2;jquery.html;358.2244440785699;396.78970145822706;397.5358694740785;399.98800035998914;420.9221294060599;14606.561803145896;20477.48806279844;21030.894845525752;21721.782782172173;21777.802220877864;16202.432914847983;19636.214551417925;21089.894550527228;21458.57082858346;21475.46311342217;5862.120681897717;6089.604175728586;6131.9386806131815;6137.5089992800695;6137.6624285664275;5674.375818659944;5706.429357064302;5708.08670612702;5999.13012613173;6056.121862329959;5659.094544872821;5822.1266809978415;5984.97007514964;6027.09593560965;6028.03551431771;5870.090136028923;5895.056790913454;5975.133605627203;5976.3724808895095;6005.6997150142315;5253.973730131325;5258.973705131488;5269.367675878919;5276.076686579833;5277.419483856773;21312.467188320417;25571.105011324515;25917.222483325342;26896.865515672296;27012.729872701217;2245.483538786072;2376.9881150594138;2494.189388448743;2565.525377805112;2762.958555621647;14928.178950157737;15221.847781522154;15360.769588456182;15405.460808871636;15473.690526189414;1739.704250277456;1740.843324100837;1752.9035903025328;1836.0544319675378;1839.6044850357105;122998;128082;129273.35363323217;131256.6874331254;132995;10157.464182020816;10805.945970270255;10831.454608167074;11134;11388.94305528467;14365.856341436453;14397.496087637091;14449.927750361181;14465.13209207451;14465.710685786225;7383.483156179016;7913.485623434458;8782.736517904474;8807.427517211361;9068.773280667903;8975.910240897507;8983.191512763882;8990.460572365679;9120.224780893699;9145.862812057825;6717.630530320879;6849.349311815369;6849.794506164823;6867.767975407913;6887.242403335629;1501.5345242974495;1862.636785826778;1870.298638010746;1886.839568693838;1897.696368581048;939.272064150289;949.586929685589;993.513178542509;999.3654029691198;1059.8728152621582;20784.204004956344;21413.357599272047;21641.242556510446;21722.23972160967;21890.781092189194;1014.9746256343503;1018.1854516386861;1018.9439580822977;1021.8262895307815;1033.612395351743;8679.349048821363;10778.514966826511;11555.653330400102;11567.537298508134;11626.651200463999;502372;504168;504213;609895;617111;125327.37336313442;143419.37515935968;144290;149898.25050874893;152790.708139378;1012.2104758288547;1046.8115739167024;1059.5973530058682;1060.7560261139827;1063.6011495689118;18304;18672.7199092011;18700.06499675038;18862.24551017999;19075.76007559504;55231.44768552263;56708;56890.43109568852;56963;57773.422265776804;84787.30425391335;85153.72269416088;85893.57053214694;86457;86666;7706.190849960673;7826.334761545332;8114.797130071795;8163.551004694798;8442.282405995558;9267.6292948284;11780.587679431179;12359.196652217577;12612.873871261538;12641.747165056831;11108.944455277671;11116.44417779124;11201.047910927336;11291.322520648788;11466.713332166762;10940.343579385262;11009.83485247738;11079.638956764638;11126.276792008493;11264.605738799102;11290.040346570633;11440.71398215018;11649;11710.70723231926;11791.233569817934;7067.787966361017;8128.309093727099;8172.754817355489;8341.791455213668;8572.699955501528;9514.33399662017;11138.721531961766;11202.83195752048;11233.49449274801;11586 05:34:14 INFO - PROCESS | 6551 | |3;mootools.html;1456.7669172932333;1628.0313213637885;1659.369439612947;1920.1071501751683;2025.574629327841;8062.959685201573;8662;8671.783205419852;8680.08859069798;8683.696070637528;8625.525596092213;9011.369204155711;9020.233280171193;9129.406588571737;9173.220276276508;2442.2917353967327;2519.722830488645;2526.2673824590847;2527.6208568714655;2528.4437423766835;2464.100603279799;2529.380301826046;2536.0996846119638;2536.5307418127613;2540.9491810163786;2477.170148000422;2503.6119401492706;2513.3842208658903;2516.748325167487;2518.7859031982302;2474.5793215153376;2481.0944005438064;2506.135383292761;2514.120057979711;2514.786243169333;2234.966475502869;2452.0682140786566;2458.188797696756;2486.2292689266205;2510.2594734553168;9135.817283654356;9211;9213.953930230373;9220.8616870747;9228.769280767967;5396.5952553558245;5466.590005749585;5619.971900140514;5655.462731040545;5667.291588551431;3481.7736847104857;3598;3646.4348026056005;3677.52192215013;3679.7056235501095;986.4722373530199;1008.3647302199568;1044.4568824211444;1062.33073163907;1075.9462026898623;12201.572944946884;12280.324582147978;12288.201266917622;12321.63035108957;12322.568710095193;4224.619784219435;4801.95198048022;5215.608829337815;5228.581713462914;5258.237555554423;9436.952815236018;9450.952745236367;9523.095305945924;9551.665691700897;9561.187299079656;5488.4785945335125;5495.230667706524;5497.230387745639;5503.11950087984;5505.724713764296;4385.780710964503;4508.9098218036115;5071.39143302806;5106.617003724661;5144.7942082316285;3939.1727737174942;3953.2686453006286;3994.2810294146752;4031.496062992126;4062.4921884764403;1201.3632774629461;1217.2148963918223;1223.853137623491;1234.7530493901256;1240.3178251961385;660.0198704923185;779.3570304498697;807.7738233294687;883.703959173684;998.5656239535825;5978.8206353809455;6072;6085.847853803691;6231.781887634002;6339.2709838368255;803.7186984555359;933.7338858425317;949.3259785552198;959.8560215967522;999.5601935148511;3960.4851369321805;4100.220958017967;4140.979295103505;4175.31107367287;4185.874423767231;10967.396793176293;11218.55125794959;11398.1451391146;11412.486438110305;11429;11265.774684506265;11358.716032099099;11379.943100284445;11412.315261084195;11422.914823091793;8300.792480188044;8380.664773409117;8604.78488037804;8614.353923455767;8640.783980400414;5961.731722072431;6002.819915402545;6085.208922840003;6107.725152368065;6134.263888333428;10094.697159085239;10536.525856336637;10694.091002264895;10710.089642380484;10712.73218169552;10457.529411176363;10873.347599144077;10883.619073332395;10893.183011274028;10897.509612067635;7093.184283807428;7210;7304.452166087671;7332.30343117403;7347.08161479815;8494.957525212334;8525.019622743464;8581.828363432822;8600.78498037554;8609;6470.935290647033;7520.360769334449;7657.923420765721;7760.068791745027;7903.802404939693;7434.591097489473;7557.773266802004;7594.126675432175;7668.6165691716305;7718.189590092959;7237.819054523679;7261.745838895613;7349.55902645843;7350.816229594303;7356.374708149867;4849.230031038716;5026.220935754964;5113.872153196051;5125.077486052397;5130.51260130287;5127.746118885045;5164.32863727713;5209.7395130244095;5212.843614691566;5302.496262855023 05:34:14 INFO - PROCESS | 6551 | |4;prototype.html;315.25756842635593;319.85926192475307;329.39720311829365;330.57686161713013;333.41152865192953;4506.084913911587;5375.650582712121;5382.730863456831;5386.78452861885;5400.083954664487;4135.565765594607;5101.811367895066;5296.179092240709;5307.52232299093;5362.3029006229235;3031.181580973136;3170.8414579271002;3777.2823163598828;4105.548389677133;4131.896702582444;4013.4381186633896;4078.77566733831;4080.5307389650275;4085.4893138357706;4123.7938103094875;3503.982480087596;3555.448905419651;3887.436321733347;3897.376419772837;4027.637512623878;3510.3505851417312;3532.4171511700665;3829.9425508617255;4033.899152521181;4076.9592304077173;3271.623763267231;3362.5877131604784;3528.788272703646;3548.3435564420665;3576.8032758198287;4426.380306757057;4848.5151484851585;5022.1211288024815;5030.195168772979;5089.720065396402;3895.1235971906094;3972.7616343019513;4436.711613745097;4457.732536047815;4714.336399934061;5536.397892990288;5539.556835453194;5548.5838562108;5566.638168519034;5568.693721845297;3548.3258180945536;3825.3114439401174;3939.231849789292;3966.4248683940955;3983.7609743415487;4983.729149066965;5385;5590.18942253375;5804.419558044204;5836.387179346192;3398.980305908218;3404.931901361959;3929.9367831887917;3955.149642826806;3964.3746064113575;3569.94645080324;3882.902927426837;4065.1869626074904;4067.2882245607493;4107.301758701028;3937.842486300573;4482.887927801831;4585.70192937458;4594.220496599509;4743.525647435229;5335.226392173113;5392.56859451243;5561.749721262553;5567.081431563827;5575.498205161555;1297.1503665099376;1375.326090215787;1387.9097858639157;1389.4720006397706;1394.4910107810576;822.679155129488;863.9092895245909;874.881890944727;881.2245224202788;886.9379143460025;4160.979195104066;4647;5109.113350956698;5222.216667499905;5227.2420499028185;4701.717896926195;4904.2643603459055;5268.762905669254;5747.557288152602;5788.9290481261105;4354.9564504355185;4836.588889944394;4871.29366241893;4893.608511319086;5073.594112471067;2968.3618022125347;2983.8358890261243;3016.4721173794937;3020.7734419918593;3022.81863088211;5711.371749107595;5923.792667256712;5954.166416701665;5994.550408719363;6045.304789949212;4795.777076845382;5582.385937546866;5650.943490565124;5925.170476133345;6000.21997140369;5370.731463426813;5645.943540564542;5720.856978575569;5966.0752583348785;5995.310539287863;4465.910681786281;5148.948510514847;5509.972450137724;5785.0454674978155;5810.73851676684;5065.341505604248;5066.442691304027;5605.075162598126;5713.885722285614;5728;5574.414686458026;5921.851953701192;5935.7032148391545;5991.460768530853;6005.7297421617;3114.735247503897;3148.244421338908;3214.791038582485;3311.9860926556717;3380.9492857606665;4014.9598504014584;4377.868663940087;4639.48965613789;4821.566059054702;4859.757012149307;4045.696572756996;4229.767362795076;4654.418197725285;4678.672492925599;4706.764661766966;4056.472658554369;4629.171477266394;4655.393889108253;4672.252439609646;4689.531046895283;3972.6226008529143;4253.617174454314;4310.9307532384;4386.692931494765;4642.955335049722;4839.95160048395;5222.7879853890345;5288.25487589087;5442.945570544244;5492;4754.072955773591;5176.22356646491;5340.291106845927;5362.249285100167;5498 05:34:14 INFO - PROCESS | 6551 | |5;yui.html;649.886269902767;732.8277854704144;735.5696917303377;753.7512620835126;765.9885101723472;11888.881111188886;15019.774703379444;15496.225188740545;15541.456049038286;15558.37766489339;14143.292835358217;14357.928210358934;14401.711965760705;14431.567052988401;14469;728.7704373122476;741.3994664321916;905.8686490458896;925.2459245714755;925.2597921662676;894.6332003878412;911.2254583603917;912.0104686415215;916.894557125929;917.7797328641145;969.3360048366856;978.6379039755301;979.2606582030556;979.304693667497;980.9558569864373;756.3986630628636;762.5767698927093;770.0336078221852;971.36861040324;974.8732664753572;581.5870731780441;696.502001069238;707.5577763897564;707.7841258416181;713.636045616734;14582.635434114232;14621.6344591385;14635.707285854223;14655.560333190022;14683.192424416653;12107.152499325051;12157.574484892986;12198.451069701885;12479.126461147724;12501.812472812915;10230.446405902263;10528.105111065568;10555.947220263926;10779.838302425469;11044;3907.7706243449675;3964.464797252363;4066.8983275418054;4080.489938757655;4253.574642535722;69020.96468552925;76172;76211.61894190493;83270;83387.16612833855;9564.808703826024;9611.711648650551;9654.806903861883;9951.900480995242;10053.949730251448;19961.101750421265;20586.58826823455;20597.279095231595;21114.78885211159;21897.562048758937;7337.339639432476;7383.409327253807;7582.924170758332;7694.422918281151;7756.844863102707;3540.539729835105;3631.2011357501838;3633.491311216379;3665.5051568037975;3675.5956844747593;3622.8913132606062;3709.9443508347395;3711;3715.6098609646137;3716.163863130828;649.0847904454696;656.4682607088274;659.1628631637793;664.6278084272823;672.4183581202323;515.4124298299942;519.828456609318;577.5581680014792;606.5208485296655;611.1230384398402;7846.9215307847335;7963.243491868263;8233.17668233172;8342.874856877152;8384.455010424303;553.6456667732655;572.2989338060926;580.4804699793661;591.5770224289654;597.5936363272929;3281.392942305681;3282.7209687176855;3912.68698504119;3963;4113.321301985139;93899;97432;97724;98550.5072474633;98828.50585747026;39118;40968.79515602403;57795;83206.583967081;88242.55878720565;73854;74795.25204747992;75052.24947750561;75331.24668753461;76009.85985210226;7648.694052237848;7713.575753333396;7871.212878712312;7886.44794864354;7909.802254943673;45226.823964609015;47091.76454117707;47612.52387476219;47679;48036.519634803204;64192.67903660452;64861;65277.347226529026;65540.34459655342;65848.34151658422;10103;10185.898141018495;10427.322224055411;10429.113525350127;10626;10424.947875260576;10579.682609521727;10606.681799546026;10871.29336593119;11321.03771179459;10094.444805535766;10330;10424.843627345444;10451.32066415711;10570.2600817942;10389.116925061455;10403.53184106732;10737.355758654505;11369.97670209685;11395.886041139815;10060.748481288027;10075.546600402853;10316.587336506756;10455.581776728846;10578.153747700011;8768.430052046597;8994.775130621787;9033.367664263438;9279.489628070522;9295.76760580991;9597.424154550748;9635.855462168212;9748.705141051096;9791.128562683849;9806 05:34:14 INFO - PROCESS | 6551 | __end_tp_report 05:34:14 INFO - PROCESS | 6551 | __start_cc_report 05:34:14 INFO - PROCESS | 6551 | _x_x_mozilla_cycle_collect,1279 05:34:14 INFO - PROCESS | 6551 | __end_cc_report 05:34:14 INFO - PROCESS | 6551 | __startTimestamp1463574854588__endTimestamp 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | ------- Summary: start ------- 05:34:14 INFO - PROCESS | 6551 | Number of tests: 6 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#0] dojo.html Cycles:165 Average:5742.69 Median:6072.54 stddev:660.31 (10.9%) stddev-sans-first:626.15 05:34:14 INFO - PROCESS | 6551 | Values: 2994.5 3399.5 3415.0 3423.5 3453.7 5704.3 5713.3 5737.3 5841.9 5902.6 5738.5 5829.5 5873.6 5888.5 5946.7 4193.7 5150.4 5151.4 5217.2 5238.1 5288.0 5288.7 5313.4 5332.2 5361.3 5119.3 5183.0 5189.3 5217.4 5246.6 5250.0 5252.5 5256.5 5260.8 5270.2 5144.4 5221.5 5222.5 5231.8 5242.1 5644.6 5678.3 5683.1 5694.7 5698.5 5965.8 6143.9 6172.8 6205.5 6219.9 6088.9 6123.7 6178.8 6180.4 6288.2 5968.9 5989.3 5995.4 5999.0 6006.8 6270.4 6311.7 6324.4 6333.9 6336.7 6077.1 6090.3 6106.2 6107.6 6204.7 6163.6 6190.0 6193.4 6199.3 6229.8 6169.2 6172.7 6179.1 6192.3 6211.1 6070.4 6122.1 6141.2 6173.5 6181.2 4266.9 4897.1 4929.9 4948.7 4988.9 4449.7 4460.5 4465.5 4484.7 4516.3 5503.0 5544.8 5570.1 5599.8 5845.5 5391.9 6157.3 6160.2 6164.2 6170.0 5640.4 5666.5 5668.8 5676.7 5677.4 5274.2 5333.1 5369.3 5428.4 5451.1 6422.4 6449.5 6454.1 6459.4 6493.0 6228.9 6248.9 6252.2 6253.6 6257.5 6154.8 6254.6 6302.6 6330.7 6455.7 6199.5 6201.3 6205.2 6205.3 6225.9 5214.9 6195.4 6199.0 6205.9 6227.6 6072.5 6114.6 6143.0 6161.6 6227.4 6165.9 6168.9 6184.0 6199.7 6203.2 5118.7 5852.9 6071.8 6084.4 6107.1 6097.5 6168.2 6177.1 6218.9 6221.3 6098.4 6109.3 6115.6 6126.2 6154.4 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#1] ext.html Cycles:180 Average:72529.07 Median:34983.80 stddev:162582.94 (464.7%) stddev-sans-first:162949.07 05:34:14 INFO - PROCESS | 6551 | Values: 497.9 499.4 511.2 527.5 578.8 30544.5 43091.1 43182.0 45953.8 46022.8 44306.1 44778.1 44861.3 44906.8 44913.0 8650.7 8825.7 9574.2 9757.0 9806.1 11757.9 11995.8 12023.1 12150.3 12203.9 11985.6 12017.3 12025.3 12032.5 12058.1 8674.9 8839.8 8858.8 11932.2 11938.5 8638.9 11659.5 11930.6 11934.5 12079.1 34985.1 41736.6 41987.0 42009.9 42121.6 2761.8 3238.6 3300.4 3329.5 3896.4 29080.1 29090.6 29461.0 29884.1 29976.4 2536.8 2727.5 2732.5 2734.6 2780.3 514835.0 518689.0 518891.0 519166.0 519782.0 61150.0 61758.4 61827.1 61863.4 61894.0 37325.8 37693.1 37798.6 38907.4 38917.4 14913.9 17149.8 17163.1 17433.8 17482.4 12083.5 12188.9 12196.0 12397.4 12505.2 17753.9 18515.1 20657.0 20697.5 20725.4 6822.8 8069.2 8079.9 8123.3 8134.5 957.5 1034.3 1146.7 1171.1 1173.2 30017.5 30592.8 30737.2 30763.7 30936.8 1103.4 1110.0 1115.9 1126.2 1177.4 15093.0 15231.9 15323.3 15570.0 15712.2 712189.0 712668.0 716993.0 717933.0 718474.0 59773.0 60066.7 60221.8 60225.1 60488.8 1333.0 1383.6 1455.8 1479.4 1512.9 29531.0 29978.9 30106.8 30315.9 30574.8 51916.0 52638.0 52696.5 52766.5 52857.5 550258.0 574378.0 574695.0 574807.0 574807.0 23288.0 23769.9 25976.5 26747.0 27168.3 42772.4 44115.8 45813.9 46283.5 46358.8 36765.3 38440.0 38580.2 39328.0 39654.2 36846.1 36963.8 37251.8 38177.0 38186.4 34982.5 35270.3 38417.4 38920.8 39005.2 26544.0 31057.0 33484.8 33796.5 33917.0 24737.6 24891.9 24956.8 25292.6 25367.6 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#2] jquery.html Cycles:180 Average:34846.40 Median:21722.01 stddev:93189.64 (429.0%) stddev-sans-first:93415.08 05:34:14 INFO - PROCESS | 6551 | Values: 358.2 396.8 397.5 400.0 420.9 14606.6 20477.5 21030.9 21721.8 21777.8 16202.4 19636.2 21089.9 21458.6 21475.5 5862.1 6089.6 6131.9 6137.5 6137.7 5674.4 5706.4 5708.1 5999.1 6056.1 5659.1 5822.1 5985.0 6027.1 6028.0 5870.1 5895.1 5975.1 5976.4 6005.7 5254.0 5259.0 5269.4 5276.1 5277.4 21312.5 25571.1 25917.2 26896.9 27012.7 2245.5 2377.0 2494.2 2565.5 2763.0 14928.2 15221.8 15360.8 15405.5 15473.7 1739.7 1740.8 1752.9 1836.1 1839.6 122998.0 128082.0 129273.4 131256.7 132995.0 10157.5 10805.9 10831.5 11134.0 11388.9 14365.9 14397.5 14449.9 14465.1 14465.7 7383.5 7913.5 8782.7 8807.4 9068.8 8975.9 8983.2 8990.5 9120.2 9145.9 6717.6 6849.3 6849.8 6867.8 6887.2 1501.5 1862.6 1870.3 1886.8 1897.7 939.3 949.6 993.5 999.4 1059.9 20784.2 21413.4 21641.2 21722.2 21890.8 1015.0 1018.2 1018.9 1021.8 1033.6 8679.3 10778.5 11555.7 11567.5 11626.7 502372.0 504168.0 504213.0 609895.0 617111.0 125327.4 143419.4 144290.0 149898.3 152790.7 1012.2 1046.8 1059.6 1060.8 1063.6 18304.0 18672.7 18700.1 18862.2 19075.8 55231.4 56708.0 56890.4 56963.0 57773.4 84787.3 85153.7 85893.6 86457.0 86666.0 7706.2 7826.3 8114.8 8163.6 8442.3 9267.6 11780.6 12359.2 12612.9 12641.7 11108.9 11116.4 11201.0 11291.3 11466.7 10940.3 11009.8 11079.6 11126.3 11264.6 11290.0 11440.7 11649.0 11710.7 11791.2 7067.8 8128.3 8172.8 8341.8 8572.7 9514.3 11138.7 11202.8 11233.5 11586.0 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#3] mootools.html Cycles:180 Average:5902.08 Median:5137.65 stddev:3328.96 (64.8%) stddev-sans-first:3321.53 05:34:14 INFO - PROCESS | 6551 | Values: 1456.8 1628.0 1659.4 1920.1 2025.6 8063.0 8662.0 8671.8 8680.1 8683.7 8625.5 9011.4 9020.2 9129.4 9173.2 2442.3 2519.7 2526.3 2527.6 2528.4 2464.1 2529.4 2536.1 2536.5 2540.9 2477.2 2503.6 2513.4 2516.7 2518.8 2474.6 2481.1 2506.1 2514.1 2514.8 2235.0 2452.1 2458.2 2486.2 2510.3 9135.8 9211.0 9214.0 9220.9 9228.8 5396.6 5466.6 5620.0 5655.5 5667.3 3481.8 3598.0 3646.4 3677.5 3679.7 986.5 1008.4 1044.5 1062.3 1075.9 12201.6 12280.3 12288.2 12321.6 12322.6 4224.6 4802.0 5215.6 5228.6 5258.2 9437.0 9451.0 9523.1 9551.7 9561.2 5488.5 5495.2 5497.2 5503.1 5505.7 4385.8 4508.9 5071.4 5106.6 5144.8 3939.2 3953.3 3994.3 4031.5 4062.5 1201.4 1217.2 1223.9 1234.8 1240.3 660.0 779.4 807.8 883.7 998.6 5978.8 6072.0 6085.8 6231.8 6339.3 803.7 933.7 949.3 959.9 999.6 3960.5 4100.2 4141.0 4175.3 4185.9 10967.4 11218.6 11398.1 11412.5 11429.0 11265.8 11358.7 11379.9 11412.3 11422.9 8300.8 8380.7 8604.8 8614.4 8640.8 5961.7 6002.8 6085.2 6107.7 6134.3 10094.7 10536.5 10694.1 10710.1 10712.7 10457.5 10873.3 10883.6 10893.2 10897.5 7093.2 7210.0 7304.5 7332.3 7347.1 8495.0 8525.0 8581.8 8600.8 8609.0 6470.9 7520.4 7657.9 7760.1 7903.8 7434.6 7557.8 7594.1 7668.6 7718.2 7237.8 7261.7 7349.6 7350.8 7356.4 4849.2 5026.2 5113.9 5125.1 5130.5 5127.7 5164.3 5209.7 5212.8 5302.5 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#4] prototype.html Cycles:180 Average:4354.20 Median:4684.10 stddev:1345.56 (28.7%) stddev-sans-first:1314.74 05:34:14 INFO - PROCESS | 6551 | Values: 315.3 319.9 329.4 330.6 333.4 4506.1 5375.7 5382.7 5386.8 5400.1 4135.6 5101.8 5296.2 5307.5 5362.3 3031.2 3170.8 3777.3 4105.5 4131.9 4013.4 4078.8 4080.5 4085.5 4123.8 3504.0 3555.4 3887.4 3897.4 4027.6 3510.4 3532.4 3829.9 4033.9 4077.0 3271.6 3362.6 3528.8 3548.3 3576.8 4426.4 4848.5 5022.1 5030.2 5089.7 3895.1 3972.8 4436.7 4457.7 4714.3 5536.4 5539.6 5548.6 5566.6 5568.7 3548.3 3825.3 3939.2 3966.4 3983.8 4983.7 5385.0 5590.2 5804.4 5836.4 3399.0 3404.9 3929.9 3955.1 3964.4 3569.9 3882.9 4065.2 4067.3 4107.3 3937.8 4482.9 4585.7 4594.2 4743.5 5335.2 5392.6 5561.7 5567.1 5575.5 1297.2 1375.3 1387.9 1389.5 1394.5 822.7 863.9 874.9 881.2 886.9 4161.0 4647.0 5109.1 5222.2 5227.2 4701.7 4904.3 5268.8 5747.6 5788.9 4355.0 4836.6 4871.3 4893.6 5073.6 2968.4 2983.8 3016.5 3020.8 3022.8 5711.4 5923.8 5954.2 5994.6 6045.3 4795.8 5582.4 5650.9 5925.2 6000.2 5370.7 5645.9 5720.9 5966.1 5995.3 4465.9 5148.9 5510.0 5785.0 5810.7 5065.3 5066.4 5605.1 5713.9 5728.0 5574.4 5921.9 5935.7 5991.5 6005.7 3114.7 3148.2 3214.8 3312.0 3380.9 4015.0 4377.9 4639.5 4821.6 4859.8 4045.7 4229.8 4654.4 4678.7 4706.8 4056.5 4629.2 4655.4 4672.3 4689.5 3972.6 4253.6 4310.9 4386.7 4643.0 4840.0 5222.8 5288.3 5442.9 5492.0 4754.1 5176.2 5340.3 5362.2 5498.0 05:34:14 INFO - PROCESS | 6551 | 05:34:14 INFO - PROCESS | 6551 | [#5] yui.html Cycles:180 Average:17724.76 Median:586.58 stddev:25328.14 (4317.9%) stddev-sans-first:25366.74 05:34:14 INFO - PROCESS | 6551 | Values: 649.9 732.8 735.6 753.8 766.0 11888.9 15019.8 15496.2 15541.5 15558.4 14143.3 14357.9 14401.7 14431.6 14469.0 728.8 741.4 905.9 925.2 925.3 894.6 911.2 912.0 916.9 917.8 969.3 978.6 979.3 979.3 981.0 756.4 762.6 770.0 971.4 974.9 581.6 696.5 707.6 707.8 713.6 14582.6 14621.6 14635.7 14655.6 14683.2 12107.2 12157.6 12198.5 12479.1 12501.8 10230.4 10528.1 10555.9 10779.8 11044.0 3907.8 3964.5 4066.9 4080.5 4253.6 69021.0 76172.0 76211.6 83270.0 83387.2 9564.8 9611.7 9654.8 9951.9 10053.9 19961.1 20586.6 20597.3 21114.8 21897.6 7337.3 7383.4 7582.9 7694.4 7756.8 3540.5 3631.2 3633.5 3665.5 3675.6 3622.9 3709.9 3711.0 3715.6 3716.2 649.1 656.5 659.2 664.6 672.4 515.4 519.8 577.6 606.5 611.1 7846.9 7963.2 8233.2 8342.9 8384.5 553.6 572.3 580.5 591.6 597.6 3281.4 3282.7 3912.7 3963.0 4113.3 93899.0 97432.0 97724.0 98550.5 98828.5 39118.0 40968.8 57795.0 83206.6 88242.6 73854.0 74795.3 75052.2 75331.2 76009.9 7648.7 7713.6 7871.2 7886.4 7909.8 45226.8 47091.8 47612.5 47679.0 48036.5 64192.7 64861.0 65277.3 65540.3 65848.3 10103.0 10185.9 10427.3 10429.1 10626.0 10424.9 10579.7 10606.7 10871.3 11321.0 10094.4 10330.0 10424.8 10451.3 10570.3 10389.1 10403.5 10737.4 11370.0 11395.9 10060.7 10075.5 10316.6 10455.6 10578.2 8768.4 8994.8 9033.4 9279.5 9295.8 9597.4 9635.9 9748.7 9791.1 9806.0 05:34:14 INFO - PROCESS | 6551 | -------- Summary: end -------- 05:34:14 INFO - PROCESS | 6551 | 05:34:15 INFO - TEST-INFO | 6551: exit 0 05:34:15 INFO - TEST-OK | dromaeo_css | took 1115704ms 05:34:15 INFO - TEST-START | kraken 05:34:15 INFO - operating with platform_type : mac_ 05:34:15 INFO - Initialising browser for kraken test... 05:34:15 INFO - TEST-INFO | started process 6556 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpu1hqZj/profile http://localhost:57391/getInfo.html) 05:34:17 INFO - PROCESS | 6556 | __metrics Screen width/height:1600/1200 05:34:17 INFO - PROCESS | 6556 | colorDepth:24 05:34:17 INFO - PROCESS | 6556 | Browser inner width/height: 1024/689 05:34:17 INFO - PROCESS | 6556 | __metrics 05:34:17 INFO - PROCESS | 6556 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:34:17 ERROR - PROCESS | 6556 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:34:18 INFO - TEST-INFO | 6556: exit 0 05:34:18 INFO - Browser initialized. 05:34:18 INFO - Running cycle 1/1 for kraken test... 05:34:18 INFO - TEST-INFO | started process 6559 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpu1hqZj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:30 INFO - PROCESS | 6559 | RSS: Main: 239063040 05:34:30 INFO - PROCESS | 6559 | 05:34:32 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-beat-detection) 05:34:33 INFO - PROCESS | 6559 | RSS: Main: 286715904 05:34:33 INFO - PROCESS | 6559 | 05:34:36 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-dft) 05:34:37 INFO - PROCESS | 6559 | RSS: Main: 295993344 05:34:37 INFO - PROCESS | 6559 | 05:34:40 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-fft) 05:34:41 INFO - PROCESS | 6559 | RSS: Main: 385372160 05:34:41 INFO - PROCESS | 6559 | 05:34:43 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-oscillator) 05:34:44 INFO - PROCESS | 6559 | RSS: Main: 284164096 05:34:44 INFO - PROCESS | 6559 | 05:34:46 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:47 INFO - PROCESS | 6559 | RSS: Main: 287891456 05:34:47 INFO - PROCESS | 6559 | 05:34:50 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:50 INFO - PROCESS | 6559 | RSS: Main: 326193152 05:34:50 INFO - PROCESS | 6559 | 05:34:53 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:54 INFO - PROCESS | 6559 | RSS: Main: 329039872 05:34:54 INFO - PROCESS | 6559 | 05:34:57 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57391/tests/kraken/driver.html?testName=json-parse-financial) 05:34:58 INFO - PROCESS | 6559 | RSS: Main: 328474624 05:34:58 INFO - PROCESS | 6559 | 05:34:59 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57391/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:35:00 INFO - PROCESS | 6559 | RSS: Main: 316166144 05:35:00 INFO - PROCESS | 6559 | 05:35:02 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:03 INFO - PROCESS | 6559 | RSS: Main: 322949120 05:35:03 INFO - PROCESS | 6559 | 05:35:05 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:05 INFO - PROCESS | 6559 | RSS: Main: 324632576 05:35:05 INFO - PROCESS | 6559 | 05:35:08 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:09 INFO - PROCESS | 6559 | RSS: Main: 329330688 05:35:09 INFO - PROCESS | 6559 | 05:35:12 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:13 INFO - PROCESS | 6559 | RSS: Main: 327155712 05:35:13 INFO - PROCESS | 6559 | 05:35:14 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57391/tests/kraken/driver.html?testName=ai-astar) 05:35:15 INFO - PROCESS | 6559 | RSS: Main: 374104064 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | __start_tp_report 05:35:15 INFO - PROCESS | 6559 | _x_x_mozilla_page_load 05:35:15 INFO - PROCESS | 6559 | _x_x_mozilla_page_load_details 05:35:15 INFO - PROCESS | 6559 | |i|pagename|runs| 05:35:15 INFO - PROCESS | 6559 | |0;ai-astar;112;104;111;112;110;111;110;112;111;108 05:35:15 INFO - PROCESS | 6559 | |1;audio-beat-detection;176;141;146;200;116;142;148;138;181;117 05:35:15 INFO - PROCESS | 6559 | |2;audio-dft;187;184;185;183;183;183;182;183;182;185 05:35:15 INFO - PROCESS | 6559 | |3;audio-fft;78;74;66;126;58;67;78;78;116;56 05:35:15 INFO - PROCESS | 6559 | |4;audio-oscillator;92;109;134;79;79;77;103;80;80;103 05:35:15 INFO - PROCESS | 6559 | |5;imaging-gaussian-blur;86;83;86;83;86;83;85;84;86;83 05:35:15 INFO - PROCESS | 6559 | |6;imaging-darkroom;101;92;91;93;92;108;143;94;94;93 05:35:15 INFO - PROCESS | 6559 | |7;imaging-desaturate;86;86;85;84;84;84;85;83;84;86 05:35:15 INFO - PROCESS | 6559 | |8;json-parse-financial;56;54;56;56;57;56;56;118;58;54 05:35:15 INFO - PROCESS | 6559 | |9;json-stringify-tinderbox;63;70;74;74;72;68;67;70;69;69 05:35:15 INFO - PROCESS | 6559 | |10;stanford-crypto-aes;85;85;84;84;85;84;82;84;84;87 05:35:15 INFO - PROCESS | 6559 | |11;stanford-crypto-ccm;144;147;148;130;132;136;135;134;206;137 05:35:15 INFO - PROCESS | 6559 | |12;stanford-crypto-pbkdf2;163;165;164;204;213;162;163;163;162;161 05:35:15 INFO - PROCESS | 6559 | |13;stanford-crypto-sha256-iterative;66;64;65;66;64;65;65;63;64;64 05:35:15 INFO - PROCESS | 6559 | __end_tp_report 05:35:15 INFO - PROCESS | 6559 | __start_cc_report 05:35:15 INFO - PROCESS | 6559 | _x_x_mozilla_cycle_collect,2216 05:35:15 INFO - PROCESS | 6559 | __end_cc_report 05:35:15 INFO - PROCESS | 6559 | __startTimestamp1463574915112__endTimestamp 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | ------- Summary: start ------- 05:35:15 INFO - PROCESS | 6559 | Number of tests: 14 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.47 (2.2%) stddev-sans-first:2.52 05:35:15 INFO - PROCESS | 6559 | Values: 112.0 104.0 111.0 112.0 110.0 111.0 110.0 112.0 111.0 108.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#1] audio-beat-detection Cycles:10 Average:150.50 Median:147.00 stddev:27.26 (18.5%) stddev-sans-first:27.31 05:35:15 INFO - PROCESS | 6559 | Values: 176.0 141.0 146.0 200.0 116.0 142.0 148.0 138.0 181.0 117.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#2] audio-dft Cycles:10 Average:183.70 Median:183.50 stddev:1.57 (0.9%) stddev-sans-first:1.12 05:35:15 INFO - PROCESS | 6559 | Values: 187.0 184.0 185.0 183.0 183.0 183.0 182.0 183.0 182.0 185.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#3] audio-fft Cycles:10 Average:79.70 Median:70.50 stddev:23.30 (33.0%) stddev-sans-first:24.70 05:35:15 INFO - PROCESS | 6559 | Values: 78.0 74.0 66.0 126.0 58.0 67.0 78.0 78.0 116.0 56.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:79.00 stddev:18.62 (23.6%) stddev-sans-first:19.74 05:35:15 INFO - PROCESS | 6559 | Values: 92.0 109.0 134.0 79.0 79.0 77.0 103.0 80.0 80.0 103.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#5] imaging-gaussian-blur Cycles:10 Average:84.50 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.41 05:35:15 INFO - PROCESS | 6559 | Values: 86.0 83.0 86.0 83.0 86.0 83.0 85.0 84.0 86.0 83.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:92.50 stddev:15.96 (17.3%) stddev-sans-first:16.93 05:35:15 INFO - PROCESS | 6559 | Values: 101.0 92.0 91.0 93.0 92.0 108.0 143.0 94.0 94.0 93.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.06 (1.2%) stddev-sans-first:1.01 05:35:15 INFO - PROCESS | 6559 | Values: 86.0 86.0 85.0 84.0 84.0 84.0 85.0 83.0 84.0 86.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:56.00 stddev:19.68 (35.1%) stddev-sans-first:20.75 05:35:15 INFO - PROCESS | 6559 | Values: 56.0 54.0 56.0 56.0 57.0 56.0 56.0 118.0 58.0 54.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:3.31 (4.7%) stddev-sans-first:2.50 05:35:15 INFO - PROCESS | 6559 | Values: 63.0 70.0 74.0 74.0 72.0 68.0 67.0 70.0 69.0 69.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.50 stddev:1.26 (1.5%) stddev-sans-first:1.32 05:35:15 INFO - PROCESS | 6559 | Values: 85.0 85.0 84.0 84.0 85.0 84.0 82.0 84.0 84.0 87.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:140.50 stddev:22.35 (15.9%) stddev-sans-first:23.70 05:35:15 INFO - PROCESS | 6559 | Values: 144.0 147.0 148.0 130.0 132.0 136.0 135.0 134.0 206.0 137.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:163.50 stddev:19.38 (11.9%) stddev-sans-first:20.29 05:35:15 INFO - PROCESS | 6559 | Values: 163.0 165.0 164.0 204.0 213.0 162.0 163.0 163.0 162.0 161.0 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - PROCESS | 6559 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:0.97 (1.5%) stddev-sans-first:0.88 05:35:15 INFO - PROCESS | 6559 | Values: 66.0 64.0 65.0 66.0 64.0 65.0 65.0 63.0 64.0 64.0 05:35:15 INFO - PROCESS | 6559 | -------- Summary: end -------- 05:35:15 INFO - PROCESS | 6559 | 05:35:15 INFO - TEST-INFO | 6559: exit 0 05:35:15 INFO - TEST-OK | kraken | took 60653ms 05:35:15 INFO - SUITE-END | took 1176s 05:35:16 INFO - Completed test suite (00:19:36) 05:35:16 INFO - javascript benchmark 05:35:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2994.5208766597348, 3399.507071474635, 3414.9584376765083, 3423.503591979165, 3453.689167974882, 5704.3154821421485, 5713.314402271728, 5737.282839645044, 5841.853953651155, 5902.645841249528, 5738.483536481715, 5829.47534721876, 5873.618214816034, 5888.528917686585, 5946.672932988684, 4193.706440549163, 5150.381954165506, 5151.381834179885, 5217.217417387385, 5238.083335416306, 5288.048151332759, 5288.6562373445795, 5313.442088580701, 5332.173513105475, 5361.276227709268, 5119.308893299394, 5182.97408512955, 5189.299444574972, 5217.426083130853, 5246.5802735781235, 5250.028744682208, 5252.527272545451, 5256.474352564751, 5260.763265653055, 5270.235815806686, 5144.4083930348115, 5221.503957124067, 5222.451642577513, 5231.790728370861, 5242.056429842627, 5644.604877658525, 5678.290213723285, 5683.090705487102, 5694.715264236772, 5698.544116470673, 5965.836661850941, 6143.877122457526, 6172.783952561684, 6205.472534834545, 6219.87560248797, 6088.939110608926, 6123.663198524079, 6178.84552886177, 6180.351063138396, 6288.182536270301, 5968.910466342965, 5989.3112292086535, 5995.370486098984, 5998.980173370537, 6006.849828754316, 6270.435660790551, 6311.715972781236, 6324.3991820776955, 6333.936660633335, 6336.746530138835, 6077.058056001327, 6090.299615544269, 6106.248323414603, 6107.595253091814, 6204.689765511706, 6163.599366041193, 6189.96905015472, 6193.4425901669065, 6199.28708198563, 6229.844253893689, 6169.198004259418, 6172.722227499773, 6179.073139029181, 6192.349803270592, 6211.068339748984, 6070.392960703983, 6122.08258527833, 6141.157652704171, 6173.475254603409, 6181.227346581677, 4266.893327666833, 4897.069556784199, 4929.901401971941, 4948.678335908155, 4988.8752781180765, 4449.710768800018, 4460.487043989918, 4465.508794032654, 4484.708493947883, 4516.299973504112, 5503.009458297545, 5544.805931792368, 5570.108782594765, 5599.832005039855, 5845.473907348359, 5391.865203369869, 6157.291911430153, 6160.168377269011, 6164.229471316085, 6169.96915015422, 5640.435956404327, 5666.518345940642, 5668.773249070074, 5676.659400435987, 5677.432256774289, 5274.182501712241, 5333.120035194227, 5369.328833895763, 5428.37573679032, 5451.1005684062475, 6422.3577642235405, 6449.5485316027425, 6454.064160696673, 6459.354064593503, 6492.967535162294, 6228.937710622836, 6248.875022499616, 6252.218472690914, 6253.593516421418, 6257.499400047895, 6154.7690461906905, 6254.593451425642, 6302.590331628429, 6330.715117819801, 6455.741770329134, 6199.504039676905, 6201.255849297932, 6205.193324867739, 6205.2553693557065, 6225.875482490234, 5214.921776173284, 6195.380461953949, 6199.0, 6205.906911396242, 6227.626342419469, 6072.544559158169, 6114.602550834182, 6143.0, 6161.630302181883, 6227.408396202353, 6165.938340616536, 6168.938310616836, 6183.96908015457, 6199.690015499297, 6203.193584833943, 5118.667286626357, 5852.853678658068, 6071.817845464643, 6084.421979911901, 6107.144999700135, 6097.494431794971, 6168.1672974149715, 6177.073438984188, 6218.9378106218355, 6221.3156552778255, 6098.390160983866, 6109.327973922954, 6115.6330620162935, 6126.172966649446, 6154.384561543989], "value": 5699.878972387101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.91037613229616, 499.3982251387079, 511.21273239211615, 527.4777969809887, 578.8118861370053, 30544.54183187254, 43091.13817723643, 43182.0, 45953.77023114888, 46022.8494287643, 44306.113877722506, 44778.10443791122, 44861.327080093826, 44906.77546612262, 44913.0, 8650.65397384104, 8825.735227943173, 9574.234061275116, 9757.0, 9806.068423499755, 11757.882421175764, 11995.820062699066, 12023.098267629919, 12150.270983741004, 12203.877961220363, 11985.580504682337, 12017.278963262188, 12025.278483290987, 12032.458539365705, 12058.09564282678, 8674.86987695185, 8839.823203535892, 8858.778530536723, 11932.22440541362, 11938.462769175365, 8638.870416943751, 11659.475323610457, 11930.582429614922, 11934.462949167308, 12079.094067944852, 34985.12537186565, 41736.582634173574, 41986.950326241786, 42009.94975125616, 42121.57878421207, 2761.765249953756, 3238.6437491875877, 3300.372929143455, 3329.51722000311, 3896.415537669344, 29080.127596172148, 29090.563641545396, 29460.9688660898, 29884.10347689573, 29976.400471990655, 2536.771690547841, 2727.5090483712925, 2732.4535092981287, 2734.5898115282866, 2780.31882188863, 514835.0, 518689.0, 518891.0, 519166.0, 519782.0, 61150.0, 61758.382416175264, 61827.07259391113, 61863.381366185764, 61894.0, 37325.813370933516, 37693.05767355783, 37798.62201378006, 38907.44370225216, 38917.41623875644, 14913.850861491463, 17149.828501715074, 17163.141842907804, 17433.82566174322, 17482.388116416118, 12083.45624446902, 12188.878111218952, 12196.024318054535, 12397.380130993415, 12505.187162834387, 17753.911230443766, 18515.139338643672, 20657.0, 20697.482562936046, 20725.378238652866, 6822.829429264209, 8069.152738962442, 8079.919200807916, 8123.3095186908595, 8134.471259368122, 957.482959202037, 1034.3276870034388, 1146.696125526736, 1171.1275100050516, 1173.1611897493162, 30017.549736753965, 30592.84703576468, 30737.2315692105, 30763.69236307653, 30936.845315773728, 1103.4096758234307, 1109.97327472088, 1115.893990070942, 1126.1610100475034, 1177.4407156600546, 15093.0, 15231.923840380727, 15323.310451029507, 15570.0, 15712.214389280718, 712189.0, 712668.0, 716993.0, 717933.0, 718474.0, 59773.0, 60066.69966650139, 60221.795564088476, 60225.09662355068, 60488.79022419527, 1333.0, 1383.6471699716508, 1455.8325792533994, 1479.408236705305, 1512.8940974131706, 29531.0, 29978.85010574933, 30106.84946575253, 30315.93894213692, 30574.84712576423, 51915.96168076693, 52638.0, 52696.47303527069, 52766.47233527616, 52857.47142528526, 550258.0, 574378.0, 574695.0, 574807.0, 574807.0, 23288.0, 23769.881150594138, 25976.480470390863, 26747.0, 27168.320791980357, 42772.35841462318, 44115.77942110269, 45813.85465363393, 46283.53716462792, 46358.768206158755, 36765.26469470649, 38440.0, 38580.228395432496, 39328.0, 39654.206915860945, 36846.078848029014, 36963.81518092392, 37251.81374093221, 38177.0, 38186.42720359141, 34982.47526287159, 35270.29459410849, 38417.4237386445, 38920.80539597284, 39005.219895602495, 26544.0, 31057.0, 33484.832575837936, 33796.49305260374, 33917.0, 24737.628935565623, 24891.87986540574, 24956.7850041467, 25292.62061069122, 25367.6194857081], "value": 20202.525440838934, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.2244440785699, 396.78970145822706, 397.5358694740785, 399.98800035998914, 420.9221294060599, 14606.561803145896, 20477.48806279844, 21030.894845525752, 21721.782782172173, 21777.802220877864, 16202.432914847983, 19636.214551417925, 21089.894550527228, 21458.57082858346, 21475.46311342217, 5862.120681897717, 6089.604175728586, 6131.9386806131815, 6137.5089992800695, 6137.6624285664275, 5674.375818659944, 5706.429357064302, 5708.08670612702, 5999.13012613173, 6056.121862329959, 5659.094544872821, 5822.1266809978415, 5984.97007514964, 6027.09593560965, 6028.03551431771, 5870.090136028923, 5895.056790913454, 5975.133605627203, 5976.3724808895095, 6005.6997150142315, 5253.973730131325, 5258.973705131488, 5269.367675878919, 5276.076686579833, 5277.419483856773, 21312.467188320417, 25571.105011324515, 25917.222483325342, 26896.865515672296, 27012.729872701217, 2245.483538786072, 2376.9881150594138, 2494.189388448743, 2565.525377805112, 2762.958555621647, 14928.178950157737, 15221.847781522154, 15360.769588456182, 15405.460808871636, 15473.690526189414, 1739.704250277456, 1740.843324100837, 1752.9035903025328, 1836.0544319675378, 1839.6044850357105, 122998.0, 128082.0, 129273.35363323217, 131256.6874331254, 132995.0, 10157.464182020816, 10805.945970270255, 10831.454608167074, 11134.0, 11388.94305528467, 14365.856341436453, 14397.496087637091, 14449.927750361181, 14465.13209207451, 14465.710685786225, 7383.483156179016, 7913.485623434458, 8782.736517904474, 8807.427517211361, 9068.773280667903, 8975.910240897507, 8983.191512763882, 8990.460572365679, 9120.224780893699, 9145.862812057825, 6717.630530320879, 6849.349311815369, 6849.794506164823, 6867.767975407913, 6887.242403335629, 1501.5345242974495, 1862.636785826778, 1870.298638010746, 1886.839568693838, 1897.696368581048, 939.272064150289, 949.586929685589, 993.513178542509, 999.3654029691198, 1059.8728152621582, 20784.204004956344, 21413.357599272047, 21641.242556510446, 21722.23972160967, 21890.781092189194, 1014.9746256343503, 1018.1854516386861, 1018.9439580822977, 1021.8262895307815, 1033.612395351743, 8679.349048821363, 10778.514966826511, 11555.653330400102, 11567.537298508134, 11626.651200463999, 502372.0, 504168.0, 504213.0, 609895.0, 617111.0, 125327.37336313442, 143419.37515935968, 144290.0, 149898.25050874893, 152790.708139378, 1012.2104758288547, 1046.8115739167024, 1059.5973530058682, 1060.7560261139827, 1063.6011495689118, 18304.0, 18672.7199092011, 18700.06499675038, 18862.24551017999, 19075.76007559504, 55231.44768552263, 56708.0, 56890.43109568852, 56963.0, 57773.422265776804, 84787.30425391335, 85153.72269416088, 85893.57053214694, 86457.0, 86666.0, 7706.190849960673, 7826.334761545332, 8114.797130071795, 8163.551004694798, 8442.282405995558, 9267.6292948284, 11780.587679431179, 12359.196652217577, 12612.873871261538, 12641.747165056831, 11108.944455277671, 11116.44417779124, 11201.047910927336, 11291.322520648788, 11466.713332166762, 10940.343579385262, 11009.83485247738, 11079.638956764638, 11126.276792008493, 11264.605738799102, 11290.040346570633, 11440.71398215018, 11649.0, 11710.70723231926, 11791.233569817934, 7067.787966361017, 8128.309093727099, 8172.754817355489, 8341.791455213668, 8572.699955501528, 9514.33399662017, 11138.721531961766, 11202.83195752048, 11233.49449274801, 11586.0], "value": 9904.906958449596, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.7669172932333, 1628.0313213637885, 1659.369439612947, 1920.1071501751683, 2025.574629327841, 8062.959685201573, 8662.0, 8671.783205419852, 8680.08859069798, 8683.696070637528, 8625.525596092213, 9011.369204155711, 9020.233280171193, 9129.406588571737, 9173.220276276508, 2442.2917353967327, 2519.722830488645, 2526.2673824590847, 2527.6208568714655, 2528.4437423766835, 2464.100603279799, 2529.380301826046, 2536.0996846119638, 2536.5307418127613, 2540.9491810163786, 2477.170148000422, 2503.6119401492706, 2513.3842208658903, 2516.748325167487, 2518.7859031982302, 2474.5793215153376, 2481.0944005438064, 2506.135383292761, 2514.120057979711, 2514.786243169333, 2234.966475502869, 2452.0682140786566, 2458.188797696756, 2486.2292689266205, 2510.2594734553168, 9135.817283654356, 9211.0, 9213.953930230373, 9220.8616870747, 9228.769280767967, 5396.5952553558245, 5466.590005749585, 5619.971900140514, 5655.462731040545, 5667.291588551431, 3481.7736847104857, 3598.0, 3646.4348026056005, 3677.52192215013, 3679.7056235501095, 986.4722373530199, 1008.3647302199568, 1044.4568824211444, 1062.33073163907, 1075.9462026898623, 12201.572944946884, 12280.324582147978, 12288.201266917622, 12321.63035108957, 12322.568710095193, 4224.619784219435, 4801.95198048022, 5215.608829337815, 5228.581713462914, 5258.237555554423, 9436.952815236018, 9450.952745236367, 9523.095305945924, 9551.665691700897, 9561.187299079656, 5488.4785945335125, 5495.230667706524, 5497.230387745639, 5503.11950087984, 5505.724713764296, 4385.780710964503, 4508.9098218036115, 5071.39143302806, 5106.617003724661, 5144.7942082316285, 3939.1727737174942, 3953.2686453006286, 3994.2810294146752, 4031.496062992126, 4062.4921884764403, 1201.3632774629461, 1217.2148963918223, 1223.853137623491, 1234.7530493901256, 1240.3178251961385, 660.0198704923185, 779.3570304498697, 807.7738233294687, 883.703959173684, 998.5656239535825, 5978.8206353809455, 6072.0, 6085.847853803691, 6231.781887634002, 6339.2709838368255, 803.7186984555359, 933.7338858425317, 949.3259785552198, 959.8560215967522, 999.5601935148511, 3960.4851369321805, 4100.220958017967, 4140.979295103505, 4175.31107367287, 4185.874423767231, 10967.396793176293, 11218.55125794959, 11398.1451391146, 11412.486438110305, 11429.0, 11265.774684506265, 11358.716032099099, 11379.943100284445, 11412.315261084195, 11422.914823091793, 8300.792480188044, 8380.664773409117, 8604.78488037804, 8614.353923455767, 8640.783980400414, 5961.731722072431, 6002.819915402545, 6085.208922840003, 6107.725152368065, 6134.263888333428, 10094.697159085239, 10536.525856336637, 10694.091002264895, 10710.089642380484, 10712.73218169552, 10457.529411176363, 10873.347599144077, 10883.619073332395, 10893.183011274028, 10897.509612067635, 7093.184283807428, 7210.0, 7304.452166087671, 7332.30343117403, 7347.08161479815, 8494.957525212334, 8525.019622743464, 8581.828363432822, 8600.78498037554, 8609.0, 6470.935290647033, 7520.360769334449, 7657.923420765721, 7760.068791745027, 7903.802404939693, 7434.591097489473, 7557.773266802004, 7594.126675432175, 7668.6165691716305, 7718.189590092959, 7237.819054523679, 7261.745838895613, 7349.55902645843, 7350.816229594303, 7356.374708149867, 4849.230031038716, 5026.220935754964, 5113.872153196051, 5125.077486052397, 5130.51260130287, 5127.746118885045, 5164.32863727713, 5209.7395130244095, 5212.843614691566, 5302.496262855023], "value": 4720.52562199486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.25756842635593, 319.85926192475307, 329.39720311829365, 330.57686161713013, 333.41152865192953, 4506.084913911587, 5375.650582712121, 5382.730863456831, 5386.78452861885, 5400.083954664487, 4135.565765594607, 5101.811367895066, 5296.179092240709, 5307.52232299093, 5362.3029006229235, 3031.181580973136, 3170.8414579271002, 3777.2823163598828, 4105.548389677133, 4131.896702582444, 4013.4381186633896, 4078.77566733831, 4080.5307389650275, 4085.4893138357706, 4123.7938103094875, 3503.982480087596, 3555.448905419651, 3887.436321733347, 3897.376419772837, 4027.637512623878, 3510.3505851417312, 3532.4171511700665, 3829.9425508617255, 4033.899152521181, 4076.9592304077173, 3271.623763267231, 3362.5877131604784, 3528.788272703646, 3548.3435564420665, 3576.8032758198287, 4426.380306757057, 4848.5151484851585, 5022.1211288024815, 5030.195168772979, 5089.720065396402, 3895.1235971906094, 3972.7616343019513, 4436.711613745097, 4457.732536047815, 4714.336399934061, 5536.397892990288, 5539.556835453194, 5548.5838562108, 5566.638168519034, 5568.693721845297, 3548.3258180945536, 3825.3114439401174, 3939.231849789292, 3966.4248683940955, 3983.7609743415487, 4983.729149066965, 5385.0, 5590.18942253375, 5804.419558044204, 5836.387179346192, 3398.980305908218, 3404.931901361959, 3929.9367831887917, 3955.149642826806, 3964.3746064113575, 3569.94645080324, 3882.902927426837, 4065.1869626074904, 4067.2882245607493, 4107.301758701028, 3937.842486300573, 4482.887927801831, 4585.70192937458, 4594.220496599509, 4743.525647435229, 5335.226392173113, 5392.56859451243, 5561.749721262553, 5567.081431563827, 5575.498205161555, 1297.1503665099376, 1375.326090215787, 1387.9097858639157, 1389.4720006397706, 1394.4910107810576, 822.679155129488, 863.9092895245909, 874.881890944727, 881.2245224202788, 886.9379143460025, 4160.979195104066, 4647.0, 5109.113350956698, 5222.216667499905, 5227.2420499028185, 4701.717896926195, 4904.2643603459055, 5268.762905669254, 5747.557288152602, 5788.9290481261105, 4354.9564504355185, 4836.588889944394, 4871.29366241893, 4893.608511319086, 5073.594112471067, 2968.3618022125347, 2983.8358890261243, 3016.4721173794937, 3020.7734419918593, 3022.81863088211, 5711.371749107595, 5923.792667256712, 5954.166416701665, 5994.550408719363, 6045.304789949212, 4795.777076845382, 5582.385937546866, 5650.943490565124, 5925.170476133345, 6000.21997140369, 5370.731463426813, 5645.943540564542, 5720.856978575569, 5966.0752583348785, 5995.310539287863, 4465.910681786281, 5148.948510514847, 5509.972450137724, 5785.0454674978155, 5810.73851676684, 5065.341505604248, 5066.442691304027, 5605.075162598126, 5713.885722285614, 5728.0, 5574.414686458026, 5921.851953701192, 5935.7032148391545, 5991.460768530853, 6005.7297421617, 3114.735247503897, 3148.244421338908, 3214.791038582485, 3311.9860926556717, 3380.9492857606665, 4014.9598504014584, 4377.868663940087, 4639.48965613789, 4821.566059054702, 4859.757012149307, 4045.696572756996, 4229.767362795076, 4654.418197725285, 4678.672492925599, 4706.764661766966, 4056.472658554369, 4629.171477266394, 4655.393889108253, 4672.252439609646, 4689.531046895283, 3972.6226008529143, 4253.617174454314, 4310.9307532384, 4386.692931494765, 4642.955335049722, 4839.95160048395, 5222.7879853890345, 5288.25487589087, 5442.945570544244, 5492.0, 4754.072955773591, 5176.22356646491, 5340.291106845927, 5362.249285100167, 5498.0], "value": 3944.1143443666215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.886269902767, 732.8277854704144, 735.5696917303377, 753.7512620835126, 765.9885101723472, 11888.881111188886, 15019.774703379444, 15496.225188740545, 15541.456049038286, 15558.37766489339, 14143.292835358217, 14357.928210358934, 14401.711965760705, 14431.567052988401, 14469.0, 728.7704373122476, 741.3994664321916, 905.8686490458896, 925.2459245714755, 925.2597921662676, 894.6332003878412, 911.2254583603917, 912.0104686415215, 916.894557125929, 917.7797328641145, 969.3360048366856, 978.6379039755301, 979.2606582030556, 979.304693667497, 980.9558569864373, 756.3986630628636, 762.5767698927093, 770.0336078221852, 971.36861040324, 974.8732664753572, 581.5870731780441, 696.502001069238, 707.5577763897564, 707.7841258416181, 713.636045616734, 14582.635434114232, 14621.6344591385, 14635.707285854223, 14655.560333190022, 14683.192424416653, 12107.152499325051, 12157.574484892986, 12198.451069701885, 12479.126461147724, 12501.812472812915, 10230.446405902263, 10528.105111065568, 10555.947220263926, 10779.838302425469, 11044.0, 3907.7706243449675, 3964.464797252363, 4066.8983275418054, 4080.489938757655, 4253.574642535722, 69020.96468552925, 76172.0, 76211.61894190493, 83270.0, 83387.16612833855, 9564.808703826024, 9611.711648650551, 9654.806903861883, 9951.900480995242, 10053.949730251448, 19961.101750421265, 20586.58826823455, 20597.279095231595, 21114.78885211159, 21897.562048758937, 7337.339639432476, 7383.409327253807, 7582.924170758332, 7694.422918281151, 7756.844863102707, 3540.539729835105, 3631.2011357501838, 3633.491311216379, 3665.5051568037975, 3675.5956844747593, 3622.8913132606062, 3709.9443508347395, 3711.0, 3715.6098609646137, 3716.163863130828, 649.0847904454696, 656.4682607088274, 659.1628631637793, 664.6278084272823, 672.4183581202323, 515.4124298299942, 519.828456609318, 577.5581680014792, 606.5208485296655, 611.1230384398402, 7846.9215307847335, 7963.243491868263, 8233.17668233172, 8342.874856877152, 8384.455010424303, 553.6456667732655, 572.2989338060926, 580.4804699793661, 591.5770224289654, 597.5936363272929, 3281.392942305681, 3282.7209687176855, 3912.68698504119, 3963.0, 4113.321301985139, 93899.0, 97432.0, 97724.0, 98550.5072474633, 98828.50585747026, 39118.0, 40968.79515602403, 57795.0, 83206.583967081, 88242.55878720565, 73854.0, 74795.25204747992, 75052.24947750561, 75331.24668753461, 76009.85985210226, 7648.694052237848, 7713.575753333396, 7871.212878712312, 7886.44794864354, 7909.802254943673, 45226.823964609015, 47091.76454117707, 47612.52387476219, 47679.0, 48036.519634803204, 64192.67903660452, 64861.0, 65277.347226529026, 65540.34459655342, 65848.34151658422, 10103.0, 10185.898141018495, 10427.322224055411, 10429.113525350127, 10626.0, 10424.947875260576, 10579.682609521727, 10606.681799546026, 10871.29336593119, 11321.03771179459, 10094.444805535766, 10330.0, 10424.843627345444, 10451.32066415711, 10570.2600817942, 10389.116925061455, 10403.53184106732, 10737.355758654505, 11369.97670209685, 11395.886041139815, 10060.748481288027, 10075.546600402853, 10316.587336506756, 10455.581776728846, 10578.153747700011, 8768.430052046597, 8994.775130621787, 9033.367664263438, 9279.489628070522, 9295.76760580991, 9597.424154550748, 9635.855462168212, 9748.705141051096, 9791.128562683849, 9806.0], "value": 6738.713832326858, "unit": "score"}], "name": "dromaeo_css", "value": 7232.37706111749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 111.0, 112.0, 110.0, 111.0, 110.0, 112.0, 111.0, 108.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 141.0, 146.0, 200.0, 116.0, 142.0, 148.0, 138.0, 181.0, 117.0], "value": 150.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 183.0, 183.0, 183.0, 182.0, 183.0, 182.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 66.0, 126.0, 58.0, 67.0, 78.0, 78.0, 116.0, 56.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 109.0, 134.0, 79.0, 79.0, 77.0, 103.0, 80.0, 80.0, 103.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 86.0, 83.0, 85.0, 84.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 92.0, 91.0, 93.0, 92.0, 108.0, 143.0, 94.0, 94.0, 93.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 84.0, 84.0, 84.0, 85.0, 83.0, 84.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 56.0, 57.0, 56.0, 56.0, 118.0, 58.0, 54.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 74.0, 74.0, 72.0, 68.0, 67.0, 70.0, 69.0, 69.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 85.0, 84.0, 82.0, 84.0, 84.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 148.0, 130.0, 132.0, 136.0, 135.0, 134.0, 206.0, 137.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 204.0, 213.0, 162.0, 163.0, 163.0, 162.0, 161.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 66.0, 64.0, 65.0, 65.0, 63.0, 64.0, 64.0], "value": 64.6, "unit": "score"}], "name": "kraken", "value": 1484.5000000000002, "alertThreshold": 2.0}]} 05:35:16 INFO - Return code: 0 05:35:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:35:16 ERROR - # TBPL SUCCESS # 05:35:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:16 INFO - 2016-05-18 05:35:16.439 screenresolution[6563:107830] starting screenresolution argv=screenresolution get 05:35:16 INFO - 2016-05-18 05:35:16.442 screenresolution[6563:107830] Display 0: 1600x1200x32@60 05:35:16 INFO - 2016-05-18 05:35:16.448 screenresolution[6564:107833] starting screenresolution argv=screenresolution list 05:35:16 INFO - Available Modes on Display 0 05:35:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:16 INFO - Intel Iris: 05:35:16 INFO - Chipset Model: Intel Iris 05:35:16 INFO - Type: GPU 05:35:16 INFO - Bus: Built-In 05:35:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:16 INFO - Vendor: Intel (0x8086) 05:35:16 INFO - Device ID: 0x0a2e 05:35:16 INFO - Revision ID: 0x0009 05:35:16 INFO - Displays: 05:35:16 INFO - Display: 05:35:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:16 INFO - Display Serial Number: 1600x1200 60 05:35:16 INFO - Main Display: Yes 05:35:16 INFO - Mirror: Off 05:35:16 INFO - Online: Yes 05:35:16 INFO - Rotation: Supported 05:35:16 INFO - Return code: 0 05:35:16 INFO - Running post-action listener: _resource_record_post_action 05:35:16 INFO - ##### 05:35:16 INFO - ##### Finished run-tests step (success) 05:35:16 INFO - ##### 05:35:16 INFO - Running post-run listener: _resource_record_post_run 05:35:16 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 638976; Write bytes: 696211456; Read time: 11; Write time: 1931 05:35:16 INFO - TinderboxPrint: CPU usage
53.8% 05:35:16 INFO - TinderboxPrint: I/O read bytes / time
638,976 / 11 05:35:16 INFO - TinderboxPrint: I/O write bytes / time
696,211,456 / 1,931 05:35:16 INFO - TinderboxPrint: CPU idle
2,240.2 (46.2%) 05:35:16 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 05:35:16 INFO - TinderboxPrint: CPU user
1,640.7 (33.8%) 05:35:16 INFO - TinderboxPrint: Swap in / out
395,911,168 / 0 05:35:16 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395952640; Write bytes: 397268480; Read time: 29935; Write time: 738 05:35:16 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 614400; Write bytes: 298095104; Read time: 10; Write time: 1190 05:35:17 INFO - Running post-run listener: _upload_blobber_files 05:35:17 INFO - Blob upload gear active. 05:35:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:35:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:35:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:35:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:35:17 INFO - (blobuploader) - INFO - Open directory for files ... 05:35:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:35:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:35:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:18 INFO - (blobuploader) - INFO - Done attempting. 05:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:35:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:19 INFO - (blobuploader) - INFO - Done attempting. 05:35:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:35:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:35:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:21 INFO - (blobuploader) - INFO - Done attempting. 05:35:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:35:21 INFO - Return code: 0 05:35:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:35:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:35:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5efe5c956c268c9bf30f9a1c61de7781013c90a359f2e1845fd9dd9055c7e507a505f517028dd21b486fcbd976f28125461ba153414c7a7b367073557e0d400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da097c5e33e5822dfe8e44370a5f7ccd27e4d7432a614449032d54aa6bff4ce807c52fa4af126bb11a4eeb3578bd0bf63eab83f4073f7b0a81f2e537f1022c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a693154f54765f67a7264398398c3f6b3eed3013a176d26783c771651565d52a129010925c93e965321f9e53c58bbcfd5cbe8717cdaaf5f8e3ffad186bf96458"} 05:35:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:35:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:35:21 INFO - Contents: 05:35:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5efe5c956c268c9bf30f9a1c61de7781013c90a359f2e1845fd9dd9055c7e507a505f517028dd21b486fcbd976f28125461ba153414c7a7b367073557e0d400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da097c5e33e5822dfe8e44370a5f7ccd27e4d7432a614449032d54aa6bff4ce807c52fa4af126bb11a4eeb3578bd0bf63eab83f4073f7b0a81f2e537f1022c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a693154f54765f67a7264398398c3f6b3eed3013a176d26783c771651565d52a129010925c93e965321f9e53c58bbcfd5cbe8717cdaaf5f8e3ffad186bf96458"} 05:35:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:35:21 INFO - Copying logs to upload dir... 05:35:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:35:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.186919 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-18 05:35:21.664540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 05:35:21.667987) ========= 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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5efe5c956c268c9bf30f9a1c61de7781013c90a359f2e1845fd9dd9055c7e507a505f517028dd21b486fcbd976f28125461ba153414c7a7b367073557e0d400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da097c5e33e5822dfe8e44370a5f7ccd27e4d7432a614449032d54aa6bff4ce807c52fa4af126bb11a4eeb3578bd0bf63eab83f4073f7b0a81f2e537f1022c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a693154f54765f67a7264398398c3f6b3eed3013a176d26783c771651565d52a129010925c93e965321f9e53c58bbcfd5cbe8717cdaaf5f8e3ffad186bf96458"} build_url:https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011645 build_url: 'https://queue.taskcluster.net/v1/task/ZwPdajLKRMCviQB8X_3bHg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5efe5c956c268c9bf30f9a1c61de7781013c90a359f2e1845fd9dd9055c7e507a505f517028dd21b486fcbd976f28125461ba153414c7a7b367073557e0d400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da097c5e33e5822dfe8e44370a5f7ccd27e4d7432a614449032d54aa6bff4ce807c52fa4af126bb11a4eeb3578bd0bf63eab83f4073f7b0a81f2e537f1022c6c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a693154f54765f67a7264398398c3f6b3eed3013a176d26783c771651565d52a129010925c93e965321f9e53c58bbcfd5cbe8717cdaaf5f8e3ffad186bf96458"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 05:35:21.696780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:35:21.697125) ========= 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.44nUD2vlu8/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.d8O0Ka70Xt/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005450 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 05:35:21.745667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 05:35:21.746012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 05:35:21.746374) ========= ========= Total master_lag: 0.16 =========