builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1490280697.43 results: success (0) buildid: 20170323062923 builduid: 1e9cab2592d44fe6a4f48ed33be2f596 revision: fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.431030) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.431502) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.431774) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.458859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.459252) ========= 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.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-23 07:51:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2017-03-23 07:51:37 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.108351 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.592353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.592692) ========= 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.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.029538 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.644192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:37.644580) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 --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 fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-23 07:51:37,747 truncating revision to first 12 chars 2017-03-23 07:51:37,747 Setting DEBUG logging. 2017-03-23 07:51:37,747 attempt 1/10 2017-03-23 07:51:37,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fcfdeb52c2b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 07:51:38,162 unpacking tar archive at: mozilla-central-fcfdeb52c2b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688418 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:38.359022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:38.359574) ========= script_repo_revision: fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:38.360230) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:38.360707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 07:51:38.383824) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-23 07:51:38.384558) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:51:38 INFO - MultiFileLogger online at 20170323 07:51:38 in /builds/slave/test 07:51:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:51:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:51:38 INFO - {'append_to_log': False, 07:51:38 INFO - 'base_work_dir': '/builds/slave/test', 07:51:38 INFO - 'blob_upload_branch': 'Firefox', 07:51:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:51:38 INFO - 'branch': 'Firefox', 07:51:38 INFO - 'buildbot_json_path': 'buildprops.json', 07:51:38 INFO - 'config_files': ('talos/mac_config.py',), 07:51:38 INFO - 'default_actions': ('clobber', 07:51:38 INFO - 'read-buildbot-config', 07:51:38 INFO - 'download-and-extract', 07:51:38 INFO - 'populate-webroot', 07:51:38 INFO - 'create-virtualenv', 07:51:38 INFO - 'install', 07:51:38 INFO - 'run-tests'), 07:51:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:51:38 INFO - 'download_minidump_stackwalk': True, 07:51:38 INFO - 'download_symbols': 'ondemand', 07:51:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:51:38 INFO - 'tooltool.py': '/tools/tooltool.py', 07:51:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:51:38 INFO - '/tools/misc-python/virtualenv.py')}, 07:51:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:51:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:51:38 INFO - 'gecko_profile': False, 07:51:38 INFO - 'gecko_profile_interval': 0, 07:51:38 INFO - 'installer_path': 'installer.exe', 07:51:38 INFO - 'log_level': 'info', 07:51:38 INFO - 'log_name': 'talos', 07:51:38 INFO - 'log_to_console': True, 07:51:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:51:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:51:38 INFO - 'opt_config_files': (), 07:51:38 INFO - 'pip_index': False, 07:51:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:38 INFO - 'cmd': ('bash', 07:51:38 INFO - '-c', 07:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:38 INFO - 'enabled': True, 07:51:38 INFO - 'halt_on_failure': False, 07:51:38 INFO - 'name': 'check_screen_resolution'},), 07:51:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:51:38 INFO - 'cmd': ('bash', 07:51:38 INFO - '-c', 07:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:51:38 INFO - 'enabled': True, 07:51:38 INFO - 'halt_on_failure': False, 07:51:38 INFO - 'name': 'check_screen_resolution'},), 07:51:38 INFO - 'run_cmd_checks_enabled': True, 07:51:38 INFO - 'suite': 'dromaeojs', 07:51:38 INFO - 'system_bits': '32', 07:51:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:51:38 INFO - 'title': 't-yosemite-r7-0025', 07:51:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:51:38 INFO - 'use_talos_json': True, 07:51:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:51:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:51:38 INFO - 'work_dir': 'build'} 07:51:38 INFO - [mozharness: 2017-03-23 14:51:38.601944Z] Running clobber step. 07:51:38 INFO - Running pre-action listener: _resource_record_pre_action 07:51:38 INFO - Running main action method: clobber 07:51:38 INFO - rmtree: /builds/slave/test/build 07:51:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:51:41 INFO - Running post-action listener: _resource_record_post_action 07:51:41 INFO - [mozharness: 2017-03-23 14:51:41.165686Z] Finished clobber step (success) 07:51:41 INFO - [mozharness: 2017-03-23 14:51:41.165826Z] Running read-buildbot-config step. 07:51:41 INFO - Running pre-action listener: _resource_record_pre_action 07:51:41 INFO - Running main action method: read_buildbot_config 07:51:41 INFO - Using buildbot properties: 07:51:41 INFO - { 07:51:41 INFO - "project": "", 07:51:41 INFO - "product": "firefox", 07:51:41 INFO - "script_repo_revision": "production", 07:51:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:51:41 INFO - "repository": "", 07:51:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:51:41 INFO - "buildid": "20170323062923", 07:51:41 INFO - "pgo_build": "False", 07:51:41 INFO - "basedir": "/builds/slave/test", 07:51:41 INFO - "buildnumber": 8, 07:51:41 INFO - "slavename": "t-yosemite-r7-0025", 07:51:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:51:41 INFO - "platform": "yosemite_r7", 07:51:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:51:41 INFO - "branch": "mozilla-central", 07:51:41 INFO - "repo_path": "mozilla-central", 07:51:41 INFO - "revision": "fcfdeb52c2b2f020631fd47b5b02ea0be50d4027", 07:51:41 INFO - "stage_platform": "macosx64", 07:51:41 INFO - "builduid": "1e9cab2592d44fe6a4f48ed33be2f596", 07:51:41 INFO - "slavebuilddir": "test" 07:51:41 INFO - } 07:51:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 07:51:41 INFO - Running post-action listener: _resource_record_post_action 07:51:41 INFO - [mozharness: 2017-03-23 14:51:41.169235Z] Finished read-buildbot-config step (success) 07:51:41 INFO - [mozharness: 2017-03-23 14:51:41.169370Z] Running download-and-extract step. 07:51:41 INFO - Running pre-action listener: _resource_record_pre_action 07:51:41 INFO - Running main action method: download_and_extract 07:51:41 INFO - mkdir: /builds/slave/test/build/tests 07:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:41 INFO - trying https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:51:41 INFO - Downloading https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:51:42 INFO - Downloaded 1325 bytes. 07:51:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:51:42 INFO - Using the following test package requirements: 07:51:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:51:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:51:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:51:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:51:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'jsshell-mac.zip'], 07:51:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:51:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:51:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:51:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:51:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:51:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:51:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:51:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:51:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:51:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:51:42 INFO - Fetch https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:51:44 INFO - Expected file size: 52411657 07:51:44 INFO - Obtained file size: 52411657 07:52:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:52:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:52:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:52:00 INFO - Fetch https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:52:02 INFO - Expected file size: 52411657 07:52:02 INFO - Obtained file size: 52411657 07:52:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:52:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:52:18 INFO - Fetch https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:52:19 INFO - Expected file size: 14404171 07:52:19 INFO - Obtained file size: 14404171 07:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:19 INFO - trying https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:52:19 INFO - Downloading https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:52:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:52:21 INFO - Downloaded 75443161 bytes. 07:52:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:52:21 INFO - mkdir: /builds/slave/test/properties 07:52:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:52:21 INFO - Writing to file /builds/slave/test/properties/build_url 07:52:21 INFO - Contents: 07:52:21 INFO - build_url:https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:52:22 INFO - Running post-action listener: _resource_record_post_action 07:52:22 INFO - Running post-action listener: set_extra_try_arguments 07:52:22 INFO - [mozharness: 2017-03-23 14:52:22.581744Z] Finished download-and-extract step (success) 07:52:22 INFO - [mozharness: 2017-03-23 14:52:22.581871Z] Running populate-webroot step. 07:52:22 INFO - Running pre-action listener: _resource_record_pre_action 07:52:22 INFO - Running main action method: populate_webroot 07:52:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:52:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:52:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:52:22 INFO - '--tppagecycles', 07:52:22 INFO - '7'], 07:52:22 INFO - 'tests': ['tsvgm']}}, 07:52:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:52:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:52:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:52:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:52:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:52:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['damp', 'tps']}, 07:52:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'tests': ['damp', 'tps']}, 07:52:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['dromaeo_dom']}, 07:52:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:52:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['basic_compositor_video']}, 07:52:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:52:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'other-e10s': {'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['a11yr', 07:52:22 INFO - 'ts_paint', 07:52:22 INFO - 'tpaint', 07:52:22 INFO - 'sessionrestore', 07:52:22 INFO - 'sessionrestore_no_auto_restore', 07:52:22 INFO - 'tabpaint']}, 07:52:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['tsvgx', 07:52:22 INFO - 'tsvgr_opacity', 07:52:22 INFO - 'tart', 07:52:22 INFO - 'tscrollx', 07:52:22 INFO - 'cart', 07:52:22 INFO - 'tsvg_static']}, 07:52:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:52:22 INFO - 'tsvgr_opacity', 07:52:22 INFO - 'tart', 07:52:22 INFO - 'tscrollx', 07:52:22 INFO - 'cart', 07:52:22 INFO - 'tsvg_static']}, 07:52:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'talos_options': ['--disable-e10s'], 07:52:22 INFO - 'tests': ['tp5o']}, 07:52:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:52:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'talos_options': ['--disable-e10s', 07:52:22 INFO - '--xperf_path', 07:52:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:22 INFO - 'tests': ['tp5n']}, 07:52:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:52:22 INFO - 'talos_options': ['--xperf_path', 07:52:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:52:22 INFO - 'tests': ['tp5n']}}, 07:52:22 INFO - 'talos.zip': {'path': '', 07:52:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:52:22 INFO - Running post-action listener: _resource_record_post_action 07:52:22 INFO - [mozharness: 2017-03-23 14:52:22.600260Z] Finished populate-webroot step (success) 07:52:22 INFO - [mozharness: 2017-03-23 14:52:22.600401Z] Running create-virtualenv step. 07:52:22 INFO - Running pre-action listener: _resource_record_pre_action 07:52:22 INFO - Running main action method: create_virtualenv 07:52:22 INFO - Creating virtualenv /builds/slave/test/build/venv 07:52:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:52:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:52:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:52:22 INFO - Using real prefix '/tools/python27' 07:52:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:52:23 INFO - Installing distribute.............................................................................................................................................................................................done. 07:52:26 INFO - Installing pip.................done. 07:52:26 INFO - Return code: 0 07:52:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:52:26 INFO - Reading from file tmpfile_stdout 07:52:26 INFO - Output received: 07:52:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:52:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:26 INFO - 'HOME': '/Users/cltbld', 07:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:26 INFO - 'LOGNAME': 'cltbld', 07:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:26 INFO - 'MOZ_NO_REMOTE': '1', 07:52:26 INFO - 'NO_EM_RESTART': '1', 07:52:26 INFO - 'PAGER': '/bin/cat', 07:52:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:26 INFO - 'PWD': '/builds/slave/test', 07:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:26 INFO - 'SHELL': '/bin/bash', 07:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:26 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:26 INFO - 'USER': 'cltbld', 07:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:26 INFO - 'XPC_FLAGS': '0x0', 07:52:26 INFO - 'XPC_SERVICE_NAME': '0', 07:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:52:26 INFO - Cleaning up... 07:52:26 INFO - Return code: 0 07:52:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:26 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:26 INFO - 'HOME': '/Users/cltbld', 07:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:26 INFO - 'LOGNAME': 'cltbld', 07:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:26 INFO - 'MOZ_NO_REMOTE': '1', 07:52:26 INFO - 'NO_EM_RESTART': '1', 07:52:26 INFO - 'PAGER': '/bin/cat', 07:52:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:26 INFO - 'PWD': '/builds/slave/test', 07:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:26 INFO - 'SHELL': '/bin/bash', 07:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:26 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:26 INFO - 'USER': 'cltbld', 07:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:26 INFO - 'XPC_FLAGS': '0x0', 07:52:26 INFO - 'XPC_SERVICE_NAME': '0', 07:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:27 INFO - Downloading/unpacking psutil>=3.1.1 07:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:52:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:31 INFO - Installing collected packages: psutil 07:52:31 INFO - Running setup.py install for psutil 07:52:31 INFO - building 'psutil._psutil_osx' extension 07:52:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:52:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:52:32 INFO - building 'psutil._psutil_posix' extension 07:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:52:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:52:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:52:32 INFO - ^ 07:52:32 INFO - 1 warning generated. 07:52:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:52:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:52:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:52:32 INFO - Successfully installed psutil 07:52:32 INFO - Cleaning up... 07:52:32 INFO - Return code: 0 07:52:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:33 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:33 INFO - 'HOME': '/Users/cltbld', 07:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:33 INFO - 'LOGNAME': 'cltbld', 07:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:33 INFO - 'MOZ_NO_REMOTE': '1', 07:52:33 INFO - 'NO_EM_RESTART': '1', 07:52:33 INFO - 'PAGER': '/bin/cat', 07:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:33 INFO - 'PWD': '/builds/slave/test', 07:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:33 INFO - 'SHELL': '/bin/bash', 07:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:33 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:33 INFO - 'USER': 'cltbld', 07:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:33 INFO - 'XPC_FLAGS': '0x0', 07:52:33 INFO - 'XPC_SERVICE_NAME': '0', 07:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:52:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:52:37 INFO - Installing collected packages: mozsystemmonitor 07:52:37 INFO - Running setup.py install for mozsystemmonitor 07:52:37 INFO - Successfully installed mozsystemmonitor 07:52:37 INFO - Cleaning up... 07:52:37 INFO - Return code: 0 07:52:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:37 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:52:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:37 INFO - 'HOME': '/Users/cltbld', 07:52:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:37 INFO - 'LOGNAME': 'cltbld', 07:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:37 INFO - 'MOZ_NO_REMOTE': '1', 07:52:37 INFO - 'NO_EM_RESTART': '1', 07:52:37 INFO - 'PAGER': '/bin/cat', 07:52:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:37 INFO - 'PWD': '/builds/slave/test', 07:52:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:37 INFO - 'SHELL': '/bin/bash', 07:52:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:37 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:37 INFO - 'USER': 'cltbld', 07:52:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:37 INFO - 'XPC_FLAGS': '0x0', 07:52:37 INFO - 'XPC_SERVICE_NAME': '0', 07:52:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:37 INFO - Downloading/unpacking jsonschema==2.5.1 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:52:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:40 INFO - Downloading functools32-3.2.3-2.tar.gz 07:52:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:52:40 INFO - warning: no files found matching '*.txt' 07:52:40 INFO - no previously-included directories found matching 'build' 07:52:40 INFO - no previously-included directories found matching 'dist' 07:52:40 INFO - no previously-included directories found matching '.git*' 07:52:40 INFO - Installing collected packages: jsonschema, functools32 07:52:40 INFO - Running setup.py install for functools32 07:52:40 INFO - warning: no files found matching '*.txt' 07:52:40 INFO - no previously-included directories found matching 'build' 07:52:40 INFO - no previously-included directories found matching 'dist' 07:52:40 INFO - no previously-included directories found matching '.git*' 07:52:40 INFO - Successfully installed jsonschema functools32 07:52:40 INFO - Cleaning up... 07:52:40 INFO - Return code: 0 07:52:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:40 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:52:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:40 INFO - 'HOME': '/Users/cltbld', 07:52:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:40 INFO - 'LOGNAME': 'cltbld', 07:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:40 INFO - 'MOZ_NO_REMOTE': '1', 07:52:40 INFO - 'NO_EM_RESTART': '1', 07:52:40 INFO - 'PAGER': '/bin/cat', 07:52:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:40 INFO - 'PWD': '/builds/slave/test', 07:52:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:40 INFO - 'SHELL': '/bin/bash', 07:52:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:40 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:40 INFO - 'USER': 'cltbld', 07:52:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:40 INFO - 'XPC_FLAGS': '0x0', 07:52:40 INFO - 'XPC_SERVICE_NAME': '0', 07:52:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:52:41 INFO - Cleaning up... 07:52:41 INFO - Return code: 0 07:52:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:52:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:52:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:41 INFO - 'HOME': '/Users/cltbld', 07:52:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:41 INFO - 'LOGNAME': 'cltbld', 07:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:41 INFO - 'MOZ_NO_REMOTE': '1', 07:52:41 INFO - 'NO_EM_RESTART': '1', 07:52:41 INFO - 'PAGER': '/bin/cat', 07:52:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:41 INFO - 'PWD': '/builds/slave/test', 07:52:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:41 INFO - 'SHELL': '/bin/bash', 07:52:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:41 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:41 INFO - 'USER': 'cltbld', 07:52:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:41 INFO - 'XPC_FLAGS': '0x0', 07:52:41 INFO - 'XPC_SERVICE_NAME': '0', 07:52:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:41 INFO - Downloading/unpacking blobuploader==1.2.4 07:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:43 INFO - Downloading blobuploader-1.2.4.tar.gz 07:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:52:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:52:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:45 INFO - Downloading docopt-0.6.1.tar.gz 07:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:52:45 INFO - Installing collected packages: blobuploader, requests, docopt 07:52:45 INFO - Running setup.py install for blobuploader 07:52:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:52:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:52:45 INFO - Running setup.py install for requests 07:52:45 INFO - Running setup.py install for docopt 07:52:46 INFO - Successfully installed blobuploader requests docopt 07:52:46 INFO - Cleaning up... 07:52:46 INFO - Return code: 0 07:52:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:46 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:52:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:46 INFO - 'HOME': '/Users/cltbld', 07:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:46 INFO - 'LOGNAME': 'cltbld', 07:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:46 INFO - 'MOZ_NO_REMOTE': '1', 07:52:46 INFO - 'NO_EM_RESTART': '1', 07:52:46 INFO - 'PAGER': '/bin/cat', 07:52:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:46 INFO - 'PWD': '/builds/slave/test', 07:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:46 INFO - 'SHELL': '/bin/bash', 07:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:46 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:46 INFO - 'USER': 'cltbld', 07:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:46 INFO - 'XPC_FLAGS': '0x0', 07:52:46 INFO - 'XPC_SERVICE_NAME': '0', 07:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZIJAwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-a2PGCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-_jYNzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-CEmjBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-g3jrJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:46 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fYdSMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zCADu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-K1nEm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6WRt2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1gMdY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ogIbnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5oQHHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-4uflgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-BQxlUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9REMAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:47 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-t3j0Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:48 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LDKGlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:52:48 INFO - Running setup.py install for manifestparser 07:52:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:52:48 INFO - Running setup.py install for mozcrash 07:52:48 INFO - Running setup.py install for mozdebug 07:52:48 INFO - Running setup.py install for mozdevice 07:52:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:52:48 INFO - Running setup.py install for mozfile 07:52:48 INFO - Running setup.py install for mozhttpd 07:52:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Running setup.py install for mozinfo 07:52:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Running setup.py install for mozInstall 07:52:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Running setup.py install for mozleak 07:52:49 INFO - Running setup.py install for mozlog 07:52:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Running setup.py install for moznetwork 07:52:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:52:49 INFO - Running setup.py install for mozprocess 07:52:49 INFO - Running setup.py install for mozprofile 07:52:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:52:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:52:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:52:50 INFO - Running setup.py install for mozrunner 07:52:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:52:50 INFO - Running setup.py install for mozscreenshot 07:52:50 INFO - Running setup.py install for moztest 07:52:50 INFO - Running setup.py install for mozversion 07:52:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:52:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:52:50 INFO - Cleaning up... 07:52:50 INFO - Return code: 0 07:52:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:52:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:50 INFO - 'HOME': '/Users/cltbld', 07:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:50 INFO - 'LOGNAME': 'cltbld', 07:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:50 INFO - 'MOZ_NO_REMOTE': '1', 07:52:50 INFO - 'NO_EM_RESTART': '1', 07:52:50 INFO - 'PAGER': '/bin/cat', 07:52:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:50 INFO - 'PWD': '/builds/slave/test', 07:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:50 INFO - 'SHELL': '/bin/bash', 07:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:50 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:50 INFO - 'USER': 'cltbld', 07:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:50 INFO - 'XPC_FLAGS': '0x0', 07:52:50 INFO - 'XPC_SERVICE_NAME': '0', 07:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZROQsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-4pYX96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-X95X27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qytBp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:52:51 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)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hMmwi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-jVzRrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-YDO1ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-UOv4vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9ImD5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:52:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:52:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:52:51 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-_xSVAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-eivi0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Fps9C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zE9wMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-o59XYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-nyfBcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-7BOmBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:52:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-uAs6cN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:52:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:52:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:52:55 INFO - Downloading blessings-1.6.tar.gz 07:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:52:55 INFO - Installing collected packages: blessings 07:52:55 INFO - Running setup.py install for blessings 07:52:55 INFO - Successfully installed blessings 07:52:55 INFO - Cleaning up... 07:52:55 INFO - Return code: 0 07:52:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:52:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:56 INFO - Reading from file tmpfile_stdout 07:52:56 INFO - Current package versions: 07:52:56 INFO - blessings == 1.6 07:52:56 INFO - blobuploader == 1.2.4 07:52:56 INFO - docopt == 0.6.1 07:52:56 INFO - functools32 == 3.2.3-2 07:52:56 INFO - jsonschema == 2.5.1 07:52:56 INFO - manifestparser == 1.1 07:52:56 INFO - mozInstall == 1.12 07:52:56 INFO - mozcrash == 1.0 07:52:56 INFO - mozdebug == 0.1 07:52:56 INFO - mozdevice == 0.49 07:52:56 INFO - mozfile == 1.2 07:52:56 INFO - mozhttpd == 0.7 07:52:56 INFO - mozinfo == 0.9 07:52:56 INFO - mozleak == 0.1 07:52:56 INFO - mozlog == 3.4 07:52:56 INFO - moznetwork == 0.27 07:52:56 INFO - mozprocess == 0.25 07:52:56 INFO - mozprofile == 0.28 07:52:56 INFO - mozrunner == 6.13 07:52:56 INFO - mozscreenshot == 0.1 07:52:56 INFO - mozsystemmonitor == 0.3 07:52:56 INFO - moztest == 0.8 07:52:56 INFO - mozversion == 1.4 07:52:56 INFO - psutil == 3.1.1 07:52:56 INFO - requests == 1.2.3 07:52:56 INFO - wsgiref == 0.1.2 07:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:56 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 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:56 INFO - 'HOME': '/Users/cltbld', 07:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:56 INFO - 'LOGNAME': 'cltbld', 07:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:56 INFO - 'MOZ_NO_REMOTE': '1', 07:52:56 INFO - 'NO_EM_RESTART': '1', 07:52:56 INFO - 'PAGER': '/bin/cat', 07:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:56 INFO - 'PWD': '/builds/slave/test', 07:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:56 INFO - 'SHELL': '/bin/bash', 07:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:56 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:56 INFO - 'USER': 'cltbld', 07:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:56 INFO - 'XPC_FLAGS': '0x0', 07:52:56 INFO - 'XPC_SERVICE_NAME': '0', 07:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:52:56 INFO - Cleaning up... 07:52:56 INFO - Return code: 0 07:52:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:52:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc1954f2c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, '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.JFWy37xhXh/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.jwDSXbceLV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:52:56 INFO - 'HOME': '/Users/cltbld', 07:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:52:56 INFO - 'LOGNAME': 'cltbld', 07:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:52:56 INFO - 'MOZ_NO_REMOTE': '1', 07:52:56 INFO - 'NO_EM_RESTART': '1', 07:52:56 INFO - 'PAGER': '/bin/cat', 07:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:52:56 INFO - 'PWD': '/builds/slave/test', 07:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:52:56 INFO - 'SHELL': '/bin/bash', 07:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:52:56 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:52:56 INFO - 'USER': 'cltbld', 07:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:52:56 INFO - 'XPC_FLAGS': '0x0', 07:52:56 INFO - 'XPC_SERVICE_NAME': '0', 07:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:52:56 INFO - Cleaning up... 07:52:56 INFO - Return code: 0 07:52:56 INFO - Running post-action listener: _resource_record_post_action 07:52:56 INFO - Running post-action listener: _start_resource_monitoring 07:52:56 INFO - Starting resource monitoring. 07:52:56 INFO - [mozharness: 2017-03-23 14:52:56.624755Z] Finished create-virtualenv step (success) 07:52:56 INFO - [mozharness: 2017-03-23 14:52:56.625717Z] Running install step. 07:52:56 INFO - Running pre-action listener: _resource_record_pre_action 07:52:56 INFO - Running main action method: install 07:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:52:56 INFO - Reading from file tmpfile_stdout 07:52:56 INFO - Detecting whether we're running mozinstall >=1.0... 07:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:52:56 INFO - Reading from file tmpfile_stdout 07:52:56 INFO - Output received: 07:52:56 INFO - Usage: mozinstall [options] installer 07:52:56 INFO - Options: 07:52:56 INFO - -h, --help show this help message and exit 07:52:56 INFO - -d DEST, --destination=DEST 07:52:56 INFO - Directory to install application into. [default: 07:52:56 INFO - "/builds/slave/test"] 07:52:56 INFO - --app=APP Application being installed. [default: firefox] 07:52:56 INFO - mkdir: /builds/slave/test/build/application 07:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:53:17 INFO - Reading from file tmpfile_stdout 07:53:17 INFO - Output received: 07:53:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:53:17 INFO - Running post-action listener: _resource_record_post_action 07:53:17 INFO - [mozharness: 2017-03-23 14:53:17.450273Z] Finished install step (success) 07:53:17 INFO - [mozharness: 2017-03-23 14:53:17.450391Z] Running run-tests step. 07:53:17 INFO - Running pre-action listener: _resource_record_pre_action 07:53:17 INFO - Running main action method: run_tests 07:53:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:17 INFO - 2017-03-23 07:53:17.464 screenresolution[1618:7296] starting screenresolution argv=screenresolution get 07:53:17 INFO - 2017-03-23 07:53:17.467 screenresolution[1618:7296] Display 0: 1600x1200x32@60 07:53:17 INFO - 2017-03-23 07:53:17.473 screenresolution[1619:7298] starting screenresolution argv=screenresolution list 07:53:17 INFO - Available Modes on Display 0 07:53:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:17 INFO - Intel Iris: 07:53:17 INFO - Chipset Model: Intel Iris 07:53:17 INFO - Type: GPU 07:53:17 INFO - Bus: Built-In 07:53:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:17 INFO - Vendor: Intel (0x8086) 07:53:17 INFO - Device ID: 0x0a2e 07:53:17 INFO - Revision ID: 0x0009 07:53:17 INFO - Displays: 07:53:17 INFO - Display: 07:53:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:17 INFO - Display Serial Number: 1600x1200 60 07:53:17 INFO - Main Display: Yes 07:53:17 INFO - Mirror: Off 07:53:17 INFO - Online: Yes 07:53:17 INFO - Rotation: Supported 07:53:17 INFO - Return code: 0 07:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:53:17 INFO - Python 2.7.3 07:53:17 INFO - Return code: 0 07:53:17 INFO - grabbing minidump binary from tooltool 07:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:53:17 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 0x10495cd30>, '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 0x104883db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104951be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:53:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:53:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:53:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:53:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:53:17 INFO - Return code: 0 07:53:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:53:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:53:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:53:17 INFO - ENV: RUST_BACKTRACE is now 1 07:53:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:53:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:53:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JFWy37xhXh/Render', 07:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:53:17 INFO - 'HOME': '/Users/cltbld', 07:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:53:17 INFO - 'LOGNAME': 'cltbld', 07:53:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:53:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:53:17 INFO - 'MOZ_NO_REMOTE': '1', 07:53:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:53:17 INFO - 'NO_EM_RESTART': '1', 07:53:17 INFO - 'PAGER': '/bin/cat', 07:53:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:53:17 INFO - 'PWD': '/builds/slave/test', 07:53:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:53:17 INFO - 'RUST_BACKTRACE': '1', 07:53:17 INFO - 'SHELL': '/bin/bash', 07:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jwDSXbceLV/Listeners', 07:53:17 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:53:17 INFO - 'USER': 'cltbld', 07:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:53:17 INFO - 'XPC_FLAGS': '0x0', 07:53:17 INFO - 'XPC_SERVICE_NAME': '0', 07:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:53:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:53:18 INFO - mozversion application_buildid: 20170323062923 07:53:18 INFO - mozversion application_changeset: fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 07:53:18 INFO - mozversion application_display_name: Nightly 07:53:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:53:18 INFO - mozversion application_name: Firefox 07:53:18 INFO - mozversion application_remotingname: firefox 07:53:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:53:18 INFO - mozversion application_vendor: Mozilla 07:53:18 INFO - mozversion application_version: 55.0a1 07:53:18 INFO - mozversion platform_buildid: 20170323062923 07:53:18 INFO - mozversion platform_changeset: fcfdeb52c2b2f020631fd47b5b02ea0be50d4027 07:53:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:53:18 INFO - mozversion platform_version: 55.0a1 07:53:18 INFO - using testdate: 1490280798 07:53:18 INFO - actual date: 1490280798 07:53:18 INFO - starting webserver on 'localhost:49243' 07:53:18 INFO - SUITE-START | Running 2 tests 07:53:18 INFO - TEST-START | dromaeo_css 07:53:18 INFO - operating with platform_type : mac_ 07:53:18 INFO - Initialising browser for dromaeo_css test... 07:53:18 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmprazSdZ/profile http://localhost:49243/getInfo.html) 07:53:20 INFO - PID 1629 | __metrics Screen width/height:1600/1200 07:53:20 INFO - PID 1629 | colorDepth:24 07:53:20 INFO - PID 1629 | Browser inner width/height: 1024/689 07:53:20 INFO - PID 1629 | __metrics 07:53:20 INFO - PID 1629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:53:20 INFO - PID 1629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:53:21 INFO - TEST-INFO | 1629: exit 0 07:53:21 INFO - Browser initialized. 07:53:21 INFO - Running cycle 1/1 for dromaeo_css test... 07:53:21 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmprazSdZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:53:33 INFO - PID 1631 | RSS: Main: 234127360 07:53:33 INFO - PID 1631 | 07:56:38 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 07:56:40 INFO - PID 1631 | RSS: Main: 421330944 07:56:40 INFO - PID 1631 | 07:58:23 INFO - PID 1631 | ************************* 07:58:23 INFO - PID 1631 | A coding exception was thrown and uncaught in a Task. 07:58:23 INFO - PID 1631 | 07:58:23 ERROR - PID 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:58:23 INFO - PID 1631 | Full stack: 07:58:23 INFO - PID 1631 | ************************* 07:59:43 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 07:59:44 INFO - PID 1631 | RSS: Main: 374784000 07:59:44 INFO - PID 1631 | 08:02:47 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:02:47 INFO - PID 1631 | RSS: Main: 337502208 08:02:47 INFO - PID 1631 | 08:05:50 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:05:50 INFO - PID 1631 | RSS: Main: 334065664 08:05:50 INFO - PID 1631 | 08:08:54 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:08:55 INFO - PID 1631 | RSS: Main: 337776640 08:08:55 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:11:58 INFO - PID 1631 | RSS: Main: 417329152 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | __start_tp_report 08:11:58 INFO - PID 1631 | _x_x_mozilla_page_load 08:11:58 INFO - PID 1631 | _x_x_mozilla_page_load_details 08:11:58 INFO - PID 1631 | |i|pagename|runs| 08:11:58 INFO - PID 1631 | |0;dojo.html;3446.5519482467275;3451.534042904207;3479.5128681984525;3514.2620049789543;3533.858645654174;6088.45203931646;6127.311699317087;6145.354737752533;6166.7533298667995;6185.969070154649;6096.889554637394;6100.46006561023;6102.633841969474;6113.445900579411;6125.418085281891;5321;5416.945830541703;5451.04606693827;5538.113901775717;5581.302337207849;5349.22436246744;5430.5927055471;5536.557075433976;5560.471755183271;5566.2207290979295;5408.4050754416985;5417.729113544347;5442.292501974757;5445.83662490126;5449.618526703132;5417.4311697272005;5425.403205647375;5429.294191755086;5433.972830135864;5456.863578410532;5322.121849894763;5380.192971054373;5386.73066346685;5392.892142157135;5408.486193811582;5920.319163296233;5923.644581325134;5931.584789064767;5946.554008449383;5955.315138759055;6288.308286088527;6316;6321.525885558602;6322.873542529123;6332.620042797447;5232.293640358579;6360.268569114564;6430.0676401921455;6495.805125846232;6516.511261655347;6190.814275571785;6196.938030619681;6231.501479881598;6235.282942461584;6246.406591373812;6333.778317758856;6633.535652504278;6648.767293144813;6658.5671931324305;6659.367360100783;6276.246850377984;6322.462590679844;6331.050342448578;6333.113364129025;6334.144890439824;6474.6762661866715;6481.254655714653;6488.805335839932;6489.740410383626;6503.6097834130105;6365.427111559982;6374.075759014917;6374.713137908712;6378.808635740936;6383.4574061204385;6251.374862513712;6277.309495955441;6315.873682526324;6318.968405157945;6388.520860935449;4328.458942632171;5210.452902445264;5231.764570594332;5237.6333656643665;5260.00059988601;4640.767961601907;4644.558766917137;4645.9303110453375;4654.813807447664;4679.789409476582;5954.493868021266;6054.031354983182;6057.727402266821;6112.266528016667;6127.754889804447;6327.905081423783;6337.904931426032;6417;6469.902951455732;6491.837704057436;6125.14388507125;6143.139960405547;6148.292946311231;6167.290761562388;6171.9691401543605;5740.741666625012;5742.626729262584;5745.2243947067445;5747.453991870849;5758.596898217168;6353.555251132377;6408.070829729756;6432.099506069248;6499.772507962199;6578.76974305897;6300.558960872695;6335.524835637438;6455;6463.127477790438;6498.740050397932;6404.519661025349;6557.540972132096;6577.901331479936;6583;6612.867742645217;6273.749050037947;6395.744170233324;6412.647304398303;6417.679116044273;6435.871282574416;6278.905816412666;6327.841803954938;6480.740770369321;6485.254195767548;6500.804975850732;6218.378162183926;6240.28236752761;6245.250569931637;6269.0909818075215;6291.653959032297;6090.756369745161;6281.811545653638;6286.214223222098;6289.0252010938375;6302.495800335871;5411.2965314508865;6233.688315584112;6275.466585340298;6279.811605651837;6296.464800492009;6208.130861679459;6267.4045965633195;6285.025820997753;6286.27707813589;6302;6272.090546870587;6292.842678933063;6299.149614802126;6301;6322.683865806784 08:11:58 INFO - PID 1631 | |1;ext.html;759.4380158682574;812.0296245986049;1131.8358837968494;1149.7528031473232;1154.7575009248062;35978.920632381036;36623.901282961524;36710;36890.63109368906;36912.909369953995;38222.80888595553;38496.61503384965;38545.421818672745;38586.61413385865;38673.41989870154;6093.421124993119;8434.536100514453;8599.48403095813;8603.311735061212;8630.223279904807;6121.540884433685;8344.541050242235;8540.444871083391;8542.145785421439;8554.230119289263;8584.914150858474;8601.95699021507;8622.13778622139;8632.956835215815;8659.09079546648;7387.630618469082;8622.956885215597;8629.180227878342;8634.784130396727;8646.956765216197;6040.124181993587;6184.474319682832;6194.38056194377;6207.782727604557;6244.687765611701;35211.29577408463;35549;35657.82171089154;35757.82121089378;37690.24619507622;2724.3461569223246;2957.009401850383;2967.139529536432;2986.716261955111;2995.6105706258263;26671.733282667312;26921.461570768475;27285.181444556696;27413.903443862222;27526.587101193498;2342.847714898526;2711.7559419652325;2733.084416720401;2749.2989287731502;2763.6821765497025;319709.9999999977;324986;325720;326436;326930;30760;37716.811415943295;50846;50873;50953;38974.415383769265;39638.60361396349;48686.026279474216;48745;48755.51244487581;13629.250391228577;13676.316184190751;13690.657733556542;13712.862871371159;13723.862761372458;11124.610638627772;11362.091032717362;11366;11381;11400.714982125513;12645.494180232688;12691.619251422288;12697.93651031739;12759.681007974876;12782.744345113046;5575.4424557543925;5619.662820230799;5630.831075067755;5634.577406694514;5637.802676906289;898.9325800564853;949.107838631684;949.8005418862116;967.6129548180784;983.7147227304001;23919.282421527383;23941.64087538688;23956.640650390258;23960.88019559891;24010.39974000664;1063.994680026595;1074.1889873145724;1087.8259478483405;1093.1582681335328;1115.2806439846254;11522.481488333045;11721.506013006217;12235.755284894252;12281.631551053482;12363.229215089485;434085;436034;446652.4686543886;447197;448966;55024;55607.721961389936;55854.16218756722;55902.440975590536;55909.72045139748;1289.368209577319;1513.5761986643756;1529.1436795394616;1535.8540938610813;1545.8917875748814;22885.42786430286;23441.531169376856;23582;23657.763422366246;23676.289711308687;32749.836250818593;38787.80606096952;38965.2206955865;39683.214255359155;40023.39964900471;426057;429752.87594803347;438701;438789;439121;32727;34035;34177;34307.31385372329;34311.485327720606;37943.05142371463;41268.96827579335;43939.78030109829;44086.77956610196;45058.87352816206;28143.554361318787;28430.857845710638;29680.40639187161;30271;30549;22751.431214219778;29646.851765741034;30364.69635303707;30789.384212316076;31040.379192416476;34457.79397721068;35705.46441803323;35830.925072247876;35928.46107308445;36752.26495470129;25025.874870625532;28934.710652894042;29159.833606656346;30088.09735707932;30213.546796798506;21201.469963251042;21287.148514059885;23478.882605586863;23724.881375593013;23738.762612373655 08:11:58 INFO - PID 1631 | |2;jquery.html;368.2285611643609;419.33637907258185;429.5875959079283;432.72089502271035;445.4009357414279;15175.924120379397;21235.57528849422;21758.171437713096;21764;22329.77670223297;17122.486325410257;22883.855807209657;23022.65466018011;23570.410739731473;23963.880180599073;4396.670249731267;6271.874562508747;6285.4343109120055;6565.376289252514;6569.40875321221;6213;6328.303886572473;6427.967860160693;6437.388448097423;6440.227172739278;6119.265688117433;6255.218097737783;6353.205849268841;6371.522135839808;6440.25937017242;5751.913721294184;6190.907136392958;6419.293877673475;6423.100765892778;6437.0988061671405;5454.290942177491;5623.9437605623825;5742.942570574282;5777.971110144465;5781.88436231273;20962;26856.328591785168;27092.91628334864;27117.593236101475;27177.45645087087;1944.2903340280836;1988.4034789563218;1989.0651393844869;2035.9592808143902;2043.6219299429654;15544;16114.194290285555;16179.514614561582;16359.672806543802;16360;1605.9036457812451;1656.4782093640586;1658.1626278729286;1665.350513299814;1683.1079527850259;112094;113645;138941;142016.28991854974;143797.99999999895;10830.891691083147;11704.414779261004;12398.6280411586;12416.875831241572;12562.183458075197;15047.021943573633;15333.386664533517;16253.918730406273;16397.180140992903;16854.662906741796;8700.34747393948;8747.737567872973;8996.28029757631;9062.410943288665;9152.176304132527;8486.236238738544;8555.091268968581;8604.651162790598;8606;8817.823643527221;6103.42017508336;7558.962205188939;7560.735374261874;7621.6570254338685;7687;1748.1783561725968;2062.9994452690435;2091.4562213824515;2126;2134.967975480338;843.2832092721138;890.8530092534788;908.7455512456522;944.1078181118777;949.3307218411004;20431.8978405107;20718.58562828735;21021;21045.789542104383;21076.367708968755;959.3524371049514;964.1419136968104;966.3863446711389;984.8768903887014;1004.2518323848633;6597.670116494252;9774.853377199348;10346.147536501303;10745;10782.25566887817;495414;564376.3562364406;685734;689469;707620;116774.41612791881;152154;156765;163125;164430.3556964439;1159.3449700919123;1199.5022065842752;1202.1765090912927;1210.6465683510714;1227.257509206917;20712.689309660065;21650.35048948534;22118.336449905884;22286.442838929157;22328.66507002364;54506.454935450136;57929.42070579409;60411;61372.69313653582;61520;84353;85769;85999;86766.1323386758;86858.56570717106;8383.874241886255;8471.39467962351;8683.305335573014;8728.563571821256;9048.864267035868;12921.612351629467;12932.482700692244;13460.798088028883;13559.932200338935;13730.45078196861;11094.611688590861;11190.776184476428;11248.102336925478;11255.324680519196;11541.884581154081;9801.656942007281;9863.605455781975;10282.948585257025;10317.535710893178;10531.78936421252;11227.66317010491;11347.54609815598;11416.94291528537;11544.59593914209;11621.593244236412;8199.9590002052;8259;8595.914040859512;8782.904985386613;8819.029906710128;11010.669679909615;11100.61147859821;11201.32792032448;11507.539698412305;11563.826542601699 08:11:58 INFO - PID 1631 | |3;mootools.html;1299.2983788754073;1358.0900796466367;1367.965800854978;1382.1637909065016;1386.2929905748065;8558.91441085589;10475.476226188699;10632.096271816905;10784.676459706212;10793.83809242861;10506.317089389184;10559.313644613096;10569.10162636175;10758.462076896143;10918.945405272963;2392.234484964812;2392.629142482918;2405.6151015837513;2414.9637755433682;2456.091246238894;2286.4855407533337;2356.4226764442737;2397.2927986244013;2418.2140804238607;2442.4748679034005;2032.430919342579;2065.989670051648;2088.2899814063217;2112.8943552822375;2352.094443639203;2347.3779448446176;2361.3388251289493;2366.4202270443852;2403.0147639467737;2428.623563347684;2323.76762323768;2331.160782118436;2334.5914464968564;2385.630227314769;2407.037185125946;10821.83767243492;10951.288166269247;11052.834207486814;11152.721181970435;11165.162612804072;6220.47125994291;6283.151774510428;6376.26672932614;6484.1894763154605;6485.83785405364;3737.9813100934593;3746.344389731786;3923.3526468132723;4100.0159961609;4118.6499147572495;1094.3652681444744;1120.1486869978876;1135.0692432205594;1136.9033632141277;1163.4706208675032;14745.631359216106;14983.32575034126;15173.696526069527;15309.928305018653;15310.004849684847;4920.114379411669;4991.650584459126;5124.410692770379;5298.072837253465;5384.246205531229;11108.444577771079;11210.66368008961;11312.377819220023;11461.598844040418;11467.54129834814;6041.818745437644;6054.878902422015;6167.352427995085;6202.689865506797;6223.284322302902;5472.398036216093;5580.49775520197;5777.364489906107;5802.564807639359;5880.7353669085;3523.013556204267;4044.272031034442;4047.3928910663635;4062.4097364671925;4076.857309994136;1230.889219970207;1244.8381710377594;1245.1221888568537;1267.6260503151593;1275.8150068240054;716.7992961970657;736.5323019882306;758.1811643425087;774.5159275452842;776.7397921696182;5615.691136987423;5757.712114394263;5936.5547583931375;6076;6108.755649774048;565.104309669179;581;587.9735411906474;592.831043152708;596.2963702830701;3210.7431405487505;3332.01705496879;4084.1219137885496;4196.202721482909;4205.495340559152;11155;12772.680682983;13832.723345533233;14232.786508202385;14299.85700143006;13447.663808404868;13504.594862154152;13528.391222395016;13599.456021759217;13799.586012419644;10009.949950250348;10041.297109202433;10285.588576457008;10409.011143941465;10437.634682786065;6522.988936714817;6534.8366290843105;6613.537052406477;6617.172853393326;6777.6272305023695;9495.952520237355;12279.2018518796;12637.431315590637;12673.619791406272;12721.491140354283;12323.815142772686;12355.196912200678;12568.622941311774;12645.178063425848;12721.427535761099;8694.08712085222;8937.374383793072;8986.505742184243;9137.77155571116;9138.543072846463;9833.67245421859;9894.653687120915;10015.24885633595;10015.599376024877;10141.239407044648;6807.3873351398615;8378.204070613281;8575.356848236532;8587.65649374018;8626.18051285127;7569.735059272899;8148;8304.252617264476;8305;8542.572871356531;7679.385649147943;7701.037370328709;7740.535567865946;7834.020747406575;7873.015873015873;4642.93035604473;5597.832065038056;5699.914501282401;5823.068309070529;5920.259967504062;4247.405363249086;5730.968425683417;5869.911951320819;5931.495822854933;5951.642901425928 08:11:58 INFO - PID 1631 | |4;prototype.html;394.0739262732578;398.96808255339573;402.0431373331472;404.56105125938353;407.65553107624066;4751.904961900768;5236.005159019788;5954.196183515224;5998.640081595107;6072;5556.222128901957;5779.335376431712;5843.941560584403;5957.344692083867;6041.848953776158;4596.931046034312;4610.2854057621125;4652.790624421892;4696.741679207642;4716.811327546894;4614.353990441341;4690.835820746274;4717.363155973934;4743.723012953784;4761.285807128941;4160.793369922734;4188.518320393148;4433.2241857674935;4505.819767209307;4710.411198600175;4347.152305300468;4471.35165401015;4471.910561788746;4479.888002799939;4499;3766.8304926278383;3818.4463252828186;3869.129445874684;3876.8255428505786;3888.6305800948867;4711.892705214272;4847.394075740533;5155;5693.3168019837885;5762.654240745569;4381.064686659945;5081.644284900059;5089.974550127263;5121.641485096045;5127.897442051138;6179.3202747697715;6266.749330026839;6301.2904451086015;6314.684265786692;6365.822322870284;3729.254149170177;4025.2955732746946;4236.6610671146545;4320.330348795942;4333.71830830995;6371.776987805404;6482.643454610042;6505.577137486048;6588.60468371904;6685.465162786965;3553.0051585556084;4178.958210417857;4318.848840290575;4389;4391.506887658148;3451.4822776583715;4086.59134086589;4425.933610995838;4437.400950871591;4444.2222611042935;4470.345971990384;4835.153848076572;5072.6195535335;5099.770510327045;5100.2349647553165;5342.385625653022;6388.904166437507;6402.039694045838;6439.227292724903;6463.353664633499;1411.2379315169899;1497.9176145311894;1505.0442968714726;1513.5383707969174;1541.9151946643042;980.9552826240032;982.3418309732497;996.2528103922059;1010.434156872154;1012.2610494339174;5325.869652139176;5741.425857414225;5938.138969849434;5939.762409503658;5964.343922168558;5412.91880621791;6534.411902928664;6571.737130514822;6582.901256481156;6671.3995740382625;4602.608778253819;4801.4718380978065;5341.572674186055;5851.473367396955;6044.879102417927;2855.500287449688;3406.2506248625264;3410.931781364359;3431.605365383013;3456.8098754568236;6430;6606.306337834458;6694.661067786462;6734.595924244561;6774.695138718769;6507.9023814642815;6636.336366363325;6710.765123220762;6760.222574403908;6780.559263647847;5335.839924802262;5342.759575819176;5695.202671625976;6739.123913891162;6792.626405547742;6259.874802504016;6394.200724909387;6594.109795177781;6612.222866396029;6644.734210631521;5583.106702927512;6015.488683461955;6070.514358851369;6092.299385570716;6568.671566421755;5562.886103280727;5594.964931487525;6149.569530133027;6699.598024118569;6718.227403848425;3838.7120965928225;4692.718436893797;4766.523347665206;4791.520847915181;4804.663673542958;5298.1522956328345;6051.727672254671;6108.5723999319625;6380.299426332623;6650.54171830655;5302.2046692996355;5758.913616295843;5951.880962380642;6162.22972128484;6353.4599559038;5557.499825015606;5979.910301345396;6200.875982480416;6337.366263373626;6338.714757835816;5164.483551644806;5987.221661184019;6045.727942242697;6097.32929377777;6203.348648391854;6202.81391558254;6355.713992870239;6404.935950640434;6419.550631455753;6422.871542569216;4750.9287360690305;6101.359357267423;6110.572259941761;6188.1027251049245;6198.6280823150755 08:11:58 INFO - PID 1631 | |5;yui.html;717.3722992381666;719.6114098386871;744.4081954845899;744.6202436757255;768.7117331000875;14688.706225875476;15400.691986160284;15473.845261547382;16805.579860503494;16926.322947082102;14935.253237338144;16411.507654770347;16770.74843877343;16860.409885654004;16872.240749166285;915.9313051521146;943.6791490893095;955.4649396338039;967.5404183013083;969.1858838575595;920.9309301802358;941.806929579438;958.5399008475936;962.4225464721181;970.7330484116853;988.9208863290955;989.990100098997;997.2171845101597;1017.760826205841;1018.9745256368576;975.4976187263564;980.6959842448864;993.4536005197112;1012.018342208057;1015.4922538730634;738.6122285799944;748.1097493982144;755.8601658693159;759.5594555157995;760.1410406240967;15561.610959726097;15673;15950.521484355488;16077.517674469784;16126.596835079217;11729.882701172965;14454.85545144556;14864.62838429037;15041.623959400993;15191.848081519154;11110.777784444346;11200.495977681025;11548.711282217928;11767.646970590897;11790.469428875722;2903.564465330196;2970.3316753730455;3093.5050391937184;3165.9208519786957;3186.64946855848;97448;97832;98608.50695746475;99682;99803.50098249534;8786.736397908073;10987.147580882425;11270.436478176058;11603.941980290045;11623.825642615368;23540;23670;23677;24097.397565061015;24157.51684966291;7390.260973902567;7567.356774674215;7683;7730.033745781778;7827.6477558510005;2945.8527073646233;4014.498187726534;4020.4572382728543;4087.6729861611;4231.513375961804;4112.527059388209;4136.875893723193;4169.541350451448;4201.537830838605;4228.429162063082;630.3098107572201;737.8893166025032;750.7184805697864;763.7555982085679;777.3936329662872;547.5510081733021;554.2794367322546;566.790287593593;573.2604939627834;596.2487266044728;9345.766355841159;9781.364211326241;10121.898781012096;10125.379939209692;10147.289689721796;526.7497938479191;559.152883381677;566.5184593095836;586.1354502109355;598.8682489852225;3875.224955008953;4393.780310984438;4450.599446049871;4618.191816432178;4688.929666055012;80998.59500702459;84575.30849382978;85247.57376213079;87042.1295787034;87334.1266587326;51426.25718495932;58113.709431452575;68192.6590367045;68462.31537684659;77458.06354841174;55517;70209.64895175594;71677.56644867074;76005.61997189978;76970.61514692503;10159.136473399843;10551.074270491308;10602.628907988183;10696.251262411557;10750.247482676137;35186.648133518334;35757.1060723484;35793.642063580075;36160.55357785659;36486;46691.83270418131;47146;47723;47729;48367.79080523015;12760.617181484571;12803.742022436072;13107.606771796862;13263.867361326264;13464.25946572948;12691.111622186358;12853.614391568268;14120.43518259258;14397.568072957829;14801.851981480479;12982.15615984958;13139.14595551286;13583.388747506187;13876.098053626481;13951.023428359917;12861.935690321488;13049.543265985645;13067.934660327019;13109.60671179866;13195.538156164488;12786.201611338029;12897.226166430044;13079.934600327319;13103.803442948554;13479.797803032767;8894.644214231359;9128.497932613504;9136.95431522838;9216.21662158724;9684.370515916444;8169.959150204211;10290.742731431774;10418.114460270961;10915.563377464812;11086.501107450345 08:11:58 INFO - PID 1631 | __end_tp_report 08:11:58 INFO - PID 1631 | __start_cc_report 08:11:58 INFO - PID 1631 | _x_x_mozilla_cycle_collect,2139 08:11:58 INFO - PID 1631 | __end_cc_report 08:11:58 INFO - PID 1631 | __startTimestamp1490281918914__endTimestamp 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | ------- Summary: start ------- 08:11:58 INFO - PID 1631 | Number of tests: 6 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#0] dojo.html Cycles:165 Average:5974.14 Median:6246.41 stddev:650.31 (10.4%) stddev-sans-first:621.34 08:11:58 INFO - PID 1631 | Values: 3446.6 3451.5 3479.5 3514.3 3533.9 6088.5 6127.3 6145.4 6166.8 6186.0 6096.9 6100.5 6102.6 6113.4 6125.4 5321.0 5416.9 5451.0 5538.1 5581.3 5349.2 5430.6 5536.6 5560.5 5566.2 5408.4 5417.7 5442.3 5445.8 5449.6 5417.4 5425.4 5429.3 5434.0 5456.9 5322.1 5380.2 5386.7 5392.9 5408.5 5920.3 5923.6 5931.6 5946.6 5955.3 6288.3 6316.0 6321.5 6322.9 6332.6 5232.3 6360.3 6430.1 6495.8 6516.5 6190.8 6196.9 6231.5 6235.3 6246.4 6333.8 6633.5 6648.8 6658.6 6659.4 6276.2 6322.5 6331.1 6333.1 6334.1 6474.7 6481.3 6488.8 6489.7 6503.6 6365.4 6374.1 6374.7 6378.8 6383.5 6251.4 6277.3 6315.9 6319.0 6388.5 4328.5 5210.5 5231.8 5237.6 5260.0 4640.8 4644.6 4645.9 4654.8 4679.8 5954.5 6054.0 6057.7 6112.3 6127.8 6327.9 6337.9 6417.0 6469.9 6491.8 6125.1 6143.1 6148.3 6167.3 6172.0 5740.7 5742.6 5745.2 5747.5 5758.6 6353.6 6408.1 6432.1 6499.8 6578.8 6300.6 6335.5 6455.0 6463.1 6498.7 6404.5 6557.5 6577.9 6583.0 6612.9 6273.7 6395.7 6412.6 6417.7 6435.9 6278.9 6327.8 6480.7 6485.3 6500.8 6218.4 6240.3 6245.3 6269.1 6291.7 6090.8 6281.8 6286.2 6289.0 6302.5 5411.3 6233.7 6275.5 6279.8 6296.5 6208.1 6267.4 6285.0 6286.3 6302.0 6272.1 6292.8 6299.1 6301.0 6322.7 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#1] ext.html Cycles:180 Average:52693.14 Median:34834.54 stddev:107424.19 (308.4%) stddev-sans-first:107654.77 08:11:58 INFO - PID 1631 | Values: 759.4 812.0 1131.8 1149.8 1154.8 35978.9 36623.9 36710.0 36890.6 36912.9 38222.8 38496.6 38545.4 38586.6 38673.4 6093.4 8434.5 8599.5 8603.3 8630.2 6121.5 8344.5 8540.4 8542.1 8554.2 8584.9 8602.0 8622.1 8633.0 8659.1 7387.6 8623.0 8629.2 8634.8 8647.0 6040.1 6184.5 6194.4 6207.8 6244.7 35211.3 35549.0 35657.8 35757.8 37690.2 2724.3 2957.0 2967.1 2986.7 2995.6 26671.7 26921.5 27285.2 27413.9 27526.6 2342.8 2711.8 2733.1 2749.3 2763.7 319710.0 324986.0 325720.0 326436.0 326930.0 30760.0 37716.8 50846.0 50873.0 50953.0 38974.4 39638.6 48686.0 48745.0 48755.5 13629.3 13676.3 13690.7 13712.9 13723.9 11124.6 11362.1 11366.0 11381.0 11400.7 12645.5 12691.6 12697.9 12759.7 12782.7 5575.4 5619.7 5630.8 5634.6 5637.8 898.9 949.1 949.8 967.6 983.7 23919.3 23941.6 23956.6 23960.9 24010.4 1064.0 1074.2 1087.8 1093.2 1115.3 11522.5 11721.5 12235.8 12281.6 12363.2 434085.0 436034.0 446652.5 447197.0 448966.0 55024.0 55607.7 55854.2 55902.4 55909.7 1289.4 1513.6 1529.1 1535.9 1545.9 22885.4 23441.5 23582.0 23657.8 23676.3 32749.8 38787.8 38965.2 39683.2 40023.4 426057.0 429752.9 438701.0 438789.0 439121.0 32727.0 34035.0 34177.0 34307.3 34311.5 37943.1 41269.0 43939.8 44086.8 45058.9 28143.6 28430.9 29680.4 30271.0 30549.0 22751.4 29646.9 30364.7 30789.4 31040.4 34457.8 35705.5 35830.9 35928.5 36752.3 25025.9 28934.7 29159.8 30088.1 30213.5 21201.5 21287.1 23478.9 23724.9 23738.8 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#2] jquery.html Cycles:180 Average:37735.55 Median:23296.53 stddev:106532.47 (457.3%) stddev-sans-first:106794.37 08:11:58 INFO - PID 1631 | Values: 368.2 419.3 429.6 432.7 445.4 15175.9 21235.6 21758.2 21764.0 22329.8 17122.5 22883.9 23022.7 23570.4 23963.9 4396.7 6271.9 6285.4 6565.4 6569.4 6213.0 6328.3 6428.0 6437.4 6440.2 6119.3 6255.2 6353.2 6371.5 6440.3 5751.9 6190.9 6419.3 6423.1 6437.1 5454.3 5623.9 5742.9 5778.0 5781.9 20962.0 26856.3 27092.9 27117.6 27177.5 1944.3 1988.4 1989.1 2036.0 2043.6 15544.0 16114.2 16179.5 16359.7 16360.0 1605.9 1656.5 1658.2 1665.4 1683.1 112094.0 113645.0 138941.0 142016.3 143798.0 10830.9 11704.4 12398.6 12416.9 12562.2 15047.0 15333.4 16253.9 16397.2 16854.7 8700.3 8747.7 8996.3 9062.4 9152.2 8486.2 8555.1 8604.7 8606.0 8817.8 6103.4 7559.0 7560.7 7621.7 7687.0 1748.2 2063.0 2091.5 2126.0 2135.0 843.3 890.9 908.7 944.1 949.3 20431.9 20718.6 21021.0 21045.8 21076.4 959.4 964.1 966.4 984.9 1004.3 6597.7 9774.9 10346.1 10745.0 10782.3 495414.0 564376.4 685734.0 689469.0 707620.0 116774.4 152154.0 156765.0 163125.0 164430.4 1159.3 1199.5 1202.2 1210.6 1227.3 20712.7 21650.4 22118.3 22286.4 22328.7 54506.5 57929.4 60411.0 61372.7 61520.0 84353.0 85769.0 85999.0 86766.1 86858.6 8383.9 8471.4 8683.3 8728.6 9048.9 12921.6 12932.5 13460.8 13559.9 13730.5 11094.6 11190.8 11248.1 11255.3 11541.9 9801.7 9863.6 10282.9 10317.5 10531.8 11227.7 11347.5 11416.9 11544.6 11621.6 8200.0 8259.0 8595.9 8782.9 8819.0 11010.7 11100.6 11201.3 11507.5 11563.8 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#3] mootools.html Cycles:180 Average:6621.58 Median:2833.42 stddev:4156.14 (146.7%) stddev-sans-first:4148.56 08:11:58 INFO - PID 1631 | Values: 1299.3 1358.1 1368.0 1382.2 1386.3 8558.9 10475.5 10632.1 10784.7 10793.8 10506.3 10559.3 10569.1 10758.5 10918.9 2392.2 2392.6 2405.6 2415.0 2456.1 2286.5 2356.4 2397.3 2418.2 2442.5 2032.4 2066.0 2088.3 2112.9 2352.1 2347.4 2361.3 2366.4 2403.0 2428.6 2323.8 2331.2 2334.6 2385.6 2407.0 10821.8 10951.3 11052.8 11152.7 11165.2 6220.5 6283.2 6376.3 6484.2 6485.8 3738.0 3746.3 3923.4 4100.0 4118.6 1094.4 1120.1 1135.1 1136.9 1163.5 14745.6 14983.3 15173.7 15309.9 15310.0 4920.1 4991.7 5124.4 5298.1 5384.2 11108.4 11210.7 11312.4 11461.6 11467.5 6041.8 6054.9 6167.4 6202.7 6223.3 5472.4 5580.5 5777.4 5802.6 5880.7 3523.0 4044.3 4047.4 4062.4 4076.9 1230.9 1244.8 1245.1 1267.6 1275.8 716.8 736.5 758.2 774.5 776.7 5615.7 5757.7 5936.6 6076.0 6108.8 565.1 581.0 588.0 592.8 596.3 3210.7 3332.0 4084.1 4196.2 4205.5 11155.0 12772.7 13832.7 14232.8 14299.9 13447.7 13504.6 13528.4 13599.5 13799.6 10009.9 10041.3 10285.6 10409.0 10437.6 6523.0 6534.8 6613.5 6617.2 6777.6 9496.0 12279.2 12637.4 12673.6 12721.5 12323.8 12355.2 12568.6 12645.2 12721.4 8694.1 8937.4 8986.5 9137.8 9138.5 9833.7 9894.7 10015.2 10015.6 10141.2 6807.4 8378.2 8575.4 8587.7 8626.2 7569.7 8148.0 8304.3 8305.0 8542.6 7679.4 7701.0 7740.5 7834.0 7873.0 4642.9 5597.8 5699.9 5823.1 5920.3 4247.4 5731.0 5869.9 5931.5 5951.6 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#4] prototype.html Cycles:180 Average:5071.55 Median:5560.19 stddev:1561.38 (28.1%) stddev-sans-first:1525.78 08:11:58 INFO - PID 1631 | Values: 394.1 399.0 402.0 404.6 407.7 4751.9 5236.0 5954.2 5998.6 6072.0 5556.2 5779.3 5843.9 5957.3 6041.8 4596.9 4610.3 4652.8 4696.7 4716.8 4614.4 4690.8 4717.4 4743.7 4761.3 4160.8 4188.5 4433.2 4505.8 4710.4 4347.2 4471.4 4471.9 4479.9 4499.0 3766.8 3818.4 3869.1 3876.8 3888.6 4711.9 4847.4 5155.0 5693.3 5762.7 4381.1 5081.6 5090.0 5121.6 5127.9 6179.3 6266.7 6301.3 6314.7 6365.8 3729.3 4025.3 4236.7 4320.3 4333.7 6371.8 6482.6 6505.6 6588.6 6685.5 3553.0 4179.0 4318.8 4389.0 4391.5 3451.5 4086.6 4425.9 4437.4 4444.2 4470.3 4835.2 5072.6 5099.8 5100.2 5342.4 6388.9 6402.0 6439.2 6463.4 1411.2 1497.9 1505.0 1513.5 1541.9 981.0 982.3 996.3 1010.4 1012.3 5325.9 5741.4 5938.1 5939.8 5964.3 5412.9 6534.4 6571.7 6582.9 6671.4 4602.6 4801.5 5341.6 5851.5 6044.9 2855.5 3406.3 3410.9 3431.6 3456.8 6430.0 6606.3 6694.7 6734.6 6774.7 6507.9 6636.3 6710.8 6760.2 6780.6 5335.8 5342.8 5695.2 6739.1 6792.6 6259.9 6394.2 6594.1 6612.2 6644.7 5583.1 6015.5 6070.5 6092.3 6568.7 5562.9 5595.0 6149.6 6699.6 6718.2 3838.7 4692.7 4766.5 4791.5 4804.7 5298.2 6051.7 6108.6 6380.3 6650.5 5302.2 5758.9 5951.9 6162.2 6353.5 5557.5 5979.9 6200.9 6337.4 6338.7 5164.5 5987.2 6045.7 6097.3 6203.3 6202.8 6355.7 6404.9 6419.6 6422.9 4750.9 6101.4 6110.6 6188.1 6198.6 08:11:58 INFO - PID 1631 | 08:11:58 INFO - PID 1631 | [#5] yui.html Cycles:180 Average:17977.39 Median:4124.70 stddev:24412.50 (591.9%) stddev-sans-first:24446.58 08:11:58 INFO - PID 1631 | Values: 717.4 719.6 744.4 744.6 768.7 14688.7 15400.7 15473.8 16805.6 16926.3 14935.3 16411.5 16770.7 16860.4 16872.2 915.9 943.7 955.5 967.5 969.2 920.9 941.8 958.5 962.4 970.7 988.9 990.0 997.2 1017.8 1019.0 975.5 980.7 993.5 1012.0 1015.5 738.6 748.1 755.9 759.6 760.1 15561.6 15673.0 15950.5 16077.5 16126.6 11729.9 14454.9 14864.6 15041.6 15191.8 11110.8 11200.5 11548.7 11767.6 11790.5 2903.6 2970.3 3093.5 3165.9 3186.6 97448.0 97832.0 98608.5 99682.0 99803.5 8786.7 10987.1 11270.4 11603.9 11623.8 23540.0 23670.0 23677.0 24097.4 24157.5 7390.3 7567.4 7683.0 7730.0 7827.6 2945.9 4014.5 4020.5 4087.7 4231.5 4112.5 4136.9 4169.5 4201.5 4228.4 630.3 737.9 750.7 763.8 777.4 547.6 554.3 566.8 573.3 596.2 9345.8 9781.4 10121.9 10125.4 10147.3 526.7 559.2 566.5 586.1 598.9 3875.2 4393.8 4450.6 4618.2 4688.9 80998.6 84575.3 85247.6 87042.1 87334.1 51426.3 58113.7 68192.7 68462.3 77458.1 55517.0 70209.6 71677.6 76005.6 76970.6 10159.1 10551.1 10602.6 10696.3 10750.2 35186.6 35757.1 35793.6 36160.6 36486.0 46691.8 47146.0 47723.0 47729.0 48367.8 12760.6 12803.7 13107.6 13263.9 13464.3 12691.1 12853.6 14120.4 14397.6 14801.9 12982.2 13139.1 13583.4 13876.1 13951.0 12861.9 13049.5 13067.9 13109.6 13195.5 12786.2 12897.2 13079.9 13103.8 13479.8 8894.6 9128.5 9137.0 9216.2 9684.4 8170.0 10290.7 10418.1 10915.6 11086.5 08:11:58 INFO - PID 1631 | -------- Summary: end -------- 08:11:58 INFO - PID 1631 | 08:11:59 INFO - TEST-INFO | 1631: exit 0 08:11:59 INFO - TEST-OK | dromaeo_css | took 1121532ms 08:11:59 INFO - TEST-START | kraken 08:11:59 INFO - operating with platform_type : mac_ 08:11:59 INFO - Initialising browser for kraken test... 08:11:59 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpRAMWXY/profile http://localhost:49243/getInfo.html) 08:12:02 INFO - PID 1641 | __metrics Screen width/height:1600/1200 08:12:02 INFO - PID 1641 | colorDepth:24 08:12:02 INFO - PID 1641 | Browser inner width/height: 1024/689 08:12:02 INFO - PID 1641 | __metrics 08:12:02 INFO - PID 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:12:02 INFO - PID 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:12:02 INFO - TEST-INFO | 1641: exit 0 08:12:02 INFO - Browser initialized. 08:12:02 INFO - Running cycle 1/1 for kraken test... 08:12:02 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpRAMWXY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:15 INFO - PID 1642 | RSS: Main: 233721856 08:12:15 INFO - PID 1642 | 08:12:17 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:12:18 INFO - PID 1642 | RSS: Main: 284565504 08:12:18 INFO - PID 1642 | 08:12:21 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:12:22 INFO - PID 1642 | RSS: Main: 285736960 08:12:22 INFO - PID 1642 | 08:12:25 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:12:25 INFO - PID 1642 | RSS: Main: 376545280 08:12:25 INFO - PID 1642 | 08:12:28 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:12:28 INFO - PID 1642 | RSS: Main: 279773184 08:12:28 INFO - PID 1642 | 08:12:30 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:12:31 INFO - PID 1642 | RSS: Main: 282824704 08:12:31 INFO - PID 1642 | 08:12:34 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:12:35 INFO - PID 1642 | RSS: Main: 324739072 08:12:35 INFO - PID 1642 | 08:12:38 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:12:38 INFO - PID 1642 | RSS: Main: 337821696 08:12:38 INFO - PID 1642 | 08:12:41 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:12:42 INFO - PID 1642 | RSS: Main: 346988544 08:12:42 INFO - PID 1642 | 08:12:43 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:12:44 INFO - PID 1642 | RSS: Main: 301084672 08:12:44 INFO - PID 1642 | 08:12:46 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:12:47 INFO - PID 1642 | RSS: Main: 305254400 08:12:47 INFO - PID 1642 | 08:12:49 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:12:49 INFO - PID 1642 | RSS: Main: 307757056 08:12:49 INFO - PID 1642 | 08:12:52 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:12:53 INFO - PID 1642 | RSS: Main: 326746112 08:12:53 INFO - PID 1642 | 08:12:56 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:12:57 INFO - PID 1642 | RSS: Main: 320544768 08:12:57 INFO - PID 1642 | 08:12:58 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:12:59 INFO - PID 1642 | RSS: Main: 382291968 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | __start_tp_report 08:12:59 INFO - PID 1642 | _x_x_mozilla_page_load 08:12:59 INFO - PID 1642 | _x_x_mozilla_page_load_details 08:12:59 INFO - PID 1642 | |i|pagename|runs| 08:12:59 INFO - PID 1642 | |0;ai-astar;111;112;112;112;112;114;112;112;113;113 08:12:59 INFO - PID 1642 | |1;audio-beat-detection;141;107;91;161;142;145;133;109;131;106 08:12:59 INFO - PID 1642 | |2;audio-dft;185;186;184;186;184;186;183;185;184;185 08:12:59 INFO - PID 1642 | |3;audio-fft;78;59;61;90;62;58;58;63;91;88 08:12:59 INFO - PID 1642 | |4;audio-oscillator;93;71;117;102;102;69;86;93;54;86 08:12:59 INFO - PID 1642 | |5;imaging-gaussian-blur;87;88;88;88;88;87;87;88;87;91 08:12:59 INFO - PID 1642 | |6;imaging-darkroom;95;95;95;101;95;95;95;95;94;95 08:12:59 INFO - PID 1642 | |7;imaging-desaturate;129;131;86;86;86;85;86;83;84;82 08:12:59 INFO - PID 1642 | |8;json-parse-financial;64;63;64;64;63;62;62;60;61;62 08:12:59 INFO - PID 1642 | |9;json-stringify-tinderbox;56;55;69;69;64;61;61;60;59;59 08:12:59 INFO - PID 1642 | |10;stanford-crypto-aes;83;83;83;83;82;82;82;80;82;83 08:12:59 INFO - PID 1642 | |11;stanford-crypto-ccm;143;142;141;137;136;137;135;155;199;176 08:12:59 INFO - PID 1642 | |12;stanford-crypto-pbkdf2;185;184;186;182;180;181;194;180;183;183 08:12:59 INFO - PID 1642 | |13;stanford-crypto-sha256-iterative;69;69;67;65;68;68;67;68;66;68 08:12:59 INFO - PID 1642 | __end_tp_report 08:12:59 INFO - PID 1642 | __start_cc_report 08:12:59 INFO - PID 1642 | _x_x_mozilla_cycle_collect,2154 08:12:59 INFO - PID 1642 | __end_cc_report 08:12:59 INFO - PID 1642 | __startTimestamp1490281979217__endTimestamp 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | ------- Summary: start ------- 08:12:59 INFO - PID 1642 | Number of tests: 14 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 08:12:59 INFO - PID 1642 | Values: 111.0 112.0 112.0 112.0 112.0 114.0 112.0 112.0 113.0 113.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:141.50 stddev:22.14 (15.6%) stddev-sans-first:22.86 08:12:59 INFO - PID 1642 | Values: 141.0 107.0 91.0 161.0 142.0 145.0 133.0 109.0 131.0 106.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 08:12:59 INFO - PID 1642 | Values: 185.0 186.0 184.0 186.0 184.0 186.0 183.0 185.0 184.0 185.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#3] audio-fft Cycles:10 Average:70.80 Median:70.50 stddev:14.24 (20.2%) stddev-sans-first:14.87 08:12:59 INFO - PID 1642 | Values: 78.0 59.0 61.0 90.0 62.0 58.0 58.0 63.0 91.0 88.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:78.50 stddev:18.54 (23.6%) stddev-sans-first:19.54 08:12:59 INFO - PID 1642 | Values: 93.0 71.0 117.0 102.0 102.0 69.0 86.0 93.0 54.0 86.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:1.20 (1.4%) stddev-sans-first:1.22 08:12:59 INFO - PID 1642 | Values: 87.0 88.0 88.0 88.0 88.0 87.0 87.0 88.0 87.0 91.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:95.00 stddev:1.96 (2.1%) stddev-sans-first:2.07 08:12:59 INFO - PID 1642 | Values: 95.0 95.0 95.0 101.0 95.0 95.0 95.0 95.0 94.0 95.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:85.50 stddev:19.14 (22.4%) stddev-sans-first:15.49 08:12:59 INFO - PID 1642 | Values: 129.0 131.0 86.0 86.0 86.0 85.0 86.0 83.0 84.0 82.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.35 (2.1%) stddev-sans-first:1.32 08:12:59 INFO - PID 1642 | Values: 64.0 63.0 64.0 64.0 63.0 62.0 62.0 60.0 61.0 62.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:4.79 (7.8%) stddev-sans-first:4.68 08:12:59 INFO - PID 1642 | Values: 56.0 55.0 69.0 69.0 64.0 61.0 61.0 60.0 59.0 59.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:0.95 (1.1%) stddev-sans-first:0.97 08:12:59 INFO - PID 1642 | Values: 83.0 83.0 83.0 83.0 82.0 82.0 82.0 80.0 82.0 83.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:142.50 stddev:21.23 (14.9%) stddev-sans-first:22.36 08:12:59 INFO - PID 1642 | Values: 143.0 142.0 141.0 137.0 136.0 137.0 135.0 155.0 199.0 176.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:183.50 stddev:4.10 (2.2%) stddev-sans-first:4.33 08:12:59 INFO - PID 1642 | Values: 185.0 184.0 186.0 182.0 180.0 181.0 194.0 180.0 183.0 183.0 08:12:59 INFO - PID 1642 | 08:12:59 INFO - PID 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.27 (1.9%) stddev-sans-first:1.22 08:12:59 INFO - PID 1642 | Values: 69.0 69.0 67.0 65.0 68.0 68.0 67.0 68.0 66.0 68.0 08:12:59 INFO - PID 1642 | -------- Summary: end -------- 08:12:59 INFO - PID 1642 | 08:13:00 INFO - TEST-INFO | 1642: exit 0 08:13:00 INFO - TEST-OK | kraken | took 60565ms 08:13:00 INFO - SUITE-END | took 1182s 08:13:00 INFO - Completed test suite (00:19:42) 08:13:00 INFO - javascript benchmark 08:13:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7458.0258225166635, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3446.5519482467275, 3451.534042904207, 3479.5128681984525, 3514.2620049789543, 3533.858645654174, 6088.45203931646, 6127.311699317087, 6145.354737752533, 6166.7533298667995, 6185.969070154649, 6096.889554637394, 6100.46006561023, 6102.633841969474, 6113.445900579411, 6125.418085281891, 5321.0, 5416.945830541703, 5451.04606693827, 5538.113901775717, 5581.302337207849, 5349.22436246744, 5430.5927055471, 5536.557075433976, 5560.471755183271, 5566.2207290979295, 5408.4050754416985, 5417.729113544347, 5442.292501974757, 5445.83662490126, 5449.618526703132, 5417.4311697272005, 5425.403205647375, 5429.294191755086, 5433.972830135864, 5456.863578410532, 5322.121849894763, 5380.192971054373, 5386.73066346685, 5392.892142157135, 5408.486193811582, 5920.319163296233, 5923.644581325134, 5931.584789064767, 5946.554008449383, 5955.315138759055, 6288.308286088527, 6316.0, 6321.525885558602, 6322.873542529123, 6332.620042797447, 5232.293640358579, 6360.268569114564, 6430.0676401921455, 6495.805125846232, 6516.511261655347, 6190.814275571785, 6196.938030619681, 6231.501479881598, 6235.282942461584, 6246.406591373812, 6333.778317758856, 6633.535652504278, 6648.767293144813, 6658.5671931324305, 6659.367360100783, 6276.246850377984, 6322.462590679844, 6331.050342448578, 6333.113364129025, 6334.144890439824, 6474.6762661866715, 6481.254655714653, 6488.805335839932, 6489.740410383626, 6503.6097834130105, 6365.427111559982, 6374.075759014917, 6374.713137908712, 6378.808635740936, 6383.4574061204385, 6251.374862513712, 6277.309495955441, 6315.873682526324, 6318.968405157945, 6388.520860935449, 4328.458942632171, 5210.452902445264, 5231.764570594332, 5237.6333656643665, 5260.00059988601, 4640.767961601907, 4644.558766917137, 4645.9303110453375, 4654.813807447664, 4679.789409476582, 5954.493868021266, 6054.031354983182, 6057.727402266821, 6112.266528016667, 6127.754889804447, 6327.905081423783, 6337.904931426032, 6417.0, 6469.902951455732, 6491.837704057436, 6125.14388507125, 6143.139960405547, 6148.292946311231, 6167.290761562388, 6171.9691401543605, 5740.741666625012, 5742.626729262584, 5745.2243947067445, 5747.453991870849, 5758.596898217168, 6353.555251132377, 6408.070829729756, 6432.099506069248, 6499.772507962199, 6578.76974305897, 6300.558960872695, 6335.524835637438, 6455.0, 6463.127477790438, 6498.740050397932, 6404.519661025349, 6557.540972132096, 6577.901331479936, 6583.0, 6612.867742645217, 6273.749050037947, 6395.744170233324, 6412.647304398303, 6417.679116044273, 6435.871282574416, 6278.905816412666, 6327.841803954938, 6480.740770369321, 6485.254195767548, 6500.804975850732, 6218.378162183926, 6240.28236752761, 6245.250569931637, 6269.0909818075215, 6291.653959032297, 6090.756369745161, 6281.811545653638, 6286.214223222098, 6289.0252010938375, 6302.495800335871, 5411.2965314508865, 6233.688315584112, 6275.466585340298, 6279.811605651837, 6296.464800492009, 6208.130861679459, 6267.4045965633195, 6285.025820997753, 6286.27707813589, 6302.0, 6272.090546870587, 6292.842678933063, 6299.149614802126, 6301.0, 6322.683865806784], "value": 5933.223264692817, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.4380158682574, 812.0296245986049, 1131.8358837968494, 1149.7528031473232, 1154.7575009248062, 35978.920632381036, 36623.901282961524, 36710.0, 36890.63109368906, 36912.909369953995, 38222.80888595553, 38496.61503384965, 38545.421818672745, 38586.61413385865, 38673.41989870154, 6093.421124993119, 8434.536100514453, 8599.48403095813, 8603.311735061212, 8630.223279904807, 6121.540884433685, 8344.541050242235, 8540.444871083391, 8542.145785421439, 8554.230119289263, 8584.914150858474, 8601.95699021507, 8622.13778622139, 8632.956835215815, 8659.09079546648, 7387.630618469082, 8622.956885215597, 8629.180227878342, 8634.784130396727, 8646.956765216197, 6040.124181993587, 6184.474319682832, 6194.38056194377, 6207.782727604557, 6244.687765611701, 35211.29577408463, 35549.0, 35657.82171089154, 35757.82121089378, 37690.24619507622, 2724.3461569223246, 2957.009401850383, 2967.139529536432, 2986.716261955111, 2995.6105706258263, 26671.733282667312, 26921.461570768475, 27285.181444556696, 27413.903443862222, 27526.587101193498, 2342.847714898526, 2711.7559419652325, 2733.084416720401, 2749.2989287731502, 2763.6821765497025, 319709.9999999977, 324986.0, 325720.0, 326436.0, 326930.0, 30760.0, 37716.811415943295, 50846.0, 50873.0, 50953.0, 38974.415383769265, 39638.60361396349, 48686.026279474216, 48745.0, 48755.51244487581, 13629.250391228577, 13676.316184190751, 13690.657733556542, 13712.862871371159, 13723.862761372458, 11124.610638627772, 11362.091032717362, 11366.0, 11381.0, 11400.714982125513, 12645.494180232688, 12691.619251422288, 12697.93651031739, 12759.681007974876, 12782.744345113046, 5575.4424557543925, 5619.662820230799, 5630.831075067755, 5634.577406694514, 5637.802676906289, 898.9325800564853, 949.107838631684, 949.8005418862116, 967.6129548180784, 983.7147227304001, 23919.282421527383, 23941.64087538688, 23956.640650390258, 23960.88019559891, 24010.39974000664, 1063.994680026595, 1074.1889873145724, 1087.8259478483405, 1093.1582681335328, 1115.2806439846254, 11522.481488333045, 11721.506013006217, 12235.755284894252, 12281.631551053482, 12363.229215089485, 434085.0, 436034.0, 446652.4686543886, 447197.0, 448966.0, 55024.0, 55607.721961389936, 55854.16218756722, 55902.440975590536, 55909.72045139748, 1289.368209577319, 1513.5761986643756, 1529.1436795394616, 1535.8540938610813, 1545.8917875748814, 22885.42786430286, 23441.531169376856, 23582.0, 23657.763422366246, 23676.289711308687, 32749.836250818593, 38787.80606096952, 38965.2206955865, 39683.214255359155, 40023.39964900471, 426057.0, 429752.87594803347, 438701.0, 438789.0, 439121.0, 32727.0, 34035.0, 34177.0, 34307.31385372329, 34311.485327720606, 37943.05142371463, 41268.96827579335, 43939.78030109829, 44086.77956610196, 45058.87352816206, 28143.554361318787, 28430.857845710638, 29680.40639187161, 30271.0, 30549.0, 22751.431214219778, 29646.851765741034, 30364.69635303707, 30789.384212316076, 31040.379192416476, 34457.79397721068, 35705.46441803323, 35830.925072247876, 35928.46107308445, 36752.26495470129, 25025.874870625532, 28934.710652894042, 29159.833606656346, 30088.09735707932, 30213.546796798506, 21201.469963251042, 21287.148514059885, 23478.882605586863, 23724.881375593013, 23738.762612373655], "value": 17142.911703265516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.2285611643609, 419.33637907258185, 429.5875959079283, 432.72089502271035, 445.4009357414279, 15175.924120379397, 21235.57528849422, 21758.171437713096, 21764.0, 22329.77670223297, 17122.486325410257, 22883.855807209657, 23022.65466018011, 23570.410739731473, 23963.880180599073, 4396.670249731267, 6271.874562508747, 6285.4343109120055, 6565.376289252514, 6569.40875321221, 6213.0, 6328.303886572473, 6427.967860160693, 6437.388448097423, 6440.227172739278, 6119.265688117433, 6255.218097737783, 6353.205849268841, 6371.522135839808, 6440.25937017242, 5751.913721294184, 6190.907136392958, 6419.293877673475, 6423.100765892778, 6437.0988061671405, 5454.290942177491, 5623.9437605623825, 5742.942570574282, 5777.971110144465, 5781.88436231273, 20962.0, 26856.328591785168, 27092.91628334864, 27117.593236101475, 27177.45645087087, 1944.2903340280836, 1988.4034789563218, 1989.0651393844869, 2035.9592808143902, 2043.6219299429654, 15544.0, 16114.194290285555, 16179.514614561582, 16359.672806543802, 16360.0, 1605.9036457812451, 1656.4782093640586, 1658.1626278729286, 1665.350513299814, 1683.1079527850259, 112094.0, 113645.0, 138941.0, 142016.28991854974, 143797.99999999895, 10830.891691083147, 11704.414779261004, 12398.6280411586, 12416.875831241572, 12562.183458075197, 15047.021943573633, 15333.386664533517, 16253.918730406273, 16397.180140992903, 16854.662906741796, 8700.34747393948, 8747.737567872973, 8996.28029757631, 9062.410943288665, 9152.176304132527, 8486.236238738544, 8555.091268968581, 8604.651162790598, 8606.0, 8817.823643527221, 6103.42017508336, 7558.962205188939, 7560.735374261874, 7621.6570254338685, 7687.0, 1748.1783561725968, 2062.9994452690435, 2091.4562213824515, 2126.0, 2134.967975480338, 843.2832092721138, 890.8530092534788, 908.7455512456522, 944.1078181118777, 949.3307218411004, 20431.8978405107, 20718.58562828735, 21021.0, 21045.789542104383, 21076.367708968755, 959.3524371049514, 964.1419136968104, 966.3863446711389, 984.8768903887014, 1004.2518323848633, 6597.670116494252, 9774.853377199348, 10346.147536501303, 10745.0, 10782.25566887817, 495414.0, 564376.3562364406, 685734.0, 689469.0, 707620.0, 116774.41612791881, 152154.0, 156765.0, 163125.0, 164430.3556964439, 1159.3449700919123, 1199.5022065842752, 1202.1765090912927, 1210.6465683510714, 1227.257509206917, 20712.689309660065, 21650.35048948534, 22118.336449905884, 22286.442838929157, 22328.66507002364, 54506.454935450136, 57929.42070579409, 60411.0, 61372.69313653582, 61520.0, 84353.0, 85769.0, 85999.0, 86766.1323386758, 86858.56570717106, 8383.874241886255, 8471.39467962351, 8683.305335573014, 8728.563571821256, 9048.864267035868, 12921.612351629467, 12932.482700692244, 13460.798088028883, 13559.932200338935, 13730.45078196861, 11094.611688590861, 11190.776184476428, 11248.102336925478, 11255.324680519196, 11541.884581154081, 9801.656942007281, 9863.605455781975, 10282.948585257025, 10317.535710893178, 10531.78936421252, 11227.66317010491, 11347.54609815598, 11416.94291528537, 11544.59593914209, 11621.593244236412, 8199.9590002052, 8259.0, 8595.914040859512, 8782.904985386613, 8819.029906710128, 11010.669679909615, 11100.61147859821, 11201.32792032448, 11507.539698412305, 11563.826542601699], "value": 10206.720770361211, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.2983788754073, 1358.0900796466367, 1367.965800854978, 1382.1637909065016, 1386.2929905748065, 8558.91441085589, 10475.476226188699, 10632.096271816905, 10784.676459706212, 10793.83809242861, 10506.317089389184, 10559.313644613096, 10569.10162636175, 10758.462076896143, 10918.945405272963, 2392.234484964812, 2392.629142482918, 2405.6151015837513, 2414.9637755433682, 2456.091246238894, 2286.4855407533337, 2356.4226764442737, 2397.2927986244013, 2418.2140804238607, 2442.4748679034005, 2032.430919342579, 2065.989670051648, 2088.2899814063217, 2112.8943552822375, 2352.094443639203, 2347.3779448446176, 2361.3388251289493, 2366.4202270443852, 2403.0147639467737, 2428.623563347684, 2323.76762323768, 2331.160782118436, 2334.5914464968564, 2385.630227314769, 2407.037185125946, 10821.83767243492, 10951.288166269247, 11052.834207486814, 11152.721181970435, 11165.162612804072, 6220.47125994291, 6283.151774510428, 6376.26672932614, 6484.1894763154605, 6485.83785405364, 3737.9813100934593, 3746.344389731786, 3923.3526468132723, 4100.0159961609, 4118.6499147572495, 1094.3652681444744, 1120.1486869978876, 1135.0692432205594, 1136.9033632141277, 1163.4706208675032, 14745.631359216106, 14983.32575034126, 15173.696526069527, 15309.928305018653, 15310.004849684847, 4920.114379411669, 4991.650584459126, 5124.410692770379, 5298.072837253465, 5384.246205531229, 11108.444577771079, 11210.66368008961, 11312.377819220023, 11461.598844040418, 11467.54129834814, 6041.818745437644, 6054.878902422015, 6167.352427995085, 6202.689865506797, 6223.284322302902, 5472.398036216093, 5580.49775520197, 5777.364489906107, 5802.564807639359, 5880.7353669085, 3523.013556204267, 4044.272031034442, 4047.3928910663635, 4062.4097364671925, 4076.857309994136, 1230.889219970207, 1244.8381710377594, 1245.1221888568537, 1267.6260503151593, 1275.8150068240054, 716.7992961970657, 736.5323019882306, 758.1811643425087, 774.5159275452842, 776.7397921696182, 5615.691136987423, 5757.712114394263, 5936.5547583931375, 6076.0, 6108.755649774048, 565.104309669179, 581.0, 587.9735411906474, 592.831043152708, 596.2963702830701, 3210.7431405487505, 3332.01705496879, 4084.1219137885496, 4196.202721482909, 4205.495340559152, 11155.0, 12772.680682983, 13832.723345533233, 14232.786508202385, 14299.85700143006, 13447.663808404868, 13504.594862154152, 13528.391222395016, 13599.456021759217, 13799.586012419644, 10009.949950250348, 10041.297109202433, 10285.588576457008, 10409.011143941465, 10437.634682786065, 6522.988936714817, 6534.8366290843105, 6613.537052406477, 6617.172853393326, 6777.6272305023695, 9495.952520237355, 12279.2018518796, 12637.431315590637, 12673.619791406272, 12721.491140354283, 12323.815142772686, 12355.196912200678, 12568.622941311774, 12645.178063425848, 12721.427535761099, 8694.08712085222, 8937.374383793072, 8986.505742184243, 9137.77155571116, 9138.543072846463, 9833.67245421859, 9894.653687120915, 10015.24885633595, 10015.599376024877, 10141.239407044648, 6807.3873351398615, 8378.204070613281, 8575.356848236532, 8587.65649374018, 8626.18051285127, 7569.735059272899, 8148.0, 8304.252617264476, 8305.0, 8542.572871356531, 7679.385649147943, 7701.037370328709, 7740.535567865946, 7834.020747406575, 7873.015873015873, 4642.93035604473, 5597.832065038056, 5699.914501282401, 5823.068309070529, 5920.259967504062, 4247.405363249086, 5730.968425683417, 5869.911951320819, 5931.495822854933, 5951.642901425928], "value": 4979.260306562787, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.0739262732578, 398.96808255339573, 402.0431373331472, 404.56105125938353, 407.65553107624066, 4751.904961900768, 5236.005159019788, 5954.196183515224, 5998.640081595107, 6072.0, 5556.222128901957, 5779.335376431712, 5843.941560584403, 5957.344692083867, 6041.848953776158, 4596.931046034312, 4610.2854057621125, 4652.790624421892, 4696.741679207642, 4716.811327546894, 4614.353990441341, 4690.835820746274, 4717.363155973934, 4743.723012953784, 4761.285807128941, 4160.793369922734, 4188.518320393148, 4433.2241857674935, 4505.819767209307, 4710.411198600175, 4347.152305300468, 4471.35165401015, 4471.910561788746, 4479.888002799939, 4499.0, 3766.8304926278383, 3818.4463252828186, 3869.129445874684, 3876.8255428505786, 3888.6305800948867, 4711.892705214272, 4847.394075740533, 5155.0, 5693.3168019837885, 5762.654240745569, 4381.064686659945, 5081.644284900059, 5089.974550127263, 5121.641485096045, 5127.897442051138, 6179.3202747697715, 6266.749330026839, 6301.2904451086015, 6314.684265786692, 6365.822322870284, 3729.254149170177, 4025.2955732746946, 4236.6610671146545, 4320.330348795942, 4333.71830830995, 6371.776987805404, 6482.643454610042, 6505.577137486048, 6588.60468371904, 6685.465162786965, 3553.0051585556084, 4178.958210417857, 4318.848840290575, 4389.0, 4391.506887658148, 3451.4822776583715, 4086.59134086589, 4425.933610995838, 4437.400950871591, 4444.2222611042935, 4470.345971990384, 4835.153848076572, 5072.6195535335, 5099.770510327045, 5100.2349647553165, 5342.385625653022, 6388.904166437507, 6402.039694045838, 6439.227292724903, 6463.353664633499, 1411.2379315169899, 1497.9176145311894, 1505.0442968714726, 1513.5383707969174, 1541.9151946643042, 980.9552826240032, 982.3418309732497, 996.2528103922059, 1010.434156872154, 1012.2610494339174, 5325.869652139176, 5741.425857414225, 5938.138969849434, 5939.762409503658, 5964.343922168558, 5412.91880621791, 6534.411902928664, 6571.737130514822, 6582.901256481156, 6671.3995740382625, 4602.608778253819, 4801.4718380978065, 5341.572674186055, 5851.473367396955, 6044.879102417927, 2855.500287449688, 3406.2506248625264, 3410.931781364359, 3431.605365383013, 3456.8098754568236, 6430.0, 6606.306337834458, 6694.661067786462, 6734.595924244561, 6774.695138718769, 6507.9023814642815, 6636.336366363325, 6710.765123220762, 6760.222574403908, 6780.559263647847, 5335.839924802262, 5342.759575819176, 5695.202671625976, 6739.123913891162, 6792.626405547742, 6259.874802504016, 6394.200724909387, 6594.109795177781, 6612.222866396029, 6644.734210631521, 5583.106702927512, 6015.488683461955, 6070.514358851369, 6092.299385570716, 6568.671566421755, 5562.886103280727, 5594.964931487525, 6149.569530133027, 6699.598024118569, 6718.227403848425, 3838.7120965928225, 4692.718436893797, 4766.523347665206, 4791.520847915181, 4804.663673542958, 5298.1522956328345, 6051.727672254671, 6108.5723999319625, 6380.299426332623, 6650.54171830655, 5302.2046692996355, 5758.913616295843, 5951.880962380642, 6162.22972128484, 6353.4599559038, 5557.499825015606, 5979.910301345396, 6200.875982480416, 6337.366263373626, 6338.714757835816, 5164.483551644806, 5987.221661184019, 6045.727942242697, 6097.32929377777, 6203.348648391854, 6202.81391558254, 6355.713992870239, 6404.935950640434, 6419.550631455753, 6422.871542569216, 4750.9287360690305, 6101.359357267423, 6110.572259941761, 6188.1027251049245, 6198.6280823150755], "value": 4594.85827400069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.3722992381666, 719.6114098386871, 744.4081954845899, 744.6202436757255, 768.7117331000875, 14688.706225875476, 15400.691986160284, 15473.845261547382, 16805.579860503494, 16926.322947082102, 14935.253237338144, 16411.507654770347, 16770.74843877343, 16860.409885654004, 16872.240749166285, 915.9313051521146, 943.6791490893095, 955.4649396338039, 967.5404183013083, 969.1858838575595, 920.9309301802358, 941.806929579438, 958.5399008475936, 962.4225464721181, 970.7330484116853, 988.9208863290955, 989.990100098997, 997.2171845101597, 1017.760826205841, 1018.9745256368576, 975.4976187263564, 980.6959842448864, 993.4536005197112, 1012.018342208057, 1015.4922538730634, 738.6122285799944, 748.1097493982144, 755.8601658693159, 759.5594555157995, 760.1410406240967, 15561.610959726097, 15673.0, 15950.521484355488, 16077.517674469784, 16126.596835079217, 11729.882701172965, 14454.85545144556, 14864.62838429037, 15041.623959400993, 15191.848081519154, 11110.777784444346, 11200.495977681025, 11548.711282217928, 11767.646970590897, 11790.469428875722, 2903.564465330196, 2970.3316753730455, 3093.5050391937184, 3165.9208519786957, 3186.64946855848, 97448.0, 97832.0, 98608.50695746475, 99682.0, 99803.50098249534, 8786.736397908073, 10987.147580882425, 11270.436478176058, 11603.941980290045, 11623.825642615368, 23540.0, 23670.0, 23677.0, 24097.397565061015, 24157.51684966291, 7390.260973902567, 7567.356774674215, 7683.0, 7730.033745781778, 7827.6477558510005, 2945.8527073646233, 4014.498187726534, 4020.4572382728543, 4087.6729861611, 4231.513375961804, 4112.527059388209, 4136.875893723193, 4169.541350451448, 4201.537830838605, 4228.429162063082, 630.3098107572201, 737.8893166025032, 750.7184805697864, 763.7555982085679, 777.3936329662872, 547.5510081733021, 554.2794367322546, 566.790287593593, 573.2604939627834, 596.2487266044728, 9345.766355841159, 9781.364211326241, 10121.898781012096, 10125.379939209692, 10147.289689721796, 526.7497938479191, 559.152883381677, 566.5184593095836, 586.1354502109355, 598.8682489852225, 3875.224955008953, 4393.780310984438, 4450.599446049871, 4618.191816432178, 4688.929666055012, 80998.59500702459, 84575.30849382978, 85247.57376213079, 87042.1295787034, 87334.1266587326, 51426.25718495932, 58113.709431452575, 68192.6590367045, 68462.31537684659, 77458.06354841174, 55517.0, 70209.64895175594, 71677.56644867074, 76005.61997189978, 76970.61514692503, 10159.136473399843, 10551.074270491308, 10602.628907988183, 10696.251262411557, 10750.247482676137, 35186.648133518334, 35757.1060723484, 35793.642063580075, 36160.55357785659, 36486.0, 46691.83270418131, 47146.0, 47723.0, 47729.0, 48367.79080523015, 12760.617181484571, 12803.742022436072, 13107.606771796862, 13263.867361326264, 13464.25946572948, 12691.111622186358, 12853.614391568268, 14120.43518259258, 14397.568072957829, 14801.851981480479, 12982.15615984958, 13139.14595551286, 13583.388747506187, 13876.098053626481, 13951.023428359917, 12861.935690321488, 13049.543265985645, 13067.934660327019, 13109.60671179866, 13195.538156164488, 12786.201611338029, 12897.226166430044, 13079.934600327319, 13103.803442948554, 13479.797803032767, 8894.644214231359, 9128.497932613504, 9136.95431522838, 9216.21662158724, 9684.370515916444, 8169.959150204211, 10290.742731431774, 10418.114460270961, 10915.563377464812, 11086.501107450345], "value": 7244.55085055643, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 112.0, 112.0, 114.0, 112.0, 112.0, 113.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 91.0, 161.0, 142.0, 145.0, 133.0, 109.0, 131.0, 106.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 186.0, 184.0, 186.0, 183.0, 185.0, 184.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 61.0, 90.0, 62.0, 58.0, 58.0, 63.0, 91.0, 88.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 117.0, 102.0, 102.0, 69.0, 86.0, 93.0, 54.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 88.0, 88.0, 87.0, 87.0, 88.0, 87.0, 91.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 101.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 86.0, 86.0, 86.0, 85.0, 86.0, 83.0, 84.0, 82.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 63.0, 62.0, 62.0, 60.0, 61.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 69.0, 69.0, 64.0, 61.0, 61.0, 60.0, 59.0, 59.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 83.0, 82.0, 82.0, 82.0, 80.0, 82.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 141.0, 137.0, 136.0, 137.0, 135.0, 155.0, 199.0, 176.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 182.0, 180.0, 181.0, 194.0, 180.0, 183.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 67.0, 65.0, 68.0, 68.0, 67.0, 68.0, 66.0, 68.0], "value": 67.5, "unit": "score"}]}]} 08:13:00 INFO - Return code: 0 08:13:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:13:00 ERROR - # TBPL SUCCESS # 08:13:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:13:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:13:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:13:00 INFO - 2017-03-23 08:13:00.563 screenresolution[1646:9758] starting screenresolution argv=screenresolution get 08:13:00 INFO - 2017-03-23 08:13:00.567 screenresolution[1646:9758] Display 0: 1600x1200x32@60 08:13:00 INFO - 2017-03-23 08:13:00.574 screenresolution[1647:9761] starting screenresolution argv=screenresolution list 08:13:00 INFO - Available Modes on Display 0 08:13:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:13:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:13:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:13:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:13:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:13:00 INFO - Intel Iris: 08:13:00 INFO - Chipset Model: Intel Iris 08:13:00 INFO - Type: GPU 08:13:00 INFO - Bus: Built-In 08:13:00 INFO - VRAM (Dynamic, Max): 1536 MB 08:13:00 INFO - Vendor: Intel (0x8086) 08:13:00 INFO - Device ID: 0x0a2e 08:13:00 INFO - Revision ID: 0x0009 08:13:00 INFO - Displays: 08:13:00 INFO - Display: 08:13:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:13:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:13:00 INFO - Display Serial Number: 1600x1200 60 08:13:00 INFO - Main Display: Yes 08:13:00 INFO - Mirror: Off 08:13:00 INFO - Online: Yes 08:13:00 INFO - Rotation: Supported 08:13:00 INFO - Return code: 0 08:13:00 INFO - Running post-action listener: _resource_record_post_action 08:13:00 INFO - [mozharness: 2017-03-23 15:13:00.813660Z] Finished run-tests step (success) 08:13:00 INFO - Running post-run listener: _resource_record_post_run 08:13:00 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 38962176; Write bytes: 514028544; Read time: 378; Write time: 1534 08:13:00 INFO - TinderboxPrint: CPU usage
53.4% 08:13:00 INFO - TinderboxPrint: I/O read bytes / time
38,962,176 / 378 08:13:00 INFO - TinderboxPrint: I/O write bytes / time
514,028,544 / 1,534 08:13:00 INFO - TinderboxPrint: CPU idle
2,241.5 (46.6%) 08:13:00 INFO - TinderboxPrint: CPU system
976.1 (20.3%) 08:13:00 INFO - TinderboxPrint: CPU user
1,596.1 (33.2%) 08:13:00 INFO - TinderboxPrint: Swap in / out
249,626,624 / 0 08:13:00 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202895872; Write bytes: 203331584; Read time: 16069; Write time: 368 08:13:00 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 38007808; Write bytes: 300747776; Read time: 362; Write time: 1136 08:13:01 INFO - Running post-run listener: _upload_blobber_files 08:13:01 INFO - Blob upload gear active. 08:13:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:13:01 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 08:13:01 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'] 08:13:01 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 08:13:01 INFO - (blobuploader) - INFO - Open directory for files ... 08:13:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:13:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:13:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:03 INFO - (blobuploader) - INFO - Done attempting. 08:13:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:13:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:13:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:04 INFO - (blobuploader) - INFO - Done attempting. 08:13:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:13:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:13:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:05 INFO - (blobuploader) - INFO - Done attempting. 08:13:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:13:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:13:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:13:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:13:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:13:07 INFO - (blobuploader) - INFO - Done attempting. 08:13:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:13:07 INFO - Return code: 0 08:13:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:13:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:13:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/937e56ad9c1885fd4663e457ea031288dac94d746231f72b54fe2d0c2dae35832201541811a36d0c9628c2800a97bcbaf99e3134a17ce690d2b539b0180124dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a8b8e21b8d5f2155fbb3ba9f57a970326101fd7eda6f7f544e937b34cb3fe35a69c070cbf745c9bec455d82dbbd614dbe03ce95ec3f0adeb2dd000a4b9c066a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d084ae1689b3f98817e94a10faa09d12a8f945f042e46a64f0ef6c18a78c3064ed184eaca1b11f54a68d7b96b81b7cbdddb91237927b4c2a21b9c8fdb1b6ba0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d9c3a3186d3c1a4692d95db0372d4fc1fffd7319b1d977df24a3315fa585dadbed40da963eed6a3496704038ac7dafdc66d614ac7c278bdf19ee3b51271283"} 08:13:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:13:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:13:07 INFO - Contents: 08:13:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/937e56ad9c1885fd4663e457ea031288dac94d746231f72b54fe2d0c2dae35832201541811a36d0c9628c2800a97bcbaf99e3134a17ce690d2b539b0180124dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a8b8e21b8d5f2155fbb3ba9f57a970326101fd7eda6f7f544e937b34cb3fe35a69c070cbf745c9bec455d82dbbd614dbe03ce95ec3f0adeb2dd000a4b9c066a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d084ae1689b3f98817e94a10faa09d12a8f945f042e46a64f0ef6c18a78c3064ed184eaca1b11f54a68d7b96b81b7cbdddb91237927b4c2a21b9c8fdb1b6ba0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d9c3a3186d3c1a4692d95db0372d4fc1fffd7319b1d977df24a3315fa585dadbed40da963eed6a3496704038ac7dafdc66d614ac7c278bdf19ee3b51271283"} 08:13:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:13:07 INFO - Copying logs to upload dir... 08:13:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:13:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.919327 ========= master_lag: -0.27 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-23 08:13:07.036959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:13:07.041149) ========= 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.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/937e56ad9c1885fd4663e457ea031288dac94d746231f72b54fe2d0c2dae35832201541811a36d0c9628c2800a97bcbaf99e3134a17ce690d2b539b0180124dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a8b8e21b8d5f2155fbb3ba9f57a970326101fd7eda6f7f544e937b34cb3fe35a69c070cbf745c9bec455d82dbbd614dbe03ce95ec3f0adeb2dd000a4b9c066a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d084ae1689b3f98817e94a10faa09d12a8f945f042e46a64f0ef6c18a78c3064ed184eaca1b11f54a68d7b96b81b7cbdddb91237927b4c2a21b9c8fdb1b6ba0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d9c3a3186d3c1a4692d95db0372d4fc1fffd7319b1d977df24a3315fa585dadbed40da963eed6a3496704038ac7dafdc66d614ac7c278bdf19ee3b51271283"} build_url:https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010721 build_url: 'https://queue.taskcluster.net/v1/task/B23ES3spQqmELTGBCkQ0TQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/937e56ad9c1885fd4663e457ea031288dac94d746231f72b54fe2d0c2dae35832201541811a36d0c9628c2800a97bcbaf99e3134a17ce690d2b539b0180124dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3a8b8e21b8d5f2155fbb3ba9f57a970326101fd7eda6f7f544e937b34cb3fe35a69c070cbf745c9bec455d82dbbd614dbe03ce95ec3f0adeb2dd000a4b9c066a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d084ae1689b3f98817e94a10faa09d12a8f945f042e46a64f0ef6c18a78c3064ed184eaca1b11f54a68d7b96b81b7cbdddb91237927b4c2a21b9c8fdb1b6ba0a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4d9c3a3186d3c1a4692d95db0372d4fc1fffd7319b1d977df24a3315fa585dadbed40da963eed6a3496704038ac7dafdc66d614ac7c278bdf19ee3b51271283"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 08:13:07.080737) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:13:07.081222) ========= 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.JFWy37xhXh/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.jwDSXbceLV/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005069 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:13:07.130321) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:13:07.130649) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 08:13:07.130997) ========= ========= Total master_lag: -0.12 =========