builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0226 starttime: 1463189851.5 results: success (0) buildid: 20160513164442 builduid: 8e9f0e099d6441e89ab9e1b18f4eb5f6 revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:31.500173) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:31.500645) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:31.500952) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:31.519321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:31.519647) ========= 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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 18:37:31-- 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% 57.6M=0s 2016-05-13 18:37:32 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.619598 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:32.155913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:32.156261) ========= 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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.020432 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:32.204917) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 18:37:32.205297) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --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 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 18:37:32,175 truncating revision to first 12 chars 2016-05-13 18:37:32,175 Setting DEBUG logging. 2016-05-13 18:37:32,175 attempt 1/10 2016-05-13 18:37:32,175 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2afd8fa9bb5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 18:37:33,329 unpacking tar archive at: mozilla-central-2afd8fa9bb5d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405714 ========= master_lag: 1.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 18:37:34.785585) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:34.785842) ========= script_repo_revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:34.786202) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:34.786455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:35.099147) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-13 18:37:35.099394) ========= /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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:37:35 INFO - MultiFileLogger online at 20160513 18:37:35 in /builds/slave/test 18:37:35 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 18:37:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:37:35 INFO - {'append_to_log': False, 18:37:35 INFO - 'base_work_dir': '/builds/slave/test', 18:37:35 INFO - 'blob_upload_branch': 'Firefox', 18:37:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:37:35 INFO - 'branch': 'Firefox', 18:37:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:37:35 INFO - 'config_files': ('talos/mac_config.py',), 18:37:35 INFO - 'default_actions': ('clobber', 18:37:35 INFO - 'read-buildbot-config', 18:37:35 INFO - 'download-and-extract', 18:37:35 INFO - 'populate-webroot', 18:37:35 INFO - 'create-virtualenv', 18:37:35 INFO - 'install', 18:37:35 INFO - 'run-tests'), 18:37:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:37:35 INFO - 'download_minidump_stackwalk': True, 18:37:35 INFO - 'download_symbols': 'ondemand', 18:37:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:37:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:37:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:37:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:37:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:37:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:37:35 INFO - 'installer_path': 'installer.exe', 18:37:35 INFO - 'log_level': 'info', 18:37:35 INFO - 'log_name': 'talos', 18:37:35 INFO - 'log_to_console': True, 18:37:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:37:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:37:35 INFO - 'opt_config_files': (), 18:37:35 INFO - 'pip_index': False, 18:37:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:35 INFO - 'cmd': ('bash', 18:37:35 INFO - '-c', 18:37:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:35 INFO - 'enabled': True, 18:37:35 INFO - 'halt_on_failure': False, 18:37:35 INFO - 'name': 'check_screen_resolution'},), 18:37:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:35 INFO - 'cmd': ('bash', 18:37:35 INFO - '-c', 18:37:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:35 INFO - 'enabled': True, 18:37:35 INFO - 'halt_on_failure': False, 18:37:35 INFO - 'name': 'check_screen_resolution'},), 18:37:35 INFO - 'run_cmd_checks_enabled': True, 18:37:35 INFO - 'sps_profile': False, 18:37:35 INFO - 'sps_profile_interval': 0, 18:37:35 INFO - 'suite': 'dromaeojs', 18:37:35 INFO - 'system_bits': '32', 18:37:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:37:35 INFO - 'title': 't-yosemite-r7-0226', 18:37:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:37:35 INFO - 'use_talos_json': True, 18:37:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:37:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:37:35 INFO - 'work_dir': 'build'} 18:37:35 INFO - ##### 18:37:35 INFO - ##### Running clobber step. 18:37:35 INFO - ##### 18:37:35 INFO - Running pre-action listener: _resource_record_pre_action 18:37:35 INFO - Running main action method: clobber 18:37:35 INFO - rmtree: /builds/slave/test/build 18:37:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:37:36 INFO - Running post-action listener: _resource_record_post_action 18:37:36 INFO - ##### 18:37:36 INFO - ##### Finished clobber step (success) 18:37:36 INFO - ##### 18:37:36 INFO - ##### 18:37:36 INFO - ##### Running read-buildbot-config step. 18:37:36 INFO - ##### 18:37:36 INFO - Running pre-action listener: _resource_record_pre_action 18:37:36 INFO - Running main action method: read_buildbot_config 18:37:36 INFO - Using buildbot properties: 18:37:36 INFO - { 18:37:36 INFO - "project": "", 18:37:36 INFO - "product": "firefox", 18:37:36 INFO - "script_repo_revision": "production", 18:37:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:37:36 INFO - "repository": "", 18:37:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:37:36 INFO - "buildid": "20160513164442", 18:37:36 INFO - "pgo_build": "False", 18:37:36 INFO - "basedir": "/builds/slave/test", 18:37:36 INFO - "buildnumber": 4, 18:37:36 INFO - "slavename": "t-yosemite-r7-0226", 18:37:36 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:37:36 INFO - "platform": "yosemite_r7", 18:37:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:37:36 INFO - "branch": "mozilla-central", 18:37:36 INFO - "repo_path": "mozilla-central", 18:37:36 INFO - "revision": "2afd8fa9bb5df5577e5566468bb423b76c63cc77", 18:37:36 INFO - "stage_platform": "macosx64", 18:37:36 INFO - "builduid": "8e9f0e099d6441e89ab9e1b18f4eb5f6", 18:37:36 INFO - "slavebuilddir": "test" 18:37:36 INFO - } 18:37:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:37:36 INFO - Running post-action listener: _resource_record_post_action 18:37:36 INFO - ##### 18:37:36 INFO - ##### Finished read-buildbot-config step (success) 18:37:36 INFO - ##### 18:37:36 INFO - ##### 18:37:36 INFO - ##### Running download-and-extract step. 18:37:36 INFO - ##### 18:37:36 INFO - Running pre-action listener: _resource_record_pre_action 18:37:36 INFO - Running main action method: download_and_extract 18:37:36 INFO - mkdir: /builds/slave/test/build/tests 18:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:36 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:37:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:40 INFO - Downloaded 1199 bytes. 18:37:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:40 INFO - Using the following test package requirements: 18:37:40 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:37:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:37:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:37:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'jsshell-mac.zip'], 18:37:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:37:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:37:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:37:40 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:37:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:37:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:40 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:37:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:40 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:56 INFO - Downloaded 18266856 bytes. 18:37:56 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'] 18:37:56 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 18:37:59 INFO - Return code: 0 18:37:59 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 18:37: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')]} 18:37:59 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:37:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:38:01 INFO - Downloaded 18266856 bytes. 18:38:01 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'] 18:38:01 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 18:38:05 INFO - Return code: 0 18:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:05 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:38:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:38:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:38:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:38:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:38:06 INFO - Downloaded 10840194 bytes. 18:38:06 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'] 18:38:06 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 18:38:06 INFO - Return code: 0 18:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:06 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:38:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:38:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:38:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:38:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:38:13 INFO - Downloaded 110020047 bytes. 18:38:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:38:13 INFO - mkdir: /builds/slave/test/properties 18:38:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:38:13 INFO - Writing to file /builds/slave/test/properties/build_url 18:38:13 INFO - Contents: 18:38:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:38:14 INFO - Running post-action listener: _resource_record_post_action 18:38:14 INFO - Running post-action listener: set_extra_try_arguments 18:38:14 INFO - ##### 18:38:14 INFO - ##### Finished download-and-extract step (success) 18:38:14 INFO - ##### 18:38:14 INFO - ##### 18:38:14 INFO - ##### Running populate-webroot step. 18:38:14 INFO - ##### 18:38:14 INFO - Running pre-action listener: _resource_record_pre_action 18:38:14 INFO - Running main action method: populate_webroot 18:38:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:38:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:38:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:38:14 INFO - '--tppagecycles', 18:38:14 INFO - '7'], 18:38:14 INFO - 'tests': ['tsvgm']}}, 18:38:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:38:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:38:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:38:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:38:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['damp', 'tps']}, 18:38:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'tests': ['damp', 'tps']}, 18:38:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['dromaeo_dom']}, 18:38:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:38:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'other-e10s': {'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['a11yr', 18:38:14 INFO - 'ts_paint', 18:38:14 INFO - 'tpaint', 18:38:14 INFO - 'sessionrestore', 18:38:14 INFO - 'sessionrestore_no_auto_restore', 18:38:14 INFO - 'tabpaint']}, 18:38:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['tsvgx', 18:38:14 INFO - 'tsvgr_opacity', 18:38:14 INFO - 'tart', 18:38:14 INFO - 'tscrollx', 18:38:14 INFO - 'cart']}, 18:38:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:38:14 INFO - 'tsvgr_opacity', 18:38:14 INFO - 'tart', 18:38:14 INFO - 'tscrollx', 18:38:14 INFO - 'cart']}, 18:38:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'talos_options': ['--disable-e10s'], 18:38:14 INFO - 'tests': ['tp5o']}, 18:38:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'tests': ['tp5o']}, 18:38:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'talos_options': ['--disable-e10s', 18:38:14 INFO - '--xperf_path', 18:38:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:14 INFO - 'tests': ['tp5n']}, 18:38:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:38:14 INFO - 'talos_options': ['--xperf_path', 18:38:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:14 INFO - 'tests': ['tp5n']}}, 18:38:14 INFO - 'talos.zip': {'path': '', 18:38:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:38:14 INFO - Running post-action listener: _resource_record_post_action 18:38:14 INFO - ##### 18:38:14 INFO - ##### Finished populate-webroot step (success) 18:38:14 INFO - ##### 18:38:14 INFO - ##### 18:38:14 INFO - ##### Running create-virtualenv step. 18:38:14 INFO - ##### 18:38:14 INFO - Running pre-action listener: _resource_record_pre_action 18:38:14 INFO - Running main action method: create_virtualenv 18:38:14 INFO - Creating virtualenv /builds/slave/test/build/venv 18:38:14 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 18:38:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:38:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:38:14 INFO - Using real prefix '/tools/python27' 18:38:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:38:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:38:18 INFO - Installing pip.................done. 18:38:18 INFO - Return code: 0 18:38:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:18 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:18 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 18:38:18 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 18:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:18 INFO - 'HOME': '/Users/cltbld', 18:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:18 INFO - 'LOGNAME': 'cltbld', 18:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:18 INFO - 'MOZ_NO_REMOTE': '1', 18:38:18 INFO - 'NO_EM_RESTART': '1', 18:38:18 INFO - 'PAGER': '/bin/cat', 18:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:18 INFO - 'PWD': '/builds/slave/test', 18:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:18 INFO - 'SHELL': '/bin/bash', 18:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:18 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:18 INFO - 'USER': 'cltbld', 18:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:18 INFO - 'XPC_FLAGS': '0x0', 18:38:18 INFO - 'XPC_SERVICE_NAME': '0', 18:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:18 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 18:38:18 INFO - Cleaning up... 18:38:18 INFO - Return code: 0 18:38:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:18 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:18 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 18:38:18 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 18:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:18 INFO - 'HOME': '/Users/cltbld', 18:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:18 INFO - 'LOGNAME': 'cltbld', 18:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:18 INFO - 'MOZ_NO_REMOTE': '1', 18:38:18 INFO - 'NO_EM_RESTART': '1', 18:38:18 INFO - 'PAGER': '/bin/cat', 18:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:18 INFO - 'PWD': '/builds/slave/test', 18:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:18 INFO - 'SHELL': '/bin/bash', 18:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:18 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:18 INFO - 'USER': 'cltbld', 18:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:18 INFO - 'XPC_FLAGS': '0x0', 18:38:18 INFO - 'XPC_SERVICE_NAME': '0', 18:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:18 INFO - Downloading/unpacking psutil>=3.1.1 18:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:38:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:21 INFO - Installing collected packages: psutil 18:38:21 INFO - Running setup.py install for psutil 18:38:21 INFO - building 'psutil._psutil_osx' extension 18:38:21 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 18:38:22 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 18:38:22 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 18:38:22 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 18:38:22 INFO - building 'psutil._psutil_posix' extension 18:38:22 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 18:38:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:38:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:38:22 INFO - ^ 18:38:22 INFO - 1 warning generated. 18:38:22 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 18:38:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:22 INFO - Successfully installed psutil 18:38:22 INFO - Cleaning up... 18:38:22 INFO - Return code: 0 18:38:22 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 18:38:22 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')]} 18:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:22 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')]} 18:38:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 18:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 18:38:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:22 INFO - 'HOME': '/Users/cltbld', 18:38:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:22 INFO - 'LOGNAME': 'cltbld', 18:38:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:22 INFO - 'MOZ_NO_REMOTE': '1', 18:38:22 INFO - 'NO_EM_RESTART': '1', 18:38:22 INFO - 'PAGER': '/bin/cat', 18:38:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:22 INFO - 'PWD': '/builds/slave/test', 18:38:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:22 INFO - 'SHELL': '/bin/bash', 18:38:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:22 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:22 INFO - 'USER': 'cltbld', 18:38:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:22 INFO - 'XPC_FLAGS': '0x0', 18:38:22 INFO - 'XPC_SERVICE_NAME': '0', 18:38:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:23 INFO - Downloading/unpacking mozsystemmonitor==0.1 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:25 INFO - Downloading mozsystemmonitor-0.1.tar.gz 18:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 18:38:25 INFO - Installing collected packages: mozsystemmonitor 18:38:25 INFO - Running setup.py install for mozsystemmonitor 18:38:25 INFO - Successfully installed mozsystemmonitor 18:38:25 INFO - Cleaning up... 18:38:25 INFO - Return code: 0 18:38:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:25 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:25 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 18:38:25 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 18:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:25 INFO - 'HOME': '/Users/cltbld', 18:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:25 INFO - 'LOGNAME': 'cltbld', 18:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:25 INFO - 'MOZ_NO_REMOTE': '1', 18:38:25 INFO - 'NO_EM_RESTART': '1', 18:38:25 INFO - 'PAGER': '/bin/cat', 18:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:25 INFO - 'PWD': '/builds/slave/test', 18:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:25 INFO - 'SHELL': '/bin/bash', 18:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:25 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:25 INFO - 'USER': 'cltbld', 18:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:25 INFO - 'XPC_FLAGS': '0x0', 18:38:25 INFO - 'XPC_SERVICE_NAME': '0', 18:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:38:26 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 18:38:26 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 18:38:26 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 18:38:26 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 18:38:26 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 18:38:26 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 18:38:28 INFO - Downloading blobuploader-1.2.4.tar.gz 18:38:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:38:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:38:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:38:29 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 18:38:29 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 18:38:29 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 18:38:29 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 18:38:29 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 18:38:29 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 18:38:29 INFO - Downloading docopt-0.6.1.tar.gz 18:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:38:29 INFO - Installing collected packages: blobuploader, requests, docopt 18:38:29 INFO - Running setup.py install for blobuploader 18:38:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:38:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:38:29 INFO - Running setup.py install for requests 18:38:30 INFO - Running setup.py install for docopt 18:38:30 INFO - Successfully installed blobuploader requests docopt 18:38:30 INFO - Cleaning up... 18:38:30 INFO - Return code: 0 18:38:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:30 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:30 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 18:38:30 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 18:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:30 INFO - 'HOME': '/Users/cltbld', 18:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:30 INFO - 'LOGNAME': 'cltbld', 18:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:30 INFO - 'MOZ_NO_REMOTE': '1', 18:38:30 INFO - 'NO_EM_RESTART': '1', 18:38:30 INFO - 'PAGER': '/bin/cat', 18:38:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:30 INFO - 'PWD': '/builds/slave/test', 18:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:30 INFO - 'SHELL': '/bin/bash', 18:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:30 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:30 INFO - 'USER': 'cltbld', 18:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:30 INFO - 'XPC_FLAGS': '0x0', 18:38:30 INFO - 'XPC_SERVICE_NAME': '0', 18:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-y4ojwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:30 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-_INTTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-aXkAVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-imBwt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-488Yf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-AMfqkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-9wr4T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-p6loSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Ka49Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-qFvSPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:31 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cw4DN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-vOGBxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XzTrMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-EJje2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-FU5axM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-yfyfHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:32 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ULVG9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:38:32 INFO - Running setup.py install for manifestparser 18:38:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:38:32 INFO - Running setup.py install for mozcrash 18:38:32 INFO - Running setup.py install for mozdebug 18:38:33 INFO - Running setup.py install for mozdevice 18:38:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Running setup.py install for mozfile 18:38:33 INFO - Running setup.py install for mozhttpd 18:38:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Running setup.py install for mozinfo 18:38:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Running setup.py install for mozInstall 18:38:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:38:33 INFO - Running setup.py install for mozleak 18:38:33 INFO - Running setup.py install for mozlog 18:38:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Running setup.py install for moznetwork 18:38:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Running setup.py install for mozprocess 18:38:34 INFO - Running setup.py install for mozprofile 18:38:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Running setup.py install for mozrunner 18:38:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:38:34 INFO - Running setup.py install for mozscreenshot 18:38:34 INFO - Running setup.py install for moztest 18:38:35 INFO - Running setup.py install for mozversion 18:38:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:38:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:38:35 INFO - Cleaning up... 18:38:35 INFO - Return code: 0 18:38:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:35 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')]} 18:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:35 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')]} 18:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:35 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:35 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 18:38:35 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 18:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:35 INFO - 'HOME': '/Users/cltbld', 18:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:35 INFO - 'LOGNAME': 'cltbld', 18:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:35 INFO - 'MOZ_NO_REMOTE': '1', 18:38:35 INFO - 'NO_EM_RESTART': '1', 18:38:35 INFO - 'PAGER': '/bin/cat', 18:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:35 INFO - 'PWD': '/builds/slave/test', 18:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:35 INFO - 'SHELL': '/bin/bash', 18:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:35 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:35 INFO - 'USER': 'cltbld', 18:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:35 INFO - 'XPC_FLAGS': '0x0', 18:38:35 INFO - 'XPC_SERVICE_NAME': '0', 18:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-_HHD8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:35 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)) 18:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-UTkSfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:35 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)) 18:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-a4llNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:35 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)) 18:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2itsPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:35 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)) 18:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:35 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ibel9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-bRrQGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-VWOJ4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-_nmYjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-HGX9wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-5OL8fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-06mMBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-0Ece9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-lhasZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-dsgngM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:36 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)) 18:38:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:36 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-nhotve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:37 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)) 18:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:37 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-SFXc3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:37 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)) 18:38:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:37 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Bz40Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:37 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)) 18:38:37 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)) 18:38:37 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)) 18:38:37 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)) 18:38:37 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)) 18:38:37 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)) 18:38:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:38:37 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 18:38:37 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 18:38:37 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 18:38:37 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 18:38:37 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 18:38:37 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 18:38:40 INFO - Downloading blessings-1.6.tar.gz 18:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:38:40 INFO - Installing collected packages: blessings 18:38:40 INFO - Running setup.py install for blessings 18:38:40 INFO - Successfully installed blessings 18:38:40 INFO - Cleaning up... 18:38:40 INFO - Return code: 0 18:38:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:38:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:40 INFO - Reading from file tmpfile_stdout 18:38:40 INFO - Current package versions: 18:38:40 INFO - blessings == 1.6 18:38:40 INFO - blobuploader == 1.2.4 18:38:40 INFO - docopt == 0.6.1 18:38:40 INFO - manifestparser == 1.1 18:38:40 INFO - mozInstall == 1.12 18:38:40 INFO - mozcrash == 0.17 18:38:40 INFO - mozdebug == 0.1 18:38:40 INFO - mozdevice == 0.48 18:38:40 INFO - mozfile == 1.2 18:38:40 INFO - mozhttpd == 0.7 18:38:40 INFO - mozinfo == 0.9 18:38:40 INFO - mozleak == 0.1 18:38:40 INFO - mozlog == 3.2 18:38:40 INFO - moznetwork == 0.27 18:38:40 INFO - mozprocess == 0.22 18:38:40 INFO - mozprofile == 0.28 18:38:40 INFO - mozrunner == 6.11 18:38:40 INFO - mozscreenshot == 0.1 18:38:40 INFO - mozsystemmonitor == 0.1 18:38:40 INFO - moztest == 0.7 18:38:40 INFO - mozversion == 1.4 18:38:40 INFO - psutil == 3.1.1 18:38:40 INFO - requests == 1.2.3 18:38:40 INFO - wsgiref == 0.1.2 18:38:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:41 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:41 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 18:38:41 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 18:38:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:41 INFO - 'HOME': '/Users/cltbld', 18:38:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:41 INFO - 'LOGNAME': 'cltbld', 18:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:41 INFO - 'MOZ_NO_REMOTE': '1', 18:38:41 INFO - 'NO_EM_RESTART': '1', 18:38:41 INFO - 'PAGER': '/bin/cat', 18:38:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:41 INFO - 'PWD': '/builds/slave/test', 18:38:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:41 INFO - 'SHELL': '/bin/bash', 18:38:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:41 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:41 INFO - 'USER': 'cltbld', 18:38:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:41 INFO - 'XPC_FLAGS': '0x0', 18:38:41 INFO - 'XPC_SERVICE_NAME': '0', 18:38:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 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)) 18:38:41 INFO - Cleaning up... 18:38:41 INFO - Return code: 0 18:38:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38: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', 'jsonschema'] in /builds/slave/test/build 18:38: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 jsonschema 18:38:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:41 INFO - 'HOME': '/Users/cltbld', 18:38:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:41 INFO - 'LOGNAME': 'cltbld', 18:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:41 INFO - 'MOZ_NO_REMOTE': '1', 18:38:41 INFO - 'NO_EM_RESTART': '1', 18:38:41 INFO - 'PAGER': '/bin/cat', 18:38:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:41 INFO - 'PWD': '/builds/slave/test', 18:38:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:41 INFO - 'SHELL': '/bin/bash', 18:38:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:41 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:41 INFO - 'USER': 'cltbld', 18:38:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:41 INFO - 'XPC_FLAGS': '0x0', 18:38:41 INFO - 'XPC_SERVICE_NAME': '0', 18:38:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:41 INFO - Downloading/unpacking jsonschema 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:38:44 INFO - Downloading/unpacking functools32 (from jsonschema) 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:44 INFO - Downloading functools32-3.2.3-2.tar.gz 18:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:38:44 INFO - warning: no files found matching '*.txt' 18:38:44 INFO - no previously-included directories found matching 'build' 18:38:44 INFO - no previously-included directories found matching 'dist' 18:38:44 INFO - no previously-included directories found matching '.git*' 18:38:44 INFO - Installing collected packages: jsonschema, functools32 18:38:44 INFO - Running setup.py install for functools32 18:38:44 INFO - warning: no files found matching '*.txt' 18:38:44 INFO - no previously-included directories found matching 'build' 18:38:44 INFO - no previously-included directories found matching 'dist' 18:38:44 INFO - no previously-included directories found matching '.git*' 18:38:44 INFO - Successfully installed jsonschema functools32 18:38:44 INFO - Cleaning up... 18:38:44 INFO - Return code: 0 18:38:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:44 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 0x1068cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068d2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb93d83ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, '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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 18:38:44 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 18:38:44 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 18:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:44 INFO - 'HOME': '/Users/cltbld', 18:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:44 INFO - 'LOGNAME': 'cltbld', 18:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:44 INFO - 'MOZ_NO_REMOTE': '1', 18:38:44 INFO - 'NO_EM_RESTART': '1', 18:38:44 INFO - 'PAGER': '/bin/cat', 18:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:44 INFO - 'PWD': '/builds/slave/test', 18:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:44 INFO - 'SHELL': '/bin/bash', 18:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:38:44 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:44 INFO - 'USER': 'cltbld', 18:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:44 INFO - 'XPC_FLAGS': '0x0', 18:38:44 INFO - 'XPC_SERVICE_NAME': '0', 18:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:45 INFO - Downloading/unpacking flake8 18:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:38:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:38:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:48 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 18:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:38:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:49 INFO - no previously-included directories found matching 'docs/_build' 18:38:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:38:49 INFO - Running setup.py install for pep8 18:38:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:49 INFO - no previously-included directories found matching 'docs/_build' 18:38:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:38:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:38:49 INFO - Cleaning up... 18:38:49 INFO - Return code: 0 18:38:49 INFO - Running post-action listener: _resource_record_post_action 18:38:49 INFO - Running post-action listener: _start_resource_monitoring 18:38:49 INFO - Starting resource monitoring. 18:38:49 INFO - ##### 18:38:49 INFO - ##### Finished create-virtualenv step (success) 18:38:49 INFO - ##### 18:38:49 INFO - ##### 18:38:49 INFO - ##### Running install step. 18:38:49 INFO - ##### 18:38:49 INFO - Running pre-action listener: _resource_record_pre_action 18:38:49 INFO - Running main action method: install 18:38:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:49 INFO - Reading from file tmpfile_stdout 18:38:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:38:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:38:49 INFO - Reading from file tmpfile_stdout 18:38:49 INFO - Output received: 18:38:49 INFO - Usage: mozinstall [options] installer 18:38:49 INFO - Options: 18:38:49 INFO - -h, --help show this help message and exit 18:38:49 INFO - -d DEST, --destination=DEST 18:38:49 INFO - Directory to install application into. [default: 18:38:49 INFO - "/builds/slave/test"] 18:38:49 INFO - --app=APP Application being installed. [default: firefox] 18:38:49 INFO - mkdir: /builds/slave/test/build/application 18:38:49 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'] 18:38:49 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 18:39:23 INFO - Reading from file tmpfile_stdout 18:39:23 INFO - Output received: 18:39:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:39:23 INFO - Running post-action listener: _resource_record_post_action 18:39:23 INFO - ##### 18:39:23 INFO - ##### Finished install step (success) 18:39:23 INFO - ##### 18:39:23 INFO - ##### 18:39:23 INFO - ##### Running run-tests step. 18:39:23 INFO - ##### 18:39:23 INFO - Running pre-action listener: _resource_record_pre_action 18:39:23 INFO - Running main action method: run_tests 18:39:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:23 INFO - 2016-05-13 18:39:23.762 screenresolution[2202:24336] starting screenresolution argv=screenresolution get 18:39:23 INFO - 2016-05-13 18:39:23.765 screenresolution[2202:24336] Display 0: 1600x1200x32@60 18:39:23 INFO - 2016-05-13 18:39:23.771 screenresolution[2203:24338] starting screenresolution argv=screenresolution list 18:39:23 INFO - Available Modes on Display 0 18:39:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:24 INFO - Intel Iris: 18:39:24 INFO - Chipset Model: Intel Iris 18:39:24 INFO - Type: GPU 18:39:24 INFO - Bus: Built-In 18:39:24 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:24 INFO - Vendor: Intel (0x8086) 18:39:24 INFO - Device ID: 0x0a2e 18:39:24 INFO - Revision ID: 0x0009 18:39:24 INFO - Displays: 18:39:24 INFO - Display: 18:39:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:24 INFO - Display Serial Number: 1600x1200 60 18:39:24 INFO - Main Display: Yes 18:39:24 INFO - Mirror: Off 18:39:24 INFO - Online: Yes 18:39:24 INFO - Rotation: Supported 18:39:24 INFO - Return code: 0 18:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:39:24 INFO - Python 2.7.3 18:39:24 INFO - Return code: 0 18:39:24 INFO - grabbing minidump binary from tooltool 18:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:39:24 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 0x106770d30>, '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 0x106594db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106767b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:39:24 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 18:39:24 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 18:39:24 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 18:39:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:39:24 INFO - Return code: 0 18:39:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:39:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:39:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:39:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:39:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:39:25 INFO - Return code: 0 18:39:25 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 18:39:25 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/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 18:39:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gDRVtMFeIr/Render', 18:39:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:25 INFO - 'HOME': '/Users/cltbld', 18:39:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:25 INFO - 'LOGNAME': 'cltbld', 18:39:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:25 INFO - 'MOZ_NO_REMOTE': '1', 18:39:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:25 INFO - 'NO_EM_RESTART': '1', 18:39:25 INFO - 'PAGER': '/bin/cat', 18:39:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:25 INFO - 'PWD': '/builds/slave/test', 18:39:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:25 INFO - 'SHELL': '/bin/bash', 18:39:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rzMm8xfSE8/Listeners', 18:39:25 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 18:39:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:25 INFO - 'USER': 'cltbld', 18:39:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:25 INFO - 'XPC_FLAGS': '0x0', 18:39:25 INFO - 'XPC_SERVICE_NAME': '0', 18:39:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:25 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 18:39:25 INFO - mozversion application_buildid: 20160513164442 18:39:25 INFO - mozversion application_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 18:39:25 INFO - mozversion application_display_name: Nightly 18:39:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:39:25 INFO - mozversion application_name: Firefox 18:39:25 INFO - mozversion application_remotingname: firefox 18:39:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:39:25 INFO - mozversion application_vendor: Mozilla 18:39:25 INFO - mozversion application_version: 49.0a1 18:39:25 INFO - mozversion platform_buildid: 20160513164442 18:39:25 INFO - mozversion platform_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 18:39:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:39:25 INFO - mozversion platform_version: 49.0a1 18:39:25 INFO - using testdate: 1463189965 18:39:25 INFO - actual date: 1463189965 18:39:25 INFO - starting webserver on 'localhost:54418' 18:39:25 INFO - SUITE-START | Running 2 tests 18:39:25 INFO - TEST-START | dromaeo_css 18:39:25 INFO - operating with platform_type : mac_ 18:39:25 INFO - Initialising browser for dromaeo_css test... 18:39:25 INFO - TEST-INFO | started process 2221 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpglOe5m/profile http://localhost:54418/getInfo.html) 18:39:27 INFO - PROCESS | 2221 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 18:39:27 INFO - PROCESS | 2221 | __metrics Screen width/height:1600/1200 18:39:27 INFO - PROCESS | 2221 | colorDepth:24 18:39:27 INFO - PROCESS | 2221 | Browser inner width/height: 1024/689 18:39:27 INFO - PROCESS | 2221 | __metrics 18:39:27 INFO - PROCESS | 2221 | 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" 18:39:27 ERROR - PROCESS | 2221 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:39:28 INFO - TEST-INFO | 2221: exit 0 18:39:28 INFO - Browser initialized. 18:39:28 INFO - Running cycle 1/1 for dromaeo_css test... 18:39:28 INFO - TEST-INFO | started process 2223 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpglOe5m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:40 INFO - PROCESS | 2223 | RSS: Main: 239063040 18:39:40 INFO - PROCESS | 2223 | 18:40:31 INFO - PROCESS | 2223 | 1463190031447 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] 18:42:42 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54418/tests/dromaeo/cssquery-ext.html) 18:42:44 INFO - PROCESS | 2223 | RSS: Main: 365912064 18:42:44 INFO - PROCESS | 2223 | 18:45:47 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-ext.html (next: http://localhost:54418/tests/dromaeo/cssquery-jquery.html) 18:45:47 INFO - PROCESS | 2223 | RSS: Main: 371400704 18:45:47 INFO - PROCESS | 2223 | 18:48:50 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54418/tests/dromaeo/cssquery-mootools.html) 18:48:50 INFO - PROCESS | 2223 | RSS: Main: 372019200 18:48:50 INFO - PROCESS | 2223 | 18:51:53 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54418/tests/dromaeo/cssquery-prototype.html) 18:51:53 INFO - PROCESS | 2223 | RSS: Main: 366211072 18:51:53 INFO - PROCESS | 2223 | 18:54:57 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54418/tests/dromaeo/cssquery-yui.html) 18:54:57 INFO - PROCESS | 2223 | RSS: Main: 337838080 18:54:57 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | Cycle 1(1): loaded http://localhost:54418/tests/dromaeo/cssquery-yui.html (next: http://localhost:54418/tests/dromaeo/cssquery-dojo.html) 18:58:00 INFO - PROCESS | 2223 | RSS: Main: 422121472 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | __start_tp_report 18:58:00 INFO - PROCESS | 2223 | _x_x_mozilla_page_load 18:58:00 INFO - PROCESS | 2223 | _x_x_mozilla_page_load_details 18:58:00 INFO - PROCESS | 2223 | |i|pagename|runs| 18:58:00 INFO - PROCESS | 2223 | |0;dojo.html;3205.599300087489;3406.1484628842773;3419.880304189354;3427.4516077427616;3482.756207065505;5540.584456165789;5542.501174894259;5592.916106258404;5604.1593760935775;5642.576806739496;4687.648426368028;5526.723663816813;5664.0937450008005;5686.601937864351;5736.569757268211;5021.397432308128;5050.772715227824;5107.872303192413;5116.411612664554;5281.207818827187;5179.637425380225;5212.192110222922;5224.660397074178;5229.973850130763;5284.44513326101;4111.691623128262;4998.750062496897;5053.469385714484;5078.009788091324;5084.949150508484;5098.5411312982005;5112.590992720573;5116.872078198037;5129.794808207667;5141.948580514184;5003.699778013331;5058.114829904752;5070.974645126751;5079.3650793650795;5130.871728206787;5507.339119305669;5587.189857470644;5589.217509548666;5602.60781745278;5647.407022262644;6201.379862013808;6217.720202590849;6221.129041934088;6222.346653601352;6225.501959843202;6064.015692756517;6095.075643002737;6120.785772497986;6220.68896555175;6225.782097626561;5755.6834374108985;5756.42435756421;5764.942350576483;5809.215755872988;5813.128030795372;6361.236651601745;6363.172787537591;6374.916264235091;6380.457661098766;6386.07401926718;5954.166416701665;6002.639841609517;6014.398560144038;6086;6092.147099406086;6212.223472065992;6229.657368844757;6240.843978900473;6241.063840423882;6246.063090536456;6086.6348019119;6132.816015519542;6148.723307451175;6155.138280640714;6160.476359509402;5792.275965504312;6047.0627052806;6068.575199736064;6084.117802918552;6122.989706698345;4768.976155119271;4804.95195048045;4807.086653535817;4817.181079216542;4874.878128046827;4327.545607711208;4364.978175109168;4371.759553224603;4399.824007039747;4405.515393306734;5513.09034009392;5573.860653483614;5579.2746942897975;5626.634268772516;5638.633488823213;6129.570930034944;6170.321264660884;6170.969145154246;6174.9073763893575;6236;5655.349634791969;5663.80176693822;5671.971640141773;5687.51656109227;5717.056685646821;5302.814401495929;5363.007843548956;5372.623916325898;5374.811881584125;5375.3346545669765;6246.406591373812;6273.431021555346;6276.340984196594;6314.557980941474;6334.936650633619;6289.96855015722;6298.905516417166;6299.181106456132;6302.432781049728;6308.810735677937;5962.582619216613;6285.43431091204;6298.905516417349;6336.17629708135;6348.111264423077;5980.9401905980385;6102.908456373069;6104.38956104386;6111.1663517948755;6172.969135154295;6237.906431403442;6240.843978900564;6259.092431597484;6262.780802671885;6265.592736472115;5845.707714614167;5994.3705910878725;6007.579469437273;6015.87968240624;6031.638101713911;5931.822045338647;5946.524278057832;6005.309389420273;6032.6983650818165;6046.183765191819;4879.926801097916;5709.0865461525955;5844.970775146097;5881.353051164283;5953.047512398169;5766.94233057664;5897.616654917417;6074.7266373012435;6087.57386982907;6089.878202436015;5813;5869;5872.236609240771;5906.911396328973;5913.438223368773 18:58:00 INFO - PROCESS | 2223 | |1;ext.html;456.49129626584147;473.81994841960073;475.51259958542494;494.99021995129925;512.4721536817075;33270.833645831764;42575.57424425755;43888.341674874864;44089.559104409025;46053;45377.54622453774;45610.31584526235;45720;45825.854353641094;45923.54076459234;8745.693900713477;9717.562709678064;9910.752231194241;9910.801783964316;9933.701988940344;8727.301815854717;11566.942165289203;11842.881571184264;11857.584984525543;12008.579699710512;10039.447830369327;11832.40837958103;11897.7620447591;11908.523659053628;11933.820992685116;8786.297096232285;11690.240134391322;11825.40872956353;11909.928106470416;11923.940380298043;11530.365829879438;11782.234154779911;11794.35131067791;11797.646070617895;11798.351090690008;40841.59158408437;41371.172576548604;41376.793116034525;41420.37869431961;41467.37798933018;3263.1352691536763;3307.8015319080932;3382.441897086978;3517.188632954881;3650.1787097903025;28836.279093022633;29279.414411711645;29313.853430732925;29790.70209297901;29896.252593685116;2635.0645520840208;2696.0833316672424;2723.1422102037795;2731;2774.3480282133683;452024;453374.73312633554;453401.7329913362;454357;454585;61375;61580.07629885555;61800.69099654473;61837.38162618406;61932.76134477375;36561.257905657185;37390.62609373871;37477.62522374796;38603.80698096492;38609.22781544353;16631.41790037367;16706.83293167077;16902.070386128635;16902.239399227063;16936.915315423343;10680.679579612468;11641.88358116425;11864.991475724488;12578.622641320775;12761.489540418279;19787.703184452246;19846.007699614962;19914.701279480818;19948.800511994985;19987.700184497244;8327.750167494985;8342.916570834213;8351.624176912053;8383.329333653293;8448.957755211184;959.2901253072815;1036.9014943580348;1042.5621239079605;1098.9450527473755;1102.8841971593029;30304.84847575748;30336.393272134435;30641.69358306433;30898.918537851067;30994.380112397626;1103.8178700514486;1143.685486491205;1182.8876256755595;1210.0984766349127;1211.9456073216359;15707.921460392625;15790.9210453947;15820.60448488797;15833.920830395775;16103.919480402523;681705;688762;689368;689498;691208;47669.76165119152;60866.69566652139;61199.776004480555;61469.38530614637;61526;1328.1566205459603;1373.3476598615578;1429.8570142985618;1487.9925600372146;1512.9470468533548;29753.553696694133;30735.538966915963;30800;31079.84460077685;31646;51509;52208;52870.942581148935;52882.471175287756;52927.73536132295;551213;551635;551955;553361;554005;23781.167659131846;44642;44802;45557.44425557418;46474.535254647024;46933.765331174494;47164;48435.03129937311;48874.75562622164;50597.74701126618;31308.373832523677;37473.06317342088;38427.580828205115;41236.58763412327;41356.58643413648;32775;39349.80325098356;39517.209655807295;39978.80010599928;40272.19455610813;38865.805670971466;39319.606803931594;39449.80275098606;39533.908877689195;40486;33112.834435827666;34283.4857477143;34361.65638343684;34488;34646;24935.501289973738;25311.62032569476;25553.87223063947;25879.870600646875;26005.219843404728 18:58:00 INFO - PROCESS | 2223 | |2;jquery.html;392.4348937529957;397.3503322068417;399.6263493633451;401.7288330376995;423.1220218047552;9529;20396.796032039674;20995.475113122142;21564.56870862582;21597.4600634984;16599;16735.748963765553;20486.487837804063;20976.68534971973;21071.68392474114;5907.291125064999;6074.453299203071;6097.054956481752;6122.295935967354;6132.601380910249;5974.283086029661;6069.514438844903;6107.725152368132;6121.234845644294;6131.632102073868;5824.126381042856;6040.852238074752;6057.182280392157;6068.241469816273;6077.574569780118;5895.941040589625;5901.822945311647;6022.45797878193;6041.214642096521;6049.183360246355;5276.419593844674;5286.127788914824;5287.154055351125;5287.682739035632;5289;23926;26070.608940865903;26090.86954565215;26506.807193676137;26900.86549567259;2241.3388050524973;2318.652202169671;2514.798816094708;2672.3452754075183;2736.0423851651963;14676.926615366961;14954.177520236492;15122.546323610199;15378.538643840702;15432.92283538575;1740.8259174082616;1831.8534517238588;1839.816018398163;1846.8153184681423;1853.4161739052156;110186;112036;112285.94713237924;112712.4364378181;112853.43573282163;8518.190771876662;9905.20758339331;10011.649592264235;10408.47957602117;11459.968602825786;12069.396530173457;14430.422783088768;14447.927760361341;14477.276136193359;14493.637659058608;7609;7620.771376858703;8688.435251708619;8835.55822208887;8870.955645221733;6761.594304341756;8724.956375218084;8742.038375778659;8868.600912958933;8945.821083578292;5854.853628659318;6710.476999575165;6740.83147921296;6854.931450685529;6886.8622627548175;1869.26164165154;1896.497428181533;1903.2196799312492;1907.9236830526625;1933.6519426503082;961.5288508630721;976.6191185437685;990.6730778842965;1009.9646512372032;1020.3418794877263;21061.578768424544;21645.242416515346;21673;21703.57410647338;21975.2308669199;817.979550511242;923.1599244687302;943.0381011368365;1001.799640071974;1022.8772547294373;10454.738631534272;11616.825747613793;11752.001079908303;11814.763704725858;11821;579434;584540;588234;613691;626066;115142;115570.04358002264;117567;117872.41063794798;122838.38580807217;1034.5654824973528;1037.901399367059;1058.7882423515327;1060.824963880951;1061.490484567412;19053.856768593374;19341.12964916554;19448.611027779647;19454.416367509;19589.51026224356;28948.39342678125;49451.867248944014;52511.203832483145;52728.736356317975;53344;66221.66889165524;67030.66484667546;67220.655586887;67698.30754231184;69474.30525694678;7882.684692612232;7950.443468957349;7996.760097197093;8007.879881801661;8268.09051004377;12331.9383403086;12411;12424.316662583284;12642.683932901384;12816.807747883975;10703.357798532114;10857.67426977192;11104.333739975627;11229.943850280697;11234.269772464764;9485.383450075724;9543.809123817346;10650.680479585624;10697.947330734149;11114;8696.78258043554;10788.568457261621;11126.276792008493;11166.72083197894;11403.030742386658;7741.380689544711;7987.680492780456;7998.680052798056;8001.719939802079;8192.631331589973;9183.976849282411;10889.618863339745;11032.172587055842;11100.000999910048;11311.151663625096 18:58:00 INFO - PROCESS | 2223 | |3;mootools.html;1250.1873782041678;1607.2606600963559;1628.820829708732;1885.8208470195336;2000.2599038355802;8191.139930307314;9023.278137748981;9230.81538369233;9232.169104780569;9403;8993.550322483883;9255.490947997874;9256.213221876149;9259.305552083588;9267.629294828215;2155.687425323335;2231.6317807561813;2402.9759702403016;2429.4533729910804;2465.950680986379;2469.086438017936;2512.309114993373;2512.786413154884;2516.547021536123;2517.1064272183385;2162.826973842089;2411.397150712322;2471.1845091119885;2475.653408522808;2479.417336925821;1971.2509246486386;2144.1209104267177;2384.4873352229265;2448.8897999590063;2451.325885381534;2439.7560243975636;2450.215930902112;2473.208573256559;2473.9381515462255;2477.5168842075805;7804.570748608824;7961.323287520568;9276.072392760738;9363.953180234123;9416.62333506659;5477.561795056386;5668.603197776158;5677.233573467571;5679.091345384719;5679.886402271932;3595.5505561804775;3604.2431089471243;3658.1037645776687;3696.6488183622514;3705.3136476054915;997.3666721631744;1015.8222311095602;1017.2726500552141;1019.4494972714725;1020.5917632946806;12583.622491325275;12650.620481385573;12654.304013279267;12659.746805064031;12663.493460261578;4953.90092198154;4979.153543897546;5084.3644544431945;5093.083245015932;5101.464346243665;10644.361338319726;10690.51892664832;10694.73263168427;10699.144068474503;10702.571897124028;5706.885862282814;5713.74288157034;5713.914291285634;5714.457126572969;5715.199872017921;4450.310201918708;4621.930671039937;4738.099761045391;4743.596794272526;4793.065352256276;4138.255114079434;4177.603127702924;4185.288500954845;4228.619424251832;4264.296391095497;1321.8612045735251;1324;1333.1867560787912;1339.163023110556;1353.221897408974;743.4387037786437;937.5078084005952;938.6292414496236;953.561361773578;1007.4207330784828;5690.772369105272;6077.24034495688;6078.6048906820915;6171;6292.46514046302;442.7609091090775;445.0475981399807;456.39071839094515;464.0440692174132;465.1812809455315;4049.919001619951;4097.282975479279;4101.5488296287385;4166.708330416842;4178.937315940263;11048.944755276172;11332.093432525378;11485.0811935045;11492.540298388138;11500.654980350602;11262.662120136409;11384.544618215179;11403.600873969372;11433.028192603555;11498;6770.83072923171;7267.382272506896;7918.049834020069;8574.3997920145;8671.349648776368;6184.6598437086395;6192.907106393498;6239.844003899939;6300.054991751274;6320.778772742932;10232.130268927225;10528.052475277262;10661.413622250851;10708.732281692708;10717.303375280582;10331.845022324822;10752.13982881352;10874.07570356497;10890.836637450286;10906.727331816768;6038.51691864641;7490.662920168715;7754.689812407666;7763.573003484863;7956.602169891598;8868.556572171494;8891.688790892287;8968.148025937526;8968.865467018131;8981.236594889508;7737.458377913492;7887.290143887078;7940.205979402013;8036.397270204875;8062.798430039296;7602.923970760222;7768.300852923264;7768.689252430066;7861.567613781297;7865.331446827084;7365.2634736525915;7398.630068496661;7428.997085393403;7500.849983000418;7523.962380188064;5036.823711170092;5048.899022019613;5132.948670513397;5135.6918584885025;5156.845294641167;5082.237664350377;5097.439281678938;5157.845264641916;5292.285541451854;5415.783368665367 18:58:00 INFO - PROCESS | 2223 | |4;prototype.html;307.80146805310574;315.4952076677317;320.257003751297;321.530565374553;326.1910462054107;4721.433427988646;5162.483751624835;5227.947720522794;5280.841574752759;5299;5207.218917162428;5209.505097015787;5209.687418754877;5230.084735171339;5290.7354632268325;3235.352929414115;3788.204477059814;3847.211046863208;3870.0905287257474;3892.5523564789987;3058.571799948009;3319.767616266862;3516.771409858351;3738.36447803874;3935.48838650975;3076.8461576921177;3956.5054368203973;3963.4054891766323;3974;4042.312806822847;3307.7684562080663;3677.0807298175187;3736.831842567091;3746.7564608300645;3873.767573945544;3488.778927375424;3493.94759078614;3497.632748561415;3539.610642829287;3548.964510354889;5000.699958002531;5014.699118052892;5045.974770126162;5076.822311219126;5091.389033316026;3696.9630303696886;4523.796429160696;4548.727076375428;4573.588377046049;4583.8166473341025;4951.564046426532;5461.781528738846;5509.752061157257;5514.6139770216105;5517.751701173457;3411.829408529564;3619.239959608485;3779.6976241900857;3784.524035626108;3816.9618303816883;5319.654222475527;5544.115000899714;5725.45022086305;5751.971240143773;5797.333306669702;3261.2988207535495;3816.3321418751607;3826.7129965252716;3827.234553089393;3843.807809609564;3467.930641387158;3677.191017976041;3910.673149476057;3911.9804400977814;3919.568847426781;4312.460942382202;4331.155424692155;4518.593326600621;4535.845473400109;4549.406838102316;4596.8161273548685;4776.307435421844;5529.944700553024;5545.197810711535;5548.833534993957;1272.10952333367;1295.3484307508002;1321.9735605288033;1325.7282257137263;1340.9195448273135;728.9976282611409;786.7207141464812;792.1603100713147;809.3161278719473;815.3069890592949;4553.222534254102;4837.467878533358;4887.462379138292;5016.423111342169;5213.661112027706;4852.694969889274;5238.847453560211;5323.920141197885;5338.252644629755;5649.4633009864;4618.621409973866;5154.536191636802;5165.66423182492;5181.378234611795;5209.947900521022;2510.2888880009546;2654.761071503574;2949.5280755079084;2974.301039255773;2987.342784587387;5821.7380217890295;5831.591788574759;5836.445537673914;5843.5909486336395;5866.472017518444;5358.32083958021;5733.885322293614;5761.308642962787;5766.336871259775;5785.71071446426;5189.922151167735;5377.059014672417;5822.371214884177;5853.414658534112;5903.35063143054;4965.510346895989;5599.720013999202;5633.746481408264;5636.802711905064;5642.633228840113;4986.902881366093;5158;5537.36320323168;5544.251526043932;5612.775488980558;5313.707746074003;5489.286392769074;5564.91484160601;5875.266796295018;5879.559033072622;3250.2361944942445;3487.8081705505424;3538.3731675033296;3757.3236817373136;3766.794625719853;5065.164247899055;5126.487351264844;5259.159294246983;5606.159076138449;5619.578531610064;4685.531446855287;5397.3523177218985;5405.756740946746;5413.751748950598;5486.451354864482;4357.975875669152;4502.977485112553;5314.256004159498;5434.456554344534;5440.483154100354;4989.426215985208;5311.628186026941;5358.866028349166;5375.274337964324;5382.569394448356;4936.728479933495;5186.118359878905;5304.374334704346;5375.392934426878;5502.862428439321;4545;5323.30796996388;5323.361196656426;5356.866078347916;5361.731913404392 18:58:00 INFO - PROCESS | 2223 | |5;yui.html;637.4135795068537;662.2648859765659;723.2731105239234;735.889616557516;747.7210891108774;15349.769753453707;15450.69098618027;15661.903666743332;15849;16194.109323987175;14556.199409032499;14702.63243418915;14779.408823647069;14785.260736963162;14788.18664973426;749.153456594048;770.7610640701372;912.9911447850129;918.545320066568;934.145257089762;941.8210539997413;945.3240932733095;948.0945696859483;949.0841338108723;952.4808979106404;999.5801763259449;999.860019597257;1001.4241810958691;1001.6794625719773;1002.478711070243;771.8726410142319;796.9322607578363;797.0674311056049;801.935845132388;837.660747397305;719.6905330707793;743.7434085240583;748.8202831320444;751.6579956119953;752.236479972828;15135.243237838064;15180.392784288615;15204.543863684106;15219.315130819028;15220.695586088215;12188.695282617917;12217.328046957413;12311.138220324581;12538.561150359694;12580.874191258154;10668.466576671135;10814.026737593655;11044.282121662067;11084.525123758052;11305;4008.6392224699916;4020.195960807821;4032.334664780308;4032.5564187939303;4082.326416141333;81411.59294203551;82047;82327.58836205781;82514.76227856588;83812;9444.669436569688;9457.243420526342;9679;9736.41581505112;9953.850692239623;18269;21669.67495487569;21779.23772667949;21811.345659630235;21822;7458.142313633893;7493.4005279577505;7519.43604229674;7879.724209652634;7881.881771773428;3728.7203459740626;4026.053877338823;4056;4135.855245066408;4196.433481480022;3336.215989242526;3438.862445502202;3441.5181874537584;3441.6214216436174;3443.345764304774;623.2458724942779;630.7413960276357;632.0961025733245;638.578538164809;638.6678926957956;527.3776943207052;530.1226470191839;539.5953035223582;551.7489542258343;552.7457369609943;7911;7931.365490760725;7980.960095199487;8001.759947201594;8239.588020598947;552.1083450227853;560.564953718482;577.9942200578024;578.8900108979365;604.0516389268806;3150.637676667167;3564.1624218308675;3775.093977445448;3911.2568611963634;3977.54258260298;93840;94038.52980735053;94385.5280723592;94685.5265723667;96459;76701.61649191719;84557;84859.57570212109;85441.14558854332;85797.57101214454;71970;72474.27525724676;72774.63612681902;73053.634731826;73575;6841.008053832266;7548.879066618962;8185.263326300424;8257.545834979133;8423.4945903246;44215.55784442114;45406.545934540234;46802.53197467982;46877;46932.53067469282;55884.44115558793;55997.44002559922;61813;66422.66788666026;66617.00074498789;10907.727306817076;11160.269717252831;11183.664490065312;11246.437678115897;11547.653570392902;12124.515019399125;12231.755364892475;12315.876841231473;12325.568605098779;13071.477140914638;11457.885421145682;11510.59712910044;11696.239744416585;11750.529978800752;11848.953819487433;11372.772544549229;11492;11574.942125289657;11922.225055371373;12074.275543467069;11101.555937762721;11251.943740281245;11299.548018079186;11446.495859058894;11975.94012029969;7990.400719945911;9442.669506567512;9538.904610953801;9641.517924103908;10085.69742907714;8472.957635211784;8695.565221738761;8979.416337938013;9634.277429192698;9652.613895444103 18:58:00 INFO - PROCESS | 2223 | __end_tp_report 18:58:00 INFO - PROCESS | 2223 | __start_cc_report 18:58:00 INFO - PROCESS | 2223 | _x_x_mozilla_cycle_collect,1344 18:58:00 INFO - PROCESS | 2223 | __end_cc_report 18:58:00 INFO - PROCESS | 2223 | __startTimestamp1463191080905__endTimestamp 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | ------- Summary: start ------- 18:58:00 INFO - PROCESS | 2223 | Number of tests: 6 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#0] dojo.html Cycles:165 Average:5666.96 Median:5869.00 stddev:658.05 (11.2%) stddev-sans-first:631.11 18:58:00 INFO - PROCESS | 2223 | Values: 3205.6 3406.1 3419.9 3427.5 3482.8 5540.6 5542.5 5592.9 5604.2 5642.6 4687.6 5526.7 5664.1 5686.6 5736.6 5021.4 5050.8 5107.9 5116.4 5281.2 5179.6 5212.2 5224.7 5230.0 5284.4 4111.7 4998.8 5053.5 5078.0 5084.9 5098.5 5112.6 5116.9 5129.8 5141.9 5003.7 5058.1 5071.0 5079.4 5130.9 5507.3 5587.2 5589.2 5602.6 5647.4 6201.4 6217.7 6221.1 6222.3 6225.5 6064.0 6095.1 6120.8 6220.7 6225.8 5755.7 5756.4 5764.9 5809.2 5813.1 6361.2 6363.2 6374.9 6380.5 6386.1 5954.2 6002.6 6014.4 6086.0 6092.1 6212.2 6229.7 6240.8 6241.1 6246.1 6086.6 6132.8 6148.7 6155.1 6160.5 5792.3 6047.1 6068.6 6084.1 6123.0 4769.0 4805.0 4807.1 4817.2 4874.9 4327.5 4365.0 4371.8 4399.8 4405.5 5513.1 5573.9 5579.3 5626.6 5638.6 6129.6 6170.3 6171.0 6174.9 6236.0 5655.3 5663.8 5672.0 5687.5 5717.1 5302.8 5363.0 5372.6 5374.8 5375.3 6246.4 6273.4 6276.3 6314.6 6334.9 6290.0 6298.9 6299.2 6302.4 6308.8 5962.6 6285.4 6298.9 6336.2 6348.1 5980.9 6102.9 6104.4 6111.2 6173.0 6237.9 6240.8 6259.1 6262.8 6265.6 5845.7 5994.4 6007.6 6015.9 6031.6 5931.8 5946.5 6005.3 6032.7 6046.2 4879.9 5709.1 5845.0 5881.4 5953.0 5766.9 5897.6 6074.7 6087.6 6089.9 5813.0 5869.0 5872.2 5906.9 5913.4 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#1] ext.html Cycles:180 Average:70218.09 Median:20105.72 stddev:153076.02 (761.4%) stddev-sans-first:153415.83 18:58:00 INFO - PROCESS | 2223 | Values: 456.5 473.8 475.5 495.0 512.5 33270.8 42575.6 43888.3 44089.6 46053.0 45377.5 45610.3 45720.0 45825.9 45923.5 8745.7 9717.6 9910.8 9910.8 9933.7 8727.3 11566.9 11842.9 11857.6 12008.6 10039.4 11832.4 11897.8 11908.5 11933.8 8786.3 11690.2 11825.4 11909.9 11923.9 11530.4 11782.2 11794.4 11797.6 11798.4 40841.6 41371.2 41376.8 41420.4 41467.4 3263.1 3307.8 3382.4 3517.2 3650.2 28836.3 29279.4 29313.9 29790.7 29896.3 2635.1 2696.1 2723.1 2731.0 2774.3 452024.0 453374.7 453401.7 454357.0 454585.0 61375.0 61580.1 61800.7 61837.4 61932.8 36561.3 37390.6 37477.6 38603.8 38609.2 16631.4 16706.8 16902.1 16902.2 16936.9 10680.7 11641.9 11865.0 12578.6 12761.5 19787.7 19846.0 19914.7 19948.8 19987.7 8327.8 8342.9 8351.6 8383.3 8449.0 959.3 1036.9 1042.6 1098.9 1102.9 30304.8 30336.4 30641.7 30898.9 30994.4 1103.8 1143.7 1182.9 1210.1 1211.9 15707.9 15790.9 15820.6 15833.9 16103.9 681705.0 688762.0 689368.0 689498.0 691208.0 47669.8 60866.7 61199.8 61469.4 61526.0 1328.2 1373.3 1429.9 1488.0 1512.9 29753.6 30735.5 30800.0 31079.8 31646.0 51509.0 52208.0 52870.9 52882.5 52927.7 551213.0 551635.0 551955.0 553361.0 554005.0 23781.2 44642.0 44802.0 45557.4 46474.5 46933.8 47164.0 48435.0 48874.8 50597.7 31308.4 37473.1 38427.6 41236.6 41356.6 32775.0 39349.8 39517.2 39978.8 40272.2 38865.8 39319.6 39449.8 39533.9 40486.0 33112.8 34283.5 34361.7 34488.0 34646.0 24935.5 25311.6 25553.9 25879.9 26005.2 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#2] jquery.html Cycles:180 Average:34228.75 Median:14292.70 stddev:99382.58 (695.3%) stddev-sans-first:99628.90 18:58:00 INFO - PROCESS | 2223 | Values: 392.4 397.4 399.6 401.7 423.1 9529.0 20396.8 20995.5 21564.6 21597.5 16599.0 16735.7 20486.5 20976.7 21071.7 5907.3 6074.5 6097.1 6122.3 6132.6 5974.3 6069.5 6107.7 6121.2 6131.6 5824.1 6040.9 6057.2 6068.2 6077.6 5895.9 5901.8 6022.5 6041.2 6049.2 5276.4 5286.1 5287.2 5287.7 5289.0 23926.0 26070.6 26090.9 26506.8 26900.9 2241.3 2318.7 2514.8 2672.3 2736.0 14676.9 14954.2 15122.5 15378.5 15432.9 1740.8 1831.9 1839.8 1846.8 1853.4 110186.0 112036.0 112285.9 112712.4 112853.4 8518.2 9905.2 10011.6 10408.5 11460.0 12069.4 14430.4 14447.9 14477.3 14493.6 7609.0 7620.8 8688.4 8835.6 8871.0 6761.6 8725.0 8742.0 8868.6 8945.8 5854.9 6710.5 6740.8 6854.9 6886.9 1869.3 1896.5 1903.2 1907.9 1933.7 961.5 976.6 990.7 1010.0 1020.3 21061.6 21645.2 21673.0 21703.6 21975.2 818.0 923.2 943.0 1001.8 1022.9 10454.7 11616.8 11752.0 11814.8 11821.0 579434.0 584540.0 588234.0 613691.0 626066.0 115142.0 115570.0 117567.0 117872.4 122838.4 1034.6 1037.9 1058.8 1060.8 1061.5 19053.9 19341.1 19448.6 19454.4 19589.5 28948.4 49451.9 52511.2 52728.7 53344.0 66221.7 67030.7 67220.7 67698.3 69474.3 7882.7 7950.4 7996.8 8007.9 8268.1 12331.9 12411.0 12424.3 12642.7 12816.8 10703.4 10857.7 11104.3 11229.9 11234.3 9485.4 9543.8 10650.7 10697.9 11114.0 8696.8 10788.6 11126.3 11166.7 11403.0 7741.4 7987.7 7998.7 8001.7 8192.6 9184.0 10889.6 11032.2 11100.0 11311.2 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#3] mootools.html Cycles:180 Average:5965.62 Median:464.61 stddev:3437.86 (739.9%) stddev-sans-first:3429.24 18:58:00 INFO - PROCESS | 2223 | Values: 1250.2 1607.3 1628.8 1885.8 2000.3 8191.1 9023.3 9230.8 9232.2 9403.0 8993.6 9255.5 9256.2 9259.3 9267.6 2155.7 2231.6 2403.0 2429.5 2466.0 2469.1 2512.3 2512.8 2516.5 2517.1 2162.8 2411.4 2471.2 2475.7 2479.4 1971.3 2144.1 2384.5 2448.9 2451.3 2439.8 2450.2 2473.2 2473.9 2477.5 7804.6 7961.3 9276.1 9364.0 9416.6 5477.6 5668.6 5677.2 5679.1 5679.9 3595.6 3604.2 3658.1 3696.6 3705.3 997.4 1015.8 1017.3 1019.4 1020.6 12583.6 12650.6 12654.3 12659.7 12663.5 4953.9 4979.2 5084.4 5093.1 5101.5 10644.4 10690.5 10694.7 10699.1 10702.6 5706.9 5713.7 5713.9 5714.5 5715.2 4450.3 4621.9 4738.1 4743.6 4793.1 4138.3 4177.6 4185.3 4228.6 4264.3 1321.9 1324.0 1333.2 1339.2 1353.2 743.4 937.5 938.6 953.6 1007.4 5690.8 6077.2 6078.6 6171.0 6292.5 442.8 445.0 456.4 464.0 465.2 4049.9 4097.3 4101.5 4166.7 4178.9 11048.9 11332.1 11485.1 11492.5 11500.7 11262.7 11384.5 11403.6 11433.0 11498.0 6770.8 7267.4 7918.0 8574.4 8671.3 6184.7 6192.9 6239.8 6300.1 6320.8 10232.1 10528.1 10661.4 10708.7 10717.3 10331.8 10752.1 10874.1 10890.8 10906.7 6038.5 7490.7 7754.7 7763.6 7956.6 8868.6 8891.7 8968.1 8968.9 8981.2 7737.5 7887.3 7940.2 8036.4 8062.8 7602.9 7768.3 7768.7 7861.6 7865.3 7365.3 7398.6 7429.0 7500.8 7524.0 5036.8 5048.9 5132.9 5135.7 5156.8 5082.2 5097.4 5157.8 5292.3 5415.8 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#4] prototype.html Cycles:180 Average:4413.75 Median:5055.57 stddev:1377.76 (27.3%) stddev-sans-first:1346.71 18:58:00 INFO - PROCESS | 2223 | Values: 307.8 315.5 320.3 321.5 326.2 4721.4 5162.5 5227.9 5280.8 5299.0 5207.2 5209.5 5209.7 5230.1 5290.7 3235.4 3788.2 3847.2 3870.1 3892.6 3058.6 3319.8 3516.8 3738.4 3935.5 3076.8 3956.5 3963.4 3974.0 4042.3 3307.8 3677.1 3736.8 3746.8 3873.8 3488.8 3493.9 3497.6 3539.6 3549.0 5000.7 5014.7 5046.0 5076.8 5091.4 3697.0 4523.8 4548.7 4573.6 4583.8 4951.6 5461.8 5509.8 5514.6 5517.8 3411.8 3619.2 3779.7 3784.5 3817.0 5319.7 5544.1 5725.5 5752.0 5797.3 3261.3 3816.3 3826.7 3827.2 3843.8 3467.9 3677.2 3910.7 3912.0 3919.6 4312.5 4331.2 4518.6 4535.8 4549.4 4596.8 4776.3 5529.9 5545.2 5548.8 1272.1 1295.3 1322.0 1325.7 1340.9 729.0 786.7 792.2 809.3 815.3 4553.2 4837.5 4887.5 5016.4 5213.7 4852.7 5238.8 5323.9 5338.3 5649.5 4618.6 5154.5 5165.7 5181.4 5209.9 2510.3 2654.8 2949.5 2974.3 2987.3 5821.7 5831.6 5836.4 5843.6 5866.5 5358.3 5733.9 5761.3 5766.3 5785.7 5189.9 5377.1 5822.4 5853.4 5903.4 4965.5 5599.7 5633.7 5636.8 5642.6 4986.9 5158.0 5537.4 5544.3 5612.8 5313.7 5489.3 5564.9 5875.3 5879.6 3250.2 3487.8 3538.4 3757.3 3766.8 5065.2 5126.5 5259.2 5606.2 5619.6 4685.5 5397.4 5405.8 5413.8 5486.5 4358.0 4503.0 5314.3 5434.5 5440.5 4989.4 5311.6 5358.9 5375.3 5382.6 4936.7 5186.1 5304.4 5375.4 5502.9 4545.0 5323.3 5323.4 5356.9 5361.7 18:58:00 INFO - PROCESS | 2223 | 18:58:00 INFO - PROCESS | 2223 | [#5] yui.html Cycles:180 Average:18408.44 Median:55940.94 stddev:26080.36 (46.6%) stddev-sans-first:26119.39 18:58:00 INFO - PROCESS | 2223 | Values: 637.4 662.3 723.3 735.9 747.7 15349.8 15450.7 15661.9 15849.0 16194.1 14556.2 14702.6 14779.4 14785.3 14788.2 749.2 770.8 913.0 918.5 934.1 941.8 945.3 948.1 949.1 952.5 999.6 999.9 1001.4 1001.7 1002.5 771.9 796.9 797.1 801.9 837.7 719.7 743.7 748.8 751.7 752.2 15135.2 15180.4 15204.5 15219.3 15220.7 12188.7 12217.3 12311.1 12538.6 12580.9 10668.5 10814.0 11044.3 11084.5 11305.0 4008.6 4020.2 4032.3 4032.6 4082.3 81411.6 82047.0 82327.6 82514.8 83812.0 9444.7 9457.2 9679.0 9736.4 9953.9 18269.0 21669.7 21779.2 21811.3 21822.0 7458.1 7493.4 7519.4 7879.7 7881.9 3728.7 4026.1 4056.0 4135.9 4196.4 3336.2 3438.9 3441.5 3441.6 3443.3 623.2 630.7 632.1 638.6 638.7 527.4 530.1 539.6 551.7 552.7 7911.0 7931.4 7981.0 8001.8 8239.6 552.1 560.6 578.0 578.9 604.1 3150.6 3564.2 3775.1 3911.3 3977.5 93840.0 94038.5 94385.5 94685.5 96459.0 76701.6 84557.0 84859.6 85441.1 85797.6 71970.0 72474.3 72774.6 73053.6 73575.0 6841.0 7548.9 8185.3 8257.5 8423.5 44215.6 45406.5 46802.5 46877.0 46932.5 55884.4 55997.4 61813.0 66422.7 66617.0 10907.7 11160.3 11183.7 11246.4 11547.7 12124.5 12231.8 12315.9 12325.6 13071.5 11457.9 11510.6 11696.2 11750.5 11849.0 11372.8 11492.0 11574.9 11922.2 12074.3 11101.6 11251.9 11299.5 11446.5 11975.9 7990.4 9442.7 9538.9 9641.5 10085.7 8473.0 8695.6 8979.4 9634.3 9652.6 18:58:00 INFO - PROCESS | 2223 | -------- Summary: end -------- 18:58:00 INFO - PROCESS | 2223 | 18:58:01 INFO - TEST-INFO | 2223: exit 0 18:58:01 INFO - TEST-OK | dromaeo_css | took 1116067ms 18:58:01 INFO - TEST-START | kraken 18:58:01 INFO - operating with platform_type : mac_ 18:58:01 INFO - Initialising browser for kraken test... 18:58:01 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpp2F0Nk/profile http://localhost:54418/getInfo.html) 18:58:03 INFO - PROCESS | 2227 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 18:58:03 INFO - PROCESS | 2227 | __metrics Screen width/height:1600/1200 18:58:03 INFO - PROCESS | 2227 | colorDepth:24 18:58:03 INFO - PROCESS | 2227 | Browser inner width/height: 1024/689 18:58:03 INFO - PROCESS | 2227 | __metrics 18:58:03 INFO - PROCESS | 2227 | 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" 18:58:03 ERROR - PROCESS | 2227 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:58:04 INFO - TEST-INFO | 2227: exit 0 18:58:04 INFO - Browser initialized. 18:58:04 INFO - Running cycle 1/1 for kraken test... 18:58:04 INFO - TEST-INFO | started process 2229 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpp2F0Nk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:16 INFO - PROCESS | 2229 | RSS: Main: 239497216 18:58:16 INFO - PROCESS | 2229 | 18:58:19 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54418/tests/kraken/driver.html?testName=audio-beat-detection) 18:58:19 INFO - PROCESS | 2229 | RSS: Main: 287264768 18:58:19 INFO - PROCESS | 2229 | 18:58:22 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54418/tests/kraken/driver.html?testName=audio-dft) 18:58:23 INFO - PROCESS | 2229 | RSS: Main: 296169472 18:58:23 INFO - PROCESS | 2229 | 18:58:27 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54418/tests/kraken/driver.html?testName=audio-fft) 18:58:27 INFO - PROCESS | 2229 | RSS: Main: 385679360 18:58:27 INFO - PROCESS | 2229 | 18:58:29 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54418/tests/kraken/driver.html?testName=audio-oscillator) 18:58:30 INFO - PROCESS | 2229 | RSS: Main: 287330304 18:58:30 INFO - PROCESS | 2229 | 18:58:32 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54418/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:58:33 INFO - PROCESS | 2229 | RSS: Main: 291217408 18:58:33 INFO - PROCESS | 2229 | 18:58:36 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54418/tests/kraken/driver.html?testName=imaging-darkroom) 18:58:37 INFO - PROCESS | 2229 | RSS: Main: 329015296 18:58:37 INFO - PROCESS | 2229 | 18:58:40 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54418/tests/kraken/driver.html?testName=imaging-desaturate) 18:58:40 INFO - PROCESS | 2229 | RSS: Main: 328482816 18:58:40 INFO - PROCESS | 2229 | 18:58:43 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54418/tests/kraken/driver.html?testName=json-parse-financial) 18:58:44 INFO - PROCESS | 2229 | RSS: Main: 328417280 18:58:44 INFO - PROCESS | 2229 | 18:58:46 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54418/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:58:46 INFO - PROCESS | 2229 | RSS: Main: 316370944 18:58:46 INFO - PROCESS | 2229 | 18:58:48 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:58:49 INFO - PROCESS | 2229 | RSS: Main: 323006464 18:58:49 INFO - PROCESS | 2229 | 18:58:51 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:58:52 INFO - PROCESS | 2229 | RSS: Main: 325406720 18:58:52 INFO - PROCESS | 2229 | 18:58:55 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:58:55 INFO - PROCESS | 2229 | RSS: Main: 329613312 18:58:55 INFO - PROCESS | 2229 | 18:58:58 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:58:59 INFO - PROCESS | 2229 | RSS: Main: 327315456 18:58:59 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:54418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54418/tests/kraken/driver.html?testName=ai-astar) 18:59:01 INFO - PROCESS | 2229 | RSS: Main: 374104064 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | __start_tp_report 18:59:01 INFO - PROCESS | 2229 | _x_x_mozilla_page_load 18:59:01 INFO - PROCESS | 2229 | _x_x_mozilla_page_load_details 18:59:01 INFO - PROCESS | 2229 | |i|pagename|runs| 18:59:01 INFO - PROCESS | 2229 | |0;ai-astar;112;104;112;111;113;113;113;113;113;113 18:59:01 INFO - PROCESS | 2229 | |1;audio-beat-detection;154;116;159;241;113;144;142;99;188;141 18:59:01 INFO - PROCESS | 2229 | |2;audio-dft;186;184;185;184;187;186;185;187;183;182 18:59:01 INFO - PROCESS | 2229 | |3;audio-fft;81;74;56;125;57;76;80;81;115;55 18:59:01 INFO - PROCESS | 2229 | |4;audio-oscillator;91;102;151;112;112;90;103;80;82;139 18:59:01 INFO - PROCESS | 2229 | |5;imaging-gaussian-blur;89;85;88;86;124;84;89;85;88;84 18:59:01 INFO - PROCESS | 2229 | |6;imaging-darkroom;100;146;93;94;100;133;94;95;94;92 18:59:01 INFO - PROCESS | 2229 | |7;imaging-desaturate;85;85;84;86;83;84;83;84;85;83 18:59:01 INFO - PROCESS | 2229 | |8;json-parse-financial;57;56;58;58;55;57;58;118;54;56 18:59:01 INFO - PROCESS | 2229 | |9;json-stringify-tinderbox;62;71;70;76;72;66;66;69;67;67 18:59:01 INFO - PROCESS | 2229 | |10;stanford-crypto-aes;85;96;104;83;85;86;84;85;86;87 18:59:01 INFO - PROCESS | 2229 | |11;stanford-crypto-ccm;137;138;137;137;137;136;134;136;219;138 18:59:01 INFO - PROCESS | 2229 | |12;stanford-crypto-pbkdf2;166;166;187;225;186;165;166;165;164;162 18:59:01 INFO - PROCESS | 2229 | |13;stanford-crypto-sha256-iterative;66;66;66;65;68;67;77;81;86;80 18:59:01 INFO - PROCESS | 2229 | __end_tp_report 18:59:01 INFO - PROCESS | 2229 | __start_cc_report 18:59:01 INFO - PROCESS | 2229 | _x_x_mozilla_cycle_collect,2284 18:59:01 INFO - PROCESS | 2229 | __end_cc_report 18:59:01 INFO - PROCESS | 2229 | __startTimestamp1463191141699__endTimestamp 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | ------- Summary: start ------- 18:59:01 INFO - PROCESS | 2229 | Number of tests: 14 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.79 (2.5%) stddev-sans-first:2.96 18:59:01 INFO - PROCESS | 2229 | Values: 112.0 104.0 112.0 111.0 113.0 113.0 113.0 113.0 113.0 113.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:156.50 stddev:41.00 (26.2%) stddev-sans-first:43.46 18:59:01 INFO - PROCESS | 2229 | Values: 154.0 116.0 159.0 241.0 113.0 144.0 142.0 99.0 188.0 141.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.66 (0.9%) stddev-sans-first:1.72 18:59:01 INFO - PROCESS | 2229 | Values: 186.0 184.0 185.0 184.0 187.0 186.0 185.0 187.0 183.0 182.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.00 stddev:23.65 (31.5%) stddev-sans-first:25.08 18:59:01 INFO - PROCESS | 2229 | Values: 81.0 74.0 56.0 125.0 57.0 76.0 80.0 81.0 115.0 55.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:115.50 stddev:23.44 (20.3%) stddev-sans-first:24.20 18:59:01 INFO - PROCESS | 2229 | Values: 91.0 102.0 151.0 112.0 112.0 90.0 103.0 80.0 82.0 139.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:87.00 stddev:12.04 (13.8%) stddev-sans-first:12.76 18:59:01 INFO - PROCESS | 2229 | Values: 89.0 85.0 88.0 86.0 124.0 84.0 89.0 85.0 88.0 84.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:93.50 stddev:19.10 (20.4%) stddev-sans-first:20.20 18:59:01 INFO - PROCESS | 2229 | Values: 100.0 146.0 93.0 94.0 100.0 133.0 94.0 95.0 94.0 92.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:1.05 18:59:01 INFO - PROCESS | 2229 | Values: 85.0 85.0 84.0 86.0 83.0 84.0 83.0 84.0 85.0 83.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:57.00 stddev:19.48 (34.2%) stddev-sans-first:20.55 18:59:01 INFO - PROCESS | 2229 | Values: 57.0 56.0 58.0 58.0 55.0 57.0 58.0 118.0 54.0 56.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.50 stddev:3.89 (5.6%) stddev-sans-first:3.32 18:59:01 INFO - PROCESS | 2229 | Values: 62.0 71.0 70.0 76.0 72.0 66.0 66.0 69.0 67.0 67.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:6.64 (7.8%) stddev-sans-first:6.95 18:59:01 INFO - PROCESS | 2229 | Values: 85.0 96.0 104.0 83.0 85.0 86.0 84.0 85.0 86.0 87.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:137.00 stddev:26.06 (19.0%) stddev-sans-first:27.49 18:59:01 INFO - PROCESS | 2229 | Values: 137.0 138.0 137.0 137.0 137.0 136.0 134.0 136.0 219.0 138.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:166.00 stddev:19.71 (11.9%) stddev-sans-first:20.63 18:59:01 INFO - PROCESS | 2229 | Values: 166.0 166.0 187.0 225.0 186.0 165.0 166.0 165.0 164.0 162.0 18:59:01 INFO - PROCESS | 2229 | 18:59:01 INFO - PROCESS | 2229 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.50 stddev:7.91 (10.9%) stddev-sans-first:8.07 18:59:01 INFO - PROCESS | 2229 | Values: 66.0 66.0 66.0 65.0 68.0 67.0 77.0 81.0 86.0 80.0 18:59:01 INFO - PROCESS | 2229 | -------- Summary: end -------- 18:59:01 INFO - PROCESS | 2229 | 18:59:02 INFO - TEST-INFO | 2229: exit 0 18:59:02 INFO - TEST-OK | kraken | took 61034ms 18:59:02 INFO - SUITE-END | took 1177s 18:59:02 INFO - Completed test suite (00:19:37) 18:59:02 INFO - javascript benchmark 18:59:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.599300087489, 3406.1484628842773, 3419.880304189354, 3427.4516077427616, 3482.756207065505, 5540.584456165789, 5542.501174894259, 5592.916106258404, 5604.1593760935775, 5642.576806739496, 4687.648426368028, 5526.723663816813, 5664.0937450008005, 5686.601937864351, 5736.569757268211, 5021.397432308128, 5050.772715227824, 5107.872303192413, 5116.411612664554, 5281.207818827187, 5179.637425380225, 5212.192110222922, 5224.660397074178, 5229.973850130763, 5284.44513326101, 4111.691623128262, 4998.750062496897, 5053.469385714484, 5078.009788091324, 5084.949150508484, 5098.5411312982005, 5112.590992720573, 5116.872078198037, 5129.794808207667, 5141.948580514184, 5003.699778013331, 5058.114829904752, 5070.974645126751, 5079.3650793650795, 5130.871728206787, 5507.339119305669, 5587.189857470644, 5589.217509548666, 5602.60781745278, 5647.407022262644, 6201.379862013808, 6217.720202590849, 6221.129041934088, 6222.346653601352, 6225.501959843202, 6064.015692756517, 6095.075643002737, 6120.785772497986, 6220.68896555175, 6225.782097626561, 5755.6834374108985, 5756.42435756421, 5764.942350576483, 5809.215755872988, 5813.128030795372, 6361.236651601745, 6363.172787537591, 6374.916264235091, 6380.457661098766, 6386.07401926718, 5954.166416701665, 6002.639841609517, 6014.398560144038, 6086.0, 6092.147099406086, 6212.223472065992, 6229.657368844757, 6240.843978900473, 6241.063840423882, 6246.063090536456, 6086.6348019119, 6132.816015519542, 6148.723307451175, 6155.138280640714, 6160.476359509402, 5792.275965504312, 6047.0627052806, 6068.575199736064, 6084.117802918552, 6122.989706698345, 4768.976155119271, 4804.95195048045, 4807.086653535817, 4817.181079216542, 4874.878128046827, 4327.545607711208, 4364.978175109168, 4371.759553224603, 4399.824007039747, 4405.515393306734, 5513.09034009392, 5573.860653483614, 5579.2746942897975, 5626.634268772516, 5638.633488823213, 6129.570930034944, 6170.321264660884, 6170.969145154246, 6174.9073763893575, 6236.0, 5655.349634791969, 5663.80176693822, 5671.971640141773, 5687.51656109227, 5717.056685646821, 5302.814401495929, 5363.007843548956, 5372.623916325898, 5374.811881584125, 5375.3346545669765, 6246.406591373812, 6273.431021555346, 6276.340984196594, 6314.557980941474, 6334.936650633619, 6289.96855015722, 6298.905516417166, 6299.181106456132, 6302.432781049728, 6308.810735677937, 5962.582619216613, 6285.43431091204, 6298.905516417349, 6336.17629708135, 6348.111264423077, 5980.9401905980385, 6102.908456373069, 6104.38956104386, 6111.1663517948755, 6172.969135154295, 6237.906431403442, 6240.843978900564, 6259.092431597484, 6262.780802671885, 6265.592736472115, 5845.707714614167, 5994.3705910878725, 6007.579469437273, 6015.87968240624, 6031.638101713911, 5931.822045338647, 5946.524278057832, 6005.309389420273, 6032.6983650818165, 6046.183765191819, 4879.926801097916, 5709.0865461525955, 5844.970775146097, 5881.353051164283, 5953.047512398169, 5766.94233057664, 5897.616654917417, 6074.7266373012435, 6087.57386982907, 6089.878202436015, 5813.0, 5869.0, 5872.236609240771, 5906.911396328973, 5913.438223368773], "value": 5624.449094524515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.49129626584147, 473.81994841960073, 475.51259958542494, 494.99021995129925, 512.4721536817075, 33270.833645831764, 42575.57424425755, 43888.341674874864, 44089.559104409025, 46053.0, 45377.54622453774, 45610.31584526235, 45720.0, 45825.854353641094, 45923.54076459234, 8745.693900713477, 9717.562709678064, 9910.752231194241, 9910.801783964316, 9933.701988940344, 8727.301815854717, 11566.942165289203, 11842.881571184264, 11857.584984525543, 12008.579699710512, 10039.447830369327, 11832.40837958103, 11897.7620447591, 11908.523659053628, 11933.820992685116, 8786.297096232285, 11690.240134391322, 11825.40872956353, 11909.928106470416, 11923.940380298043, 11530.365829879438, 11782.234154779911, 11794.35131067791, 11797.646070617895, 11798.351090690008, 40841.59158408437, 41371.172576548604, 41376.793116034525, 41420.37869431961, 41467.37798933018, 3263.1352691536763, 3307.8015319080932, 3382.441897086978, 3517.188632954881, 3650.1787097903025, 28836.279093022633, 29279.414411711645, 29313.853430732925, 29790.70209297901, 29896.252593685116, 2635.0645520840208, 2696.0833316672424, 2723.1422102037795, 2731.0, 2774.3480282133683, 452024.0, 453374.73312633554, 453401.7329913362, 454357.0, 454585.0, 61375.0, 61580.07629885555, 61800.69099654473, 61837.38162618406, 61932.76134477375, 36561.257905657185, 37390.62609373871, 37477.62522374796, 38603.80698096492, 38609.22781544353, 16631.41790037367, 16706.83293167077, 16902.070386128635, 16902.239399227063, 16936.915315423343, 10680.679579612468, 11641.88358116425, 11864.991475724488, 12578.622641320775, 12761.489540418279, 19787.703184452246, 19846.007699614962, 19914.701279480818, 19948.800511994985, 19987.700184497244, 8327.750167494985, 8342.916570834213, 8351.624176912053, 8383.329333653293, 8448.957755211184, 959.2901253072815, 1036.9014943580348, 1042.5621239079605, 1098.9450527473755, 1102.8841971593029, 30304.84847575748, 30336.393272134435, 30641.69358306433, 30898.918537851067, 30994.380112397626, 1103.8178700514486, 1143.685486491205, 1182.8876256755595, 1210.0984766349127, 1211.9456073216359, 15707.921460392625, 15790.9210453947, 15820.60448488797, 15833.920830395775, 16103.919480402523, 681705.0, 688762.0, 689368.0, 689498.0, 691208.0, 47669.76165119152, 60866.69566652139, 61199.776004480555, 61469.38530614637, 61526.0, 1328.1566205459603, 1373.3476598615578, 1429.8570142985618, 1487.9925600372146, 1512.9470468533548, 29753.553696694133, 30735.538966915963, 30800.0, 31079.84460077685, 31646.0, 51509.0, 52208.0, 52870.942581148935, 52882.471175287756, 52927.73536132295, 551213.0, 551635.0, 551955.0, 553361.0, 554005.0, 23781.167659131846, 44642.0, 44802.0, 45557.44425557418, 46474.535254647024, 46933.765331174494, 47164.0, 48435.03129937311, 48874.75562622164, 50597.74701126618, 31308.373832523677, 37473.06317342088, 38427.580828205115, 41236.58763412327, 41356.58643413648, 32775.0, 39349.80325098356, 39517.209655807295, 39978.80010599928, 40272.19455610813, 38865.805670971466, 39319.606803931594, 39449.80275098606, 39533.908877689195, 40486.0, 33112.834435827666, 34283.4857477143, 34361.65638343684, 34488.0, 34646.0, 24935.501289973738, 25311.62032569476, 25553.87223063947, 25879.870600646875, 26005.219843404728], "value": 20558.129494785848, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4348937529957, 397.3503322068417, 399.6263493633451, 401.7288330376995, 423.1220218047552, 9529.0, 20396.796032039674, 20995.475113122142, 21564.56870862582, 21597.4600634984, 16599.0, 16735.748963765553, 20486.487837804063, 20976.68534971973, 21071.68392474114, 5907.291125064999, 6074.453299203071, 6097.054956481752, 6122.295935967354, 6132.601380910249, 5974.283086029661, 6069.514438844903, 6107.725152368132, 6121.234845644294, 6131.632102073868, 5824.126381042856, 6040.852238074752, 6057.182280392157, 6068.241469816273, 6077.574569780118, 5895.941040589625, 5901.822945311647, 6022.45797878193, 6041.214642096521, 6049.183360246355, 5276.419593844674, 5286.127788914824, 5287.154055351125, 5287.682739035632, 5289.0, 23926.0, 26070.608940865903, 26090.86954565215, 26506.807193676137, 26900.86549567259, 2241.3388050524973, 2318.652202169671, 2514.798816094708, 2672.3452754075183, 2736.0423851651963, 14676.926615366961, 14954.177520236492, 15122.546323610199, 15378.538643840702, 15432.92283538575, 1740.8259174082616, 1831.8534517238588, 1839.816018398163, 1846.8153184681423, 1853.4161739052156, 110186.0, 112036.0, 112285.94713237924, 112712.4364378181, 112853.43573282163, 8518.190771876662, 9905.20758339331, 10011.649592264235, 10408.47957602117, 11459.968602825786, 12069.396530173457, 14430.422783088768, 14447.927760361341, 14477.276136193359, 14493.637659058608, 7609.0, 7620.771376858703, 8688.435251708619, 8835.55822208887, 8870.955645221733, 6761.594304341756, 8724.956375218084, 8742.038375778659, 8868.600912958933, 8945.821083578292, 5854.853628659318, 6710.476999575165, 6740.83147921296, 6854.931450685529, 6886.8622627548175, 1869.26164165154, 1896.497428181533, 1903.2196799312492, 1907.9236830526625, 1933.6519426503082, 961.5288508630721, 976.6191185437685, 990.6730778842965, 1009.9646512372032, 1020.3418794877263, 21061.578768424544, 21645.242416515346, 21673.0, 21703.57410647338, 21975.2308669199, 817.979550511242, 923.1599244687302, 943.0381011368365, 1001.799640071974, 1022.8772547294373, 10454.738631534272, 11616.825747613793, 11752.001079908303, 11814.763704725858, 11821.0, 579434.0, 584540.0, 588234.0, 613691.0, 626066.0, 115142.0, 115570.04358002264, 117567.0, 117872.41063794798, 122838.38580807217, 1034.5654824973528, 1037.901399367059, 1058.7882423515327, 1060.824963880951, 1061.490484567412, 19053.856768593374, 19341.12964916554, 19448.611027779647, 19454.416367509, 19589.51026224356, 28948.39342678125, 49451.867248944014, 52511.203832483145, 52728.736356317975, 53344.0, 66221.66889165524, 67030.66484667546, 67220.655586887, 67698.30754231184, 69474.30525694678, 7882.684692612232, 7950.443468957349, 7996.760097197093, 8007.879881801661, 8268.09051004377, 12331.9383403086, 12411.0, 12424.316662583284, 12642.683932901384, 12816.807747883975, 10703.357798532114, 10857.67426977192, 11104.333739975627, 11229.943850280697, 11234.269772464764, 9485.383450075724, 9543.809123817346, 10650.680479585624, 10697.947330734149, 11114.0, 8696.78258043554, 10788.568457261621, 11126.276792008493, 11166.72083197894, 11403.030742386658, 7741.380689544711, 7987.680492780456, 7998.680052798056, 8001.719939802079, 8192.631331589973, 9183.976849282411, 10889.618863339745, 11032.172587055842, 11100.000999910048, 11311.151663625096], "value": 9652.47064800394, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.1873782041678, 1607.2606600963559, 1628.820829708732, 1885.8208470195336, 2000.2599038355802, 8191.139930307314, 9023.278137748981, 9230.81538369233, 9232.169104780569, 9403.0, 8993.550322483883, 9255.490947997874, 9256.213221876149, 9259.305552083588, 9267.629294828215, 2155.687425323335, 2231.6317807561813, 2402.9759702403016, 2429.4533729910804, 2465.950680986379, 2469.086438017936, 2512.309114993373, 2512.786413154884, 2516.547021536123, 2517.1064272183385, 2162.826973842089, 2411.397150712322, 2471.1845091119885, 2475.653408522808, 2479.417336925821, 1971.2509246486386, 2144.1209104267177, 2384.4873352229265, 2448.8897999590063, 2451.325885381534, 2439.7560243975636, 2450.215930902112, 2473.208573256559, 2473.9381515462255, 2477.5168842075805, 7804.570748608824, 7961.323287520568, 9276.072392760738, 9363.953180234123, 9416.62333506659, 5477.561795056386, 5668.603197776158, 5677.233573467571, 5679.091345384719, 5679.886402271932, 3595.5505561804775, 3604.2431089471243, 3658.1037645776687, 3696.6488183622514, 3705.3136476054915, 997.3666721631744, 1015.8222311095602, 1017.2726500552141, 1019.4494972714725, 1020.5917632946806, 12583.622491325275, 12650.620481385573, 12654.304013279267, 12659.746805064031, 12663.493460261578, 4953.90092198154, 4979.153543897546, 5084.3644544431945, 5093.083245015932, 5101.464346243665, 10644.361338319726, 10690.51892664832, 10694.73263168427, 10699.144068474503, 10702.571897124028, 5706.885862282814, 5713.74288157034, 5713.914291285634, 5714.457126572969, 5715.199872017921, 4450.310201918708, 4621.930671039937, 4738.099761045391, 4743.596794272526, 4793.065352256276, 4138.255114079434, 4177.603127702924, 4185.288500954845, 4228.619424251832, 4264.296391095497, 1321.8612045735251, 1324.0, 1333.1867560787912, 1339.163023110556, 1353.221897408974, 743.4387037786437, 937.5078084005952, 938.6292414496236, 953.561361773578, 1007.4207330784828, 5690.772369105272, 6077.24034495688, 6078.6048906820915, 6171.0, 6292.46514046302, 442.7609091090775, 445.0475981399807, 456.39071839094515, 464.0440692174132, 465.1812809455315, 4049.919001619951, 4097.282975479279, 4101.5488296287385, 4166.708330416842, 4178.937315940263, 11048.944755276172, 11332.093432525378, 11485.0811935045, 11492.540298388138, 11500.654980350602, 11262.662120136409, 11384.544618215179, 11403.600873969372, 11433.028192603555, 11498.0, 6770.83072923171, 7267.382272506896, 7918.049834020069, 8574.3997920145, 8671.349648776368, 6184.6598437086395, 6192.907106393498, 6239.844003899939, 6300.054991751274, 6320.778772742932, 10232.130268927225, 10528.052475277262, 10661.413622250851, 10708.732281692708, 10717.303375280582, 10331.845022324822, 10752.13982881352, 10874.07570356497, 10890.836637450286, 10906.727331816768, 6038.51691864641, 7490.662920168715, 7754.689812407666, 7763.573003484863, 7956.602169891598, 8868.556572171494, 8891.688790892287, 8968.148025937526, 8968.865467018131, 8981.236594889508, 7737.458377913492, 7887.290143887078, 7940.205979402013, 8036.397270204875, 8062.798430039296, 7602.923970760222, 7768.300852923264, 7768.689252430066, 7861.567613781297, 7865.331446827084, 7365.2634736525915, 7398.630068496661, 7428.997085393403, 7500.849983000418, 7523.962380188064, 5036.823711170092, 5048.899022019613, 5132.948670513397, 5135.6918584885025, 5156.845294641167, 5082.237664350377, 5097.439281678938, 5157.845264641916, 5292.285541451854, 5415.783368665367], "value": 4669.568793266822, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.80146805310574, 315.4952076677317, 320.257003751297, 321.530565374553, 326.1910462054107, 4721.433427988646, 5162.483751624835, 5227.947720522794, 5280.841574752759, 5299.0, 5207.218917162428, 5209.505097015787, 5209.687418754877, 5230.084735171339, 5290.7354632268325, 3235.352929414115, 3788.204477059814, 3847.211046863208, 3870.0905287257474, 3892.5523564789987, 3058.571799948009, 3319.767616266862, 3516.771409858351, 3738.36447803874, 3935.48838650975, 3076.8461576921177, 3956.5054368203973, 3963.4054891766323, 3974.0, 4042.312806822847, 3307.7684562080663, 3677.0807298175187, 3736.831842567091, 3746.7564608300645, 3873.767573945544, 3488.778927375424, 3493.94759078614, 3497.632748561415, 3539.610642829287, 3548.964510354889, 5000.699958002531, 5014.699118052892, 5045.974770126162, 5076.822311219126, 5091.389033316026, 3696.9630303696886, 4523.796429160696, 4548.727076375428, 4573.588377046049, 4583.8166473341025, 4951.564046426532, 5461.781528738846, 5509.752061157257, 5514.6139770216105, 5517.751701173457, 3411.829408529564, 3619.239959608485, 3779.6976241900857, 3784.524035626108, 3816.9618303816883, 5319.654222475527, 5544.115000899714, 5725.45022086305, 5751.971240143773, 5797.333306669702, 3261.2988207535495, 3816.3321418751607, 3826.7129965252716, 3827.234553089393, 3843.807809609564, 3467.930641387158, 3677.191017976041, 3910.673149476057, 3911.9804400977814, 3919.568847426781, 4312.460942382202, 4331.155424692155, 4518.593326600621, 4535.845473400109, 4549.406838102316, 4596.8161273548685, 4776.307435421844, 5529.944700553024, 5545.197810711535, 5548.833534993957, 1272.10952333367, 1295.3484307508002, 1321.9735605288033, 1325.7282257137263, 1340.9195448273135, 728.9976282611409, 786.7207141464812, 792.1603100713147, 809.3161278719473, 815.3069890592949, 4553.222534254102, 4837.467878533358, 4887.462379138292, 5016.423111342169, 5213.661112027706, 4852.694969889274, 5238.847453560211, 5323.920141197885, 5338.252644629755, 5649.4633009864, 4618.621409973866, 5154.536191636802, 5165.66423182492, 5181.378234611795, 5209.947900521022, 2510.2888880009546, 2654.761071503574, 2949.5280755079084, 2974.301039255773, 2987.342784587387, 5821.7380217890295, 5831.591788574759, 5836.445537673914, 5843.5909486336395, 5866.472017518444, 5358.32083958021, 5733.885322293614, 5761.308642962787, 5766.336871259775, 5785.71071446426, 5189.922151167735, 5377.059014672417, 5822.371214884177, 5853.414658534112, 5903.35063143054, 4965.510346895989, 5599.720013999202, 5633.746481408264, 5636.802711905064, 5642.633228840113, 4986.902881366093, 5158.0, 5537.36320323168, 5544.251526043932, 5612.775488980558, 5313.707746074003, 5489.286392769074, 5564.91484160601, 5875.266796295018, 5879.559033072622, 3250.2361944942445, 3487.8081705505424, 3538.3731675033296, 3757.3236817373136, 3766.794625719853, 5065.164247899055, 5126.487351264844, 5259.159294246983, 5606.159076138449, 5619.578531610064, 4685.531446855287, 5397.3523177218985, 5405.756740946746, 5413.751748950598, 5486.451354864482, 4357.975875669152, 4502.977485112553, 5314.256004159498, 5434.456554344534, 5440.483154100354, 4989.426215985208, 5311.628186026941, 5358.866028349166, 5375.274337964324, 5382.569394448356, 4936.728479933495, 5186.118359878905, 5304.374334704346, 5375.392934426878, 5502.862428439321, 4545.0, 5323.30796996388, 5323.361196656426, 5356.866078347916, 5361.731913404392], "value": 3975.1356216598965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.4135795068537, 662.2648859765659, 723.2731105239234, 735.889616557516, 747.7210891108774, 15349.769753453707, 15450.69098618027, 15661.903666743332, 15849.0, 16194.109323987175, 14556.199409032499, 14702.63243418915, 14779.408823647069, 14785.260736963162, 14788.18664973426, 749.153456594048, 770.7610640701372, 912.9911447850129, 918.545320066568, 934.145257089762, 941.8210539997413, 945.3240932733095, 948.0945696859483, 949.0841338108723, 952.4808979106404, 999.5801763259449, 999.860019597257, 1001.4241810958691, 1001.6794625719773, 1002.478711070243, 771.8726410142319, 796.9322607578363, 797.0674311056049, 801.935845132388, 837.660747397305, 719.6905330707793, 743.7434085240583, 748.8202831320444, 751.6579956119953, 752.236479972828, 15135.243237838064, 15180.392784288615, 15204.543863684106, 15219.315130819028, 15220.695586088215, 12188.695282617917, 12217.328046957413, 12311.138220324581, 12538.561150359694, 12580.874191258154, 10668.466576671135, 10814.026737593655, 11044.282121662067, 11084.525123758052, 11305.0, 4008.6392224699916, 4020.195960807821, 4032.334664780308, 4032.5564187939303, 4082.326416141333, 81411.59294203551, 82047.0, 82327.58836205781, 82514.76227856588, 83812.0, 9444.669436569688, 9457.243420526342, 9679.0, 9736.41581505112, 9953.850692239623, 18269.0, 21669.67495487569, 21779.23772667949, 21811.345659630235, 21822.0, 7458.142313633893, 7493.4005279577505, 7519.43604229674, 7879.724209652634, 7881.881771773428, 3728.7203459740626, 4026.053877338823, 4056.0, 4135.855245066408, 4196.433481480022, 3336.215989242526, 3438.862445502202, 3441.5181874537584, 3441.6214216436174, 3443.345764304774, 623.2458724942779, 630.7413960276357, 632.0961025733245, 638.578538164809, 638.6678926957956, 527.3776943207052, 530.1226470191839, 539.5953035223582, 551.7489542258343, 552.7457369609943, 7911.0, 7931.365490760725, 7980.960095199487, 8001.759947201594, 8239.588020598947, 552.1083450227853, 560.564953718482, 577.9942200578024, 578.8900108979365, 604.0516389268806, 3150.637676667167, 3564.1624218308675, 3775.093977445448, 3911.2568611963634, 3977.54258260298, 93840.0, 94038.52980735053, 94385.5280723592, 94685.5265723667, 96459.0, 76701.61649191719, 84557.0, 84859.57570212109, 85441.14558854332, 85797.57101214454, 71970.0, 72474.27525724676, 72774.63612681902, 73053.634731826, 73575.0, 6841.008053832266, 7548.879066618962, 8185.263326300424, 8257.545834979133, 8423.4945903246, 44215.55784442114, 45406.545934540234, 46802.53197467982, 46877.0, 46932.53067469282, 55884.44115558793, 55997.44002559922, 61813.0, 66422.66788666026, 66617.00074498789, 10907.727306817076, 11160.269717252831, 11183.664490065312, 11246.437678115897, 11547.653570392902, 12124.515019399125, 12231.755364892475, 12315.876841231473, 12325.568605098779, 13071.477140914638, 11457.885421145682, 11510.59712910044, 11696.239744416585, 11750.529978800752, 11848.953819487433, 11372.772544549229, 11492.0, 11574.942125289657, 11922.225055371373, 12074.275543467069, 11101.555937762721, 11251.943740281245, 11299.548018079186, 11446.495859058894, 11975.94012029969, 7990.400719945911, 9442.669506567512, 9538.904610953801, 9641.517924103908, 10085.69742907714, 8472.957635211784, 8695.565221738761, 8979.416337938013, 9634.277429192698, 9652.613895444103], "value": 6899.9612345632695, "unit": "score"}], "name": "dromaeo_css", "value": 7231.098379675777, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 104.0, 112.0, 111.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 116.0, 159.0, 241.0, 113.0, 144.0, 142.0, 99.0, 188.0, 141.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 184.0, 187.0, 186.0, 185.0, 187.0, 183.0, 182.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 56.0, 125.0, 57.0, 76.0, 80.0, 81.0, 115.0, 55.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 102.0, 151.0, 112.0, 112.0, 90.0, 103.0, 80.0, 82.0, 139.0], "value": 106.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 124.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 146.0, 93.0, 94.0, 100.0, 133.0, 94.0, 95.0, 94.0, 92.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 86.0, 83.0, 84.0, 83.0, 84.0, 85.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 58.0, 55.0, 57.0, 58.0, 118.0, 54.0, 56.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 71.0, 70.0, 76.0, 72.0, 66.0, 66.0, 69.0, 67.0, 67.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 96.0, 104.0, 83.0, 85.0, 86.0, 84.0, 85.0, 86.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 137.0, 137.0, 137.0, 136.0, 134.0, 136.0, 219.0, 138.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 187.0, 225.0, 186.0, 165.0, 166.0, 165.0, 164.0, 162.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 68.0, 67.0, 77.0, 81.0, 86.0, 80.0], "value": 72.2, "unit": "score"}], "name": "kraken", "value": 1522.7000000000003, "alertThreshold": 2.0}]} 18:59:02 INFO - Return code: 0 18:59:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:59:02 ERROR - # TBPL SUCCESS # 18:59:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:59:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:59:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:59:03 INFO - 2016-05-13 18:59:03.000 screenresolution[2233:26405] starting screenresolution argv=screenresolution get 18:59:03 INFO - 2016-05-13 18:59:03.003 screenresolution[2233:26405] Display 0: 1600x1200x32@60 18:59:03 INFO - 2016-05-13 18:59:03.009 screenresolution[2234:26408] starting screenresolution argv=screenresolution list 18:59:03 INFO - Available Modes on Display 0 18:59:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:59:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:59:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:59:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:59:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:59:03 INFO - Intel Iris: 18:59:03 INFO - Chipset Model: Intel Iris 18:59:03 INFO - Type: GPU 18:59:03 INFO - Bus: Built-In 18:59:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:59:03 INFO - Vendor: Intel (0x8086) 18:59:03 INFO - Device ID: 0x0a2e 18:59:03 INFO - Revision ID: 0x0009 18:59:03 INFO - Displays: 18:59:03 INFO - Display: 18:59:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:59:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:59:03 INFO - Display Serial Number: 1600x1200 60 18:59:03 INFO - Main Display: Yes 18:59:03 INFO - Mirror: Off 18:59:03 INFO - Online: Yes 18:59:03 INFO - Rotation: Supported 18:59:03 INFO - Return code: 0 18:59:03 INFO - Running post-action listener: _resource_record_post_action 18:59:03 INFO - ##### 18:59:03 INFO - ##### Finished run-tests step (success) 18:59:03 INFO - ##### 18:59:03 INFO - Running post-run listener: _resource_record_post_run 18:59:03 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 544768; Write bytes: 696732160; Read time: 4; Write time: 1905 18:59:03 INFO - TinderboxPrint: CPU usage
53.8% 18:59:03 INFO - TinderboxPrint: I/O read bytes / time
544,768 / 4 18:59:03 INFO - TinderboxPrint: I/O write bytes / time
696,732,160 / 1,905 18:59:03 INFO - TinderboxPrint: CPU idle
2,241.3 (46.2%) 18:59:03 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 18:59:03 INFO - TinderboxPrint: CPU user
1,640.4 (33.8%) 18:59:03 INFO - TinderboxPrint: Swap in / out
394,698,752 / 0 18:59:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 392749568; Write bytes: 390143488; Read time: 30357; Write time: 795 18:59:03 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 544768; Write bytes: 295570432; Read time: 4; Write time: 1072 18:59:03 INFO - Running post-run listener: _upload_blobber_files 18:59:03 INFO - Blob upload gear active. 18:59:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:59:03 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 18:59:03 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'] 18:59:03 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 18:59:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:59:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:59:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:59:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:05 INFO - (blobuploader) - INFO - Done attempting. 18:59:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:59:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:59:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:06 INFO - (blobuploader) - INFO - Done attempting. 18:59:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:59:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:59:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:09 INFO - (blobuploader) - INFO - Done attempting. 18:59:09 INFO - (blobuploader) - INFO - Iteration through files over. 18:59:09 INFO - Return code: 0 18:59:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:59:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:59:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50f0c9584e4941c07fb9f67feb1c08d9910e146021876fe6e6904c6d058b363764e52d6e8863f9f4cfbba409c1e3f55b90a8ed787fbee9f444bfc7471c61514e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b33664de9e95b664e4d3497368c51328f4fc50a3ee59862b8fb12a3b2d00a50f5f5767a6470d64d0f32efdd66065b546107352b969002fe6277fe89baa6dc87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d18b247f87b4096e33988ef0da82c642ff681f7eb4f19f384e6a65a34bfcfc6ec9ebb9dbd2d8b8917c133dfb2bcb20b7de7eea549475a9171193fb0f703d4"} 18:59:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:59:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:59:09 INFO - Contents: 18:59:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/50f0c9584e4941c07fb9f67feb1c08d9910e146021876fe6e6904c6d058b363764e52d6e8863f9f4cfbba409c1e3f55b90a8ed787fbee9f444bfc7471c61514e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b33664de9e95b664e4d3497368c51328f4fc50a3ee59862b8fb12a3b2d00a50f5f5767a6470d64d0f32efdd66065b546107352b969002fe6277fe89baa6dc87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d18b247f87b4096e33988ef0da82c642ff681f7eb4f19f384e6a65a34bfcfc6ec9ebb9dbd2d8b8917c133dfb2bcb20b7de7eea549475a9171193fb0f703d4"} 18:59:09 INFO - Running post-run listener: copy_logs_to_upload_dir 18:59:09 INFO - Copying logs to upload dir... 18:59:09 INFO - mkdir: /builds/slave/test/build/upload/logs 18:59:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.343959 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-13 18:59:09.493970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:59:09.497470) ========= 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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/50f0c9584e4941c07fb9f67feb1c08d9910e146021876fe6e6904c6d058b363764e52d6e8863f9f4cfbba409c1e3f55b90a8ed787fbee9f444bfc7471c61514e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b33664de9e95b664e4d3497368c51328f4fc50a3ee59862b8fb12a3b2d00a50f5f5767a6470d64d0f32efdd66065b546107352b969002fe6277fe89baa6dc87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d18b247f87b4096e33988ef0da82c642ff681f7eb4f19f384e6a65a34bfcfc6ec9ebb9dbd2d8b8917c133dfb2bcb20b7de7eea549475a9171193fb0f703d4"} build_url:https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011440 build_url: 'https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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/50f0c9584e4941c07fb9f67feb1c08d9910e146021876fe6e6904c6d058b363764e52d6e8863f9f4cfbba409c1e3f55b90a8ed787fbee9f444bfc7471c61514e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b33664de9e95b664e4d3497368c51328f4fc50a3ee59862b8fb12a3b2d00a50f5f5767a6470d64d0f32efdd66065b546107352b969002fe6277fe89baa6dc87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/561d18b247f87b4096e33988ef0da82c642ff681f7eb4f19f384e6a65a34bfcfc6ec9ebb9dbd2d8b8917c133dfb2bcb20b7de7eea549475a9171193fb0f703d4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:59:09.526385) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:59:09.526726) ========= 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.gDRVtMFeIr/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.rzMm8xfSE8/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005403 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:59:09.574998) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:59:09.575363) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:59:09.575698) ========= ========= Total master_lag: 1.33 =========