builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0179 starttime: 1490656206.47 results: success (0) buildid: 20170327142223 builduid: 0806d6fb823c4450be886428cd087542 revision: a6f35285bd1e674553527873614c55e0b7366bdb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.470349) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.470806) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.471107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.498171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.498583) ========= 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.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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-03-27 16:10:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.32M=0.001s 2017-03-27 16:10:06 (9.32 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.634076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.634533) ========= 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.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.026632 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:06.758541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 16:10:06.758899) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a6f35285bd1e674553527873614c55e0b7366bdb --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 a6f35285bd1e674553527873614c55e0b7366bdb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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-03-27 16:10:06,859 truncating revision to first 12 chars 2017-03-27 16:10:06,859 Setting DEBUG logging. 2017-03-27 16:10:06,859 attempt 1/10 2017-03-27 16:10:06,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a6f35285bd1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 16:10:07,947 unpacking tar archive at: mozilla-central-a6f35285bd1e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.351735 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-27 16:10:08.136619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:08.137243) ========= script_repo_revision: a6f35285bd1e674553527873614c55e0b7366bdb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:08.137943) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:08.138441) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 16:10:08.159891) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-27 16:10:08.160460) ========= /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.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:10:08 INFO - MultiFileLogger online at 20170327 16:10:08 in /builds/slave/test 16:10: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 16:10:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:08 INFO - {'append_to_log': False, 16:10:08 INFO - 'base_work_dir': '/builds/slave/test', 16:10:08 INFO - 'blob_upload_branch': 'Firefox', 16:10:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:08 INFO - 'branch': 'Firefox', 16:10:08 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:08 INFO - 'config_files': ('talos/mac_config.py',), 16:10:08 INFO - 'default_actions': ('clobber', 16:10:08 INFO - 'read-buildbot-config', 16:10:08 INFO - 'download-and-extract', 16:10:08 INFO - 'populate-webroot', 16:10:08 INFO - 'create-virtualenv', 16:10:08 INFO - 'install', 16:10:08 INFO - 'run-tests'), 16:10:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:08 INFO - 'download_minidump_stackwalk': True, 16:10:08 INFO - 'download_symbols': 'ondemand', 16:10:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:08 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:08 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:08 INFO - 'gecko_profile': False, 16:10:08 INFO - 'gecko_profile_interval': 0, 16:10:08 INFO - 'installer_path': 'installer.exe', 16:10:08 INFO - 'log_level': 'info', 16:10:08 INFO - 'log_name': 'talos', 16:10:08 INFO - 'log_to_console': True, 16:10:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:08 INFO - 'opt_config_files': (), 16:10:08 INFO - 'pip_index': False, 16:10:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:08 INFO - 'cmd': ('bash', 16:10:08 INFO - '-c', 16:10:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:08 INFO - 'enabled': True, 16:10:08 INFO - 'halt_on_failure': False, 16:10:08 INFO - 'name': 'check_screen_resolution'},), 16:10:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:08 INFO - 'cmd': ('bash', 16:10:08 INFO - '-c', 16:10:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:08 INFO - 'enabled': True, 16:10:08 INFO - 'halt_on_failure': False, 16:10:08 INFO - 'name': 'check_screen_resolution'},), 16:10:08 INFO - 'run_cmd_checks_enabled': True, 16:10:08 INFO - 'suite': 'dromaeojs-e10s', 16:10:08 INFO - 'system_bits': '32', 16:10:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:08 INFO - 'title': 't-yosemite-r7-0179', 16:10:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:08 INFO - 'use_talos_json': True, 16:10:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:08 INFO - 'work_dir': 'build'} 16:10:08 INFO - [mozharness: 2017-03-27 23:10:08.373372Z] Running clobber step. 16:10:08 INFO - Running pre-action listener: _resource_record_pre_action 16:10:08 INFO - Running main action method: clobber 16:10:08 INFO - rmtree: /builds/slave/test/build 16:10:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:10 INFO - Running post-action listener: _resource_record_post_action 16:10:10 INFO - [mozharness: 2017-03-27 23:10:10.902171Z] Finished clobber step (success) 16:10:10 INFO - [mozharness: 2017-03-27 23:10:10.902355Z] Running read-buildbot-config step. 16:10:10 INFO - Running pre-action listener: _resource_record_pre_action 16:10:10 INFO - Running main action method: read_buildbot_config 16:10:10 INFO - Using buildbot properties: 16:10:10 INFO - { 16:10:10 INFO - "project": "", 16:10:10 INFO - "product": "firefox", 16:10:10 INFO - "script_repo_revision": "production", 16:10:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:10:10 INFO - "repository": "", 16:10:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:10:10 INFO - "buildid": "20170327142223", 16:10:10 INFO - "pgo_build": "False", 16:10:10 INFO - "basedir": "/builds/slave/test", 16:10:10 INFO - "buildnumber": 26, 16:10:10 INFO - "slavename": "t-yosemite-r7-0179", 16:10:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:10:10 INFO - "platform": "yosemite_r7", 16:10:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:10:10 INFO - "branch": "mozilla-central", 16:10:10 INFO - "repo_path": "mozilla-central", 16:10:10 INFO - "revision": "a6f35285bd1e674553527873614c55e0b7366bdb", 16:10:10 INFO - "stage_platform": "macosx64", 16:10:10 INFO - "builduid": "0806d6fb823c4450be886428cd087542", 16:10:10 INFO - "slavebuilddir": "test" 16:10:10 INFO - } 16:10:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:10:10 INFO - Running post-action listener: _resource_record_post_action 16:10:10 INFO - [mozharness: 2017-03-27 23:10:10.905694Z] Finished read-buildbot-config step (success) 16:10:10 INFO - [mozharness: 2017-03-27 23:10:10.905810Z] Running download-and-extract step. 16:10:10 INFO - Running pre-action listener: _resource_record_pre_action 16:10:10 INFO - Running main action method: download_and_extract 16:10:10 INFO - mkdir: /builds/slave/test/build/tests 16:10: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')]} 16:10:10 INFO - trying https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/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 16:10:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/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 16:10:11 INFO - Downloaded 1325 bytes. 16:10:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:10:11 INFO - Using the following test package requirements: 16:10:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:10:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:10:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:10:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'jsshell-mac.zip'], 16:10:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:10:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:10:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:10:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:10:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:10:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:10:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:10:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:10:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:10:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:11 INFO - Fetch https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:10:13 INFO - Expected file size: 52418547 16:10:13 INFO - Obtained file size: 52418547 16:10: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 16:10:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:10:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:30 INFO - Fetch https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:10:32 INFO - Expected file size: 52418547 16:10:32 INFO - Obtained file size: 52418547 16:10:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:10:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:10:47 INFO - Fetch https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:10:48 INFO - Expected file size: 14404171 16:10:48 INFO - Obtained file size: 14404171 16:10:48 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')]} 16:10:48 INFO - trying https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:10:48 INFO - Downloading https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:10:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/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 16:10:50 INFO - Downloaded 75551645 bytes. 16:10:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:10:50 INFO - mkdir: /builds/slave/test/properties 16:10:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:50 INFO - Contents: 16:10:50 INFO - build_url:https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:10:51 INFO - Running post-action listener: _resource_record_post_action 16:10:51 INFO - Running post-action listener: set_extra_try_arguments 16:10:51 INFO - [mozharness: 2017-03-27 23:10:51.573584Z] Finished download-and-extract step (success) 16:10:51 INFO - [mozharness: 2017-03-27 23:10:51.573704Z] Running populate-webroot step. 16:10:51 INFO - Running pre-action listener: _resource_record_pre_action 16:10:51 INFO - Running main action method: populate_webroot 16:10:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:51 INFO - '--tppagecycles', 16:10:51 INFO - '7'], 16:10:51 INFO - 'tests': ['tsvgm']}}, 16:10:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['damp', 'tps']}, 16:10:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'tests': ['damp', 'tps']}, 16:10:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['dromaeo_dom']}, 16:10:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['basic_compositor_video']}, 16:10:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['a11yr', 16:10:51 INFO - 'ts_paint', 16:10:51 INFO - 'tpaint', 16:10:51 INFO - 'sessionrestore', 16:10:51 INFO - 'sessionrestore_no_auto_restore', 16:10:51 INFO - 'tabpaint']}, 16:10:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['tsvgx', 16:10:51 INFO - 'tsvgr_opacity', 16:10:51 INFO - 'tart', 16:10:51 INFO - 'tscrollx', 16:10:51 INFO - 'cart', 16:10:51 INFO - 'tsvg_static']}, 16:10:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:51 INFO - 'tsvgr_opacity', 16:10:51 INFO - 'tart', 16:10:51 INFO - 'tscrollx', 16:10:51 INFO - 'cart', 16:10:51 INFO - 'tsvg_static']}, 16:10:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'talos_options': ['--disable-e10s'], 16:10:51 INFO - 'tests': ['tp5o']}, 16:10:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:10:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'talos_options': ['--disable-e10s', 16:10:51 INFO - '--xperf_path', 16:10:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:51 INFO - 'tests': ['tp5n']}, 16:10:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:10:51 INFO - 'talos_options': ['--xperf_path', 16:10:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:51 INFO - 'tests': ['tp5n']}}, 16:10:51 INFO - 'talos.zip': {'path': '', 16:10:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:51 INFO - Running post-action listener: _resource_record_post_action 16:10:51 INFO - [mozharness: 2017-03-27 23:10:51.592271Z] Finished populate-webroot step (success) 16:10:51 INFO - [mozharness: 2017-03-27 23:10:51.592408Z] Running create-virtualenv step. 16:10:51 INFO - Running pre-action listener: _resource_record_pre_action 16:10:51 INFO - Running main action method: create_virtualenv 16:10:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10: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 16:10:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:51 INFO - Using real prefix '/tools/python27' 16:10:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:55 INFO - Installing pip.................done. 16:10:55 INFO - Return code: 0 16:10:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:10:55 INFO - Reading from file tmpfile_stdout 16:10:55 INFO - Output received: 16:10: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) 16:10:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:10: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 16:10: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 16:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:55 INFO - 'HOME': '/Users/cltbld', 16:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:55 INFO - 'LOGNAME': 'cltbld', 16:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:55 INFO - 'MOZ_NO_REMOTE': '1', 16:10:55 INFO - 'NO_EM_RESTART': '1', 16:10:55 INFO - 'PAGER': '/bin/cat', 16:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:55 INFO - 'PWD': '/builds/slave/test', 16:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:55 INFO - 'SHELL': '/bin/bash', 16:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:10:55 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:55 INFO - 'USER': 'cltbld', 16:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:55 INFO - 'XPC_FLAGS': '0x0', 16:10:55 INFO - 'XPC_SERVICE_NAME': '0', 16:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:10:55 INFO - Cleaning up... 16:10:55 INFO - Return code: 0 16:10:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:10:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:55 INFO - 'HOME': '/Users/cltbld', 16:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:55 INFO - 'LOGNAME': 'cltbld', 16:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:55 INFO - 'MOZ_NO_REMOTE': '1', 16:10:55 INFO - 'NO_EM_RESTART': '1', 16:10:55 INFO - 'PAGER': '/bin/cat', 16:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:55 INFO - 'PWD': '/builds/slave/test', 16:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:55 INFO - 'SHELL': '/bin/bash', 16:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:10:55 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:55 INFO - 'USER': 'cltbld', 16:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:55 INFO - 'XPC_FLAGS': '0x0', 16:10:55 INFO - 'XPC_SERVICE_NAME': '0', 16:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:56 INFO - Downloading/unpacking psutil>=3.1.1 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:59 INFO - Installing collected packages: psutil 16:10:59 INFO - Running setup.py install for psutil 16:10:59 INFO - building 'psutil._psutil_osx' extension 16:10:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:11: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 16:11: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 16:11: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 16:11:00 INFO - building 'psutil._psutil_posix' extension 16:11: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 16:11:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:11:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:11:00 INFO - ^ 16:11:00 INFO - 1 warning generated. 16:11: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 16:11:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:00 INFO - Successfully installed psutil 16:11:00 INFO - Cleaning up... 16:11:00 INFO - Return code: 0 16:11:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11:00 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')]} 16:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:00 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')]} 16:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:00 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:00 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 16:11:00 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 16:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:00 INFO - 'HOME': '/Users/cltbld', 16:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:00 INFO - 'LOGNAME': 'cltbld', 16:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:00 INFO - 'MOZ_NO_REMOTE': '1', 16:11:00 INFO - 'NO_EM_RESTART': '1', 16:11:00 INFO - 'PAGER': '/bin/cat', 16:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:00 INFO - 'PWD': '/builds/slave/test', 16:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:00 INFO - 'SHELL': '/bin/bash', 16:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:00 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:00 INFO - 'USER': 'cltbld', 16:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:00 INFO - 'XPC_FLAGS': '0x0', 16:11:00 INFO - 'XPC_SERVICE_NAME': '0', 16:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:01 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 16:11:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:03 INFO - Installing collected packages: mozsystemmonitor 16:11:03 INFO - Running setup.py install for mozsystemmonitor 16:11:04 INFO - Successfully installed mozsystemmonitor 16:11:04 INFO - Cleaning up... 16:11:04 INFO - Return code: 0 16:11:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11:04 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')]} 16:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:04 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')]} 16:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:04 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:04 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 16:11:04 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 16:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:04 INFO - 'HOME': '/Users/cltbld', 16:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:04 INFO - 'LOGNAME': 'cltbld', 16:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:04 INFO - 'MOZ_NO_REMOTE': '1', 16:11:04 INFO - 'NO_EM_RESTART': '1', 16:11:04 INFO - 'PAGER': '/bin/cat', 16:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:04 INFO - 'PWD': '/builds/slave/test', 16:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:04 INFO - 'SHELL': '/bin/bash', 16:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:04 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:04 INFO - 'USER': 'cltbld', 16:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:04 INFO - 'XPC_FLAGS': '0x0', 16:11:04 INFO - 'XPC_SERVICE_NAME': '0', 16:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:05 INFO - Downloading/unpacking jsonschema==2.5.1 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:07 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 16:11:07 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 16:11:07 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 16:11:07 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 16:11:07 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 16:11:07 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 16:11:08 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:08 INFO - warning: no files found matching '*.txt' 16:11:08 INFO - no previously-included directories found matching 'build' 16:11:08 INFO - no previously-included directories found matching 'dist' 16:11:08 INFO - no previously-included directories found matching '.git*' 16:11:08 INFO - Installing collected packages: jsonschema, functools32 16:11:08 INFO - Running setup.py install for functools32 16:11:08 INFO - warning: no files found matching '*.txt' 16:11:08 INFO - no previously-included directories found matching 'build' 16:11:08 INFO - no previously-included directories found matching 'dist' 16:11:08 INFO - no previously-included directories found matching '.git*' 16:11:08 INFO - Successfully installed jsonschema functools32 16:11:08 INFO - Cleaning up... 16:11:08 INFO - Return code: 0 16:11:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11:08 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')]} 16:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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')]} 16:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:08 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 16:11:08 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 16:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:08 INFO - 'HOME': '/Users/cltbld', 16:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:08 INFO - 'LOGNAME': 'cltbld', 16:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:08 INFO - 'MOZ_NO_REMOTE': '1', 16:11:08 INFO - 'NO_EM_RESTART': '1', 16:11:08 INFO - 'PAGER': '/bin/cat', 16:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:08 INFO - 'PWD': '/builds/slave/test', 16:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:08 INFO - 'SHELL': '/bin/bash', 16:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:08 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:08 INFO - 'USER': 'cltbld', 16:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:08 INFO - 'XPC_FLAGS': '0x0', 16:11:08 INFO - 'XPC_SERVICE_NAME': '0', 16:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:08 INFO - Cleaning up... 16:11:08 INFO - Return code: 0 16:11:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11:08 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')]} 16:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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')]} 16:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:08 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 16:11:08 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 16:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:08 INFO - 'HOME': '/Users/cltbld', 16:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:08 INFO - 'LOGNAME': 'cltbld', 16:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:08 INFO - 'MOZ_NO_REMOTE': '1', 16:11:08 INFO - 'NO_EM_RESTART': '1', 16:11:08 INFO - 'PAGER': '/bin/cat', 16:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:08 INFO - 'PWD': '/builds/slave/test', 16:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:08 INFO - 'SHELL': '/bin/bash', 16:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:08 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:08 INFO - 'USER': 'cltbld', 16:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:08 INFO - 'XPC_FLAGS': '0x0', 16:11:08 INFO - 'XPC_SERVICE_NAME': '0', 16:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:11 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:11 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 16:11:11 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 16:11:11 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 16:11:11 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 16:11:11 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 16:11:11 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 16:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11: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 16:11:12 INFO - Downloading docopt-0.6.1.tar.gz 16:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:13 INFO - Running setup.py install for blobuploader 16:11:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:13 INFO - Running setup.py install for requests 16:11:13 INFO - Running setup.py install for docopt 16:11:13 INFO - Successfully installed blobuploader requests docopt 16:11:13 INFO - Cleaning up... 16:11:13 INFO - Return code: 0 16:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:13 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')]} 16:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:13 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')]} 16:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:13 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:13 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 16:11:13 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 16:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:13 INFO - 'HOME': '/Users/cltbld', 16:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:13 INFO - 'LOGNAME': 'cltbld', 16:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:13 INFO - 'MOZ_NO_REMOTE': '1', 16:11:13 INFO - 'NO_EM_RESTART': '1', 16:11:13 INFO - 'PAGER': '/bin/cat', 16:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:13 INFO - 'PWD': '/builds/slave/test', 16:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:13 INFO - 'SHELL': '/bin/bash', 16:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:13 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:13 INFO - 'USER': 'cltbld', 16:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:13 INFO - 'XPC_FLAGS': '0x0', 16:11:13 INFO - 'XPC_SERVICE_NAME': '0', 16:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-uoLzir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-h5bcAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-_pAwnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-XBemex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-DDhpbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-YhgU7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1xtnLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ti0iE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:14 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-0gEC4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-zGhE6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-a_EsZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Lndfqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-_nHMVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Idyh3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ToQzcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-CG7Zc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:15 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-E6ZDiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:15 INFO - Running setup.py install for manifestparser 16:11:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:16 INFO - Running setup.py install for mozcrash 16:11:16 INFO - Running setup.py install for mozdebug 16:11:16 INFO - Running setup.py install for mozdevice 16:11:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:16 INFO - Running setup.py install for mozfile 16:11:16 INFO - Running setup.py install for mozhttpd 16:11:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:16 INFO - Running setup.py install for mozinfo 16:11:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:16 INFO - Running setup.py install for mozInstall 16:11:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Running setup.py install for mozleak 16:11:17 INFO - Running setup.py install for mozlog 16:11:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Running setup.py install for moznetwork 16:11:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Running setup.py install for mozprocess 16:11:17 INFO - Running setup.py install for mozprofile 16:11:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:17 INFO - Running setup.py install for mozrunner 16:11:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:18 INFO - Running setup.py install for mozscreenshot 16:11:18 INFO - Running setup.py install for moztest 16:11:18 INFO - Running setup.py install for mozversion 16:11:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:18 INFO - Cleaning up... 16:11:18 INFO - Return code: 0 16:11:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:18 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 16:11:18 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 16:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:18 INFO - 'HOME': '/Users/cltbld', 16:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:18 INFO - 'LOGNAME': 'cltbld', 16:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:18 INFO - 'MOZ_NO_REMOTE': '1', 16:11:18 INFO - 'NO_EM_RESTART': '1', 16:11:18 INFO - 'PAGER': '/bin/cat', 16:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:18 INFO - 'PWD': '/builds/slave/test', 16:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:18 INFO - 'SHELL': '/bin/bash', 16:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:18 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:18 INFO - 'USER': 'cltbld', 16:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:18 INFO - 'XPC_FLAGS': '0x0', 16:11:18 INFO - 'XPC_SERVICE_NAME': '0', 16:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:18 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-H4aHiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:18 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)) 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:18 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-hZ4Ika-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:18 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)) 16:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:18 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-cO0_60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11: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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-koBOGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-a4sggr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11: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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-PxAedR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-3dgOfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-sphzcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-5O6DdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-C2RIxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1a38DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:19 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)) 16:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:19 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-UsByNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11: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)) 16:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-EatlZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11: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)) 16:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-CHxQbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11: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)) 16:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wFHHLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:20 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)) 16:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-LgBNZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:20 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)) 16:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-IFv6t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:20 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)) 16:11:20 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)) 16:11:20 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)) 16:11:20 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)) 16:11:20 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:20 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:23 INFO - Downloading blessings-1.6.tar.gz 16:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:11:23 INFO - Installing collected packages: blessings 16:11:23 INFO - Running setup.py install for blessings 16:11:23 INFO - Successfully installed blessings 16:11:23 INFO - Cleaning up... 16:11:23 INFO - Return code: 0 16:11:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:23 INFO - Reading from file tmpfile_stdout 16:11:23 INFO - Current package versions: 16:11:23 INFO - blessings == 1.6 16:11:23 INFO - blobuploader == 1.2.4 16:11:23 INFO - docopt == 0.6.1 16:11:23 INFO - functools32 == 3.2.3-2 16:11:23 INFO - jsonschema == 2.5.1 16:11:23 INFO - manifestparser == 1.1 16:11:23 INFO - mozInstall == 1.12 16:11:23 INFO - mozcrash == 1.0 16:11:23 INFO - mozdebug == 0.1 16:11:23 INFO - mozdevice == 0.49 16:11:23 INFO - mozfile == 1.2 16:11:23 INFO - mozhttpd == 0.7 16:11:23 INFO - mozinfo == 0.9 16:11:23 INFO - mozleak == 0.1 16:11:23 INFO - mozlog == 3.4 16:11:23 INFO - moznetwork == 0.27 16:11:23 INFO - mozprocess == 0.25 16:11:23 INFO - mozprofile == 0.28 16:11:23 INFO - mozrunner == 6.13 16:11:23 INFO - mozscreenshot == 0.1 16:11:23 INFO - mozsystemmonitor == 0.3 16:11:23 INFO - moztest == 0.8 16:11:23 INFO - mozversion == 1.4 16:11:23 INFO - psutil == 3.1.1 16:11:23 INFO - requests == 1.2.3 16:11:23 INFO - wsgiref == 0.1.2 16:11:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:23 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')]} 16:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:23 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')]} 16:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:23 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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11:23 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 16:11:23 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 16:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:23 INFO - 'HOME': '/Users/cltbld', 16:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:23 INFO - 'LOGNAME': 'cltbld', 16:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:23 INFO - 'MOZ_NO_REMOTE': '1', 16:11:23 INFO - 'NO_EM_RESTART': '1', 16:11:23 INFO - 'PAGER': '/bin/cat', 16:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:23 INFO - 'PWD': '/builds/slave/test', 16:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:23 INFO - 'SHELL': '/bin/bash', 16:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:23 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:23 INFO - 'USER': 'cltbld', 16:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:23 INFO - 'XPC_FLAGS': '0x0', 16:11:23 INFO - 'XPC_SERVICE_NAME': '0', 16:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11: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)) 16:11:24 INFO - Cleaning up... 16:11:24 INFO - Return code: 0 16:11:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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 0x109a19b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a1c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82bbcae430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, '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.QmOYh0PRmm/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.tkwiocL9wq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 16:11: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 16:11: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 16:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:24 INFO - 'HOME': '/Users/cltbld', 16:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:24 INFO - 'LOGNAME': 'cltbld', 16:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:24 INFO - 'MOZ_NO_REMOTE': '1', 16:11:24 INFO - 'NO_EM_RESTART': '1', 16:11:24 INFO - 'PAGER': '/bin/cat', 16:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:24 INFO - 'PWD': '/builds/slave/test', 16:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:24 INFO - 'SHELL': '/bin/bash', 16:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:24 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:24 INFO - 'USER': 'cltbld', 16:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:24 INFO - 'XPC_FLAGS': '0x0', 16:11:24 INFO - 'XPC_SERVICE_NAME': '0', 16:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:11:24 INFO - Cleaning up... 16:11:24 INFO - Return code: 0 16:11:24 INFO - Running post-action listener: _resource_record_post_action 16:11:24 INFO - Running post-action listener: _start_resource_monitoring 16:11:24 INFO - Starting resource monitoring. 16:11:24 INFO - [mozharness: 2017-03-27 23:11:24.415949Z] Finished create-virtualenv step (success) 16:11:24 INFO - [mozharness: 2017-03-27 23:11:24.416982Z] Running install step. 16:11:24 INFO - Running pre-action listener: _resource_record_pre_action 16:11:24 INFO - Running main action method: install 16:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:24 INFO - Reading from file tmpfile_stdout 16:11:24 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:24 INFO - Reading from file tmpfile_stdout 16:11:24 INFO - Output received: 16:11:24 INFO - Usage: mozinstall [options] installer 16:11:24 INFO - Options: 16:11:24 INFO - -h, --help show this help message and exit 16:11:24 INFO - -d DEST, --destination=DEST 16:11:24 INFO - Directory to install application into. [default: 16:11:24 INFO - "/builds/slave/test"] 16:11:24 INFO - --app=APP Application being installed. [default: firefox] 16:11:24 INFO - mkdir: /builds/slave/test/build/application 16:11:24 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'] 16:11:24 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 16:11:45 INFO - Reading from file tmpfile_stdout 16:11:45 INFO - Output received: 16:11:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:11:45 INFO - Running post-action listener: _resource_record_post_action 16:11:45 INFO - [mozharness: 2017-03-27 23:11:45.033846Z] Finished install step (success) 16:11:45 INFO - [mozharness: 2017-03-27 23:11:45.033935Z] Running run-tests step. 16:11:45 INFO - Running pre-action listener: _resource_record_pre_action 16:11:45 INFO - Running main action method: run_tests 16:11:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:45 INFO - 2017-03-27 16:11:45.046 screenresolution[1624:7395] starting screenresolution argv=screenresolution get 16:11:45 INFO - 2017-03-27 16:11:45.048 screenresolution[1624:7395] Display 0: 1600x1200x32@60 16:11:45 INFO - 2017-03-27 16:11:45.053 screenresolution[1625:7398] starting screenresolution argv=screenresolution list 16:11:45 INFO - Available Modes on Display 0 16:11:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:45 INFO - Intel Iris: 16:11:45 INFO - Chipset Model: Intel Iris 16:11:45 INFO - Type: GPU 16:11:45 INFO - Bus: Built-In 16:11:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:45 INFO - Vendor: Intel (0x8086) 16:11:45 INFO - Device ID: 0x0a2e 16:11:45 INFO - Revision ID: 0x0009 16:11:45 INFO - Displays: 16:11:45 INFO - Display: 16:11:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:45 INFO - Display Serial Number: 1600x1200 60 16:11:45 INFO - Main Display: Yes 16:11:45 INFO - Mirror: Off 16:11:45 INFO - Online: Yes 16:11:45 INFO - Rotation: Supported 16:11:45 INFO - Return code: 0 16:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:45 INFO - Python 2.7.3 16:11:45 INFO - Return code: 0 16:11:45 INFO - grabbing minidump binary from tooltool 16:11: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')]} 16:11: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 0x1097f7d30>, '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 0x10971edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097ecbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11: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 16:11: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 16:11: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 16:11:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:45 INFO - Return code: 0 16:11:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:45 INFO - ENV: RUST_BACKTRACE is now 1 16:11:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11: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/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 16:11: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/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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 16:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QmOYh0PRmm/Render', 16:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:45 INFO - 'HOME': '/Users/cltbld', 16:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:45 INFO - 'LOGNAME': 'cltbld', 16:11:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:45 INFO - 'MOZ_NO_REMOTE': '1', 16:11:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:45 INFO - 'NO_EM_RESTART': '1', 16:11:45 INFO - 'PAGER': '/bin/cat', 16:11:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:45 INFO - 'PWD': '/builds/slave/test', 16:11:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:45 INFO - 'RUST_BACKTRACE': '1', 16:11:45 INFO - 'SHELL': '/bin/bash', 16:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tkwiocL9wq/Listeners', 16:11:45 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 16:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:45 INFO - 'USER': 'cltbld', 16:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:45 INFO - 'XPC_FLAGS': '0x0', 16:11:45 INFO - 'XPC_SERVICE_NAME': '0', 16:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11: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/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 16:11:45 INFO - mozversion application_buildid: 20170327142223 16:11:45 INFO - mozversion application_changeset: a6f35285bd1e674553527873614c55e0b7366bdb 16:11:45 INFO - mozversion application_display_name: Nightly 16:11:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:45 INFO - mozversion application_name: Firefox 16:11:45 INFO - mozversion application_remotingname: firefox 16:11:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:11:45 INFO - mozversion application_vendor: Mozilla 16:11:45 INFO - mozversion application_version: 55.0a1 16:11:45 INFO - mozversion platform_buildid: 20170327142223 16:11:45 INFO - mozversion platform_changeset: a6f35285bd1e674553527873614c55e0b7366bdb 16:11:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:11:45 INFO - mozversion platform_version: 55.0a1 16:11:45 INFO - using testdate: 1490656305 16:11:45 INFO - actual date: 1490656305 16:11:45 INFO - starting webserver on 'localhost:49251' 16:11:45 INFO - SUITE-START | Running 2 tests 16:11:45 INFO - TEST-START | dromaeo_css 16:11:45 INFO - operating with platform_type : mac_ 16:11:45 INFO - Initialising browser for dromaeo_css test... 16:11:45 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile http://localhost:49251/getInfo.html) 16:11:48 INFO - PID 1635 | 2017-03-27 16:11:48.703 plugin-container[1637:7579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 16:11:48 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:11:48 INFO - PID 1635 | 2017-03-27 16:11:48.707 plugin-container[1637:7579] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 16:11:48 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:11:48 INFO - PID 1635 | 2017-03-27 16:11:48.707 plugin-container[1637:7579] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:11:48 INFO - PID 1635 | __metrics Screen width/height:1600/1200 16:11:48 INFO - PID 1635 | colorDepth:24 16:11:48 INFO - PID 1635 | Browser inner width/height: 1024/689 16:11:48 INFO - PID 1635 | __metrics 16:11:48 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:11:48 ERROR - PID 1635 | 1490656308924 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:11:49 INFO - TEST-INFO | 1635: exit 0 16:11:49 INFO - Browser initialized. 16:11:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:49 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp6sHm4B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:52 INFO - PID 1638 | 2017-03-27 16:11:52.227 plugin-container[1639:7713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 16:11:52 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:11:52 INFO - PID 1638 | 2017-03-27 16:11:52.228 plugin-container[1639:7713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d23, name = 'com.apple.CFPasteboardClient' 16:11:52 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:11:52 INFO - PID 1638 | 2017-03-27 16:11:52.228 plugin-container[1639:7713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:12:01 INFO - PID 1638 | RSS: Main: 229134336 16:12:01 INFO - PID 1638 | 16:15:05 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 16:15:07 INFO - PID 1638 | RSS: Main: 250630144 16:15:07 INFO - PID 1638 | 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:16:50 INFO - PID 1638 | 16:16:50 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:16:50 INFO - PID 1638 | Full stack: 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:16:50 INFO - PID 1638 | 16:16:50 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:16:50 INFO - PID 1638 | Full stack: 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:16:50 INFO - PID 1638 | 16:16:50 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:16:50 INFO - PID 1638 | Full stack: 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | ************************* 16:16:50 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 16:16:50 INFO - PID 1638 | 16:16:50 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:16:50 INFO - PID 1638 | Full stack: 16:16:50 INFO - PID 1638 | ************************* 16:18:10 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 16:18:10 INFO - PID 1638 | RSS: Main: 260169728 16:18:10 INFO - PID 1638 | 16:21:12 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 16:21:13 INFO - PID 1638 | RSS: Main: 271261696 16:21:13 INFO - PID 1638 | 16:24:15 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 16:24:15 INFO - PID 1638 | RSS: Main: 266756096 16:24:15 INFO - PID 1638 | 16:27:18 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 16:27:19 INFO - PID 1638 | RSS: Main: 276975616 16:27:19 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 16:30:22 INFO - PID 1638 | RSS: Main: 270643200 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | __start_tp_report 16:30:22 INFO - PID 1638 | _x_x_mozilla_page_load 16:30:22 INFO - PID 1638 | _x_x_mozilla_page_load_details 16:30:22 INFO - PID 1638 | |i|pagename|runs| 16:30:22 INFO - PID 1638 | |0;dojo.html;2978.672346041935;2983.9850800745994;3309.354675838211;3584.4085725855252;3634.836432360544;6101.731934812926;6180.072989051644;6224.719887605057;6254.1869556957645;6259.968700156499;6117.248930028871;6163.876722465559;6180.103884936681;6282.151909492217;6295.087212354206;4556.225441674915;5655.26481557397;5695.943040569603;5711.514521265697;5780.942190578104;5545.334559852823;5643.971780141114;5709.514691251248;5746.942530574704;5824.0099183138755;5545.445455454463;5565.359983601857;5578.163275508706;5663.461971112738;5702.486776190142;4623.9768801156115;5526.50261476469;5580.581456390787;5604.719764011783;5649.491545760901;5557.3886872444;5595.552355811526;5612.494875461228;5636.379998200195;5647.717614119319;6015.2781666200335;6179.134921111048;6222.3466536013975;6250.249970003583;6283;5604.915926261068;6444.935550644481;6519.543631945766;6575.835604109936;6654.800355989328;5856.472917437409;6680.899786503206;6702.597844129319;6746.190457145125;6768.255491895888;6268.404501572343;6301.243850737895;6316.684165791692;6333.429991300805;6334.9366506334345;6638.502112341594;6735.696893639798;6944.097267355211;6986.126734158231;6988.580685158907;6516.478681705452;6529.314421985718;6568.474522038226;6588.341165883373;6594.505412094016;5593.692346920959;6206;6478.773242936569;6732.966335168293;6793.45652347811;6356.968215158895;6444.871102577922;6605.1083103780375;6646.634435106115;6696.330366963265;6466.288708242089;6527.3472652734345;6568.7372505099265;6633.369829866155;6648.601083934979;5417.783288668488;5436.075867102602;5436.728163591804;5442;5484.478974497496;4807.61539076873;4831.057943700945;4868.391451068616;4869.440014398318;4874.926876096791;5257.369115706139;6193.194884665055;6254.468370188585;6308.053791931247;6331.05034244867;6740.4270636995425;6752.459803215731;6775.661216939134;6783.287754785775;6786.083878676315;6289.0880822280515;6294.8426289342215;6303.464205542488;6305.96847015762;6312.873742525216;5798.333191682926;5922.230110085661;6009.429104235091;6023.548233882389;6095.66473843934;6901.792946211622;6909.654517274015;6927.480438967198;6931;6941.965290173517;6588.374104460068;6633.469322454095;6791.320867913169;6793.184817821893;6804.183497980274;6812.591244525344;6873.1064961554675;6886.035954966409;6906.37842594169;6929.203141638776;6659.234188068241;6665.900011499928;6671.699773510107;6672.599644021374;6696.19645642526;6331.90502142459;6725.764598239038;6757;6771.62756048422;6793.980902864412;5264.763085661231;6475.546711730133;6494.545381823416;6496.512761542808;6516.543841931019;6495.382938620824;6545.410913017851;6547.247066587403;6551.312112228147;6562.967185164234;5632.239647647515;6327.746890124344;6364.554481186457;6404;6435.710393032418;5394.406615272395;6329.145565348803;6352.269489008823;6472.935270647234;6485.383888530582;6382.0745991831855;6389.2971773105855;6392.360763923571;6404.519661025349;6408.743650253938 16:30:22 INFO - PID 1638 | |1;ext.html;716.7957132217317;721.8087206890175;815.339574944295;1283.5122653391716;1289.4390939941131;38403.80798096009;38863.80568097159;38948.22103557927;38977.805110974405;39017;38238.80880595593;38643;38687;38688;38848.22303553934;7950.602469876512;8119.309858662021;8460.534670593115;8700.173483519056;8719.694810681627;8562.529060901648;8679.21887030167;8709.129087091273;8714.128587141298;8718.476891386505;8705.216530512253;8761.824763504726;8774.473531588093;8793;8801.383903126783;8741.694040708577;8750.168733970262;8756.824863502727;8779.078196789309;8800.647974081028;8578.656853725843;8581.39930204886;8632.438891472035;8650.65397384104;8651.826963460759;38043.8097809512;38186.618133818585;38218.617813822064;38252.42621360682;38296;3002.2018130244337;3004.2038859702197;3009.7893147479685;3017.4568577656014;3017.7736669749856;27096.86451567749;27115;27371;27383.726162738316;28158.43683126326;2761.62718033067;2787.198865140541;2799.412123454077;2813.71862813717;2814.690384057752;325359;327306;328501;330694.3465282658;330907.3454632735;49094;49432.75283623559;49470;49547;49597.0080598386;41535.96160096022;51786;51801.704957376365;51830.48169518332;51888.74055629698;14469.987100902836;14687.92656036713;14819.333130009176;14866.884983626054;14877.47928822486;11886.940565297291;11945.104117191071;11952.58165964187;11962.940185299018;12015.75968480643;13101.017423693262;13508.797368039488;13553.59339219825;13586.320683965761;13633.25017124048;5222.582193424517;5735.741891614887;5917.852053698606;5958.553108516879;5964.642121472725;904.7376260884402;938.202527851321;964.4502633498979;966.3428868369576;1012.2509343086064;22313.3306000817;22743.658845117337;23149.652755208685;24132;24144.7585524146;912.4297314178639;926.8239034583407;1051.6381286234346;1091.3833683968533;1107.2802678259195;9613.471259080678;12170;12189.878101218874;12232.715564865739;12248.693782655504;433875;435055;435936;436502;436940;39656.80171599181;40510;53347.73326133422;54047.72976135094;54121.64695882634;1216.9148159628742;1454.9709005820037;1501.7146742118962;1512.5613572064199;1519.8100237470317;27246.72753272442;27517.174484765488;27868.88524458999;28101.156965291073;28787.5681864768;36470.89997350255;36560.63439365679;36566.817165914;36570.720024799004;36600;329112;330172.6982730238;331640;332167;332461;30982.84508577443;31105.377892442477;31284.687153128176;31992.68007319897;32046.6795332053;38970.61029389783;39090.60909390869;40058.59941400549;40324.3951340736;40362.9909252271;21884.562308754055;27608.30979225455;27984.72015279821;29148.417031659672;29304.362607365438;25857.22428327153;25898.870505647352;26655.33361665974;26729.33176670599;26947.461050778482;30630.540541891445;31004.844975774977;31066.223344416572;31066.53400199044;31279.218019549695;22119.44701382478;23953.922073506386;26995.595066074417;27038.459230815668;27124.1862744118;20796.896015519826;21257.362279131652;21899.67150492776;22285.66286022844;22430.16593644044 16:30:22 INFO - PID 1638 | |2;jquery.html;428.15866821695363;433.11545109570267;436.16474451425523;437.8533191380888;459.9926161705862;19251.518712032208;21037.894810525944;21563.73580774016;21660.56678866422;21836.563268734615;20875;22024.339269821932;22610.86945652715;22828.88585557074;23305.154649482858;6212.9068063979075;6383.2978372379;6412.519061070415;6413.198350206224;6416.358364163593;5953.464188223059;6183.227096612924;6224.875502489947;6238.344973777756;6271.2788029376525;6039.879202415949;6210.19267495225;6226.470749986234;6226.910290699132;6237.220347456568;6257.7184026718905;6262;6278.87442251157;6309.116723658692;6316.273628532732;5446.91829622556;5509.47659972306;5594.748236329375;5595.608307418483;5625.493705566478;27258.727412725817;27608.861955690292;27960.72039279622;28101.71898281032;28262.728177231867;1656.7680524726547;1914.0621095663164;1929.2765213045109;1943.1158822735622;1984.603079384129;15312;16505.587360316087;16575.83424165755;16688.74966875498;17141.828581714148;1718.1666891557584;1726.1800644693797;1738.669652765983;1742.8170042145518;1760.4014635024153;151906;156520;158529.2073539625;159154.20422897927;161090;12375.381230938418;12387.75224495505;12441.37793110359;12606.432710528048;12710.491580336868;16326.346946122023;16422.260998255108;16444.931079479797;16472;16483.258253378626;8747.91252087471;8979.14698103679;9050.637974481078;9176.587053582605;9185.678501252558;8581.313494920392;8601.311895048506;8617.396782225183;8703.260222881;8747.026011156237;6159.969200153971;6884.690188941509;7884.408669349822;7913.327367173854;7929.960350198212;1902.2961504243242;2107.315122585166;2107.3361891004283;2111.4932416220304;2116.915323387078;947.9052094790603;954.179405711088;955.6607404371487;989.2877128467492;991.8363470027365;19354.612907741765;20868.791312086683;21006.579868402852;21016.36950891476;21311.573768524544;982.2387649571639;985.8324084905584;1020.5152552537634;1021.8313978193663;1023.3859684189426;9307.90692093084;9904.860940991734;10141.036601522843;10254.025867542572;10816.78366432667;518077;674991;677341;679703;681298;141401.2929935344;143957;144424;145413;146365;1150.5110328110686;1171.8398785202587;1191.9761604767857;1192.4574318684977;1193.0276824388095;15485.91598588088;17493.03788291656;17893.105344732452;18084.638307234043;18293.451196464128;54147.938904583105;59414.522950180944;60809.695951519956;61359.772804544555;61725;79661.00847478627;79713;80963.59518202371;81938;81974;7828.060632724109;8290.41967062324;8300.007998080539;8456.408051436587;8891.066438023914;13451.596452106452;13748.450061997408;13830;14025.579232623038;14029.01796874209;9503.429794212369;11664.88335116638;11736.413179340829;11830.876066773642;12008.879911200776;10732.195085368472;10735.194860385345;10868.51091700891;11100.833487497532;11119.499622516845;11582.594609188638;11760.82358764602;11882.881171188523;12192.878071219175;12208.023358131151;8673.392862499564;8737.956310218407;8816.426932249384;8873.979492358461;8874;11459.197856150324;11486.712832179262;11903.821442678194;11941.582044628396;12137.878621213675 16:30:22 INFO - PID 1638 | |3;mootools.html;1396.4972609860451;1415.9782065924062;1453.2951518513519;1468.1411374346008;1491.7836913647518;8079.555624440653;9335.906640933588;9558.42649441034;10675.252732308742;10912.181586381024;10362.792744145112;10670.78658426831;10712.625058122967;10715.035646791786;10752.193585481082;2076.3874656976154;2447.0823441209545;2486.204414587333;2492.1277552856536;2494.850308981458;2333.7666233376694;2365.7634236576378;2366.7041619797524;2387.164492427654;2387.3673476528734;2384.1059602649007;2406.181898154627;2416.6858308419883;2426.4297889995837;2472.1471092473066;2424.4302588891596;2446.0093662066893;2450.1179575352858;2456.6806315179;2457.709702406233;2130.9147634094616;2177.085624037908;2269.239804665439;2414.553307638075;2432.85402875828;9240.260779137652;10725.14198864089;10973.561057557688;10974.670759877137;10982.670519884416;6192.133101365813;6395.680215989182;6436.131122298477;6453.548251622388;6485.156929599122;4005;4035.959640403588;4074.6129117733767;4077.4495443115097;4102.200070986159;1181.6868529839599;1193.486800675709;1216.5498765456837;1236.5486597391887;1242.0808601634726;14992.400303987937;15150.01524900889;15161.469348572748;15167.924160379238;15179.392824287015;5644.096944488862;5746.88506229873;5844.795432159854;5879.882402352015;5900.291964964146;9724.124828765445;11446.771064578828;11465;11542.365169915567;11554.711132221593;6259.280182778948;6345.873082538324;6345.904811427832;6355.777547785805;6359.554831161774;5784.103463963008;5890.764369425175;5920.822375328747;5965.343812180657;5971.373005834412;4053.7162398631813;4225.492940847056;4251.87244382669;4281.186574550888;4295.871123866289;1091.3833683968533;1250.756102559992;1266.5187228852976;1277.4379273119798;1286.2346903592347;769.5382770337752;777.7044723005222;798.5528104261632;800.0639252074969;808.9231523005305;5889.146073819358;6026.397360264026;6107.328193898667;6147.631142131487;6199;518.6447283610663;549.9945000550023;559.7509108446784;573.2003854622778;587.4243241623233;3669.8898133573302;3742.6818720408523;3926.6845606721495;3931.6854651628373;4182.351735481006;11438.828417573743;13221.471141154438;13788.93105534466;13830.101043432145;13859.514916977858;13701.862981370059;13708.725825483434;13745.793813092812;13780.89752819772;13780.931095344458;10062.496875156214;10153.695389138338;10169.338992965582;10192.929742376944;10298.639547615647;6321.5258855586935;6354.90467642995;6375.585586936834;6387.914054610635;6418.711157997995;12013.339266340436;12277.69305767363;12307.44616492278;12324.137310388187;12461.626151215478;11654.88345116538;11839.940800295943;12405.069619778733;12481.812772808233;12591;8829;9173.495457749888;9240.0297968715;9243.445393276425;9259.537023148681;9962.153216976349;9988.101070903653;10001.699949001542;10083.899161008296;10104.191664666956;8622.525761083201;8673.045964943725;8676.609552570024;8692.869606955774;8742.912570874465;8394;8537;8542.487450752975;8551.615177316911;8593.95703021481;7641.847163056596;7689.807754805951;7784.494007889469;7820.765377038698;7864.803379915319;5107.514786095315;5707.514861236676;5811.651300921958;5940.6435613863305;6022.45797878193;5879.676617786063;5974.761009559743;6050.818475445743;6057;6063.514918806573 16:30:22 INFO - PID 1638 | |4;prototype.html;394.292245419472;398.82252397683044;398.88432354617163;400.8196311659752;408.1734487662483;6137.018077107664;6195.4114359135865;6201.782937597185;6259.186305780254;6265.279492858323;4647.790849411776;4956.826511072113;5934.525237980962;6077.535804591374;6135.171751813504;4732.006278681481;4761.238201887699;4792.664513484057;4794.304825800257;4810.8934944962675;4765.5695595246325;4778.116048531016;4780.569748722614;4782.880427989293;4788.401449818773;3914.667253283474;4534.38785763921;4662.997455547057;4704.270838020112;4720.575148236658;4031.778252196128;4546.408966834323;4679.251319788817;4719.693219940693;4719.740414277213;4146.564610715862;4159.563245859172;4163.875083747493;4178.415021896906;4189.685773566995;4781.665283430161;5331.573474122061;6009.669468179249;6034.215551978258;6046.3651316612;4680.765961701902;5115.411727651331;5387.326584176978;5392.4631480028;5427.43011983744;6340.524460665422;6572.704228309737;6638;6647.5375417408095;6653.2681405045405;3663.303972245265;3938.428927805452;4195.286801243766;4417.4919884213405;4449.7997590108525;6675.7595287699405;6917.75787847428;6954.7218111276;7071.363577278018;7098.609576473292;3700.3154416433044;4337.37108119321;4437.844675436344;4469.1620820779035;4491.932621010687;3483.7387195960405;3980.044789250559;4517.728936263835;4554.931676024862;4594.356790049329;4940.6788558743565;5152.562032227228;5330.786768529216;5360.544353729899;5364.409914909357;6596.439302659328;6626.304238055031;6627.514681994252;6631.403173714389;6661.566998145105;1407.479232683893;1469.5591322603177;1532.9540113796604;1548.3342162870074;1551.4492355213963;801.8757092650649;963.691618682015;1017.6539976407909;1022.7187523431116;1036.5180191211123;5448.754806033738;6201.348858369806;6239.282482514478;6242.719077641517;6292.370762923762;5638.887222255532;5698.800541981011;6115.694215289218;6747;6787.2534021257625;5355.768173320158;5744.684042377626;5973.641581505123;6194.845128871724;6236.937630623726;3169.8256595887447;3593.4789455528803;3611.3138503684218;3629.546306711661;3631.763935344194;6706.765263215764;7046.365827075588;7104.822379440556;7119.679614417364;7181.174164970991;7094.432445404355;7152.892706609405;7162.713491460283;7174.892376614355;7186.820329491805;6865.691043903036;7089.680964356617;7176.2371486976845;7190.4607154464675;7207.279272072751;6230.314665386894;6563.737450501926;6811.2167100784045;6968.930310696828;6969.7909062728195;6248.781292654917;6265.840819448416;6452.677366131581;6824.679608932788;6847.828804279933;5406.78372865081;5591.916121258103;5634.464725851038;6942.687579058854;7294.44694356992;3804.410316400962;4491.214037543482;4516.254817955;4519.593236608721;4531.43357080365;6229.137934480651;6744.715707036487;6812.557183783038;6918.308169183041;6931.5097254090615;6348.650824204713;6657.966710166418;6680.331966803281;6689.929611262175;6696.665166741741;6213.844653883689;6495.772647957298;6583.670816459254;6653.500987425866;6712.093867327848;5659.745311461076;6479.838004049937;6524.510661700297;6560.3111673275525;6570.5400621956005;6575.30959249291;6600.768973085919;6685.164354455693;6686.966565167143;6730.697118629576;6142.170806941006;6429.196350456193;6538.444232240123;6541.70562324706;6543.509236807164 16:30:22 INFO - PID 1638 | |5;yui.html;723.7358364197069;726.2991213478991;754.7396148328826;756.4213376766771;759.7302957450105;15283.388664453423;15553.766693499578;15605.843941560595;15649.139297338641;15723.292451839681;14242.71514569708;14423.927880360583;14428.92785536071;14429.63925901853;14456.638584035405;916.6196028648103;942.4769253064546;963.6001059560274;969.3117886300735;971.5579411367846;841.7600983719641;945.9499955049889;953.4422362917667;957.9521023948774;964.6720115160844;895.1809094678395;976.9156236577394;1004.6031817432109;1010.6513252927729;1012.3976234140712;899.7210864631952;933.2720478027115;949.4540639132457;991.5438898106879;1003.5684655598089;696.1889398850333;743.22333161846;744.7281742164125;754.4040208235488;756.2248695087525;16047;16435.26041328143;16447.91776041124;16541.25564349607;16655.66688666232;14283.64290892736;14391.13653180802;14501.564953051426;14596.927015364961;14636.487722929647;9257.989846908837;11644;11764;12012.339321337324;12045.457954392074;2877.395746893155;3039.7720170987263;3081.1526830121898;3096.7058129477664;3150.5274208868623;108947;109281;111096;112809;114408;10222;10584.364938103738;11085.50115244816;11544.653660390202;11824.52701891932;21809.56380872374;23698.81505924697;24876;24896.50620962748;24916.750832491805;7445.702171913171;7806.726764563213;7838.412119091091;7979.281864632211;8155.46989445696;3701.2967536168044;3801.3917773156163;3803.4294855771304;3805.7335986481235;3919.0821858993127;4067.776272305052;4131.731437456556;4162.875113746592;4389.297712366006;4643.976780116078;859.1090978856104;865.2645251536144;873.5501216873315;880.4541184465544;903.6475774447971;532.8454748123079;538.7575590984073;548.4789450022495;588.8733922206746;595.7408527290643;9042.86435703465;10072.597096116073;10350.792984140275;10552.314099583502;10560.683179504627;544.4011587253989;576.7375843990974;583.5739909865737;589.9026660601039;594.197832925547;4802.845538609078;4867.294242334912;4886.0160557497;4930.531599498042;4932.704037757746;73999;91460;93832.53083734537;95042;96544.51727741456;69342;72216;82745.17254827375;84026;86053.56973215094;65755.67122164358;66902;68709.3129068703;71475.64262178655;72542;9664.215346191102;9727.610895564098;9905.55425005862;10259.666243388312;10750.462476875968;36304.455433168;36392;36730;38095.809520952214;38244.61755382411;48472;51758.74120629373;55581.444185559245;61382.38617613767;63370.68314658397;11726.70683232926;12835.87164128384;13592.66018349549;13624;14234.430622775388;14374.712505749618;14539.219204848829;14568.34442450071;14806.111633302036;14889.851101488846;11734.706632333919;13557.86442135566;13564.525241616891;13580.64193580634;13740;13319.66700832487;13550.119331742557;13786.448542058206;13789.448422063006;14526.709465810412;13519.324033798073;13600.387982540611;13657.863421365659;13671.589852304447;14160;9849.901500984899;9944.30389872702;10164.796704066026;10226;10898.727531811768;9157.862632060658;9547.33168678186;10275.691729248134;10500.894991049992;11283.435828208392 16:30:22 INFO - PID 1638 | __end_tp_report 16:30:22 INFO - PID 1638 | __start_cc_report 16:30:22 INFO - PID 1638 | _x_x_mozilla_cycle_collect,428 16:30:22 INFO - PID 1638 | __end_cc_report 16:30:22 INFO - PID 1638 | __startTimestamp1490657422743__endTimestamp 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | ------- Summary: start ------- 16:30:22 INFO - PID 1638 | Number of tests: 6 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6142.82 Median:6331.91 stddev:736.68 (11.6%) stddev-sans-first:695.86 16:30:22 INFO - PID 1638 | Values: 2978.7 2984.0 3309.4 3584.4 3634.8 6101.7 6180.1 6224.7 6254.2 6260.0 6117.2 6163.9 6180.1 6282.2 6295.1 4556.2 5655.3 5695.9 5711.5 5780.9 5545.3 5644.0 5709.5 5746.9 5824.0 5545.4 5565.4 5578.2 5663.5 5702.5 4624.0 5526.5 5580.6 5604.7 5649.5 5557.4 5595.6 5612.5 5636.4 5647.7 6015.3 6179.1 6222.3 6250.2 6283.0 5604.9 6444.9 6519.5 6575.8 6654.8 5856.5 6680.9 6702.6 6746.2 6768.3 6268.4 6301.2 6316.7 6333.4 6334.9 6638.5 6735.7 6944.1 6986.1 6988.6 6516.5 6529.3 6568.5 6588.3 6594.5 5593.7 6206.0 6478.8 6733.0 6793.5 6357.0 6444.9 6605.1 6646.6 6696.3 6466.3 6527.3 6568.7 6633.4 6648.6 5417.8 5436.1 5436.7 5442.0 5484.5 4807.6 4831.1 4868.4 4869.4 4874.9 5257.4 6193.2 6254.5 6308.1 6331.1 6740.4 6752.5 6775.7 6783.3 6786.1 6289.1 6294.8 6303.5 6306.0 6312.9 5798.3 5922.2 6009.4 6023.5 6095.7 6901.8 6909.7 6927.5 6931.0 6942.0 6588.4 6633.5 6791.3 6793.2 6804.2 6812.6 6873.1 6886.0 6906.4 6929.2 6659.2 6665.9 6671.7 6672.6 6696.2 6331.9 6725.8 6757.0 6771.6 6794.0 5264.8 6475.5 6494.5 6496.5 6516.5 6495.4 6545.4 6547.2 6551.3 6563.0 5632.2 6327.7 6364.6 6404.0 6435.7 5394.4 6329.1 6352.3 6472.9 6485.4 6382.1 6389.3 6392.4 6404.5 6408.7 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:49660.26 Median:330800.85 stddev:97598.27 (29.5%) stddev-sans-first:97802.88 16:30:22 INFO - PID 1638 | Values: 716.8 721.8 815.3 1283.5 1289.4 38403.8 38863.8 38948.2 38977.8 39017.0 38238.8 38643.0 38687.0 38688.0 38848.2 7950.6 8119.3 8460.5 8700.2 8719.7 8562.5 8679.2 8709.1 8714.1 8718.5 8705.2 8761.8 8774.5 8793.0 8801.4 8741.7 8750.2 8756.8 8779.1 8800.6 8578.7 8581.4 8632.4 8650.7 8651.8 38043.8 38186.6 38218.6 38252.4 38296.0 3002.2 3004.2 3009.8 3017.5 3017.8 27096.9 27115.0 27371.0 27383.7 28158.4 2761.6 2787.2 2799.4 2813.7 2814.7 325359.0 327306.0 328501.0 330694.3 330907.3 49094.0 49432.8 49470.0 49547.0 49597.0 41536.0 51786.0 51801.7 51830.5 51888.7 14470.0 14687.9 14819.3 14866.9 14877.5 11886.9 11945.1 11952.6 11962.9 12015.8 13101.0 13508.8 13553.6 13586.3 13633.3 5222.6 5735.7 5917.9 5958.6 5964.6 904.7 938.2 964.5 966.3 1012.3 22313.3 22743.7 23149.7 24132.0 24144.8 912.4 926.8 1051.6 1091.4 1107.3 9613.5 12170.0 12189.9 12232.7 12248.7 433875.0 435055.0 435936.0 436502.0 436940.0 39656.8 40510.0 53347.7 54047.7 54121.6 1216.9 1455.0 1501.7 1512.6 1519.8 27246.7 27517.2 27868.9 28101.2 28787.6 36470.9 36560.6 36566.8 36570.7 36600.0 329112.0 330172.7 331640.0 332167.0 332461.0 30982.8 31105.4 31284.7 31992.7 32046.7 38970.6 39090.6 40058.6 40324.4 40363.0 21884.6 27608.3 27984.7 29148.4 29304.4 25857.2 25898.9 26655.3 26729.3 26947.5 30630.5 31004.8 31066.2 31066.5 31279.2 22119.4 23953.9 26995.6 27038.5 27124.2 20796.9 21257.4 21899.7 22285.7 22430.2 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:38751.72 Median:21930.45 stddev:109337.50 (498.6%) stddev-sans-first:109606.36 16:30:22 INFO - PID 1638 | Values: 428.2 433.1 436.2 437.9 460.0 19251.5 21037.9 21563.7 21660.6 21836.6 20875.0 22024.3 22610.9 22828.9 23305.2 6212.9 6383.3 6412.5 6413.2 6416.4 5953.5 6183.2 6224.9 6238.3 6271.3 6039.9 6210.2 6226.5 6226.9 6237.2 6257.7 6262.0 6278.9 6309.1 6316.3 5446.9 5509.5 5594.7 5595.6 5625.5 27258.7 27608.9 27960.7 28101.7 28262.7 1656.8 1914.1 1929.3 1943.1 1984.6 15312.0 16505.6 16575.8 16688.7 17141.8 1718.2 1726.2 1738.7 1742.8 1760.4 151906.0 156520.0 158529.2 159154.2 161090.0 12375.4 12387.8 12441.4 12606.4 12710.5 16326.3 16422.3 16444.9 16472.0 16483.3 8747.9 8979.1 9050.6 9176.6 9185.7 8581.3 8601.3 8617.4 8703.3 8747.0 6160.0 6884.7 7884.4 7913.3 7930.0 1902.3 2107.3 2107.3 2111.5 2116.9 947.9 954.2 955.7 989.3 991.8 19354.6 20868.8 21006.6 21016.4 21311.6 982.2 985.8 1020.5 1021.8 1023.4 9307.9 9904.9 10141.0 10254.0 10816.8 518077.0 674991.0 677341.0 679703.0 681298.0 141401.3 143957.0 144424.0 145413.0 146365.0 1150.5 1171.8 1192.0 1192.5 1193.0 15485.9 17493.0 17893.1 18084.6 18293.5 54147.9 59414.5 60809.7 61359.8 61725.0 79661.0 79713.0 80963.6 81938.0 81974.0 7828.1 8290.4 8300.0 8456.4 8891.1 13451.6 13748.5 13830.0 14025.6 14029.0 9503.4 11664.9 11736.4 11830.9 12008.9 10732.2 10735.2 10868.5 11100.8 11119.5 11582.6 11760.8 11882.9 12192.9 12208.0 8673.4 8738.0 8816.4 8874.0 8874.0 11459.2 11486.7 11903.8 11941.6 12137.9 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6691.96 Median:3929.19 stddev:4103.69 (104.4%) stddev-sans-first:4095.91 16:30:22 INFO - PID 1638 | Values: 1396.5 1416.0 1453.3 1468.1 1491.8 8079.6 9335.9 9558.4 10675.3 10912.2 10362.8 10670.8 10712.6 10715.0 10752.2 2076.4 2447.1 2486.2 2492.1 2494.9 2333.8 2365.8 2366.7 2387.2 2387.4 2384.1 2406.2 2416.7 2426.4 2472.1 2424.4 2446.0 2450.1 2456.7 2457.7 2130.9 2177.1 2269.2 2414.6 2432.9 9240.3 10725.1 10973.6 10974.7 10982.7 6192.1 6395.7 6436.1 6453.5 6485.2 4005.0 4036.0 4074.6 4077.4 4102.2 1181.7 1193.5 1216.5 1236.5 1242.1 14992.4 15150.0 15161.5 15167.9 15179.4 5644.1 5746.9 5844.8 5879.9 5900.3 9724.1 11446.8 11465.0 11542.4 11554.7 6259.3 6345.9 6345.9 6355.8 6359.6 5784.1 5890.8 5920.8 5965.3 5971.4 4053.7 4225.5 4251.9 4281.2 4295.9 1091.4 1250.8 1266.5 1277.4 1286.2 769.5 777.7 798.6 800.1 808.9 5889.1 6026.4 6107.3 6147.6 6199.0 518.6 550.0 559.8 573.2 587.4 3669.9 3742.7 3926.7 3931.7 4182.4 11438.8 13221.5 13788.9 13830.1 13859.5 13701.9 13708.7 13745.8 13780.9 13780.9 10062.5 10153.7 10169.3 10192.9 10298.6 6321.5 6354.9 6375.6 6387.9 6418.7 12013.3 12277.7 12307.4 12324.1 12461.6 11654.9 11839.9 12405.1 12481.8 12591.0 8829.0 9173.5 9240.0 9243.4 9259.5 9962.2 9988.1 10001.7 10083.9 10104.2 8622.5 8673.0 8676.6 8692.9 8742.9 8394.0 8537.0 8542.5 8551.6 8594.0 7641.8 7689.8 7784.5 7820.8 7864.8 5107.5 5707.5 5811.7 5940.6 6022.5 5879.7 5974.8 6050.8 6057.0 6063.5 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5343.84 Median:6061.95 stddev:1696.62 (28.0%) stddev-sans-first:1660.21 16:30:22 INFO - PID 1638 | Values: 394.3 398.8 398.9 400.8 408.2 6137.0 6195.4 6201.8 6259.2 6265.3 4647.8 4956.8 5934.5 6077.5 6135.2 4732.0 4761.2 4792.7 4794.3 4810.9 4765.6 4778.1 4780.6 4782.9 4788.4 3914.7 4534.4 4663.0 4704.3 4720.6 4031.8 4546.4 4679.3 4719.7 4719.7 4146.6 4159.6 4163.9 4178.4 4189.7 4781.7 5331.6 6009.7 6034.2 6046.4 4680.8 5115.4 5387.3 5392.5 5427.4 6340.5 6572.7 6638.0 6647.5 6653.3 3663.3 3938.4 4195.3 4417.5 4449.8 6675.8 6917.8 6954.7 7071.4 7098.6 3700.3 4337.4 4437.8 4469.2 4491.9 3483.7 3980.0 4517.7 4554.9 4594.4 4940.7 5152.6 5330.8 5360.5 5364.4 6596.4 6626.3 6627.5 6631.4 6661.6 1407.5 1469.6 1533.0 1548.3 1551.4 801.9 963.7 1017.7 1022.7 1036.5 5448.8 6201.3 6239.3 6242.7 6292.4 5638.9 5698.8 6115.7 6747.0 6787.3 5355.8 5744.7 5973.6 6194.8 6236.9 3169.8 3593.5 3611.3 3629.5 3631.8 6706.8 7046.4 7104.8 7119.7 7181.2 7094.4 7152.9 7162.7 7174.9 7186.8 6865.7 7089.7 7176.2 7190.5 7207.3 6230.3 6563.7 6811.2 6968.9 6969.8 6248.8 6265.8 6452.7 6824.7 6847.8 5406.8 5591.9 5634.5 6942.7 7294.4 3804.4 4491.2 4516.3 4519.6 4531.4 6229.1 6744.7 6812.6 6918.3 6931.5 6348.7 6658.0 6680.3 6689.9 6696.7 6213.8 6495.8 6583.7 6653.5 6712.1 5659.7 6479.8 6524.5 6560.3 6570.5 6575.3 6600.8 6685.2 6687.0 6730.7 6142.2 6429.2 6538.4 6541.7 6543.5 16:30:22 INFO - PID 1638 | 16:30:22 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:19175.75 Median:3804.58 stddev:27059.62 (711.2%) stddev-sans-first:27100.05 16:30:22 INFO - PID 1638 | Values: 723.7 726.3 754.7 756.4 759.7 15283.4 15553.8 15605.8 15649.1 15723.3 14242.7 14423.9 14428.9 14429.6 14456.6 916.6 942.5 963.6 969.3 971.6 841.8 945.9 953.4 958.0 964.7 895.2 976.9 1004.6 1010.7 1012.4 899.7 933.3 949.5 991.5 1003.6 696.2 743.2 744.7 754.4 756.2 16047.0 16435.3 16447.9 16541.3 16655.7 14283.6 14391.1 14501.6 14596.9 14636.5 9258.0 11644.0 11764.0 12012.3 12045.5 2877.4 3039.8 3081.2 3096.7 3150.5 108947.0 109281.0 111096.0 112809.0 114408.0 10222.0 10584.4 11085.5 11544.7 11824.5 21809.6 23698.8 24876.0 24896.5 24916.8 7445.7 7806.7 7838.4 7979.3 8155.5 3701.3 3801.4 3803.4 3805.7 3919.1 4067.8 4131.7 4162.9 4389.3 4644.0 859.1 865.3 873.6 880.5 903.6 532.8 538.8 548.5 588.9 595.7 9042.9 10072.6 10350.8 10552.3 10560.7 544.4 576.7 583.6 589.9 594.2 4802.8 4867.3 4886.0 4930.5 4932.7 73999.0 91460.0 93832.5 95042.0 96544.5 69342.0 72216.0 82745.2 84026.0 86053.6 65755.7 66902.0 68709.3 71475.6 72542.0 9664.2 9727.6 9905.6 10259.7 10750.5 36304.5 36392.0 36730.0 38095.8 38244.6 48472.0 51758.7 55581.4 61382.4 63370.7 11726.7 12835.9 13592.7 13624.0 14234.4 14374.7 14539.2 14568.3 14806.1 14889.9 11734.7 13557.9 13564.5 13580.6 13740.0 13319.7 13550.1 13786.4 13789.4 14526.7 13519.3 13600.4 13657.9 13671.6 14160.0 9849.9 9944.3 10164.8 10226.0 10898.7 9157.9 9547.3 10275.7 10500.9 11283.4 16:30:22 INFO - PID 1638 | -------- Summary: end -------- 16:30:22 INFO - PID 1638 | 16:30:23 INFO - TEST-INFO | 1638: exit 0 16:30:23 INFO - TEST-OK | dromaeo_css | took 1117795ms 16:30:23 INFO - TEST-START | kraken 16:30:23 INFO - operating with platform_type : mac_ 16:30:23 INFO - Initialising browser for kraken test... 16:30:23 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile http://localhost:49251/getInfo.html) 16:30:26 INFO - PID 1650 | 2017-03-27 16:30:26.305 plugin-container[1651:10208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 16:30:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:26 INFO - PID 1650 | 2017-03-27 16:30:26.308 plugin-container[1651:10208] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:30:26 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:26 INFO - PID 1650 | 2017-03-27 16:30:26.308 plugin-container[1651:10208] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:26 INFO - PID 1650 | __metrics Screen width/height:1600/1200 16:30:26 INFO - PID 1650 | colorDepth:24 16:30:26 INFO - PID 1650 | Browser inner width/height: 1024/689 16:30:26 INFO - PID 1650 | __metrics 16:30:26 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:30:26 ERROR - PID 1650 | 1490657426480 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:30:27 INFO - TEST-INFO | 1650: exit 0 16:30:27 INFO - Browser initialized. 16:30:27 INFO - Running cycle 1/1 for kraken test... 16:30:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpTC724n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:29 INFO - PID 1652 | 2017-03-27 16:30:29.758 plugin-container[1653:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 16:30:29 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:29 INFO - PID 1652 | 2017-03-27 16:30:29.758 plugin-container[1653:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 16:30:29 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:30:29 INFO - PID 1652 | 2017-03-27 16:30:29.758 plugin-container[1653:10336] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:30:39 INFO - PID 1652 | RSS: Main: 227389440 16:30:39 INFO - PID 1652 | 16:30:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:43 INFO - PID 1652 | RSS: Main: 241831936 16:30:43 INFO - PID 1652 | 16:30:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 16:30:46 INFO - PID 1652 | RSS: Main: 250454016 16:30:46 INFO - PID 1652 | 16:30:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 16:30:50 INFO - PID 1652 | RSS: Main: 253222912 16:30:50 INFO - PID 1652 | 16:30:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 16:30:53 INFO - PID 1652 | RSS: Main: 255676416 16:30:53 INFO - PID 1652 | 16:30:55 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:55 INFO - PID 1652 | RSS: Main: 256081920 16:30:55 INFO - PID 1652 | 16:30:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:59 INFO - PID 1652 | RSS: Main: 261709824 16:30:59 INFO - PID 1652 | 16:31:02 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:03 INFO - PID 1652 | RSS: Main: 262803456 16:31:03 INFO - PID 1652 | 16:31:06 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 16:31:07 INFO - PID 1652 | RSS: Main: 260038656 16:31:07 INFO - PID 1652 | 16:31:09 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:09 INFO - PID 1652 | RSS: Main: 260595712 16:31:09 INFO - PID 1652 | 16:31:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:12 INFO - PID 1652 | RSS: Main: 260972544 16:31:12 INFO - PID 1652 | 16:31:14 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:15 INFO - PID 1652 | RSS: Main: 261189632 16:31:15 INFO - PID 1652 | 16:31:18 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:19 INFO - PID 1652 | RSS: Main: 261832704 16:31:19 INFO - PID 1652 | 16:31:22 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:22 INFO - PID 1652 | RSS: Main: 261353472 16:31:22 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 16:31:24 INFO - PID 1652 | RSS: Main: 263077888 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | __start_tp_report 16:31:24 INFO - PID 1652 | _x_x_mozilla_page_load 16:31:24 INFO - PID 1652 | _x_x_mozilla_page_load_details 16:31:24 INFO - PID 1652 | |i|pagename|runs| 16:31:24 INFO - PID 1652 | |0;ai-astar;104;134;171;151;116;117;113;119;99;169 16:31:24 INFO - PID 1652 | |1;audio-beat-detection;142;115;114;153;114;118;114;123;117;150 16:31:24 INFO - PID 1652 | |2;audio-dft;275;250;210;205;220;203;197;200;195;192 16:31:24 INFO - PID 1652 | |3;audio-fft;97;59;60;85;60;63;60;80;104;83 16:31:24 INFO - PID 1652 | |4;audio-oscillator;88;71;87;68;70;70;85;86;101;83 16:31:24 INFO - PID 1652 | |5;imaging-gaussian-blur;90;84;132;108;90;89;93;116;90;89 16:31:24 INFO - PID 1652 | |6;imaging-darkroom;96;98;98;98;96;96;98;98;98;140 16:31:24 INFO - PID 1652 | |7;imaging-desaturate;141;138;95;148;96;145;105;91;145;145 16:31:24 INFO - PID 1652 | |8;json-parse-financial;74;55;59;60;60;95;104;104;92;103 16:31:24 INFO - PID 1652 | |9;json-stringify-tinderbox;57;62;58;60;64;63;67;55;72;88 16:31:24 INFO - PID 1652 | |10;stanford-crypto-aes;84;83;85;84;74;96;115;76;97;106 16:31:24 INFO - PID 1652 | |11;stanford-crypto-ccm;152;151;150;151;156;150;132;139;208;161 16:31:24 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;187;181;183;185;188;189;184;188;184;161 16:31:24 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;69;70;69;70;69;67;68;69;71;70 16:31:24 INFO - PID 1652 | __end_tp_report 16:31:24 INFO - PID 1652 | __start_cc_report 16:31:24 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1000 16:31:24 INFO - PID 1652 | __end_cc_report 16:31:24 INFO - PID 1652 | __startTimestamp1490657484653__endTimestamp 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | ------- Summary: start ------- 16:31:24 INFO - PID 1652 | Number of tests: 14 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:129.30 Median:142.50 stddev:25.96 (18.2%) stddev-sans-first:25.87 16:31:24 INFO - PID 1652 | Values: 104.0 134.0 171.0 151.0 116.0 117.0 113.0 119.0 99.0 169.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:120.50 stddev:15.87 (13.2%) stddev-sans-first:15.75 16:31:24 INFO - PID 1652 | Values: 142.0 115.0 114.0 153.0 114.0 118.0 114.0 123.0 117.0 150.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:214.70 Median:207.50 stddev:27.07 (13.0%) stddev-sans-first:17.87 16:31:24 INFO - PID 1652 | Values: 275.0 250.0 210.0 205.0 220.0 203.0 197.0 200.0 195.0 192.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:75.10 Median:71.50 stddev:16.96 (23.7%) stddev-sans-first:16.03 16:31:24 INFO - PID 1652 | Values: 97.0 59.0 60.0 85.0 60.0 63.0 60.0 80.0 104.0 83.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:84.00 stddev:10.75 (12.8%) stddev-sans-first:11.10 16:31:24 INFO - PID 1652 | Values: 88.0 71.0 87.0 68.0 70.0 70.0 85.0 86.0 101.0 83.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:89.50 stddev:15.47 (17.3%) stddev-sans-first:16.13 16:31:24 INFO - PID 1652 | Values: 90.0 84.0 132.0 108.0 90.0 89.0 93.0 116.0 90.0 89.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:98.00 stddev:13.53 (13.8%) stddev-sans-first:14.19 16:31:24 INFO - PID 1652 | Values: 96.0 98.0 98.0 98.0 96.0 96.0 98.0 98.0 98.0 140.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:124.90 Median:146.50 stddev:24.61 (16.8%) stddev-sans-first:25.40 16:31:24 INFO - PID 1652 | Values: 141.0 138.0 95.0 148.0 96.0 145.0 105.0 91.0 145.0 145.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:80.60 Median:60.00 stddev:20.95 (34.9%) stddev-sans-first:22.08 16:31:24 INFO - PID 1652 | Values: 74.0 55.0 59.0 60.0 60.0 95.0 104.0 104.0 92.0 103.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:63.50 stddev:9.62 (15.1%) stddev-sans-first:9.80 16:31:24 INFO - PID 1652 | Values: 57.0 62.0 58.0 60.0 64.0 63.0 67.0 55.0 72.0 88.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:84.00 stddev:13.18 (15.7%) stddev-sans-first:13.80 16:31:24 INFO - PID 1652 | Values: 84.0 83.0 85.0 84.0 74.0 96.0 115.0 76.0 97.0 106.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:151.50 stddev:20.34 (13.4%) stddev-sans-first:21.54 16:31:24 INFO - PID 1652 | Values: 152.0 151.0 150.0 151.0 156.0 150.0 132.0 139.0 208.0 161.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.00 Median:186.00 stddev:8.14 (4.4%) stddev-sans-first:8.50 16:31:24 INFO - PID 1652 | Values: 187.0 181.0 183.0 185.0 188.0 189.0 184.0 188.0 184.0 161.0 16:31:24 INFO - PID 1652 | 16:31:24 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.14 (1.6%) stddev-sans-first:1.20 16:31:24 INFO - PID 1652 | Values: 69.0 70.0 69.0 70.0 69.0 67.0 68.0 69.0 71.0 70.0 16:31:24 INFO - PID 1652 | -------- Summary: end -------- 16:31:24 INFO - PID 1652 | 16:31:25 INFO - TEST-INFO | 1652: exit 0 16:31:25 INFO - TEST-OK | kraken | took 62268ms 16:31:25 INFO - SUITE-END | took 1180s 16:31:25 INFO - Completed test suite (00:19:40) 16:31:25 INFO - javascript benchmark 16:31:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7623.968937755102, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2978.672346041935, 2983.9850800745994, 3309.354675838211, 3584.4085725855252, 3634.836432360544, 6101.731934812926, 6180.072989051644, 6224.719887605057, 6254.1869556957645, 6259.968700156499, 6117.248930028871, 6163.876722465559, 6180.103884936681, 6282.151909492217, 6295.087212354206, 4556.225441674915, 5655.26481557397, 5695.943040569603, 5711.514521265697, 5780.942190578104, 5545.334559852823, 5643.971780141114, 5709.514691251248, 5746.942530574704, 5824.0099183138755, 5545.445455454463, 5565.359983601857, 5578.163275508706, 5663.461971112738, 5702.486776190142, 4623.9768801156115, 5526.50261476469, 5580.581456390787, 5604.719764011783, 5649.491545760901, 5557.3886872444, 5595.552355811526, 5612.494875461228, 5636.379998200195, 5647.717614119319, 6015.2781666200335, 6179.134921111048, 6222.3466536013975, 6250.249970003583, 6283.0, 5604.915926261068, 6444.935550644481, 6519.543631945766, 6575.835604109936, 6654.800355989328, 5856.472917437409, 6680.899786503206, 6702.597844129319, 6746.190457145125, 6768.255491895888, 6268.404501572343, 6301.243850737895, 6316.684165791692, 6333.429991300805, 6334.9366506334345, 6638.502112341594, 6735.696893639798, 6944.097267355211, 6986.126734158231, 6988.580685158907, 6516.478681705452, 6529.314421985718, 6568.474522038226, 6588.341165883373, 6594.505412094016, 5593.692346920959, 6206.0, 6478.773242936569, 6732.966335168293, 6793.45652347811, 6356.968215158895, 6444.871102577922, 6605.1083103780375, 6646.634435106115, 6696.330366963265, 6466.288708242089, 6527.3472652734345, 6568.7372505099265, 6633.369829866155, 6648.601083934979, 5417.783288668488, 5436.075867102602, 5436.728163591804, 5442.0, 5484.478974497496, 4807.61539076873, 4831.057943700945, 4868.391451068616, 4869.440014398318, 4874.926876096791, 5257.369115706139, 6193.194884665055, 6254.468370188585, 6308.053791931247, 6331.05034244867, 6740.4270636995425, 6752.459803215731, 6775.661216939134, 6783.287754785775, 6786.083878676315, 6289.0880822280515, 6294.8426289342215, 6303.464205542488, 6305.96847015762, 6312.873742525216, 5798.333191682926, 5922.230110085661, 6009.429104235091, 6023.548233882389, 6095.66473843934, 6901.792946211622, 6909.654517274015, 6927.480438967198, 6931.0, 6941.965290173517, 6588.374104460068, 6633.469322454095, 6791.320867913169, 6793.184817821893, 6804.183497980274, 6812.591244525344, 6873.1064961554675, 6886.035954966409, 6906.37842594169, 6929.203141638776, 6659.234188068241, 6665.900011499928, 6671.699773510107, 6672.599644021374, 6696.19645642526, 6331.90502142459, 6725.764598239038, 6757.0, 6771.62756048422, 6793.980902864412, 5264.763085661231, 6475.546711730133, 6494.545381823416, 6496.512761542808, 6516.543841931019, 6495.382938620824, 6545.410913017851, 6547.247066587403, 6551.312112228147, 6562.967185164234, 5632.239647647515, 6327.746890124344, 6364.554481186457, 6404.0, 6435.710393032418, 5394.406615272395, 6329.145565348803, 6352.269489008823, 6472.935270647234, 6485.383888530582, 6382.0745991831855, 6389.2971773105855, 6392.360763923571, 6404.519661025349, 6408.743650253938], "value": 6093.201712442877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.7957132217317, 721.8087206890175, 815.339574944295, 1283.5122653391716, 1289.4390939941131, 38403.80798096009, 38863.80568097159, 38948.22103557927, 38977.805110974405, 39017.0, 38238.80880595593, 38643.0, 38687.0, 38688.0, 38848.22303553934, 7950.602469876512, 8119.309858662021, 8460.534670593115, 8700.173483519056, 8719.694810681627, 8562.529060901648, 8679.21887030167, 8709.129087091273, 8714.128587141298, 8718.476891386505, 8705.216530512253, 8761.824763504726, 8774.473531588093, 8793.0, 8801.383903126783, 8741.694040708577, 8750.168733970262, 8756.824863502727, 8779.078196789309, 8800.647974081028, 8578.656853725843, 8581.39930204886, 8632.438891472035, 8650.65397384104, 8651.826963460759, 38043.8097809512, 38186.618133818585, 38218.617813822064, 38252.42621360682, 38296.0, 3002.2018130244337, 3004.2038859702197, 3009.7893147479685, 3017.4568577656014, 3017.7736669749856, 27096.86451567749, 27115.0, 27371.0, 27383.726162738316, 28158.43683126326, 2761.62718033067, 2787.198865140541, 2799.412123454077, 2813.71862813717, 2814.690384057752, 325359.0, 327306.0, 328501.0, 330694.3465282658, 330907.3454632735, 49094.0, 49432.75283623559, 49470.0, 49547.0, 49597.0080598386, 41535.96160096022, 51786.0, 51801.704957376365, 51830.48169518332, 51888.74055629698, 14469.987100902836, 14687.92656036713, 14819.333130009176, 14866.884983626054, 14877.47928822486, 11886.940565297291, 11945.104117191071, 11952.58165964187, 11962.940185299018, 12015.75968480643, 13101.017423693262, 13508.797368039488, 13553.59339219825, 13586.320683965761, 13633.25017124048, 5222.582193424517, 5735.741891614887, 5917.852053698606, 5958.553108516879, 5964.642121472725, 904.7376260884402, 938.202527851321, 964.4502633498979, 966.3428868369576, 1012.2509343086064, 22313.3306000817, 22743.658845117337, 23149.652755208685, 24132.0, 24144.7585524146, 912.4297314178639, 926.8239034583407, 1051.6381286234346, 1091.3833683968533, 1107.2802678259195, 9613.471259080678, 12170.0, 12189.878101218874, 12232.715564865739, 12248.693782655504, 433875.0, 435055.0, 435936.0, 436502.0, 436940.0, 39656.80171599181, 40510.0, 53347.73326133422, 54047.72976135094, 54121.64695882634, 1216.9148159628742, 1454.9709005820037, 1501.7146742118962, 1512.5613572064199, 1519.8100237470317, 27246.72753272442, 27517.174484765488, 27868.88524458999, 28101.156965291073, 28787.5681864768, 36470.89997350255, 36560.63439365679, 36566.817165914, 36570.720024799004, 36600.0, 329112.0, 330172.6982730238, 331640.0, 332167.0, 332461.0, 30982.84508577443, 31105.377892442477, 31284.687153128176, 31992.68007319897, 32046.6795332053, 38970.61029389783, 39090.60909390869, 40058.59941400549, 40324.3951340736, 40362.9909252271, 21884.562308754055, 27608.30979225455, 27984.72015279821, 29148.417031659672, 29304.362607365438, 25857.22428327153, 25898.870505647352, 26655.33361665974, 26729.33176670599, 26947.461050778482, 30630.540541891445, 31004.844975774977, 31066.223344416572, 31066.53400199044, 31279.218019549695, 22119.44701382478, 23953.922073506386, 26995.595066074417, 27038.459230815668, 27124.1862744118, 20796.896015519826, 21257.362279131652, 21899.67150492776, 22285.66286022844, 22430.16593644044], "value": 17176.047899169953, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.15866821695363, 433.11545109570267, 436.16474451425523, 437.8533191380888, 459.9926161705862, 19251.518712032208, 21037.894810525944, 21563.73580774016, 21660.56678866422, 21836.563268734615, 20875.0, 22024.339269821932, 22610.86945652715, 22828.88585557074, 23305.154649482858, 6212.9068063979075, 6383.2978372379, 6412.519061070415, 6413.198350206224, 6416.358364163593, 5953.464188223059, 6183.227096612924, 6224.875502489947, 6238.344973777756, 6271.2788029376525, 6039.879202415949, 6210.19267495225, 6226.470749986234, 6226.910290699132, 6237.220347456568, 6257.7184026718905, 6262.0, 6278.87442251157, 6309.116723658692, 6316.273628532732, 5446.91829622556, 5509.47659972306, 5594.748236329375, 5595.608307418483, 5625.493705566478, 27258.727412725817, 27608.861955690292, 27960.72039279622, 28101.71898281032, 28262.728177231867, 1656.7680524726547, 1914.0621095663164, 1929.2765213045109, 1943.1158822735622, 1984.603079384129, 15312.0, 16505.587360316087, 16575.83424165755, 16688.74966875498, 17141.828581714148, 1718.1666891557584, 1726.1800644693797, 1738.669652765983, 1742.8170042145518, 1760.4014635024153, 151906.0, 156520.0, 158529.2073539625, 159154.20422897927, 161090.0, 12375.381230938418, 12387.75224495505, 12441.37793110359, 12606.432710528048, 12710.491580336868, 16326.346946122023, 16422.260998255108, 16444.931079479797, 16472.0, 16483.258253378626, 8747.91252087471, 8979.14698103679, 9050.637974481078, 9176.587053582605, 9185.678501252558, 8581.313494920392, 8601.311895048506, 8617.396782225183, 8703.260222881, 8747.026011156237, 6159.969200153971, 6884.690188941509, 7884.408669349822, 7913.327367173854, 7929.960350198212, 1902.2961504243242, 2107.315122585166, 2107.3361891004283, 2111.4932416220304, 2116.915323387078, 947.9052094790603, 954.179405711088, 955.6607404371487, 989.2877128467492, 991.8363470027365, 19354.612907741765, 20868.791312086683, 21006.579868402852, 21016.36950891476, 21311.573768524544, 982.2387649571639, 985.8324084905584, 1020.5152552537634, 1021.8313978193663, 1023.3859684189426, 9307.90692093084, 9904.860940991734, 10141.036601522843, 10254.025867542572, 10816.78366432667, 518077.0, 674991.0, 677341.0, 679703.0, 681298.0, 141401.2929935344, 143957.0, 144424.0, 145413.0, 146365.0, 1150.5110328110686, 1171.8398785202587, 1191.9761604767857, 1192.4574318684977, 1193.0276824388095, 15485.91598588088, 17493.03788291656, 17893.105344732452, 18084.638307234043, 18293.451196464128, 54147.938904583105, 59414.522950180944, 60809.695951519956, 61359.772804544555, 61725.0, 79661.00847478627, 79713.0, 80963.59518202371, 81938.0, 81974.0, 7828.060632724109, 8290.41967062324, 8300.007998080539, 8456.408051436587, 8891.066438023914, 13451.596452106452, 13748.450061997408, 13830.0, 14025.579232623038, 14029.01796874209, 9503.429794212369, 11664.88335116638, 11736.413179340829, 11830.876066773642, 12008.879911200776, 10732.195085368472, 10735.194860385345, 10868.51091700891, 11100.833487497532, 11119.499622516845, 11582.594609188638, 11760.82358764602, 11882.881171188523, 12192.878071219175, 12208.023358131151, 8673.392862499564, 8737.956310218407, 8816.426932249384, 8873.979492358461, 8874.0, 11459.197856150324, 11486.712832179262, 11903.821442678194, 11941.582044628396, 12137.878621213675], "value": 10343.857065153226, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.4972609860451, 1415.9782065924062, 1453.2951518513519, 1468.1411374346008, 1491.7836913647518, 8079.555624440653, 9335.906640933588, 9558.42649441034, 10675.252732308742, 10912.181586381024, 10362.792744145112, 10670.78658426831, 10712.625058122967, 10715.035646791786, 10752.193585481082, 2076.3874656976154, 2447.0823441209545, 2486.204414587333, 2492.1277552856536, 2494.850308981458, 2333.7666233376694, 2365.7634236576378, 2366.7041619797524, 2387.164492427654, 2387.3673476528734, 2384.1059602649007, 2406.181898154627, 2416.6858308419883, 2426.4297889995837, 2472.1471092473066, 2424.4302588891596, 2446.0093662066893, 2450.1179575352858, 2456.6806315179, 2457.709702406233, 2130.9147634094616, 2177.085624037908, 2269.239804665439, 2414.553307638075, 2432.85402875828, 9240.260779137652, 10725.14198864089, 10973.561057557688, 10974.670759877137, 10982.670519884416, 6192.133101365813, 6395.680215989182, 6436.131122298477, 6453.548251622388, 6485.156929599122, 4005.0, 4035.959640403588, 4074.6129117733767, 4077.4495443115097, 4102.200070986159, 1181.6868529839599, 1193.486800675709, 1216.5498765456837, 1236.5486597391887, 1242.0808601634726, 14992.400303987937, 15150.01524900889, 15161.469348572748, 15167.924160379238, 15179.392824287015, 5644.096944488862, 5746.88506229873, 5844.795432159854, 5879.882402352015, 5900.291964964146, 9724.124828765445, 11446.771064578828, 11465.0, 11542.365169915567, 11554.711132221593, 6259.280182778948, 6345.873082538324, 6345.904811427832, 6355.777547785805, 6359.554831161774, 5784.103463963008, 5890.764369425175, 5920.822375328747, 5965.343812180657, 5971.373005834412, 4053.7162398631813, 4225.492940847056, 4251.87244382669, 4281.186574550888, 4295.871123866289, 1091.3833683968533, 1250.756102559992, 1266.5187228852976, 1277.4379273119798, 1286.2346903592347, 769.5382770337752, 777.7044723005222, 798.5528104261632, 800.0639252074969, 808.9231523005305, 5889.146073819358, 6026.397360264026, 6107.328193898667, 6147.631142131487, 6199.0, 518.6447283610663, 549.9945000550023, 559.7509108446784, 573.2003854622778, 587.4243241623233, 3669.8898133573302, 3742.6818720408523, 3926.6845606721495, 3931.6854651628373, 4182.351735481006, 11438.828417573743, 13221.471141154438, 13788.93105534466, 13830.101043432145, 13859.514916977858, 13701.862981370059, 13708.725825483434, 13745.793813092812, 13780.89752819772, 13780.931095344458, 10062.496875156214, 10153.695389138338, 10169.338992965582, 10192.929742376944, 10298.639547615647, 6321.5258855586935, 6354.90467642995, 6375.585586936834, 6387.914054610635, 6418.711157997995, 12013.339266340436, 12277.69305767363, 12307.44616492278, 12324.137310388187, 12461.626151215478, 11654.88345116538, 11839.940800295943, 12405.069619778733, 12481.812772808233, 12591.0, 8829.0, 9173.495457749888, 9240.0297968715, 9243.445393276425, 9259.537023148681, 9962.153216976349, 9988.101070903653, 10001.699949001542, 10083.899161008296, 10104.191664666956, 8622.525761083201, 8673.045964943725, 8676.609552570024, 8692.869606955774, 8742.912570874465, 8394.0, 8537.0, 8542.487450752975, 8551.615177316911, 8593.95703021481, 7641.847163056596, 7689.807754805951, 7784.494007889469, 7820.765377038698, 7864.803379915319, 5107.514786095315, 5707.514861236676, 5811.651300921958, 5940.6435613863305, 6022.45797878193, 5879.676617786063, 5974.761009559743, 6050.818475445743, 6057.0, 6063.514918806573], "value": 5072.370445902142, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.292245419472, 398.82252397683044, 398.88432354617163, 400.8196311659752, 408.1734487662483, 6137.018077107664, 6195.4114359135865, 6201.782937597185, 6259.186305780254, 6265.279492858323, 4647.790849411776, 4956.826511072113, 5934.525237980962, 6077.535804591374, 6135.171751813504, 4732.006278681481, 4761.238201887699, 4792.664513484057, 4794.304825800257, 4810.8934944962675, 4765.5695595246325, 4778.116048531016, 4780.569748722614, 4782.880427989293, 4788.401449818773, 3914.667253283474, 4534.38785763921, 4662.997455547057, 4704.270838020112, 4720.575148236658, 4031.778252196128, 4546.408966834323, 4679.251319788817, 4719.693219940693, 4719.740414277213, 4146.564610715862, 4159.563245859172, 4163.875083747493, 4178.415021896906, 4189.685773566995, 4781.665283430161, 5331.573474122061, 6009.669468179249, 6034.215551978258, 6046.3651316612, 4680.765961701902, 5115.411727651331, 5387.326584176978, 5392.4631480028, 5427.43011983744, 6340.524460665422, 6572.704228309737, 6638.0, 6647.5375417408095, 6653.2681405045405, 3663.303972245265, 3938.428927805452, 4195.286801243766, 4417.4919884213405, 4449.7997590108525, 6675.7595287699405, 6917.75787847428, 6954.7218111276, 7071.363577278018, 7098.609576473292, 3700.3154416433044, 4337.37108119321, 4437.844675436344, 4469.1620820779035, 4491.932621010687, 3483.7387195960405, 3980.044789250559, 4517.728936263835, 4554.931676024862, 4594.356790049329, 4940.6788558743565, 5152.562032227228, 5330.786768529216, 5360.544353729899, 5364.409914909357, 6596.439302659328, 6626.304238055031, 6627.514681994252, 6631.403173714389, 6661.566998145105, 1407.479232683893, 1469.5591322603177, 1532.9540113796604, 1548.3342162870074, 1551.4492355213963, 801.8757092650649, 963.691618682015, 1017.6539976407909, 1022.7187523431116, 1036.5180191211123, 5448.754806033738, 6201.348858369806, 6239.282482514478, 6242.719077641517, 6292.370762923762, 5638.887222255532, 5698.800541981011, 6115.694215289218, 6747.0, 6787.2534021257625, 5355.768173320158, 5744.684042377626, 5973.641581505123, 6194.845128871724, 6236.937630623726, 3169.8256595887447, 3593.4789455528803, 3611.3138503684218, 3629.546306711661, 3631.763935344194, 6706.765263215764, 7046.365827075588, 7104.822379440556, 7119.679614417364, 7181.174164970991, 7094.432445404355, 7152.892706609405, 7162.713491460283, 7174.892376614355, 7186.820329491805, 6865.691043903036, 7089.680964356617, 7176.2371486976845, 7190.4607154464675, 7207.279272072751, 6230.314665386894, 6563.737450501926, 6811.2167100784045, 6968.930310696828, 6969.7909062728195, 6248.781292654917, 6265.840819448416, 6452.677366131581, 6824.679608932788, 6847.828804279933, 5406.78372865081, 5591.916121258103, 5634.464725851038, 6942.687579058854, 7294.44694356992, 3804.410316400962, 4491.214037543482, 4516.254817955, 4519.593236608721, 4531.43357080365, 6229.137934480651, 6744.715707036487, 6812.557183783038, 6918.308169183041, 6931.5097254090615, 6348.650824204713, 6657.966710166418, 6680.331966803281, 6689.929611262175, 6696.665166741741, 6213.844653883689, 6495.772647957298, 6583.670816459254, 6653.500987425866, 6712.093867327848, 5659.745311461076, 6479.838004049937, 6524.510661700297, 6560.3111673275525, 6570.5400621956005, 6575.30959249291, 6600.768973085919, 6685.164354455693, 6686.966565167143, 6730.697118629576, 6142.170806941006, 6429.196350456193, 6538.444232240123, 6541.70562324706, 6543.509236807164], "value": 4808.891611140223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.7358364197069, 726.2991213478991, 754.7396148328826, 756.4213376766771, 759.7302957450105, 15283.388664453423, 15553.766693499578, 15605.843941560595, 15649.139297338641, 15723.292451839681, 14242.71514569708, 14423.927880360583, 14428.92785536071, 14429.63925901853, 14456.638584035405, 916.6196028648103, 942.4769253064546, 963.6001059560274, 969.3117886300735, 971.5579411367846, 841.7600983719641, 945.9499955049889, 953.4422362917667, 957.9521023948774, 964.6720115160844, 895.1809094678395, 976.9156236577394, 1004.6031817432109, 1010.6513252927729, 1012.3976234140712, 899.7210864631952, 933.2720478027115, 949.4540639132457, 991.5438898106879, 1003.5684655598089, 696.1889398850333, 743.22333161846, 744.7281742164125, 754.4040208235488, 756.2248695087525, 16047.0, 16435.26041328143, 16447.91776041124, 16541.25564349607, 16655.66688666232, 14283.64290892736, 14391.13653180802, 14501.564953051426, 14596.927015364961, 14636.487722929647, 9257.989846908837, 11644.0, 11764.0, 12012.339321337324, 12045.457954392074, 2877.395746893155, 3039.7720170987263, 3081.1526830121898, 3096.7058129477664, 3150.5274208868623, 108947.0, 109281.0, 111096.0, 112809.0, 114408.0, 10222.0, 10584.364938103738, 11085.50115244816, 11544.653660390202, 11824.52701891932, 21809.56380872374, 23698.81505924697, 24876.0, 24896.50620962748, 24916.750832491805, 7445.702171913171, 7806.726764563213, 7838.412119091091, 7979.281864632211, 8155.46989445696, 3701.2967536168044, 3801.3917773156163, 3803.4294855771304, 3805.7335986481235, 3919.0821858993127, 4067.776272305052, 4131.731437456556, 4162.875113746592, 4389.297712366006, 4643.976780116078, 859.1090978856104, 865.2645251536144, 873.5501216873315, 880.4541184465544, 903.6475774447971, 532.8454748123079, 538.7575590984073, 548.4789450022495, 588.8733922206746, 595.7408527290643, 9042.86435703465, 10072.597096116073, 10350.792984140275, 10552.314099583502, 10560.683179504627, 544.4011587253989, 576.7375843990974, 583.5739909865737, 589.9026660601039, 594.197832925547, 4802.845538609078, 4867.294242334912, 4886.0160557497, 4930.531599498042, 4932.704037757746, 73999.0, 91460.0, 93832.53083734537, 95042.0, 96544.51727741456, 69342.0, 72216.0, 82745.17254827375, 84026.0, 86053.56973215094, 65755.67122164358, 66902.0, 68709.3129068703, 71475.64262178655, 72542.0, 9664.215346191102, 9727.610895564098, 9905.55425005862, 10259.666243388312, 10750.462476875968, 36304.455433168, 36392.0, 36730.0, 38095.809520952214, 38244.61755382411, 48472.0, 51758.74120629373, 55581.444185559245, 61382.38617613767, 63370.68314658397, 11726.70683232926, 12835.87164128384, 13592.66018349549, 13624.0, 14234.430622775388, 14374.712505749618, 14539.219204848829, 14568.34442450071, 14806.111633302036, 14889.851101488846, 11734.706632333919, 13557.86442135566, 13564.525241616891, 13580.64193580634, 13740.0, 13319.66700832487, 13550.119331742557, 13786.448542058206, 13789.448422063006, 14526.709465810412, 13519.324033798073, 13600.387982540611, 13657.863421365659, 13671.589852304447, 14160.0, 9849.901500984899, 9944.30389872702, 10164.796704066026, 10226.0, 10898.727531811768, 9157.862632060658, 9547.33168678186, 10275.691729248134, 10500.894991049992, 11283.435828208392], "value": 7436.153793607836, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 134.0, 171.0, 151.0, 116.0, 117.0, 113.0, 119.0, 99.0, 169.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 114.0, 153.0, 114.0, 118.0, 114.0, 123.0, 117.0, 150.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [275.0, 250.0, 210.0, 205.0, 220.0, 203.0, 197.0, 200.0, 195.0, 192.0], "value": 214.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 59.0, 60.0, 85.0, 60.0, 63.0, 60.0, 80.0, 104.0, 83.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 87.0, 68.0, 70.0, 70.0, 85.0, 86.0, 101.0, 83.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 132.0, 108.0, 90.0, 89.0, 93.0, 116.0, 90.0, 89.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 98.0, 98.0, 96.0, 96.0, 98.0, 98.0, 98.0, 140.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 95.0, 148.0, 96.0, 145.0, 105.0, 91.0, 145.0, 145.0], "value": 124.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 55.0, 59.0, 60.0, 60.0, 95.0, 104.0, 104.0, 92.0, 103.0], "value": 80.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 58.0, 60.0, 64.0, 63.0, 67.0, 55.0, 72.0, 88.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 84.0, 74.0, 96.0, 115.0, 76.0, 97.0, 106.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 150.0, 151.0, 156.0, 150.0, 132.0, 139.0, 208.0, 161.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 183.0, 185.0, 188.0, 189.0, 184.0, 188.0, 184.0, 161.0], "value": 183.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 70.0, 69.0, 67.0, 68.0, 69.0, 71.0, 70.0], "value": 69.2, "unit": "score"}]}]} 16:31:25 INFO - Return code: 0 16:31:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:25 ERROR - # TBPL SUCCESS # 16:31:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:25 INFO - 2017-03-27 16:31:25.979 screenresolution[1657:10715] starting screenresolution argv=screenresolution get 16:31:25 INFO - 2017-03-27 16:31:25.982 screenresolution[1657:10715] Display 0: 1600x1200x32@60 16:31:25 INFO - 2017-03-27 16:31:25.988 screenresolution[1658:10718] starting screenresolution argv=screenresolution list 16:31:25 INFO - Available Modes on Display 0 16:31:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:26 INFO - Intel Iris: 16:31:26 INFO - Chipset Model: Intel Iris 16:31:26 INFO - Type: GPU 16:31:26 INFO - Bus: Built-In 16:31:26 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:26 INFO - Vendor: Intel (0x8086) 16:31:26 INFO - Device ID: 0x0a2e 16:31:26 INFO - Revision ID: 0x0009 16:31:26 INFO - Displays: 16:31:26 INFO - Display: 16:31:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:26 INFO - Display Serial Number: 1600x1200 60 16:31:26 INFO - Main Display: Yes 16:31:26 INFO - Mirror: Off 16:31:26 INFO - Online: Yes 16:31:26 INFO - Rotation: Supported 16:31:26 INFO - Return code: 0 16:31:26 INFO - Running post-action listener: _resource_record_post_action 16:31:26 INFO - [mozharness: 2017-03-27 23:31:26.226099Z] Finished run-tests step (success) 16:31:26 INFO - Running post-run listener: _resource_record_post_run 16:31:26 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 45345280; Write bytes: 519663104; Read time: 611; Write time: 2193 16:31:26 INFO - TinderboxPrint: CPU usage
54.1% 16:31:26 INFO - TinderboxPrint: I/O read bytes / time
45,345,280 / 611 16:31:26 INFO - TinderboxPrint: I/O write bytes / time
519,663,104 / 2,193 16:31:26 INFO - TinderboxPrint: CPU idle
2,206.2 (45.9%) 16:31:26 INFO - TinderboxPrint: CPU system
968.0 (20.1%) 16:31:26 INFO - TinderboxPrint: CPU user
1,631.5 (33.9%) 16:31:26 INFO - TinderboxPrint: Swap in / out
256,524,288 / 0 16:31:26 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204925440; Write bytes: 205522432; Read time: 15645; Write time: 596 16:31:26 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 43510272; Write bytes: 303306752; Read time: 591; Write time: 1567 16:31:26 INFO - Running post-run listener: _upload_blobber_files 16:31:26 INFO - Blob upload gear active. 16:31:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:26 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 16:31:26 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'] 16:31:26 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 16:31:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:31:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:31:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:29 INFO - (blobuploader) - INFO - Done attempting. 16:31:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:31:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:31:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:30 INFO - (blobuploader) - INFO - Done attempting. 16:31:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:31:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:31:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:31 INFO - (blobuploader) - INFO - Done attempting. 16:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:31:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:33 INFO - (blobuploader) - INFO - Done attempting. 16:31:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:31:33 INFO - Return code: 0 16:31:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:31:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:31:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002fa021ecdf1aff1c58a64000be2b67d3677328b704ce523a4639b63056a34f9f9c738c8476126cca6f662a6bd81cc1b836142b0f1aefbd54534a8f17ead4dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b1099c60b98f525de4814c249c30cf15f63b739fe7d61a65990da90aafa53ad71a62bfa703380e89f144c5888d9a6f5e405b75a33b5a0cbc1652377a7e880b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a84ef9448dccf4ed5b3e0fefc7065091096f0e59b532ad6b0d99e1db814c8a8ed43c11c1ffc3d60d0dd332d58d41d775c246caf96d0fc6b052d7e44d917b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60204ab76cc09409690f46117c53d802caa99e9f1ab1a205783cae67f54fb48fbb45d6f5a2b57175e3b58f736773dd5ab7d1621b7b41b39a2fbd73231881309"} 16:31:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:31:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:31:33 INFO - Contents: 16:31:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/002fa021ecdf1aff1c58a64000be2b67d3677328b704ce523a4639b63056a34f9f9c738c8476126cca6f662a6bd81cc1b836142b0f1aefbd54534a8f17ead4dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b1099c60b98f525de4814c249c30cf15f63b739fe7d61a65990da90aafa53ad71a62bfa703380e89f144c5888d9a6f5e405b75a33b5a0cbc1652377a7e880b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a84ef9448dccf4ed5b3e0fefc7065091096f0e59b532ad6b0d99e1db814c8a8ed43c11c1ffc3d60d0dd332d58d41d775c246caf96d0fc6b052d7e44d917b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60204ab76cc09409690f46117c53d802caa99e9f1ab1a205783cae67f54fb48fbb45d6f5a2b57175e3b58f736773dd5ab7d1621b7b41b39a2fbd73231881309"} 16:31:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:31:33 INFO - Copying logs to upload dir... 16:31:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:31:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.072148 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-27 16:31:33.273791) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 16:31:33.277528) ========= 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.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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/002fa021ecdf1aff1c58a64000be2b67d3677328b704ce523a4639b63056a34f9f9c738c8476126cca6f662a6bd81cc1b836142b0f1aefbd54534a8f17ead4dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b1099c60b98f525de4814c249c30cf15f63b739fe7d61a65990da90aafa53ad71a62bfa703380e89f144c5888d9a6f5e405b75a33b5a0cbc1652377a7e880b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a84ef9448dccf4ed5b3e0fefc7065091096f0e59b532ad6b0d99e1db814c8a8ed43c11c1ffc3d60d0dd332d58d41d775c246caf96d0fc6b052d7e44d917b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60204ab76cc09409690f46117c53d802caa99e9f1ab1a205783cae67f54fb48fbb45d6f5a2b57175e3b58f736773dd5ab7d1621b7b41b39a2fbd73231881309"} build_url:https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011017 build_url: 'https://queue.taskcluster.net/v1/task/cwwL-mutT6y_soM0f6MMpA/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/002fa021ecdf1aff1c58a64000be2b67d3677328b704ce523a4639b63056a34f9f9c738c8476126cca6f662a6bd81cc1b836142b0f1aefbd54534a8f17ead4dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b1099c60b98f525de4814c249c30cf15f63b739fe7d61a65990da90aafa53ad71a62bfa703380e89f144c5888d9a6f5e405b75a33b5a0cbc1652377a7e880b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a84ef9448dccf4ed5b3e0fefc7065091096f0e59b532ad6b0d99e1db814c8a8ed43c11c1ffc3d60d0dd332d58d41d775c246caf96d0fc6b052d7e44d917b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b60204ab76cc09409690f46117c53d802caa99e9f1ab1a205783cae67f54fb48fbb45d6f5a2b57175e3b58f736773dd5ab7d1621b7b41b39a2fbd73231881309"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 16:31:33.313618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:31:33.314240) ========= 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.QmOYh0PRmm/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.tkwiocL9wq/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005394 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 16:31:33.364288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 16:31:33.364647) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 16:31:33.365063) ========= ========= Total master_lag: 0.26 =========