builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0335 starttime: 1491574865.77 results: success (0) buildid: 20170407060634 builduid: cca98223c08341509298ee25d8fc8bcd revision: 6471400d8fbe3579149744cf64a4e060bb353c97 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.774178) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.774657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.774934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.816184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.816759) ========= 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.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 --2017-04-07 07:21:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.1M=0.001s 2017-04-07 07:21:05 (10.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123706 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.965596) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:05.965909) ========= 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.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.036963 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:06.025881) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-07 07:21:06.026546) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6471400d8fbe3579149744cf64a4e060bb353c97 --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 6471400d8fbe3579149744cf64a4e060bb353c97 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 2017-04-07 07:21:06,127 truncating revision to first 12 chars 2017-04-07 07:21:06,127 Setting DEBUG logging. 2017-04-07 07:21:06,127 attempt 1/10 2017-04-07 07:21:06,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6471400d8fbe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 07:21:07,867 unpacking tar archive at: mozilla-central-6471400d8fbe/testing/mozharness/ program finished with exit code 0 elapsedTime=2.011108 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-07 07:21:08.063511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:08.063976) ========= script_repo_revision: 6471400d8fbe3579149744cf64a4e060bb353c97 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:08.064508) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:08.064880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 07:21:08.085953) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-07 07:21:08.086571) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:21:08 INFO - MultiFileLogger online at 20170407 07:21:08 in /builds/slave/test 07:21:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:21:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:21:08 INFO - {'append_to_log': False, 07:21:08 INFO - 'base_work_dir': '/builds/slave/test', 07:21:08 INFO - 'blob_upload_branch': 'Firefox', 07:21:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:21:08 INFO - 'branch': 'Firefox', 07:21:08 INFO - 'buildbot_json_path': 'buildprops.json', 07:21:08 INFO - 'config_files': ('talos/mac_config.py',), 07:21:08 INFO - 'default_actions': ('clobber', 07:21:08 INFO - 'read-buildbot-config', 07:21:08 INFO - 'download-and-extract', 07:21:08 INFO - 'populate-webroot', 07:21:08 INFO - 'create-virtualenv', 07:21:08 INFO - 'install', 07:21:08 INFO - 'run-tests'), 07:21:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:21:08 INFO - 'download_minidump_stackwalk': True, 07:21:08 INFO - 'download_symbols': 'ondemand', 07:21:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:21:08 INFO - 'tooltool.py': '/tools/tooltool.py', 07:21:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:21:08 INFO - '/tools/misc-python/virtualenv.py')}, 07:21:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:21:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:21:08 INFO - 'gecko_profile': False, 07:21:08 INFO - 'gecko_profile_interval': 0, 07:21:08 INFO - 'installer_path': 'installer.exe', 07:21:08 INFO - 'log_level': 'info', 07:21:08 INFO - 'log_name': 'talos', 07:21:08 INFO - 'log_to_console': True, 07:21:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:21:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:21:08 INFO - 'opt_config_files': (), 07:21:08 INFO - 'pip_index': False, 07:21:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:21:08 INFO - 'cmd': ('bash', 07:21:08 INFO - '-c', 07:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:21:08 INFO - 'enabled': True, 07:21:08 INFO - 'halt_on_failure': False, 07:21:08 INFO - 'name': 'check_screen_resolution'},), 07:21:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:21:08 INFO - 'cmd': ('bash', 07:21:08 INFO - '-c', 07:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:21:08 INFO - 'enabled': True, 07:21:08 INFO - 'halt_on_failure': False, 07:21:08 INFO - 'name': 'check_screen_resolution'},), 07:21:08 INFO - 'run_cmd_checks_enabled': True, 07:21:08 INFO - 'suite': 'dromaeojs-e10s', 07:21:08 INFO - 'system_bits': '32', 07:21:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:21:08 INFO - 'title': 't-yosemite-r7-0335', 07:21:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:21:08 INFO - 'use_talos_json': True, 07:21:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:21:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:21:08 INFO - 'work_dir': 'build'} 07:21:08 INFO - [mozharness: 2017-04-07 14:21:08.291994Z] Running clobber step. 07:21:08 INFO - Running pre-action listener: _resource_record_pre_action 07:21:08 INFO - Running main action method: clobber 07:21:08 INFO - rmtree: /builds/slave/test/build 07:21:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:21:10 INFO - Running post-action listener: _resource_record_post_action 07:21:10 INFO - [mozharness: 2017-04-07 14:21:10.854005Z] Finished clobber step (success) 07:21:10 INFO - [mozharness: 2017-04-07 14:21:10.854142Z] Running read-buildbot-config step. 07:21:10 INFO - Running pre-action listener: _resource_record_pre_action 07:21:10 INFO - Running main action method: read_buildbot_config 07:21:10 INFO - Using buildbot properties: 07:21:10 INFO - { 07:21:10 INFO - "project": "", 07:21:10 INFO - "product": "firefox", 07:21:10 INFO - "script_repo_revision": "production", 07:21:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:21:10 INFO - "repository": "", 07:21:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:21:10 INFO - "buildid": "20170407060634", 07:21:10 INFO - "pgo_build": "False", 07:21:10 INFO - "basedir": "/builds/slave/test", 07:21:10 INFO - "buildnumber": 37, 07:21:10 INFO - "slavename": "t-yosemite-r7-0335", 07:21:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:21:10 INFO - "platform": "yosemite_r7", 07:21:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:21:10 INFO - "branch": "mozilla-central", 07:21:10 INFO - "repo_path": "mozilla-central", 07:21:10 INFO - "revision": "6471400d8fbe3579149744cf64a4e060bb353c97", 07:21:10 INFO - "stage_platform": "macosx64", 07:21:10 INFO - "builduid": "cca98223c08341509298ee25d8fc8bcd", 07:21:10 INFO - "slavebuilddir": "test" 07:21:10 INFO - } 07:21:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:21:10 INFO - Running post-action listener: _resource_record_post_action 07:21:10 INFO - [mozharness: 2017-04-07 14:21:10.857463Z] Finished read-buildbot-config step (success) 07:21:10 INFO - [mozharness: 2017-04-07 14:21:10.857622Z] Running download-and-extract step. 07:21:10 INFO - Running pre-action listener: _resource_record_pre_action 07:21:10 INFO - Running main action method: download_and_extract 07:21:10 INFO - mkdir: /builds/slave/test/build/tests 07:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:10 INFO - trying https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:21:10 INFO - Downloading https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:21:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:21:12 INFO - Downloaded 1325 bytes. 07:21:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:21:12 INFO - Using the following test package requirements: 07:21:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:21:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:21:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:21:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:21:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'jsshell-mac.zip'], 07:21:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:21:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:21:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:21:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:21:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:21:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:21:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:21:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:21:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:21:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:21:12 INFO - Fetch https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:21:13 INFO - Expected file size: 52519023 07:21:13 INFO - Obtained file size: 52519023 07:21:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:21:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:21:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:21:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:21:31 INFO - Expected file size: 52519023 07:21:31 INFO - Obtained file size: 52519023 07:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:21:48 INFO - Expected file size: 14404138 07:21:48 INFO - Obtained file size: 14404138 07:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:49 INFO - trying https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:49 INFO - Downloading https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:21:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:21:51 INFO - Downloaded 70887541 bytes. 07:21:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:51 INFO - mkdir: /builds/slave/test/properties 07:21:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:21:51 INFO - Writing to file /builds/slave/test/properties/build_url 07:21:51 INFO - Contents: 07:21:51 INFO - build_url:https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:21:51 INFO - Running post-action listener: _resource_record_post_action 07:21:51 INFO - Running post-action listener: set_extra_try_arguments 07:21:51 INFO - [mozharness: 2017-04-07 14:21:51.927791Z] Finished download-and-extract step (success) 07:21:51 INFO - [mozharness: 2017-04-07 14:21:51.927916Z] Running populate-webroot step. 07:21:51 INFO - Running pre-action listener: _resource_record_pre_action 07:21:51 INFO - Running main action method: populate_webroot 07:21:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:21:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:21:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:21:51 INFO - '--tppagecycles', 07:21:51 INFO - '7'], 07:21:51 INFO - 'tests': ['tsvgm']}}, 07:21:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:21:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:21:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:21:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:21:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['damp', 'tps']}, 07:21:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'tests': ['damp', 'tps']}, 07:21:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['dromaeo_dom']}, 07:21:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:21:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['basic_compositor_video']}, 07:21:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:21:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'other-e10s': {'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['a11yr', 07:21:51 INFO - 'ts_paint', 07:21:51 INFO - 'tpaint', 07:21:51 INFO - 'sessionrestore', 07:21:51 INFO - 'sessionrestore_no_auto_restore', 07:21:51 INFO - 'tabpaint']}, 07:21:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['tsvgx', 07:21:51 INFO - 'tsvgr_opacity', 07:21:51 INFO - 'tart', 07:21:51 INFO - 'tscrollx', 07:21:51 INFO - 'cart', 07:21:51 INFO - 'tsvg_static']}, 07:21:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:21:51 INFO - 'tsvgr_opacity', 07:21:51 INFO - 'tart', 07:21:51 INFO - 'tscrollx', 07:21:51 INFO - 'cart', 07:21:51 INFO - 'tsvg_static']}, 07:21:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'talos_options': ['--disable-e10s'], 07:21:51 INFO - 'tests': ['tp5o']}, 07:21:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:21:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'talos_options': ['--disable-e10s', 07:21:51 INFO - '--xperf_path', 07:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:51 INFO - 'tests': ['tp5n']}, 07:21:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:21:51 INFO - 'talos_options': ['--xperf_path', 07:21:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:51 INFO - 'tests': ['tp5n']}}, 07:21:51 INFO - 'talos.zip': {'path': '', 07:21:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:21:51 INFO - Running post-action listener: _resource_record_post_action 07:21:51 INFO - [mozharness: 2017-04-07 14:21:51.946262Z] Finished populate-webroot step (success) 07:21:51 INFO - [mozharness: 2017-04-07 14:21:51.946373Z] Running create-virtualenv step. 07:21:51 INFO - Running pre-action listener: _resource_record_pre_action 07:21:51 INFO - Running main action method: create_virtualenv 07:21:51 INFO - Creating virtualenv /builds/slave/test/build/venv 07:21:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:21:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:21:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:21:52 INFO - Using real prefix '/tools/python27' 07:21:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:21:52 INFO - Installing distribute.............................................................................................................................................................................................done. 07:21:55 INFO - Installing pip.................done. 07:21:55 INFO - Return code: 0 07:21:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:21:55 INFO - Reading from file tmpfile_stdout 07:21:55 INFO - Output received: 07:21:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:21:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:55 INFO - 'HOME': '/Users/cltbld', 07:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:55 INFO - 'LOGNAME': 'cltbld', 07:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:55 INFO - 'MOZ_NO_REMOTE': '1', 07:21:55 INFO - 'NO_EM_RESTART': '1', 07:21:55 INFO - 'PAGER': '/bin/cat', 07:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:55 INFO - 'PWD': '/builds/slave/test', 07:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:55 INFO - 'SHELL': '/bin/bash', 07:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:21:55 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:55 INFO - 'USER': 'cltbld', 07:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:55 INFO - 'XPC_FLAGS': '0x0', 07:21:55 INFO - 'XPC_SERVICE_NAME': '0', 07:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:56 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 07:21:56 INFO - Cleaning up... 07:21:56 INFO - Return code: 0 07:21:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:56 INFO - 'HOME': '/Users/cltbld', 07:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:56 INFO - 'LOGNAME': 'cltbld', 07:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:56 INFO - 'MOZ_NO_REMOTE': '1', 07:21:56 INFO - 'NO_EM_RESTART': '1', 07:21:56 INFO - 'PAGER': '/bin/cat', 07:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:56 INFO - 'PWD': '/builds/slave/test', 07:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:56 INFO - 'SHELL': '/bin/bash', 07:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:21:56 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:56 INFO - 'USER': 'cltbld', 07:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:56 INFO - 'XPC_FLAGS': '0x0', 07:21:56 INFO - 'XPC_SERVICE_NAME': '0', 07:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:56 INFO - Downloading/unpacking psutil>=3.1.1 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:59 INFO - Installing collected packages: psutil 07:21:59 INFO - Running setup.py install for psutil 07:22:00 INFO - building 'psutil._psutil_osx' extension 07:22:00 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 07:22:00 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 07:22:00 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 07:22:00 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 07:22:00 INFO - building 'psutil._psutil_posix' extension 07:22:00 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 07:22:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:22:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:22:00 INFO - ^ 07:22:00 INFO - 1 warning generated. 07:22:00 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 07:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:22:00 INFO - Successfully installed psutil 07:22:00 INFO - Cleaning up... 07:22:01 INFO - Return code: 0 07:22:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:22:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:02 INFO - 'HOME': '/Users/cltbld', 07:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:02 INFO - 'LOGNAME': 'cltbld', 07:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:02 INFO - 'MOZ_NO_REMOTE': '1', 07:22:02 INFO - 'NO_EM_RESTART': '1', 07:22:02 INFO - 'PAGER': '/bin/cat', 07:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:02 INFO - 'PWD': '/builds/slave/test', 07:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:02 INFO - 'SHELL': '/bin/bash', 07:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:02 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:02 INFO - 'USER': 'cltbld', 07:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:02 INFO - 'XPC_FLAGS': '0x0', 07:22:02 INFO - 'XPC_SERVICE_NAME': '0', 07:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:22:05 INFO - Installing collected packages: mozsystemmonitor 07:22:05 INFO - Running setup.py install for mozsystemmonitor 07:22:05 INFO - Successfully installed mozsystemmonitor 07:22:05 INFO - Cleaning up... 07:22:05 INFO - Return code: 0 07:22:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:22:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:05 INFO - 'HOME': '/Users/cltbld', 07:22:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:05 INFO - 'LOGNAME': 'cltbld', 07:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:05 INFO - 'MOZ_NO_REMOTE': '1', 07:22:05 INFO - 'NO_EM_RESTART': '1', 07:22:05 INFO - 'PAGER': '/bin/cat', 07:22:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:05 INFO - 'PWD': '/builds/slave/test', 07:22:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:05 INFO - 'SHELL': '/bin/bash', 07:22:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:05 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:05 INFO - 'USER': 'cltbld', 07:22:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:05 INFO - 'XPC_FLAGS': '0x0', 07:22:05 INFO - 'XPC_SERVICE_NAME': '0', 07:22:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:05 INFO - Downloading/unpacking jsonschema==2.5.1 07:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:22:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:22:08 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 07:22:08 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 07:22:08 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 07:22:08 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 07:22:08 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 07:22:08 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 07:22:08 INFO - Downloading functools32-3.2.3-2.tar.gz 07:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:22:08 INFO - warning: no files found matching '*.txt' 07:22:08 INFO - no previously-included directories found matching 'build' 07:22:08 INFO - no previously-included directories found matching 'dist' 07:22:08 INFO - no previously-included directories found matching '.git*' 07:22:08 INFO - Installing collected packages: jsonschema, functools32 07:22:08 INFO - Running setup.py install for functools32 07:22:09 INFO - warning: no files found matching '*.txt' 07:22:09 INFO - no previously-included directories found matching 'build' 07:22:09 INFO - no previously-included directories found matching 'dist' 07:22:09 INFO - no previously-included directories found matching '.git*' 07:22:09 INFO - Successfully installed jsonschema functools32 07:22:09 INFO - Cleaning up... 07:22:09 INFO - Return code: 0 07:22:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:09 INFO - 'HOME': '/Users/cltbld', 07:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:09 INFO - 'LOGNAME': 'cltbld', 07:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:09 INFO - 'MOZ_NO_REMOTE': '1', 07:22:09 INFO - 'NO_EM_RESTART': '1', 07:22:09 INFO - 'PAGER': '/bin/cat', 07:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:09 INFO - 'PWD': '/builds/slave/test', 07:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:09 INFO - 'SHELL': '/bin/bash', 07:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:09 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:09 INFO - 'USER': 'cltbld', 07:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:09 INFO - 'XPC_FLAGS': '0x0', 07:22:09 INFO - 'XPC_SERVICE_NAME': '0', 07:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:22:09 INFO - Cleaning up... 07:22:09 INFO - Return code: 0 07:22:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:22:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:09 INFO - 'HOME': '/Users/cltbld', 07:22:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:09 INFO - 'LOGNAME': 'cltbld', 07:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:09 INFO - 'MOZ_NO_REMOTE': '1', 07:22:09 INFO - 'NO_EM_RESTART': '1', 07:22:09 INFO - 'PAGER': '/bin/cat', 07:22:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:09 INFO - 'PWD': '/builds/slave/test', 07:22:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:09 INFO - 'SHELL': '/bin/bash', 07:22:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:09 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:09 INFO - 'USER': 'cltbld', 07:22:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:09 INFO - 'XPC_FLAGS': '0x0', 07:22:09 INFO - 'XPC_SERVICE_NAME': '0', 07:22:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:09 INFO - Downloading/unpacking blobuploader==1.2.4 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:12 INFO - Downloading blobuploader-1.2.4.tar.gz 07:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:22:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:22:13 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 07:22:13 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 07:22:13 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 07:22:13 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 07:22:13 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 07:22:13 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 07:22:13 INFO - Downloading docopt-0.6.1.tar.gz 07:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:22:13 INFO - Installing collected packages: blobuploader, requests, docopt 07:22:13 INFO - Running setup.py install for blobuploader 07:22:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:22:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:22:13 INFO - Running setup.py install for requests 07:22:14 INFO - Running setup.py install for docopt 07:22:14 INFO - Successfully installed blobuploader requests docopt 07:22:14 INFO - Cleaning up... 07:22:14 INFO - Return code: 0 07:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:14 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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:14 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 07:22:14 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 07:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:14 INFO - 'HOME': '/Users/cltbld', 07:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:14 INFO - 'LOGNAME': 'cltbld', 07:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:14 INFO - 'MOZ_NO_REMOTE': '1', 07:22:14 INFO - 'NO_EM_RESTART': '1', 07:22:14 INFO - 'PAGER': '/bin/cat', 07:22:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:14 INFO - 'PWD': '/builds/slave/test', 07:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:14 INFO - 'SHELL': '/bin/bash', 07:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:14 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:14 INFO - 'USER': 'cltbld', 07:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:14 INFO - 'XPC_FLAGS': '0x0', 07:22:14 INFO - 'XPC_SERVICE_NAME': '0', 07:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:14 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Hwl_no-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:14 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-8hj_OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-4Bq6HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-rXkj5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-bsIK5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ZtwgmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Um3MCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-nI8Dfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-4mNNjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-k50sDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-9MiYxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:15 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ObNKZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:16 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-lqo9nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:16 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ynCqf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:16 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-m_mTLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:16 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-tDjlj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:16 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-_zte8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:22:16 INFO - Running setup.py install for manifestparser 07:22:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:22:16 INFO - Running setup.py install for mozcrash 07:22:16 INFO - Running setup.py install for mozdebug 07:22:16 INFO - Running setup.py install for mozdevice 07:22:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Running setup.py install for mozfile 07:22:17 INFO - Running setup.py install for mozhttpd 07:22:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Running setup.py install for mozinfo 07:22:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Running setup.py install for mozInstall 07:22:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:22:17 INFO - Running setup.py install for mozleak 07:22:17 INFO - Running setup.py install for mozlog 07:22:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Running setup.py install for moznetwork 07:22:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Running setup.py install for mozprocess 07:22:18 INFO - Running setup.py install for mozprofile 07:22:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Running setup.py install for mozrunner 07:22:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:22:18 INFO - Running setup.py install for mozscreenshot 07:22:18 INFO - Running setup.py install for moztest 07:22:18 INFO - Running setup.py install for mozversion 07:22:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:22:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:22:19 INFO - Cleaning up... 07:22:19 INFO - Return code: 0 07:22:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:19 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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:19 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 07:22:19 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 07:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:19 INFO - 'HOME': '/Users/cltbld', 07:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:19 INFO - 'LOGNAME': 'cltbld', 07:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:19 INFO - 'MOZ_NO_REMOTE': '1', 07:22:19 INFO - 'NO_EM_RESTART': '1', 07:22:19 INFO - 'PAGER': '/bin/cat', 07:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:19 INFO - 'PWD': '/builds/slave/test', 07:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:19 INFO - 'SHELL': '/bin/bash', 07:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:19 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:19 INFO - 'USER': 'cltbld', 07:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:19 INFO - 'XPC_FLAGS': '0x0', 07:22:19 INFO - 'XPC_SERVICE_NAME': '0', 07:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-iUZOvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:22:19 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)) 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-dQbqnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-VRsWUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:22:19 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)) 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-nhTzBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-g2XYPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:22:19 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)) 07:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:22:19 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-62kTiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-EDkRMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-TMSqAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-yAK5CU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3evngo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:22:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-s13j5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-K2OljX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:22:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Tt9FQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:22:20 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-faKSGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:22:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 07:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:20 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-vTVufC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:22:21 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)) 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:22:21 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-hUvMdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 07:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:22:21 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-6oKcRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:22:21 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)) 07:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:22:21 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)) 07:22:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:22:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:22:23 INFO - Downloading blessings-1.6.tar.gz 07:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:22:23 INFO - Installing collected packages: blessings 07:22:24 INFO - Running setup.py install for blessings 07:22:24 INFO - Successfully installed blessings 07:22:24 INFO - Cleaning up... 07:22:24 INFO - Return code: 0 07:22:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:24 INFO - Reading from file tmpfile_stdout 07:22:24 INFO - Current package versions: 07:22:24 INFO - blessings == 1.6 07:22:24 INFO - blobuploader == 1.2.4 07:22:24 INFO - docopt == 0.6.1 07:22:24 INFO - functools32 == 3.2.3-2 07:22:24 INFO - jsonschema == 2.5.1 07:22:24 INFO - manifestparser == 1.1 07:22:24 INFO - mozInstall == 1.12 07:22:24 INFO - mozcrash == 1.0 07:22:24 INFO - mozdebug == 0.1 07:22:24 INFO - mozdevice == 0.50 07:22:24 INFO - mozfile == 1.2 07:22:24 INFO - mozhttpd == 0.7 07:22:24 INFO - mozinfo == 0.9 07:22:24 INFO - mozleak == 0.1 07:22:24 INFO - mozlog == 3.4 07:22:24 INFO - moznetwork == 0.27 07:22:24 INFO - mozprocess == 0.25 07:22:24 INFO - mozprofile == 0.28 07:22:24 INFO - mozrunner == 6.13 07:22:24 INFO - mozscreenshot == 0.1 07:22:24 INFO - mozsystemmonitor == 0.3 07:22:24 INFO - moztest == 0.8 07:22:24 INFO - mozversion == 1.4 07:22:24 INFO - psutil == 3.1.1 07:22:24 INFO - requests == 1.2.3 07:22:24 INFO - wsgiref == 0.1.2 07:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:24 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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:24 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 07:22:24 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 07:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:24 INFO - 'HOME': '/Users/cltbld', 07:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:24 INFO - 'LOGNAME': 'cltbld', 07:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:24 INFO - 'MOZ_NO_REMOTE': '1', 07:22:24 INFO - 'NO_EM_RESTART': '1', 07:22:24 INFO - 'PAGER': '/bin/cat', 07:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:24 INFO - 'PWD': '/builds/slave/test', 07:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:24 INFO - 'SHELL': '/bin/bash', 07:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:24 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:24 INFO - 'USER': 'cltbld', 07:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:24 INFO - 'XPC_FLAGS': '0x0', 07:22:24 INFO - 'XPC_SERVICE_NAME': '0', 07:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 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)) 07:22:24 INFO - Cleaning up... 07:22:24 INFO - Return code: 0 07:22:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:22:24 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 0x105e04b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e07768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bebdb7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, '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.HcfkVQfrED/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.6OYgWwzCyV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:22:24 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 07:22:24 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 07:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:24 INFO - 'HOME': '/Users/cltbld', 07:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:24 INFO - 'LOGNAME': 'cltbld', 07:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:24 INFO - 'MOZ_NO_REMOTE': '1', 07:22:24 INFO - 'NO_EM_RESTART': '1', 07:22:24 INFO - 'PAGER': '/bin/cat', 07:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:24 INFO - 'PWD': '/builds/slave/test', 07:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:24 INFO - 'SHELL': '/bin/bash', 07:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:24 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:24 INFO - 'USER': 'cltbld', 07:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:24 INFO - 'XPC_FLAGS': '0x0', 07:22:24 INFO - 'XPC_SERVICE_NAME': '0', 07:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:22:25 INFO - Cleaning up... 07:22:25 INFO - Return code: 0 07:22:25 INFO - Running post-action listener: _resource_record_post_action 07:22:25 INFO - Running post-action listener: _start_resource_monitoring 07:22:25 INFO - Starting resource monitoring. 07:22:25 INFO - [mozharness: 2017-04-07 14:22:25.105958Z] Finished create-virtualenv step (success) 07:22:25 INFO - [mozharness: 2017-04-07 14:22:25.106896Z] Running install step. 07:22:25 INFO - Running pre-action listener: _resource_record_pre_action 07:22:25 INFO - Running main action method: install 07:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:22:25 INFO - Reading from file tmpfile_stdout 07:22:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:22:25 INFO - Reading from file tmpfile_stdout 07:22:25 INFO - Output received: 07:22:25 INFO - Usage: mozinstall [options] installer 07:22:25 INFO - Options: 07:22:25 INFO - -h, --help show this help message and exit 07:22:25 INFO - -d DEST, --destination=DEST 07:22:25 INFO - Directory to install application into. [default: 07:22:25 INFO - "/builds/slave/test"] 07:22:25 INFO - --app=APP Application being installed. [default: firefox] 07:22:25 INFO - mkdir: /builds/slave/test/build/application 07:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:22:45 INFO - Reading from file tmpfile_stdout 07:22:45 INFO - Output received: 07:22:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:22:45 INFO - Running post-action listener: _resource_record_post_action 07:22:45 INFO - [mozharness: 2017-04-07 14:22:45.077551Z] Finished install step (success) 07:22:45 INFO - [mozharness: 2017-04-07 14:22:45.077678Z] Running run-tests step. 07:22:45 INFO - Running pre-action listener: _resource_record_pre_action 07:22:45 INFO - Running main action method: run_tests 07:22:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:22:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:22:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:22:45 INFO - 2017-04-07 07:22:45.092 screenresolution[1627:7515] starting screenresolution argv=screenresolution get 07:22:45 INFO - 2017-04-07 07:22:45.094 screenresolution[1627:7515] Display 0: 1600x1200x32@60 07:22:45 INFO - 2017-04-07 07:22:45.100 screenresolution[1628:7517] starting screenresolution argv=screenresolution list 07:22:45 INFO - Available Modes on Display 0 07:22:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:22:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:22:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:22:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:22:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:22:45 INFO - Intel Iris: 07:22:45 INFO - Chipset Model: Intel Iris 07:22:45 INFO - Type: GPU 07:22:45 INFO - Bus: Built-In 07:22:45 INFO - VRAM (Dynamic, Max): 1536 MB 07:22:45 INFO - Vendor: Intel (0x8086) 07:22:45 INFO - Device ID: 0x0a2e 07:22:45 INFO - Revision ID: 0x0009 07:22:45 INFO - Displays: 07:22:45 INFO - Display: 07:22:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:22:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:22:45 INFO - Display Serial Number: 1600x1200 60 07:22:45 INFO - Main Display: Yes 07:22:45 INFO - Mirror: Off 07:22:45 INFO - Online: Yes 07:22:45 INFO - Rotation: Supported 07:22:45 INFO - Return code: 0 07:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:22:45 INFO - Python 2.7.3 07:22:45 INFO - Return code: 0 07:22:45 INFO - grabbing minidump binary from tooltool 07:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:22:45 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 0x105be2d30>, '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 0x105b09db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bd7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:22:45 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 07:22:45 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 07:22:45 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 07:22:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:22:45 INFO - Return code: 0 07:22:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:22:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - ENV: RUST_BACKTRACE is now 1 07:22:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:22:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HcfkVQfrED/Render', 07:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:45 INFO - 'HOME': '/Users/cltbld', 07:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:45 INFO - 'LOGNAME': 'cltbld', 07:22:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:22:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:45 INFO - 'MOZ_NO_REMOTE': '1', 07:22:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:22:45 INFO - 'NO_EM_RESTART': '1', 07:22:45 INFO - 'PAGER': '/bin/cat', 07:22:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:45 INFO - 'PWD': '/builds/slave/test', 07:22:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:45 INFO - 'RUST_BACKTRACE': '1', 07:22:45 INFO - 'SHELL': '/bin/bash', 07:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OYgWwzCyV/Listeners', 07:22:45 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:45 INFO - 'USER': 'cltbld', 07:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:45 INFO - 'XPC_FLAGS': '0x0', 07:22:45 INFO - 'XPC_SERVICE_NAME': '0', 07:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:22:45 INFO - mozversion application_buildid: 20170407060634 07:22:45 INFO - mozversion application_changeset: 6471400d8fbe3579149744cf64a4e060bb353c97 07:22:45 INFO - mozversion application_display_name: Nightly 07:22:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:22:45 INFO - mozversion application_name: Firefox 07:22:45 INFO - mozversion application_remotingname: firefox 07:22:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:22:45 INFO - mozversion application_vendor: Mozilla 07:22:45 INFO - mozversion application_version: 55.0a1 07:22:45 INFO - mozversion platform_buildid: 20170407060634 07:22:45 INFO - mozversion platform_changeset: 6471400d8fbe3579149744cf64a4e060bb353c97 07:22:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:22:45 INFO - mozversion platform_version: 55.0a1 07:22:45 INFO - using testdate: 1491574965 07:22:45 INFO - actual date: 1491574965 07:22:45 INFO - starting webserver on 'localhost:49241' 07:22:45 INFO - SUITE-START | Running 2 tests 07:22:45 INFO - TEST-START | dromaeo_css 07:22:45 INFO - operating with platform_type : mac_ 07:22:45 INFO - Initialising browser for dromaeo_css test... 07:22:45 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpKQ5aoR/profile http://localhost:49241/getInfo.html) 07:22:46 INFO - PID 1638 | 1491574966340 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 07:22:46 INFO - PID 1638 | 1491574966341 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 07:22:46 INFO - PID 1638 | 1491574966391 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 07:22:48 INFO - PID 1638 | 2017-04-07 07:22:48.646 plugin-container[1640:7695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4b, name = 'com.apple.tsm.portname' 07:22:48 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:48 INFO - PID 1638 | 2017-04-07 07:22:48.651 plugin-container[1640:7695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 07:22:48 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:48 INFO - PID 1638 | 2017-04-07 07:22:48.651 plugin-container[1640:7695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:22:48 INFO - PID 1638 | __metrics Screen width/height:1600/1200 07:22:48 INFO - PID 1638 | colorDepth:24 07:22:48 INFO - PID 1638 | Browser inner width/height: 1024/689 07:22:48 INFO - PID 1638 | __metrics 07:22:48 ERROR - PID 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 07:22:48 INFO - PID 1638 | 1491574968842 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 07:22:49 INFO - TEST-INFO | 1638: exit 0 07:22:49 INFO - Browser initialized. 07:22:49 INFO - Running cycle 1/1 for dromaeo_css test... 07:22:49 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpKQ5aoR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:50 INFO - PID 1641 | 1491574970183 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 07:22:50 INFO - PID 1641 | 1491574970184 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 07:22:52 INFO - PID 1641 | 2017-04-07 07:22:52.364 plugin-container[1642:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 07:22:52 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:52 INFO - PID 1641 | 2017-04-07 07:22:52.366 plugin-container[1642:7822] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 07:22:52 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:22:52 INFO - PID 1641 | 2017-04-07 07:22:52.366 plugin-container[1642:7822] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:23:02 INFO - PID 1641 | RSS: Main: 234184704 07:23:02 INFO - PID 1641 | 07:26:06 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 07:26:08 INFO - PID 1641 | RSS: Main: 251842560 07:26:08 INFO - PID 1641 | 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 07:27:50 INFO - PID 1641 | 07:27:50 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:27:50 INFO - PID 1641 | Full stack: 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 07:27:50 INFO - PID 1641 | 07:27:50 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:27:50 INFO - PID 1641 | Full stack: 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 07:27:50 INFO - PID 1641 | 07:27:50 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:27:50 INFO - PID 1641 | Full stack: 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | ************************* 07:27:50 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 07:27:50 INFO - PID 1641 | 07:27:50 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:27:50 INFO - PID 1641 | Full stack: 07:27:50 INFO - PID 1641 | ************************* 07:29:10 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 07:29:10 INFO - PID 1641 | RSS: Main: 260009984 07:29:10 INFO - PID 1641 | 07:32:12 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 07:32:13 INFO - PID 1641 | RSS: Main: 273002496 07:32:13 INFO - PID 1641 | 07:35:15 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 07:35:15 INFO - PID 1641 | RSS: Main: 265322496 07:35:15 INFO - PID 1641 | 07:38:18 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 07:38:19 INFO - PID 1641 | RSS: Main: 278740992 07:38:19 INFO - PID 1641 | 07:41:21 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 07:41:22 INFO - PID 1641 | RSS: Main: 272564224 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | __start_tp_report 07:41:22 INFO - PID 1641 | _x_x_mozilla_page_load 07:41:22 INFO - PID 1641 | _x_x_mozilla_page_load_details 07:41:22 INFO - PID 1641 | |i|pagename|runs| 07:41:22 INFO - PID 1641 | |0;dojo.html;3626.129728865072;3631.05592545938;3671.467637192608;3681.4845921570986;3682.8710995115175;6156.815295541141;6271.372862713726;6302.432781049704;6320.968395158029;6344.238691357038;6310.52671049672;6340.3976622220935;6352.364763523646;6355.904661430082;6397.712102955378;5151.665141765773;5169.250458683488;5462.553310215625;5566.997940370732;5611.466910643482;4779.761011949406;5651.943480565204;5676.347220069683;5702.77188912443;5729.369769325392;5389.380221274564;5455.699936503511;5531.170324451325;5555.3333599967855;5561.971035358431;4558.566936141061;4754.191787396151;5483.287172667568;5586;5605.215269862182;5527.226188333637;5549.306336707911;5552.056150454432;5558.44415558445;5573.08044172711;6093.11649810775;6349.682515874234;6354.5551811373225;6355.777547785851;6356.618602883842;6576.5067619928695;6627.668616569153;6707.564008339491;6780.288069752654;6803.081583986148;6832.658367081626;6915.273896240873;6918.238993710688;6921.238663746984;6947.131608548932;6300.653464059475;6393.104965304908;6394.808155755335;6403.263624683128;6409.807705768834;6765.9661701691175;6906.412954898889;6980.930190698028;7036.226015138331;7101.82245443868;6691;6737.427318677869;6761.391474767294;6763.729450821912;6773;6824.078749368871;6868.793936181923;6913.792586222321;6949.583025018515;6953.096097507291;6503.967480162569;6609.603423794588;6693.564918280394;6742.359475849787;6819.727210911508;6758.8648227036165;6796.966015169893;6813.761518346834;6856.862862742717;6874.553154044971;5478.58910581708;5482.396936337;5487.149491828773;5501.284832971768;5507.834764957058;4749.121412538691;4763.857084287477;4771.403574553181;4779.761011949388;4811.013742182844;6179.69101544921;6357.364263573606;6407.615543067431;6414.550981431348;6459.09572659828;5700.9429905701245;6850.588964662136;6861.4853885958755;6862.279460656559;6863.588184708933;6254.874902502015;6381.840453988594;6410.262819775786;6425.421712045938;6484.5460817742305;6100.23747031621;6112.807455516499;6137.41694539018;6141.315210873529;6146.293176284695;7029.613371264425;7066.081409416743;7077.752278670222;7104.964475177591;7111.004459375588;6586.637734924625;6642.468602511691;6743.460523158037;6896.724131034903;6931.1682598088555;7004.684789184601;7049.859002820018;7076.080109585927;7083.468739844429;7100.254473280438;5608.85977850557;6234.345393733593;6269.968650156902;6798.082258895183;6798.52410331272;6599.373059559334;6783.898241526283;6794.150731158606;6889.242183359726;6928.064711263914;6601.273859875506;6620.437262832713;6689.2976237496305;6698.899516507159;6703.798886033427;6583.506237032146;6664.933350666432;6685.064091027163;6692.0965669635925;6698.83252918681;6228.501719862491;6334.6832658365975;6372.36276372359;6522.086907832812;6538.084668146558;6645.169353830771;6683.799486015428;6706.463482921452;6712.194536655633;6732.293109223461;6494;6506.25178104524;6516.413522782972;6519.348065193442;6525.934740652533 07:41:22 INFO - PID 1641 | |1;ext.html;805.8388322335533;833.5707110837919;1097.7804439112178;1126.3467189030364;1281.5706738242689;28792.85603571982;37020.44469332962;37059.629403705956;37223.81388093059;37917;36720.632793672055;37006.4449033264;37048.07379815506;37086.072848178745;37115.814420927854;7865.842683146348;8031;8033.437659363847;8327.292180164693;8575.871361929576;8554.101819308977;8627.95686021569;8666.826663466727;8677.045524992245;8681.21869031787;8394.24451799338;8598.570071496431;8628.741137765877;8662.480251184918;8664.523451210182;7475.738349157753;8625.00812406575;8629.093945135764;8673.219410253045;8691.34814888886;8399.244068033906;8425;8446.11315811837;8475.406721529496;8486.0665326814;29578.11265662012;35716.107097322514;35738;36048.45927311092;36179.09552261188;3456.792592444461;3459.41189997701;3499.3001399720156;3499.9650003500146;3500.43992961125;29216.41567168666;29537.26156846096;29654.85172574145;29681.961131360516;29723.554146687817;3063.494523403636;3107.39405815866;3131.874725010997;3140.670229625879;3142.701443362877;290024;292372;292735;292781.5360923182;294704.5264773684;38477.61522384796;38843.80578097091;46381.6085517435;47357.81605459891;47630.28554571684;44777.55222447799;48066.27900581494;48099.5190048102;48100.27849582259;48120;14241.715165696629;14305.28473576317;14415.279236038155;14427.278636068155;14430.206338651265;11671.474783634756;11696.766064678659;11698.766024679458;11700.94691477771;11713.707157320965;12897.226166430044;12899.90350820172;12946.51941928279;12947.223166610034;13016.088873778745;5424.294841670558;5925.318588362394;5962.910556341659;5979.103134529768;6000.849978750566;903.5437104262305;986.8026394721085;989.4014121456415;995.1640621877658;1027.2501074215716;20094.497637558885;20597;21882.890585547288;23577.646335304984;23597.764022359555;950;1090.5419723716059;1096.3915027160003;1124.2018167101287;1139.1058019454688;9659.420434773936;11703.707407314885;12054.819177712341;12072.15494915365;12093.697657558632;345036;346143;346256.5374346275;347792;348429;50390.49609503858;50521;50654.74672626613;50672.74663626732;50754.74622626863;1481.2963842174881;1514.2807166595999;1519.4529969211076;1531.0660497096762;1543.428931295428;20982.531222814257;22706;23260.418489537897;23279.254055945534;23324.76675233226;36150.63849361578;36371.45442818413;36509.26981460303;36519.81740091283;36600.084997874204;318255.4087229549;322570;323851;324375;324940;30844.22889427782;30964.535531966587;31114;31625.683743163194;31689.20776980594;41191.7940410296;41353;41517.58482415258;41546.58453415427;41715.7914210427;28112.43775124527;28326.433471330045;29248.122556323346;29492.852535737184;29603.555946660388;28505;28803.71196288094;29335.413291733617;29403.558946616246;30116.945906893154;33909.321813564085;34292.82853585716;34503.65496345004;34601.9619411408;34652;28988.855055724587;29092;29264.26839329034;29676.703232967393;29909.551356729233;22276;22493;23291.184808532296;23521.529569408172;23751.643725343787 07:41:22 INFO - PID 1641 | |2;jquery.html;425.42567533829316;432.4829492354498;437.5449532486214;448.20220008385076;453.9273716205408;21219.787802121973;21376.038078286496;21870.1251949922;22097.447563810914;22266.777332226673;21866.453338666543;22029.669554956647;22243.332700018986;22398.88800555995;22525.77474225257;5043.52086551777;5998.800239952027;6175.197224360827;6375.043743438475;6394.26465956414;6145.692715364236;6258.468030217414;6282.120503129566;6309.905351419732;6312.558120931537;6042;6230.28351739549;6240.188775459206;6270.184875966118;6320.051992201161;6257.4368306852375;6299.433051025429;6306.716197771088;6317;6320.620762754226;5524.751386187631;5545.944540554583;5583.441655834425;5584.972075139639;5630.690312032836;26669.73330266711;26913;26963.730362696515;27378.589321160198;27567.44865102687;2195.9560808783895;2247.494313779403;2258.8531745436494;2268.2514770125817;2272.091163534583;13774.173549587056;14704.411823527153;17262.395816146374;18446.077696115142;18589.535261618566;1838.586318078435;2044.642187617161;2094.2984100326407;2156.4177672028463;2171.4896999205175;148626;149971;150203;151673.72489412667;153040.9999999989;12110.273383597012;12651.620451386472;12730.618081457571;12744.299063551594;12780.233186008869;16272.477765946298;16521.43046410589;16549.91725041367;16575.088370139758;16583.50249492517;8939.061398553189;9153.725388238363;9221.49281789508;9229.53852307382;9310.906890931139;7277.053982982178;7279.5996220207335;8758.56207189638;8818.470891746516;8959.865602015974;7884.605769711491;8001.1198768135455;8017.679292828221;8022.318102961197;8037.437379383504;2164.14516266074;2183.1376606240447;2185.737711474633;2187.8687278763327;2204.3026869310543;950.9429434233969;953.142172045151;953.1945506047379;955.3455882720359;1004.2367800471694;19767.505812354808;21250.043748031065;21272.468188295108;21409.892950535148;21625.134994600354;984.4585477987079;992.4095163377779;1005.7737009172878;1034.524118905297;1044.7022598559374;9623.326367154372;9849.950750246346;10562.996515331031;10892.525360915026;11025.83461248082;604642;710030;710990.5603775791;728529;729122;129028;156828;159357;163407;164634;1193.6061099837034;1202.0984261803646;1245.3026305268897;1246.2896149194871;1247.7255003899309;21587.784122159206;21780.128794848664;21806.20263542018;21972.780272197073;22231.221907233168;63501.68249158725;63749.35007798908;64888.70222595616;65038;65757.67121164364;90164.09835901557;90169;90308.64537031819;90862.63706044282;91478.13913956347;7515.774526764206;8602.61288242018;8717.694880679146;8766.649334026823;8923.196912277926;9958.651447199603;12482.375881206084;12835.908947739546;13678.31608419555;14027;8992.820143597222;11636.592719254786;11676.474558644712;11862.110341724232;12072.637820865388;10679.733006674895;10755.838662420227;10838.837417438586;10912.290701104721;11186.608468703556;11395.658130256106;11849.703757406134;11876.643700688994;12105;12158.209716368761;7350.117985841734;8735.694250701195;8766.123387661182;8889.822203556021;8897.510636915033;9191.816163676824;10560.471976401303;11274.774504510027;11633;11678.766424671629 07:41:22 INFO - PID 1641 | |3;mootools.html;1358.884494817941;1384.6123085536053;1394.1356359057384;1440.9783853242197;1471.1981969826447;10803.351798892063;10894.564217431292;11161.888381116187;11190.048845848112;11206.943965280172;11030.613928512505;11100.111991040718;11148.21962462628;11203.383813890232;11231.887681123186;2211.661944523564;2442.389402649342;2482.366996415911;2494.5260400523935;2496.613024981131;2096.2139197800825;2380.3573035280465;2391.390195500145;2463.4334103156284;2478.9876050619723;2380.285914225734;2428;2429.8056155507606;2454.1778504201116;2460.987695061522;2375.964360534593;2437.902483900642;2450.5588993981164;2450.742672019439;2472.320111969205;2393.7313223991314;2433.345325178878;2449.865257410842;2451.7548245175517;2476.3437689012426;11148.996590306911;11205.935436133554;11210.831837522444;11240.325580465198;11276;6240.219972503437;6448.45188159007;6464.191976003;6469.579477334005;6501.609903405763;3846;4116.567760385176;4124.175164967019;4194.28697121487;4199;1191.7259030422965;1191.9642410727606;1207.9094067944939;1245.9252444853248;1246.2896149194962;14136.92931535346;14534.781978270334;15331.23343832804;15390.538283851502;15419;5206.947930520646;5265.236540701577;5430.972845135828;5461.2627295315415;5464.9453505464435;11424.971752542311;11524;11558.711032224093;11567;11596.710082248012;6378.649174295366;6393.936060639334;6397.424231819065;6399.584027038228;6399.968000160063;5722.828315150552;5986.880262394728;6003.099535069687;6023.3073196582955;6026.728797204137;4302.612764851178;4345.608895199384;4359.38968544404;4375.321825117112;4425.380446737459;1059.3527065413202;1284.6960335259719;1298.9220646761225;1303.3222724183372;1304.2891624064907;756.5158298688843;769.734441617641;781.2226834299908;783.1502819440857;806.4435539477741;5914.378990206053;5954.136650185699;6061.575689701678;6092.238470191226;6238.251409830849;550.8182299841043;584.6433675457968;591.721890711365;592.2596754057428;604.9788257410969;3184.7611429142908;3445.7415693823064;3562.3587754203973;4009.578994205625;4246.787660616957;13752.793708094387;14226.502072427415;14329.35517901697;14356.85643143576;14403.639909002359;13827.377768000464;13913.860861391257;14301;14306.356213970397;14323.212223327608;10259.17926565873;10410.167186625204;10422.374657520573;10538.631147909786;10541;6226.813195603958;6604.735810567716;6731;6749.156355455568;6838.179418469816;12243.020558355132;12319.69200769988;12402.00783937301;12420.62738117858;12460.190087644274;12754.362281886055;12832.743345132858;12869.806952895528;12984.415701293654;13068;7040.718371265092;8244.093149753402;8278.461899976483;8294.792630184053;8296.294814940557;10079.092881640687;10084.394936303845;10175.542100605639;10185.439800810731;10208.285420020527;7829.099653539915;8467.280281176169;8670.566471676264;8712.562819056473;8794.164554367326;7724.961375193313;8422.410431269753;8471.95764021176;8502.659893604186;8603.95698021531;7362.263773622595;7738.883916741366;7834.0599128105;7903.881441778263;7910.683572657029;4836.854894353175;5289.391719952255;5818.73815678304;5888.558358123244;6130.601510901777;5895.4399332063385;6011.428914253139;6085;6087.086936959491;6204.5036397089025 07:41:22 INFO - PID 1641 | |4;prototype.html;395.2569169960474;402.8026267129107;405.14110086616375;406.2342484416873;413.2499513383207;5098.694078355296;5238.580913526918;6287.245530536331;6317.36826317368;6343.936560634393;5168.22476628505;5394.730263486829;5661.7735290588325;5863.355030946593;6273.3412991635905;4178.853740119104;4444.33334999751;4540.659450541206;4566.58900698937;4593.242115051029;3972.9936382126984;4173.874783756477;4563.155816174002;4606.838760643378;4623.653226008063;4572.690791150464;4578.679492435531;4585.954140458586;4595.540445955411;4602.792874320647;4259.31850903857;4424.513303536608;4502.57225563571;4580.97709511452;4581.839635612754;3388.9830550847087;3887.067103895045;3893.4743809585625;3903.512060992376;3911.0249324091437;6051.697415129226;6053.697315134225;6080.513558915321;6095.603785753956;6133.539984501181;4147.4815648044;5068.974655126701;5318.803269264423;5432.185172224158;5506.311711036121;5710.458176292419;6670.366315200048;6699.129113215299;6701.798946031627;6702.19573651165;4043.6158564936286;4417.757023363713;4428.4243048403505;4432.955670443286;4454.799534020977;6692.263850976388;7008.719651213945;7021.754238601625;7025;7201.4238860891;3744.6068162842507;4524.819007239674;4541.636669066466;4544.750038747835;4552.203364411214;3976.250449802078;4509.098180363941;4637.350770892078;4638.165130276582;4644.256918893028;4699.342092107107;4937.185364414903;5097.923531147036;5412.702301373462;5483.341998960151;6732.081356813383;6737.966310168418;6740.528163028541;6752.818256805138;6760.93239067603;1554.1918202534619;1555.2534783303852;1565.3269094289337;1573.2684301799718;1573.7560678094915;780.0171783552651;832.8292699996487;850.5449584472277;993.4089216915924;1033.5194134727387;5926.555508336892;6481.935180648227;6484.383983521558;6500.089987401768;6515;5782.681952492653;6960.3039696031;6978.895316570255;6996.405305548984;7031.718731250795;5032.6980381177245;5509.173623956439;6283.434490895841;6488.28628850826;6495.090687303781;3058.9847050765047;3615.8192090395373;3633.218857945554;3650.233450975272;3672.559292884871;5655.784006438553;6897.48268879836;6945.89581156283;7192;7225.2774722528375;6297;6994;7267.0552828132;7272.163701174327;7274.981502589641;6133.754649814046;6944.51388402807;7173.210946795848;7208.675609597476;7290;6989.615571143432;7037.296270372922;7045.859082818212;7065.187503436965;7097.574145551077;5046.520580544842;5575.303087114111;5624.634398764068;6097.349388034268;6844.691988860613;6283.560150789401;7022.754203602849;7078.0444639975;7298.080767692965;7307.817304567428;4183.49798024239;4518.548145185455;4521.932171017504;4544.88637784058;4564.201264778584;6407.8718425630295;6601.570897891814;6941.544363741768;7041.612711300722;7051.365377116084;6148.692565371803;6763.526553141233;6772.9163333866345;6790.7623233186605;6845.178578570603;5810.82567522975;6075.605085669417;6462;6744.966275168593;6752.662366881735;6602.372774586406;6621.370969757865;6626.469882409299;6642.900356494753;6667.599944003376;6328.430441260309;6738.663066846736;6794.388505034571;6811.352921472452;6815.42068924147;6425.678716064272;6486.156799616019;6526.738930442729;6537.215534135934;6538.738450461929 07:41:22 INFO - PID 1641 | |5;yui.html;794.3764145146058;822.670931627349;837.158655551171;838.5723281126624;840.3865178419753;15904.284307206175;16208.594785130377;16215.27031283592;16699.83300166998;16808.66382672346;14936.954413191053;15253.084814911113;15335.923320383383;15407.229638518058;15545.766813497807;1001.5442973447082;1012.7721262715903;1014.7463134216446;1017.9236557258198;1019.7705516258857;979.4955597867068;993.3741742702124;996.7408473796793;1011.7217765114616;1029.1098200056933;1088.787686401152;1089.2756317049193;1089.6948854320804;1092.2790957967743;1098.2531878322736;1080.8162612355918;1082.5345101606385;1082.7401423658343;1086.5165001574342;1090.231386872253;711.8006958051753;800.7637746864689;808.6563210635456;810.6514198894473;813.894193754814;15361;15488;15630;16882.577935551588;16996.235169417283;12261.816072758917;14209.928950355285;14835.406583736638;14994.625134371729;15213;12759.170653907466;13084.280364579945;13225.470981160743;13237.404316805767;13478.528251511249;3332.3168750406107;3338;3364.4448665970085;3367.44437167867;3369.124027752802;86743.56628216819;106633;108965;112473;113112.43443782728;10214;11392.772144557062;11674.824877626843;11729.814190029832;11786;18323.267069317346;20102;24028.51942961166;24276.0289588418;24643.137490187757;6882.294629558594;8075.192480751878;8142.755717328371;8188.5905704715715;8318.292945099613;4078.5513593504693;4108.198901214294;4111.239420707178;4178.6448151907425;4181.811818468176;3114.2214446388402;4099.688099808092;4113.526944401432;4120.855770048034;4214.473190851144;779.668640827646;916.3722849847876;920.3787443475627;920.4385324951774;921.2583869984593;589.8731772668897;598.3478008970156;618.2609555841276;625.9436650701459;639.7057353617388;9818.558164882597;10234;10387.636432724817;10413.635522756667;10506.684799456028;589.9351071382144;596.3887015808831;624.9095328651532;626.4017862940881;657.3262406033758;3798.3352913240074;4500.819967201341;4721.5750582447745;4771.355866957916;4785.521447855256;76950;95574;95629.52185239168;96878;97145.51427242819;77435;95952.52023739836;96338.55492167623;96999;99489;53910.99157109551;64306;79012;80613.59693201496;82926;8941.731748047569;10104.292699510963;10764.838527421938;11246.493907774005;11268.887311126784;37751;39129.41305880357;39760.60239397685;39915.40126898157;40097;39127.804360978014;50942.490575093776;51160.97678046344;51235;51308.48691513186;13218.20989042276;13551.52569660057;13553.390097445836;13558.389872455566;14037.719245615235;14150.646233844236;14304.785428218793;14409.855901441271;14651.63370915693;14932.850671493581;13274.137181083199;13310;13926.791098133739;13938.641076527862;13981.720365592835;13559.118657287245;13782.517611883535;13794.37925293344;14195.43218271299;14638.121712696846;13347.933260333637;13357.933210333886;13463.394147263201;13553.593392197856;14401.351939162972;9647.951760241154;9755.414675119515;9825.606975720892;10012.64955726546;10392.636257730943;10413.271071024956;11023.645511028166;11115.444227788417;11268.267562608404;11339.376334301693 07:41:22 INFO - PID 1641 | __end_tp_report 07:41:22 INFO - PID 1641 | __start_cc_report 07:41:22 INFO - PID 1641 | _x_x_mozilla_cycle_collect,426 07:41:22 INFO - PID 1641 | __end_cc_report 07:41:22 INFO - PID 1641 | __startTimestamp1491576082135__endTimestamp 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | ------- Summary: start ------- 07:41:22 INFO - PID 1641 | Number of tests: 6 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6259.98 Median:6494.00 stddev:759.08 (11.7%) stddev-sans-first:732.75 07:41:22 INFO - PID 1641 | Values: 3626.1 3631.1 3671.5 3681.5 3682.9 6156.8 6271.4 6302.4 6321.0 6344.2 6310.5 6340.4 6352.4 6355.9 6397.7 5151.7 5169.3 5462.6 5567.0 5611.5 4779.8 5651.9 5676.3 5702.8 5729.4 5389.4 5455.7 5531.2 5555.3 5562.0 4558.6 4754.2 5483.3 5586.0 5605.2 5527.2 5549.3 5552.1 5558.4 5573.1 6093.1 6349.7 6354.6 6355.8 6356.6 6576.5 6627.7 6707.6 6780.3 6803.1 6832.7 6915.3 6918.2 6921.2 6947.1 6300.7 6393.1 6394.8 6403.3 6409.8 6766.0 6906.4 6980.9 7036.2 7101.8 6691.0 6737.4 6761.4 6763.7 6773.0 6824.1 6868.8 6913.8 6949.6 6953.1 6504.0 6609.6 6693.6 6742.4 6819.7 6758.9 6797.0 6813.8 6856.9 6874.6 5478.6 5482.4 5487.1 5501.3 5507.8 4749.1 4763.9 4771.4 4779.8 4811.0 6179.7 6357.4 6407.6 6414.6 6459.1 5700.9 6850.6 6861.5 6862.3 6863.6 6254.9 6381.8 6410.3 6425.4 6484.5 6100.2 6112.8 6137.4 6141.3 6146.3 7029.6 7066.1 7077.8 7105.0 7111.0 6586.6 6642.5 6743.5 6896.7 6931.2 7004.7 7049.9 7076.1 7083.5 7100.3 5608.9 6234.3 6270.0 6798.1 6798.5 6599.4 6783.9 6794.2 6889.2 6928.1 6601.3 6620.4 6689.3 6698.9 6703.8 6583.5 6664.9 6685.1 6692.1 6698.8 6228.5 6334.7 6372.4 6522.1 6538.1 6645.2 6683.8 6706.5 6712.2 6732.3 6494.0 6506.3 6516.4 6519.3 6525.9 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:45814.48 Median:34398.24 stddev:84607.09 (246.0%) stddev-sans-first:84776.94 07:41:22 INFO - PID 1641 | Values: 805.8 833.6 1097.8 1126.3 1281.6 28792.9 37020.4 37059.6 37223.8 37917.0 36720.6 37006.4 37048.1 37086.1 37115.8 7865.8 8031.0 8033.4 8327.3 8575.9 8554.1 8628.0 8666.8 8677.0 8681.2 8394.2 8598.6 8628.7 8662.5 8664.5 7475.7 8625.0 8629.1 8673.2 8691.3 8399.2 8425.0 8446.1 8475.4 8486.1 29578.1 35716.1 35738.0 36048.5 36179.1 3456.8 3459.4 3499.3 3500.0 3500.4 29216.4 29537.3 29654.9 29682.0 29723.6 3063.5 3107.4 3131.9 3140.7 3142.7 290024.0 292372.0 292735.0 292781.5 294704.5 38477.6 38843.8 46381.6 47357.8 47630.3 44777.6 48066.3 48099.5 48100.3 48120.0 14241.7 14305.3 14415.3 14427.3 14430.2 11671.5 11696.8 11698.8 11700.9 11713.7 12897.2 12899.9 12946.5 12947.2 13016.1 5424.3 5925.3 5962.9 5979.1 6000.8 903.5 986.8 989.4 995.2 1027.3 20094.5 20597.0 21882.9 23577.6 23597.8 950.0 1090.5 1096.4 1124.2 1139.1 9659.4 11703.7 12054.8 12072.2 12093.7 345036.0 346143.0 346256.5 347792.0 348429.0 50390.5 50521.0 50654.7 50672.7 50754.7 1481.3 1514.3 1519.5 1531.1 1543.4 20982.5 22706.0 23260.4 23279.3 23324.8 36150.6 36371.5 36509.3 36519.8 36600.1 318255.4 322570.0 323851.0 324375.0 324940.0 30844.2 30964.5 31114.0 31625.7 31689.2 41191.8 41353.0 41517.6 41546.6 41715.8 28112.4 28326.4 29248.1 29492.9 29603.6 28505.0 28803.7 29335.4 29403.6 30116.9 33909.3 34292.8 34503.7 34602.0 34652.0 28988.9 29092.0 29264.3 29676.7 29909.6 22276.0 22493.0 23291.2 23521.5 23751.6 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:40767.73 Median:12392.31 stddev:117176.62 (945.6%) stddev-sans-first:117466.18 07:41:22 INFO - PID 1641 | Values: 425.4 432.5 437.5 448.2 453.9 21219.8 21376.0 21870.1 22097.4 22266.8 21866.5 22029.7 22243.3 22398.9 22525.8 5043.5 5998.8 6175.2 6375.0 6394.3 6145.7 6258.5 6282.1 6309.9 6312.6 6042.0 6230.3 6240.2 6270.2 6320.1 6257.4 6299.4 6306.7 6317.0 6320.6 5524.8 5545.9 5583.4 5585.0 5630.7 26669.7 26913.0 26963.7 27378.6 27567.4 2196.0 2247.5 2258.9 2268.3 2272.1 13774.2 14704.4 17262.4 18446.1 18589.5 1838.6 2044.6 2094.3 2156.4 2171.5 148626.0 149971.0 150203.0 151673.7 153041.0 12110.3 12651.6 12730.6 12744.3 12780.2 16272.5 16521.4 16549.9 16575.1 16583.5 8939.1 9153.7 9221.5 9229.5 9310.9 7277.1 7279.6 8758.6 8818.5 8959.9 7884.6 8001.1 8017.7 8022.3 8037.4 2164.1 2183.1 2185.7 2187.9 2204.3 950.9 953.1 953.2 955.3 1004.2 19767.5 21250.0 21272.5 21409.9 21625.1 984.5 992.4 1005.8 1034.5 1044.7 9623.3 9850.0 10563.0 10892.5 11025.8 604642.0 710030.0 710990.6 728529.0 729122.0 129028.0 156828.0 159357.0 163407.0 164634.0 1193.6 1202.1 1245.3 1246.3 1247.7 21587.8 21780.1 21806.2 21972.8 22231.2 63501.7 63749.4 64888.7 65038.0 65757.7 90164.1 90169.0 90308.6 90862.6 91478.1 7515.8 8602.6 8717.7 8766.6 8923.2 9958.7 12482.4 12835.9 13678.3 14027.0 8992.8 11636.6 11676.5 11862.1 12072.6 10679.7 10755.8 10838.8 10912.3 11186.6 11395.7 11849.7 11876.6 12105.0 12158.2 7350.1 8735.7 8766.1 8889.8 8897.5 9191.8 10560.5 11274.8 11633.0 11678.8 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6796.73 Median:2477.67 stddev:4249.65 (171.5%) stddev-sans-first:4241.92 07:41:22 INFO - PID 1641 | Values: 1358.9 1384.6 1394.1 1441.0 1471.2 10803.4 10894.6 11161.9 11190.0 11206.9 11030.6 11100.1 11148.2 11203.4 11231.9 2211.7 2442.4 2482.4 2494.5 2496.6 2096.2 2380.4 2391.4 2463.4 2479.0 2380.3 2428.0 2429.8 2454.2 2461.0 2376.0 2437.9 2450.6 2450.7 2472.3 2393.7 2433.3 2449.9 2451.8 2476.3 11149.0 11205.9 11210.8 11240.3 11276.0 6240.2 6448.5 6464.2 6469.6 6501.6 3846.0 4116.6 4124.2 4194.3 4199.0 1191.7 1192.0 1207.9 1245.9 1246.3 14136.9 14534.8 15331.2 15390.5 15419.0 5206.9 5265.2 5431.0 5461.3 5464.9 11425.0 11524.0 11558.7 11567.0 11596.7 6378.6 6393.9 6397.4 6399.6 6400.0 5722.8 5986.9 6003.1 6023.3 6026.7 4302.6 4345.6 4359.4 4375.3 4425.4 1059.4 1284.7 1298.9 1303.3 1304.3 756.5 769.7 781.2 783.2 806.4 5914.4 5954.1 6061.6 6092.2 6238.3 550.8 584.6 591.7 592.3 605.0 3184.8 3445.7 3562.4 4009.6 4246.8 13752.8 14226.5 14329.4 14356.9 14403.6 13827.4 13913.9 14301.0 14306.4 14323.2 10259.2 10410.2 10422.4 10538.6 10541.0 6226.8 6604.7 6731.0 6749.2 6838.2 12243.0 12319.7 12402.0 12420.6 12460.2 12754.4 12832.7 12869.8 12984.4 13068.0 7040.7 8244.1 8278.5 8294.8 8296.3 10079.1 10084.4 10175.5 10185.4 10208.3 7829.1 8467.3 8670.6 8712.6 8794.2 7725.0 8422.4 8472.0 8502.7 8604.0 7362.3 7738.9 7834.1 7903.9 7910.7 4836.9 5289.4 5818.7 5888.6 6130.6 5895.4 6011.4 6085.0 6087.1 6204.5 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5368.19 Median:6088.06 stddev:1734.16 (28.5%) stddev-sans-first:1698.38 07:41:22 INFO - PID 1641 | Values: 395.3 402.8 405.1 406.2 413.2 5098.7 5238.6 6287.2 6317.4 6343.9 5168.2 5394.7 5661.8 5863.4 6273.3 4178.9 4444.3 4540.7 4566.6 4593.2 3973.0 4173.9 4563.2 4606.8 4623.7 4572.7 4578.7 4586.0 4595.5 4602.8 4259.3 4424.5 4502.6 4581.0 4581.8 3389.0 3887.1 3893.5 3903.5 3911.0 6051.7 6053.7 6080.5 6095.6 6133.5 4147.5 5069.0 5318.8 5432.2 5506.3 5710.5 6670.4 6699.1 6701.8 6702.2 4043.6 4417.8 4428.4 4433.0 4454.8 6692.3 7008.7 7021.8 7025.0 7201.4 3744.6 4524.8 4541.6 4544.8 4552.2 3976.3 4509.1 4637.4 4638.2 4644.3 4699.3 4937.2 5097.9 5412.7 5483.3 6732.1 6738.0 6740.5 6752.8 6760.9 1554.2 1555.3 1565.3 1573.3 1573.8 780.0 832.8 850.5 993.4 1033.5 5926.6 6481.9 6484.4 6500.1 6515.0 5782.7 6960.3 6978.9 6996.4 7031.7 5032.7 5509.2 6283.4 6488.3 6495.1 3059.0 3615.8 3633.2 3650.2 3672.6 5655.8 6897.5 6945.9 7192.0 7225.3 6297.0 6994.0 7267.1 7272.2 7275.0 6133.8 6944.5 7173.2 7208.7 7290.0 6989.6 7037.3 7045.9 7065.2 7097.6 5046.5 5575.3 5624.6 6097.3 6844.7 6283.6 7022.8 7078.0 7298.1 7307.8 4183.5 4518.5 4521.9 4544.9 4564.2 6407.9 6601.6 6941.5 7041.6 7051.4 6148.7 6763.5 6772.9 6790.8 6845.2 5810.8 6075.6 6462.0 6745.0 6752.7 6602.4 6621.4 6626.5 6642.9 6667.6 6328.4 6738.7 6794.4 6811.4 6815.4 6425.7 6486.2 6526.7 6537.2 6538.7 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:19533.18 Median:17659.75 stddev:27693.65 (156.8%) stddev-sans-first:27735.59 07:41:22 INFO - PID 1641 | Values: 794.4 822.7 837.2 838.6 840.4 15904.3 16208.6 16215.3 16699.8 16808.7 14937.0 15253.1 15335.9 15407.2 15545.8 1001.5 1012.8 1014.7 1017.9 1019.8 979.5 993.4 996.7 1011.7 1029.1 1088.8 1089.3 1089.7 1092.3 1098.3 1080.8 1082.5 1082.7 1086.5 1090.2 711.8 800.8 808.7 810.7 813.9 15361.0 15488.0 15630.0 16882.6 16996.2 12261.8 14209.9 14835.4 14994.6 15213.0 12759.2 13084.3 13225.5 13237.4 13478.5 3332.3 3338.0 3364.4 3367.4 3369.1 86743.6 106633.0 108965.0 112473.0 113112.4 10214.0 11392.8 11674.8 11729.8 11786.0 18323.3 20102.0 24028.5 24276.0 24643.1 6882.3 8075.2 8142.8 8188.6 8318.3 4078.6 4108.2 4111.2 4178.6 4181.8 3114.2 4099.7 4113.5 4120.9 4214.5 779.7 916.4 920.4 920.4 921.3 589.9 598.3 618.3 625.9 639.7 9818.6 10234.0 10387.6 10413.6 10506.7 589.9 596.4 624.9 626.4 657.3 3798.3 4500.8 4721.6 4771.4 4785.5 76950.0 95574.0 95629.5 96878.0 97145.5 77435.0 95952.5 96338.6 96999.0 99489.0 53911.0 64306.0 79012.0 80613.6 82926.0 8941.7 10104.3 10764.8 11246.5 11268.9 37751.0 39129.4 39760.6 39915.4 40097.0 39127.8 50942.5 51161.0 51235.0 51308.5 13218.2 13551.5 13553.4 13558.4 14037.7 14150.6 14304.8 14409.9 14651.6 14932.9 13274.1 13310.0 13926.8 13938.6 13981.7 13559.1 13782.5 13794.4 14195.4 14638.1 13347.9 13357.9 13463.4 13553.6 14401.4 9648.0 9755.4 9825.6 10012.6 10392.6 10413.3 11023.6 11115.4 11268.3 11339.4 07:41:22 INFO - PID 1641 | -------- Summary: end -------- 07:41:22 INFO - PID 1641 | 07:41:22 INFO - PID 1641 | 1491576082165 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 07:41:22 INFO - TEST-INFO | 1641: exit 0 07:41:22 INFO - TEST-OK | dromaeo_css | took 1117084ms 07:41:22 INFO - TEST-START | kraken 07:41:22 INFO - operating with platform_type : mac_ 07:41:22 INFO - Initialising browser for kraken test... 07:41:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpRu0BkO/profile http://localhost:49241/getInfo.html) 07:41:23 INFO - PID 1653 | 1491576083375 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 07:41:23 INFO - PID 1653 | 1491576083376 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 07:41:23 INFO - PID 1653 | 1491576083426 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 07:41:25 INFO - PID 1653 | 2017-04-07 07:41:25.585 plugin-container[1654:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 07:41:25 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:25 INFO - PID 1653 | 2017-04-07 07:41:25.590 plugin-container[1654:10222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:41:25 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:25 INFO - PID 1653 | 2017-04-07 07:41:25.590 plugin-container[1654:10222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:41:25 INFO - PID 1653 | __metrics Screen width/height:1600/1200 07:41:25 INFO - PID 1653 | colorDepth:24 07:41:25 INFO - PID 1653 | Browser inner width/height: 1024/689 07:41:25 INFO - PID 1653 | __metrics 07:41:25 ERROR - PID 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 07:41:25 INFO - PID 1653 | 1491576085762 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 07:41:26 INFO - TEST-INFO | 1653: exit 0 07:41:26 INFO - Browser initialized. 07:41:26 INFO - Running cycle 1/1 for kraken test... 07:41:26 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpRu0BkO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:41:27 INFO - PID 1655 | 1491576087084 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 07:41:27 INFO - PID 1655 | 1491576087085 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 07:41:29 INFO - PID 1655 | 2017-04-07 07:41:29.278 plugin-container[1656:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 07:41:29 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:29 INFO - PID 1655 | 2017-04-07 07:41:29.278 plugin-container[1656:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 07:41:29 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:41:29 INFO - PID 1655 | 2017-04-07 07:41:29.278 plugin-container[1656:10348] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:41:38 INFO - PID 1655 | RSS: Main: 233484288 07:41:38 INFO - PID 1655 | 07:41:41 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 07:41:42 INFO - PID 1655 | RSS: Main: 248606720 07:41:42 INFO - PID 1655 | 07:41:45 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 07:41:46 INFO - PID 1655 | RSS: Main: 257155072 07:41:46 INFO - PID 1655 | 07:41:49 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 07:41:50 INFO - PID 1655 | RSS: Main: 259608576 07:41:50 INFO - PID 1655 | 07:41:52 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 07:41:52 INFO - PID 1655 | RSS: Main: 260542464 07:41:52 INFO - PID 1655 | 07:41:55 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:41:55 INFO - PID 1655 | RSS: Main: 260886528 07:41:55 INFO - PID 1655 | 07:41:58 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 07:41:59 INFO - PID 1655 | RSS: Main: 267472896 07:41:59 INFO - PID 1655 | 07:42:02 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 07:42:03 INFO - PID 1655 | RSS: Main: 269021184 07:42:03 INFO - PID 1655 | 07:42:06 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 07:42:07 INFO - PID 1655 | RSS: Main: 268787712 07:42:07 INFO - PID 1655 | 07:42:08 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:42:09 INFO - PID 1655 | RSS: Main: 267927552 07:42:09 INFO - PID 1655 | 07:42:11 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:42:11 INFO - PID 1655 | RSS: Main: 267939840 07:42:11 INFO - PID 1655 | 07:42:14 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:42:14 INFO - PID 1655 | RSS: Main: 267968512 07:42:14 INFO - PID 1655 | 07:42:17 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:42:18 INFO - PID 1655 | RSS: Main: 268513280 07:42:18 INFO - PID 1655 | 07:42:22 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:42:22 INFO - PID 1655 | RSS: Main: 265351168 07:42:22 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 07:42:24 INFO - PID 1655 | RSS: Main: 268738560 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | __start_tp_report 07:42:24 INFO - PID 1655 | _x_x_mozilla_page_load 07:42:24 INFO - PID 1655 | _x_x_mozilla_page_load_details 07:42:24 INFO - PID 1655 | |i|pagename|runs| 07:42:24 INFO - PID 1655 | |0;ai-astar;109;123;152;184;143;124;122;122;119;119 07:42:24 INFO - PID 1655 | |1;audio-beat-detection;189;150;114;156;157;157;104;162;184;97 07:42:24 INFO - PID 1655 | |2;audio-dft;223;223;221;214;242;214;208;216;204;210 07:42:24 INFO - PID 1655 | |3;audio-fft;87;61;59;92;59;64;60;60;76;59 07:42:24 INFO - PID 1655 | |4;audio-oscillator;92;88;106;110;113;78;86;71;74;87 07:42:24 INFO - PID 1655 | |5;imaging-gaussian-blur;90;90;98;106;90;90;90;91;99;92 07:42:24 INFO - PID 1655 | |6;imaging-darkroom;100;101;101;101;101;100;143;135;100;100 07:42:24 INFO - PID 1655 | |7;imaging-desaturate;155;164;101;95;161;101;99;106;101;104 07:42:24 INFO - PID 1655 | |8;json-parse-financial;50;51;52;51;63;52;52;52;51;52 07:42:24 INFO - PID 1655 | |9;json-stringify-tinderbox;59;60;64;69;67;71;87;92;60;58 07:42:24 INFO - PID 1655 | |10;stanford-crypto-aes;90;86;88;88;88;91;106;118;118;80 07:42:24 INFO - PID 1655 | |11;stanford-crypto-ccm;143;140;155;173;171;176;169;149;222;170 07:42:24 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;200;204;196;193;196;245;238;265;202;246 07:42:24 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;74;73;76;107;106;77;76;72;71;71 07:42:24 INFO - PID 1655 | __end_tp_report 07:42:24 INFO - PID 1655 | __start_cc_report 07:42:24 INFO - PID 1655 | _x_x_mozilla_cycle_collect,995 07:42:24 INFO - PID 1655 | __end_cc_report 07:42:24 INFO - PID 1655 | __startTimestamp1491576144876__endTimestamp 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | ------- Summary: start ------- 07:42:24 INFO - PID 1655 | Number of tests: 14 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:131.70 Median:123.50 stddev:22.20 (18.0%) stddev-sans-first:21.98 07:42:24 INFO - PID 1655 | Values: 109.0 123.0 152.0 184.0 143.0 124.0 122.0 122.0 119.0 119.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:159.50 stddev:31.77 (19.9%) stddev-sans-first:29.85 07:42:24 INFO - PID 1655 | Values: 189.0 150.0 114.0 156.0 157.0 157.0 104.0 162.0 184.0 97.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:217.50 Median:218.50 stddev:10.69 (4.9%) stddev-sans-first:11.15 07:42:24 INFO - PID 1655 | Values: 223.0 223.0 221.0 214.0 242.0 214.0 208.0 216.0 204.0 210.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:67.70 Median:62.50 stddev:12.63 (20.2%) stddev-sans-first:11.30 07:42:24 INFO - PID 1655 | Values: 87.0 61.0 59.0 92.0 59.0 64.0 60.0 60.0 76.0 59.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:82.00 stddev:14.82 (18.1%) stddev-sans-first:15.71 07:42:24 INFO - PID 1655 | Values: 92.0 88.0 106.0 110.0 113.0 78.0 86.0 71.0 74.0 87.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:93.60 Median:90.50 stddev:5.54 (6.1%) stddev-sans-first:5.72 07:42:24 INFO - PID 1655 | Values: 90.0 90.0 98.0 106.0 90.0 90.0 90.0 91.0 99.0 92.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:101.00 stddev:16.35 (16.2%) stddev-sans-first:17.07 07:42:24 INFO - PID 1655 | Values: 100.0 101.0 101.0 101.0 101.0 100.0 143.0 135.0 100.0 100.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:118.70 Median:158.00 stddev:28.72 (18.2%) stddev-sans-first:27.30 07:42:24 INFO - PID 1655 | Values: 155.0 164.0 101.0 95.0 161.0 101.0 99.0 106.0 101.0 104.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.00 stddev:3.72 (7.1%) stddev-sans-first:3.82 07:42:24 INFO - PID 1655 | Values: 50.0 51.0 52.0 51.0 63.0 52.0 52.0 52.0 51.0 52.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:68.00 stddev:11.87 (17.5%) stddev-sans-first:12.06 07:42:24 INFO - PID 1655 | Values: 59.0 60.0 64.0 69.0 67.0 71.0 87.0 92.0 60.0 58.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:88.00 stddev:13.63 (15.5%) stddev-sans-first:14.32 07:42:24 INFO - PID 1655 | Values: 90.0 86.0 88.0 88.0 88.0 91.0 106.0 118.0 118.0 80.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:166.80 Median:170.50 stddev:23.44 (13.7%) stddev-sans-first:23.22 07:42:24 INFO - PID 1655 | Values: 143.0 140.0 155.0 173.0 171.0 176.0 169.0 149.0 222.0 170.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:218.50 Median:221.00 stddev:26.85 (12.1%) stddev-sans-first:27.63 07:42:24 INFO - PID 1655 | Values: 200.0 204.0 196.0 193.0 196.0 245.0 238.0 265.0 202.0 246.0 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.30 Median:73.50 stddev:13.97 (19.0%) stddev-sans-first:14.63 07:42:24 INFO - PID 1655 | Values: 74.0 73.0 76.0 107.0 106.0 77.0 76.0 72.0 71.0 71.0 07:42:24 INFO - PID 1655 | -------- Summary: end -------- 07:42:24 INFO - PID 1655 | 07:42:24 INFO - PID 1655 | 1491576144902 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 07:42:26 INFO - TEST-INFO | 1655: exit 0 07:42:26 INFO - TEST-OK | kraken | took 63324ms 07:42:26 INFO - SUITE-END | took 1180s 07:42:26 INFO - Completed test suite (00:19:40) 07:42:26 INFO - javascript benchmark 07:42:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7729.089121641982, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3626.129728865072, 3631.05592545938, 3671.467637192608, 3681.4845921570986, 3682.8710995115175, 6156.815295541141, 6271.372862713726, 6302.432781049704, 6320.968395158029, 6344.238691357038, 6310.52671049672, 6340.3976622220935, 6352.364763523646, 6355.904661430082, 6397.712102955378, 5151.665141765773, 5169.250458683488, 5462.553310215625, 5566.997940370732, 5611.466910643482, 4779.761011949406, 5651.943480565204, 5676.347220069683, 5702.77188912443, 5729.369769325392, 5389.380221274564, 5455.699936503511, 5531.170324451325, 5555.3333599967855, 5561.971035358431, 4558.566936141061, 4754.191787396151, 5483.287172667568, 5586.0, 5605.215269862182, 5527.226188333637, 5549.306336707911, 5552.056150454432, 5558.44415558445, 5573.08044172711, 6093.11649810775, 6349.682515874234, 6354.5551811373225, 6355.777547785851, 6356.618602883842, 6576.5067619928695, 6627.668616569153, 6707.564008339491, 6780.288069752654, 6803.081583986148, 6832.658367081626, 6915.273896240873, 6918.238993710688, 6921.238663746984, 6947.131608548932, 6300.653464059475, 6393.104965304908, 6394.808155755335, 6403.263624683128, 6409.807705768834, 6765.9661701691175, 6906.412954898889, 6980.930190698028, 7036.226015138331, 7101.82245443868, 6691.0, 6737.427318677869, 6761.391474767294, 6763.729450821912, 6773.0, 6824.078749368871, 6868.793936181923, 6913.792586222321, 6949.583025018515, 6953.096097507291, 6503.967480162569, 6609.603423794588, 6693.564918280394, 6742.359475849787, 6819.727210911508, 6758.8648227036165, 6796.966015169893, 6813.761518346834, 6856.862862742717, 6874.553154044971, 5478.58910581708, 5482.396936337, 5487.149491828773, 5501.284832971768, 5507.834764957058, 4749.121412538691, 4763.857084287477, 4771.403574553181, 4779.761011949388, 4811.013742182844, 6179.69101544921, 6357.364263573606, 6407.615543067431, 6414.550981431348, 6459.09572659828, 5700.9429905701245, 6850.588964662136, 6861.4853885958755, 6862.279460656559, 6863.588184708933, 6254.874902502015, 6381.840453988594, 6410.262819775786, 6425.421712045938, 6484.5460817742305, 6100.23747031621, 6112.807455516499, 6137.41694539018, 6141.315210873529, 6146.293176284695, 7029.613371264425, 7066.081409416743, 7077.752278670222, 7104.964475177591, 7111.004459375588, 6586.637734924625, 6642.468602511691, 6743.460523158037, 6896.724131034903, 6931.1682598088555, 7004.684789184601, 7049.859002820018, 7076.080109585927, 7083.468739844429, 7100.254473280438, 5608.85977850557, 6234.345393733593, 6269.968650156902, 6798.082258895183, 6798.52410331272, 6599.373059559334, 6783.898241526283, 6794.150731158606, 6889.242183359726, 6928.064711263914, 6601.273859875506, 6620.437262832713, 6689.2976237496305, 6698.899516507159, 6703.798886033427, 6583.506237032146, 6664.933350666432, 6685.064091027163, 6692.0965669635925, 6698.83252918681, 6228.501719862491, 6334.6832658365975, 6372.36276372359, 6522.086907832812, 6538.084668146558, 6645.169353830771, 6683.799486015428, 6706.463482921452, 6712.194536655633, 6732.293109223461, 6494.0, 6506.25178104524, 6516.413522782972, 6519.348065193442, 6525.934740652533], "value": 6208.644719467583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.8388322335533, 833.5707110837919, 1097.7804439112178, 1126.3467189030364, 1281.5706738242689, 28792.85603571982, 37020.44469332962, 37059.629403705956, 37223.81388093059, 37917.0, 36720.632793672055, 37006.4449033264, 37048.07379815506, 37086.072848178745, 37115.814420927854, 7865.842683146348, 8031.0, 8033.437659363847, 8327.292180164693, 8575.871361929576, 8554.101819308977, 8627.95686021569, 8666.826663466727, 8677.045524992245, 8681.21869031787, 8394.24451799338, 8598.570071496431, 8628.741137765877, 8662.480251184918, 8664.523451210182, 7475.738349157753, 8625.00812406575, 8629.093945135764, 8673.219410253045, 8691.34814888886, 8399.244068033906, 8425.0, 8446.11315811837, 8475.406721529496, 8486.0665326814, 29578.11265662012, 35716.107097322514, 35738.0, 36048.45927311092, 36179.09552261188, 3456.792592444461, 3459.41189997701, 3499.3001399720156, 3499.9650003500146, 3500.43992961125, 29216.41567168666, 29537.26156846096, 29654.85172574145, 29681.961131360516, 29723.554146687817, 3063.494523403636, 3107.39405815866, 3131.874725010997, 3140.670229625879, 3142.701443362877, 290024.0, 292372.0, 292735.0, 292781.5360923182, 294704.5264773684, 38477.61522384796, 38843.80578097091, 46381.6085517435, 47357.81605459891, 47630.28554571684, 44777.55222447799, 48066.27900581494, 48099.5190048102, 48100.27849582259, 48120.0, 14241.715165696629, 14305.28473576317, 14415.279236038155, 14427.278636068155, 14430.206338651265, 11671.474783634756, 11696.766064678659, 11698.766024679458, 11700.94691477771, 11713.707157320965, 12897.226166430044, 12899.90350820172, 12946.51941928279, 12947.223166610034, 13016.088873778745, 5424.294841670558, 5925.318588362394, 5962.910556341659, 5979.103134529768, 6000.849978750566, 903.5437104262305, 986.8026394721085, 989.4014121456415, 995.1640621877658, 1027.2501074215716, 20094.497637558885, 20597.0, 21882.890585547288, 23577.646335304984, 23597.764022359555, 950.0, 1090.5419723716059, 1096.3915027160003, 1124.2018167101287, 1139.1058019454688, 9659.420434773936, 11703.707407314885, 12054.819177712341, 12072.15494915365, 12093.697657558632, 345036.0, 346143.0, 346256.5374346275, 347792.0, 348429.0, 50390.49609503858, 50521.0, 50654.74672626613, 50672.74663626732, 50754.74622626863, 1481.2963842174881, 1514.2807166595999, 1519.4529969211076, 1531.0660497096762, 1543.428931295428, 20982.531222814257, 22706.0, 23260.418489537897, 23279.254055945534, 23324.76675233226, 36150.63849361578, 36371.45442818413, 36509.26981460303, 36519.81740091283, 36600.084997874204, 318255.4087229549, 322570.0, 323851.0, 324375.0, 324940.0, 30844.22889427782, 30964.535531966587, 31114.0, 31625.683743163194, 31689.20776980594, 41191.7940410296, 41353.0, 41517.58482415258, 41546.58453415427, 41715.7914210427, 28112.43775124527, 28326.433471330045, 29248.122556323346, 29492.852535737184, 29603.555946660388, 28505.0, 28803.71196288094, 29335.413291733617, 29403.558946616246, 30116.945906893154, 33909.321813564085, 34292.82853585716, 34503.65496345004, 34601.9619411408, 34652.0, 28988.855055724587, 29092.0, 29264.26839329034, 29676.703232967393, 29909.551356729233, 22276.0, 22493.0, 23291.184808532296, 23521.529569408172, 23751.643725343787], "value": 17102.268164361514, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.42567533829316, 432.4829492354498, 437.5449532486214, 448.20220008385076, 453.9273716205408, 21219.787802121973, 21376.038078286496, 21870.1251949922, 22097.447563810914, 22266.777332226673, 21866.453338666543, 22029.669554956647, 22243.332700018986, 22398.88800555995, 22525.77474225257, 5043.52086551777, 5998.800239952027, 6175.197224360827, 6375.043743438475, 6394.26465956414, 6145.692715364236, 6258.468030217414, 6282.120503129566, 6309.905351419732, 6312.558120931537, 6042.0, 6230.28351739549, 6240.188775459206, 6270.184875966118, 6320.051992201161, 6257.4368306852375, 6299.433051025429, 6306.716197771088, 6317.0, 6320.620762754226, 5524.751386187631, 5545.944540554583, 5583.441655834425, 5584.972075139639, 5630.690312032836, 26669.73330266711, 26913.0, 26963.730362696515, 27378.589321160198, 27567.44865102687, 2195.9560808783895, 2247.494313779403, 2258.8531745436494, 2268.2514770125817, 2272.091163534583, 13774.173549587056, 14704.411823527153, 17262.395816146374, 18446.077696115142, 18589.535261618566, 1838.586318078435, 2044.642187617161, 2094.2984100326407, 2156.4177672028463, 2171.4896999205175, 148626.0, 149971.0, 150203.0, 151673.72489412667, 153040.9999999989, 12110.273383597012, 12651.620451386472, 12730.618081457571, 12744.299063551594, 12780.233186008869, 16272.477765946298, 16521.43046410589, 16549.91725041367, 16575.088370139758, 16583.50249492517, 8939.061398553189, 9153.725388238363, 9221.49281789508, 9229.53852307382, 9310.906890931139, 7277.053982982178, 7279.5996220207335, 8758.56207189638, 8818.470891746516, 8959.865602015974, 7884.605769711491, 8001.1198768135455, 8017.679292828221, 8022.318102961197, 8037.437379383504, 2164.14516266074, 2183.1376606240447, 2185.737711474633, 2187.8687278763327, 2204.3026869310543, 950.9429434233969, 953.142172045151, 953.1945506047379, 955.3455882720359, 1004.2367800471694, 19767.505812354808, 21250.043748031065, 21272.468188295108, 21409.892950535148, 21625.134994600354, 984.4585477987079, 992.4095163377779, 1005.7737009172878, 1034.524118905297, 1044.7022598559374, 9623.326367154372, 9849.950750246346, 10562.996515331031, 10892.525360915026, 11025.83461248082, 604642.0, 710030.0, 710990.5603775791, 728529.0, 729122.0, 129028.0, 156828.0, 159357.0, 163407.0, 164634.0, 1193.6061099837034, 1202.0984261803646, 1245.3026305268897, 1246.2896149194871, 1247.7255003899309, 21587.784122159206, 21780.128794848664, 21806.20263542018, 21972.780272197073, 22231.221907233168, 63501.68249158725, 63749.35007798908, 64888.70222595616, 65038.0, 65757.67121164364, 90164.09835901557, 90169.0, 90308.64537031819, 90862.63706044282, 91478.13913956347, 7515.774526764206, 8602.61288242018, 8717.694880679146, 8766.649334026823, 8923.196912277926, 9958.651447199603, 12482.375881206084, 12835.908947739546, 13678.31608419555, 14027.0, 8992.820143597222, 11636.592719254786, 11676.474558644712, 11862.110341724232, 12072.637820865388, 10679.733006674895, 10755.838662420227, 10838.837417438586, 10912.290701104721, 11186.608468703556, 11395.658130256106, 11849.703757406134, 11876.643700688994, 12105.0, 12158.209716368761, 7350.117985841734, 8735.694250701195, 8766.123387661182, 8889.822203556021, 8897.510636915033, 9191.816163676824, 10560.471976401303, 11274.774504510027, 11633.0, 11678.766424671629], "value": 10588.482095140609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.884494817941, 1384.6123085536053, 1394.1356359057384, 1440.9783853242197, 1471.1981969826447, 10803.351798892063, 10894.564217431292, 11161.888381116187, 11190.048845848112, 11206.943965280172, 11030.613928512505, 11100.111991040718, 11148.21962462628, 11203.383813890232, 11231.887681123186, 2211.661944523564, 2442.389402649342, 2482.366996415911, 2494.5260400523935, 2496.613024981131, 2096.2139197800825, 2380.3573035280465, 2391.390195500145, 2463.4334103156284, 2478.9876050619723, 2380.285914225734, 2428.0, 2429.8056155507606, 2454.1778504201116, 2460.987695061522, 2375.964360534593, 2437.902483900642, 2450.5588993981164, 2450.742672019439, 2472.320111969205, 2393.7313223991314, 2433.345325178878, 2449.865257410842, 2451.7548245175517, 2476.3437689012426, 11148.996590306911, 11205.935436133554, 11210.831837522444, 11240.325580465198, 11276.0, 6240.219972503437, 6448.45188159007, 6464.191976003, 6469.579477334005, 6501.609903405763, 3846.0, 4116.567760385176, 4124.175164967019, 4194.28697121487, 4199.0, 1191.7259030422965, 1191.9642410727606, 1207.9094067944939, 1245.9252444853248, 1246.2896149194962, 14136.92931535346, 14534.781978270334, 15331.23343832804, 15390.538283851502, 15419.0, 5206.947930520646, 5265.236540701577, 5430.972845135828, 5461.2627295315415, 5464.9453505464435, 11424.971752542311, 11524.0, 11558.711032224093, 11567.0, 11596.710082248012, 6378.649174295366, 6393.936060639334, 6397.424231819065, 6399.584027038228, 6399.968000160063, 5722.828315150552, 5986.880262394728, 6003.099535069687, 6023.3073196582955, 6026.728797204137, 4302.612764851178, 4345.608895199384, 4359.38968544404, 4375.321825117112, 4425.380446737459, 1059.3527065413202, 1284.6960335259719, 1298.9220646761225, 1303.3222724183372, 1304.2891624064907, 756.5158298688843, 769.734441617641, 781.2226834299908, 783.1502819440857, 806.4435539477741, 5914.378990206053, 5954.136650185699, 6061.575689701678, 6092.238470191226, 6238.251409830849, 550.8182299841043, 584.6433675457968, 591.721890711365, 592.2596754057428, 604.9788257410969, 3184.7611429142908, 3445.7415693823064, 3562.3587754203973, 4009.578994205625, 4246.787660616957, 13752.793708094387, 14226.502072427415, 14329.35517901697, 14356.85643143576, 14403.639909002359, 13827.377768000464, 13913.860861391257, 14301.0, 14306.356213970397, 14323.212223327608, 10259.17926565873, 10410.167186625204, 10422.374657520573, 10538.631147909786, 10541.0, 6226.813195603958, 6604.735810567716, 6731.0, 6749.156355455568, 6838.179418469816, 12243.020558355132, 12319.69200769988, 12402.00783937301, 12420.62738117858, 12460.190087644274, 12754.362281886055, 12832.743345132858, 12869.806952895528, 12984.415701293654, 13068.0, 7040.718371265092, 8244.093149753402, 8278.461899976483, 8294.792630184053, 8296.294814940557, 10079.092881640687, 10084.394936303845, 10175.542100605639, 10185.439800810731, 10208.285420020527, 7829.099653539915, 8467.280281176169, 8670.566471676264, 8712.562819056473, 8794.164554367326, 7724.961375193313, 8422.410431269753, 8471.95764021176, 8502.659893604186, 8603.95698021531, 7362.263773622595, 7738.883916741366, 7834.0599128105, 7903.881441778263, 7910.683572657029, 4836.854894353175, 5289.391719952255, 5818.73815678304, 5888.558358123244, 6130.601510901777, 5895.4399332063385, 6011.428914253139, 6085.0, 6087.086936959491, 6204.5036397089025], "value": 5119.812634078696, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2569169960474, 402.8026267129107, 405.14110086616375, 406.2342484416873, 413.2499513383207, 5098.694078355296, 5238.580913526918, 6287.245530536331, 6317.36826317368, 6343.936560634393, 5168.22476628505, 5394.730263486829, 5661.7735290588325, 5863.355030946593, 6273.3412991635905, 4178.853740119104, 4444.33334999751, 4540.659450541206, 4566.58900698937, 4593.242115051029, 3972.9936382126984, 4173.874783756477, 4563.155816174002, 4606.838760643378, 4623.653226008063, 4572.690791150464, 4578.679492435531, 4585.954140458586, 4595.540445955411, 4602.792874320647, 4259.31850903857, 4424.513303536608, 4502.57225563571, 4580.97709511452, 4581.839635612754, 3388.9830550847087, 3887.067103895045, 3893.4743809585625, 3903.512060992376, 3911.0249324091437, 6051.697415129226, 6053.697315134225, 6080.513558915321, 6095.603785753956, 6133.539984501181, 4147.4815648044, 5068.974655126701, 5318.803269264423, 5432.185172224158, 5506.311711036121, 5710.458176292419, 6670.366315200048, 6699.129113215299, 6701.798946031627, 6702.19573651165, 4043.6158564936286, 4417.757023363713, 4428.4243048403505, 4432.955670443286, 4454.799534020977, 6692.263850976388, 7008.719651213945, 7021.754238601625, 7025.0, 7201.4238860891, 3744.6068162842507, 4524.819007239674, 4541.636669066466, 4544.750038747835, 4552.203364411214, 3976.250449802078, 4509.098180363941, 4637.350770892078, 4638.165130276582, 4644.256918893028, 4699.342092107107, 4937.185364414903, 5097.923531147036, 5412.702301373462, 5483.341998960151, 6732.081356813383, 6737.966310168418, 6740.528163028541, 6752.818256805138, 6760.93239067603, 1554.1918202534619, 1555.2534783303852, 1565.3269094289337, 1573.2684301799718, 1573.7560678094915, 780.0171783552651, 832.8292699996487, 850.5449584472277, 993.4089216915924, 1033.5194134727387, 5926.555508336892, 6481.935180648227, 6484.383983521558, 6500.089987401768, 6515.0, 5782.681952492653, 6960.3039696031, 6978.895316570255, 6996.405305548984, 7031.718731250795, 5032.6980381177245, 5509.173623956439, 6283.434490895841, 6488.28628850826, 6495.090687303781, 3058.9847050765047, 3615.8192090395373, 3633.218857945554, 3650.233450975272, 3672.559292884871, 5655.784006438553, 6897.48268879836, 6945.89581156283, 7192.0, 7225.2774722528375, 6297.0, 6994.0, 7267.0552828132, 7272.163701174327, 7274.981502589641, 6133.754649814046, 6944.51388402807, 7173.210946795848, 7208.675609597476, 7290.0, 6989.615571143432, 7037.296270372922, 7045.859082818212, 7065.187503436965, 7097.574145551077, 5046.520580544842, 5575.303087114111, 5624.634398764068, 6097.349388034268, 6844.691988860613, 6283.560150789401, 7022.754203602849, 7078.0444639975, 7298.080767692965, 7307.817304567428, 4183.49798024239, 4518.548145185455, 4521.932171017504, 4544.88637784058, 4564.201264778584, 6407.8718425630295, 6601.570897891814, 6941.544363741768, 7041.612711300722, 7051.365377116084, 6148.692565371803, 6763.526553141233, 6772.9163333866345, 6790.7623233186605, 6845.178578570603, 5810.82567522975, 6075.605085669417, 6462.0, 6744.966275168593, 6752.662366881735, 6602.372774586406, 6621.370969757865, 6626.469882409299, 6642.900356494753, 6667.599944003376, 6328.430441260309, 6738.663066846736, 6794.388505034571, 6811.352921472452, 6815.42068924147, 6425.678716064272, 6486.156799616019, 6526.738930442729, 6537.215534135934, 6538.738450461929], "value": 4819.262782819557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.3764145146058, 822.670931627349, 837.158655551171, 838.5723281126624, 840.3865178419753, 15904.284307206175, 16208.594785130377, 16215.27031283592, 16699.83300166998, 16808.66382672346, 14936.954413191053, 15253.084814911113, 15335.923320383383, 15407.229638518058, 15545.766813497807, 1001.5442973447082, 1012.7721262715903, 1014.7463134216446, 1017.9236557258198, 1019.7705516258857, 979.4955597867068, 993.3741742702124, 996.7408473796793, 1011.7217765114616, 1029.1098200056933, 1088.787686401152, 1089.2756317049193, 1089.6948854320804, 1092.2790957967743, 1098.2531878322736, 1080.8162612355918, 1082.5345101606385, 1082.7401423658343, 1086.5165001574342, 1090.231386872253, 711.8006958051753, 800.7637746864689, 808.6563210635456, 810.6514198894473, 813.894193754814, 15361.0, 15488.0, 15630.0, 16882.577935551588, 16996.235169417283, 12261.816072758917, 14209.928950355285, 14835.406583736638, 14994.625134371729, 15213.0, 12759.170653907466, 13084.280364579945, 13225.470981160743, 13237.404316805767, 13478.528251511249, 3332.3168750406107, 3338.0, 3364.4448665970085, 3367.44437167867, 3369.124027752802, 86743.56628216819, 106633.0, 108965.0, 112473.0, 113112.43443782728, 10214.0, 11392.772144557062, 11674.824877626843, 11729.814190029832, 11786.0, 18323.267069317346, 20102.0, 24028.51942961166, 24276.0289588418, 24643.137490187757, 6882.294629558594, 8075.192480751878, 8142.755717328371, 8188.5905704715715, 8318.292945099613, 4078.5513593504693, 4108.198901214294, 4111.239420707178, 4178.6448151907425, 4181.811818468176, 3114.2214446388402, 4099.688099808092, 4113.526944401432, 4120.855770048034, 4214.473190851144, 779.668640827646, 916.3722849847876, 920.3787443475627, 920.4385324951774, 921.2583869984593, 589.8731772668897, 598.3478008970156, 618.2609555841276, 625.9436650701459, 639.7057353617388, 9818.558164882597, 10234.0, 10387.636432724817, 10413.635522756667, 10506.684799456028, 589.9351071382144, 596.3887015808831, 624.9095328651532, 626.4017862940881, 657.3262406033758, 3798.3352913240074, 4500.819967201341, 4721.5750582447745, 4771.355866957916, 4785.521447855256, 76950.0, 95574.0, 95629.52185239168, 96878.0, 97145.51427242819, 77435.0, 95952.52023739836, 96338.55492167623, 96999.0, 99489.0, 53910.99157109551, 64306.0, 79012.0, 80613.59693201496, 82926.0, 8941.731748047569, 10104.292699510963, 10764.838527421938, 11246.493907774005, 11268.887311126784, 37751.0, 39129.41305880357, 39760.60239397685, 39915.40126898157, 40097.0, 39127.804360978014, 50942.490575093776, 51160.97678046344, 51235.0, 51308.48691513186, 13218.20989042276, 13551.52569660057, 13553.390097445836, 13558.389872455566, 14037.719245615235, 14150.646233844236, 14304.785428218793, 14409.855901441271, 14651.63370915693, 14932.850671493581, 13274.137181083199, 13310.0, 13926.791098133739, 13938.641076527862, 13981.720365592835, 13559.118657287245, 13782.517611883535, 13794.37925293344, 14195.43218271299, 14638.121712696846, 13347.933260333637, 13357.933210333886, 13463.394147263201, 13553.593392197856, 14401.351939162972, 9647.951760241154, 9755.414675119515, 9825.606975720892, 10012.64955726546, 10392.636257730943, 10413.271071024956, 11023.645511028166, 11115.444227788417, 11268.267562608404, 11339.376334301693], "value": 7684.583172732429, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1657.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 152.0, 184.0, 143.0, 124.0, 122.0, 122.0, 119.0, 119.0], "value": 131.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 150.0, 114.0, 156.0, 157.0, 157.0, 104.0, 162.0, 184.0, 97.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 223.0, 221.0, 214.0, 242.0, 214.0, 208.0, 216.0, 204.0, 210.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 61.0, 59.0, 92.0, 59.0, 64.0, 60.0, 60.0, 76.0, 59.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 106.0, 110.0, 113.0, 78.0, 86.0, 71.0, 74.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 98.0, 106.0, 90.0, 90.0, 90.0, 91.0, 99.0, 92.0], "value": 93.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 101.0, 101.0, 101.0, 100.0, 143.0, 135.0, 100.0, 100.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 164.0, 101.0, 95.0, 161.0, 101.0, 99.0, 106.0, 101.0, 104.0], "value": 118.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 52.0, 51.0, 63.0, 52.0, 52.0, 52.0, 51.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 64.0, 69.0, 67.0, 71.0, 87.0, 92.0, 60.0, 58.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 88.0, 88.0, 88.0, 91.0, 106.0, 118.0, 118.0, 80.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 155.0, 173.0, 171.0, 176.0, 169.0, 149.0, 222.0, 170.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 204.0, 196.0, 193.0, 196.0, 245.0, 238.0, 265.0, 202.0, 246.0], "value": 218.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 76.0, 107.0, 106.0, 77.0, 76.0, 72.0, 71.0, 71.0], "value": 80.3, "unit": "score"}]}]} 07:42:26 INFO - Return code: 0 07:42:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:42:26 ERROR - # TBPL SUCCESS # 07:42:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:42:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:42:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:42:26 INFO - 2017-04-07 07:42:26.694 screenresolution[1660:10743] starting screenresolution argv=screenresolution get 07:42:26 INFO - 2017-04-07 07:42:26.696 screenresolution[1660:10743] Display 0: 1600x1200x32@60 07:42:26 INFO - 2017-04-07 07:42:26.702 screenresolution[1661:10747] starting screenresolution argv=screenresolution list 07:42:26 INFO - Available Modes on Display 0 07:42:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:42:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:42:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:42:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:42:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:42:26 INFO - Intel Iris: 07:42:26 INFO - Chipset Model: Intel Iris 07:42:26 INFO - Type: GPU 07:42:26 INFO - Bus: Built-In 07:42:26 INFO - VRAM (Dynamic, Max): 1536 MB 07:42:26 INFO - Vendor: Intel (0x8086) 07:42:26 INFO - Device ID: 0x0a2e 07:42:26 INFO - Revision ID: 0x0009 07:42:26 INFO - Displays: 07:42:26 INFO - Display: 07:42:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:42:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:42:26 INFO - Display Serial Number: 1600x1200 60 07:42:26 INFO - Main Display: Yes 07:42:26 INFO - Mirror: Off 07:42:26 INFO - Online: Yes 07:42:26 INFO - Rotation: Supported 07:42:26 INFO - Return code: 0 07:42:26 INFO - Running post-action listener: _resource_record_post_action 07:42:26 INFO - [mozharness: 2017-04-07 14:42:26.945676Z] Finished run-tests step (success) 07:42:26 INFO - Running post-run listener: _resource_record_post_run 07:42:27 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39205376; Write bytes: 523981824; Read time: 245; Write time: 1618 07:42:27 INFO - TinderboxPrint: CPU usage
54.2% 07:42:27 INFO - TinderboxPrint: I/O read bytes / time
39,205,376 / 245 07:42:27 INFO - TinderboxPrint: I/O write bytes / time
523,981,824 / 1,618 07:42:27 INFO - TinderboxPrint: CPU idle
2,199.5 (45.8%) 07:42:27 INFO - TinderboxPrint: CPU system
970.7 (20.2%) 07:42:27 INFO - TinderboxPrint: CPU user
1,635.5 (34.0%) 07:42:27 INFO - TinderboxPrint: Swap in / out
246,489,088 / 0 07:42:27 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 193974784; Write bytes: 194648064; Read time: 14977; Write time: 403 07:42:27 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 38230528; Write bytes: 312491008; Read time: 233; Write time: 1163 07:42:27 INFO - Running post-run listener: _upload_blobber_files 07:42:27 INFO - Blob upload gear active. 07:42:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:42:27 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 07:42:27 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'] 07:42:27 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 07:42:27 INFO - (blobuploader) - INFO - Open directory for files ... 07:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:29 INFO - (blobuploader) - INFO - Done attempting. 07:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:42:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:29 INFO - (blobuploader) - INFO - Done attempting. 07:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:30 INFO - (blobuploader) - INFO - Done attempting. 07:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:42:32 INFO - (blobuploader) - INFO - Done attempting. 07:42:32 INFO - (blobuploader) - INFO - Iteration through files over. 07:42:32 INFO - Return code: 0 07:42:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:42:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:42:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fef96c4e79a65aab4159efd37a5dc85cd728331c90a7dd36b0eeffcf16eabf8ae20db6ddbcb30e22b5677d158b2227648c026a20788d1972e671e88dc2b6c6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3133b29f152de4d14cdf6ea7aaa6b3b2b151d736c06339b95561e9aa93524816b40ee9c9bc12338fea0d842189f302abf2100e922bded1fffad6b8a6c90a4672", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d70fe22e8ea57de2c540ec8ce1a65279a1996a2c2aac52bf91772d3bf5e17514feb4ef2ed03d15881fb92f44b49394f1311ed847722683b3747051dbb1e540c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25829f5213c4bb2d0501615b3b93ccf7b887673f6f195f4399f4299d0dbf78c2c4fb14082aa8300dab665e503ccfb7bda48a581b82c4649967caf9f5a847ec5e"} 07:42:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:42:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:42:32 INFO - Contents: 07:42:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fef96c4e79a65aab4159efd37a5dc85cd728331c90a7dd36b0eeffcf16eabf8ae20db6ddbcb30e22b5677d158b2227648c026a20788d1972e671e88dc2b6c6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3133b29f152de4d14cdf6ea7aaa6b3b2b151d736c06339b95561e9aa93524816b40ee9c9bc12338fea0d842189f302abf2100e922bded1fffad6b8a6c90a4672", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d70fe22e8ea57de2c540ec8ce1a65279a1996a2c2aac52bf91772d3bf5e17514feb4ef2ed03d15881fb92f44b49394f1311ed847722683b3747051dbb1e540c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25829f5213c4bb2d0501615b3b93ccf7b887673f6f195f4399f4299d0dbf78c2c4fb14082aa8300dab665e503ccfb7bda48a581b82c4649967caf9f5a847ec5e"} 07:42:32 INFO - Running post-run listener: copy_logs_to_upload_dir 07:42:32 INFO - Copying logs to upload dir... 07:42:32 INFO - mkdir: /builds/slave/test/build/upload/logs 07:42:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.689195 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-07 07:42:33.021988) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 07:42:33.025930) ========= 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.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fef96c4e79a65aab4159efd37a5dc85cd728331c90a7dd36b0eeffcf16eabf8ae20db6ddbcb30e22b5677d158b2227648c026a20788d1972e671e88dc2b6c6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3133b29f152de4d14cdf6ea7aaa6b3b2b151d736c06339b95561e9aa93524816b40ee9c9bc12338fea0d842189f302abf2100e922bded1fffad6b8a6c90a4672", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d70fe22e8ea57de2c540ec8ce1a65279a1996a2c2aac52bf91772d3bf5e17514feb4ef2ed03d15881fb92f44b49394f1311ed847722683b3747051dbb1e540c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25829f5213c4bb2d0501615b3b93ccf7b887673f6f195f4399f4299d0dbf78c2c4fb14082aa8300dab665e503ccfb7bda48a581b82c4649967caf9f5a847ec5e"} build_url:https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://queue.taskcluster.net/v1/task/FGk_GJAMTD6o7y8QibFoEg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fef96c4e79a65aab4159efd37a5dc85cd728331c90a7dd36b0eeffcf16eabf8ae20db6ddbcb30e22b5677d158b2227648c026a20788d1972e671e88dc2b6c6f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3133b29f152de4d14cdf6ea7aaa6b3b2b151d736c06339b95561e9aa93524816b40ee9c9bc12338fea0d842189f302abf2100e922bded1fffad6b8a6c90a4672", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d70fe22e8ea57de2c540ec8ce1a65279a1996a2c2aac52bf91772d3bf5e17514feb4ef2ed03d15881fb92f44b49394f1311ed847722683b3747051dbb1e540c9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25829f5213c4bb2d0501615b3b93ccf7b887673f6f195f4399f4299d0dbf78c2c4fb14082aa8300dab665e503ccfb7bda48a581b82c4649967caf9f5a847ec5e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 07:42:33.063637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:42:33.063997) ========= 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.HcfkVQfrED/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.6OYgWwzCyV/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.005158 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 07:42:33.116588) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 07:42:33.116974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 07:42:33.117347) ========= ========= Total master_lag: 0.39 =========