builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0373 starttime: 1490960380.33 results: success (0) buildid: 20170331030216 builduid: 89ebdfa22f734479a7cddee9ebf48909 revision: 8df9fabf2587b7020889755acb9e75b664fe13cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.327424) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.327967) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.328259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.354233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.354633) ========= 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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-31 04:39:40-- 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% 147M=0s 2017-03-31 04:39:40 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.459408) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.459835) ========= 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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.021076 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:40.508646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:39:40.509024) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8df9fabf2587b7020889755acb9e75b664fe13cf --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 8df9fabf2587b7020889755acb9e75b664fe13cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-31 04:39:40,596 truncating revision to first 12 chars 2017-03-31 04:39:40,596 Setting DEBUG logging. 2017-03-31 04:39:40,596 attempt 1/10 2017-03-31 04:39:40,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8df9fabf2587?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-31 04:39:41,462 unpacking tar archive at: mozilla-central-8df9fabf2587/testing/mozharness/ program finished with exit code 0 elapsedTime=1.132755 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:39:41.714922) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:41.715280) ========= script_repo_revision: 8df9fabf2587b7020889755acb9e75b664fe13cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:41.715789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:41.716204) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:39:41.736237) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-31 04:39:41.736629) ========= /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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:39:41 INFO - MultiFileLogger online at 20170331 04:39:41 in /builds/slave/test 04:39:41 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 04:39:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:39:41 INFO - {'append_to_log': False, 04:39:41 INFO - 'base_work_dir': '/builds/slave/test', 04:39:41 INFO - 'blob_upload_branch': 'Firefox', 04:39:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:39:41 INFO - 'branch': 'Firefox', 04:39:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:39:41 INFO - 'config_files': ('talos/mac_config.py',), 04:39:41 INFO - 'default_actions': ('clobber', 04:39:41 INFO - 'read-buildbot-config', 04:39:41 INFO - 'download-and-extract', 04:39:41 INFO - 'populate-webroot', 04:39:41 INFO - 'create-virtualenv', 04:39:41 INFO - 'install', 04:39:41 INFO - 'run-tests'), 04:39:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:39:41 INFO - 'download_minidump_stackwalk': True, 04:39:41 INFO - 'download_symbols': 'ondemand', 04:39:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:39:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:39:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:39:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:39:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:39:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:39:41 INFO - 'gecko_profile': False, 04:39:41 INFO - 'gecko_profile_interval': 0, 04:39:41 INFO - 'installer_path': 'installer.exe', 04:39:41 INFO - 'log_level': 'info', 04:39:41 INFO - 'log_name': 'talos', 04:39:41 INFO - 'log_to_console': True, 04:39:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:39:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:39:41 INFO - 'opt_config_files': (), 04:39:41 INFO - 'pip_index': False, 04:39:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:41 INFO - 'cmd': ('bash', 04:39:41 INFO - '-c', 04:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:41 INFO - 'enabled': True, 04:39:41 INFO - 'halt_on_failure': False, 04:39:41 INFO - 'name': 'check_screen_resolution'},), 04:39:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:39:41 INFO - 'cmd': ('bash', 04:39:41 INFO - '-c', 04:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:39:41 INFO - 'enabled': True, 04:39:41 INFO - 'halt_on_failure': False, 04:39:41 INFO - 'name': 'check_screen_resolution'},), 04:39:41 INFO - 'run_cmd_checks_enabled': True, 04:39:41 INFO - 'suite': 'dromaeojs', 04:39:41 INFO - 'system_bits': '32', 04:39:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:39:41 INFO - 'title': 't-yosemite-r7-0373', 04:39:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:39:41 INFO - 'use_talos_json': True, 04:39:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:39:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:39:41 INFO - 'work_dir': 'build'} 04:39:41 INFO - [mozharness: 2017-03-31 11:39:41.946145Z] Running clobber step. 04:39:41 INFO - Running pre-action listener: _resource_record_pre_action 04:39:41 INFO - Running main action method: clobber 04:39:41 INFO - rmtree: /builds/slave/test/build 04:39:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:39:43 INFO - Running post-action listener: _resource_record_post_action 04:39:43 INFO - [mozharness: 2017-03-31 11:39:43.931348Z] Finished clobber step (success) 04:39:43 INFO - [mozharness: 2017-03-31 11:39:43.931480Z] Running read-buildbot-config step. 04:39:43 INFO - Running pre-action listener: _resource_record_pre_action 04:39:43 INFO - Running main action method: read_buildbot_config 04:39:43 INFO - Using buildbot properties: 04:39:43 INFO - { 04:39:43 INFO - "project": "", 04:39:43 INFO - "product": "firefox", 04:39:43 INFO - "script_repo_revision": "production", 04:39:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:39:43 INFO - "repository": "", 04:39:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:39:43 INFO - "buildid": "20170331030216", 04:39:43 INFO - "stage_platform": "macosx64", 04:39:43 INFO - "basedir": "/builds/slave/test", 04:39:43 INFO - "nightly_build": "True", 04:39:43 INFO - "buildnumber": 40, 04:39:43 INFO - "slavename": "t-yosemite-r7-0373", 04:39:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:39:43 INFO - "platform": "yosemite_r7", 04:39:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:39:43 INFO - "branch": "mozilla-central", 04:39:43 INFO - "repo_path": "mozilla-central", 04:39:43 INFO - "revision": "8df9fabf2587b7020889755acb9e75b664fe13cf", 04:39:43 INFO - "pgo_build": "False", 04:39:43 INFO - "builduid": "89ebdfa22f734479a7cddee9ebf48909", 04:39:43 INFO - "slavebuilddir": "test" 04:39:43 INFO - } 04:39:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:39:43 INFO - Running post-action listener: _resource_record_post_action 04:39:43 INFO - [mozharness: 2017-03-31 11:39:43.934892Z] Finished read-buildbot-config step (success) 04:39:43 INFO - [mozharness: 2017-03-31 11:39:43.935025Z] Running download-and-extract step. 04:39:43 INFO - Running pre-action listener: _resource_record_pre_action 04:39:43 INFO - Running main action method: download_and_extract 04:39:43 INFO - mkdir: /builds/slave/test/build/tests 04:39:43 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')]} 04:39:43 INFO - trying https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:39:43 INFO - Downloading https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/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 04:39:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/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 04:39:44 INFO - Downloaded 1325 bytes. 04:39:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:39:44 INFO - Using the following test package requirements: 04:39:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:39:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:39:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:39:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:39:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'jsshell-mac.zip'], 04:39:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:39:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:39:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:39:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:39:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:39:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:39:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:39:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:39:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:39:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:39:44 INFO - Fetch https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:39:46 INFO - Expected file size: 52508258 04:39:46 INFO - Obtained file size: 52508258 04:40:02 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 04:40:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:40:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:40:02 INFO - Fetch https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:40:04 INFO - Expected file size: 52508258 04:40:04 INFO - Obtained file size: 52508258 04:40:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:40:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:40:20 INFO - Fetch https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:40:21 INFO - Expected file size: 14404152 04:40:21 INFO - Obtained file size: 14404152 04:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:40:22 INFO - trying https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:40:22 INFO - Downloading https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/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 04:40:24 INFO - Downloaded 75621648 bytes. 04:40:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:40:24 INFO - mkdir: /builds/slave/test/properties 04:40:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:40:24 INFO - Writing to file /builds/slave/test/properties/build_url 04:40:24 INFO - Contents: 04:40:24 INFO - build_url:https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:40:25 INFO - Running post-action listener: _resource_record_post_action 04:40:25 INFO - Running post-action listener: set_extra_try_arguments 04:40:25 INFO - [mozharness: 2017-03-31 11:40:25.606670Z] Finished download-and-extract step (success) 04:40:25 INFO - [mozharness: 2017-03-31 11:40:25.606821Z] Running populate-webroot step. 04:40:25 INFO - Running pre-action listener: _resource_record_pre_action 04:40:25 INFO - Running main action method: populate_webroot 04:40:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:40:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:40:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:40:25 INFO - '--tppagecycles', 04:40:25 INFO - '7'], 04:40:25 INFO - 'tests': ['tsvgm']}}, 04:40:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:40:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:40:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:40:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:40:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['damp', 'tps']}, 04:40:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'tests': ['damp', 'tps']}, 04:40:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['dromaeo_dom']}, 04:40:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:40:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['basic_compositor_video']}, 04:40:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:40:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'other-e10s': {'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['a11yr', 04:40:25 INFO - 'ts_paint', 04:40:25 INFO - 'tpaint', 04:40:25 INFO - 'sessionrestore', 04:40:25 INFO - 'sessionrestore_no_auto_restore', 04:40:25 INFO - 'tabpaint']}, 04:40:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['tsvgx', 04:40:25 INFO - 'tsvgr_opacity', 04:40:25 INFO - 'tart', 04:40:25 INFO - 'tscrollx', 04:40:25 INFO - 'cart', 04:40:25 INFO - 'tsvg_static']}, 04:40:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:40:25 INFO - 'tsvgr_opacity', 04:40:25 INFO - 'tart', 04:40:25 INFO - 'tscrollx', 04:40:25 INFO - 'cart', 04:40:25 INFO - 'tsvg_static']}, 04:40:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'talos_options': ['--disable-e10s'], 04:40:25 INFO - 'tests': ['tp5o']}, 04:40:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:40:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'talos_options': ['--disable-e10s', 04:40:25 INFO - '--xperf_path', 04:40:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:25 INFO - 'tests': ['tp5n']}, 04:40:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:40:25 INFO - 'talos_options': ['--xperf_path', 04:40:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:25 INFO - 'tests': ['tp5n']}}, 04:40:25 INFO - 'talos.zip': {'path': '', 04:40:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:40:25 INFO - Running post-action listener: _resource_record_post_action 04:40:25 INFO - [mozharness: 2017-03-31 11:40:25.625654Z] Finished populate-webroot step (success) 04:40:25 INFO - [mozharness: 2017-03-31 11:40:25.625795Z] Running create-virtualenv step. 04:40:25 INFO - Running pre-action listener: _resource_record_pre_action 04:40:25 INFO - Running main action method: create_virtualenv 04:40:25 INFO - Creating virtualenv /builds/slave/test/build/venv 04:40:25 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 04:40:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:40:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:40:25 INFO - Using real prefix '/tools/python27' 04:40:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:40:26 INFO - Installing distribute.............................................................................................................................................................................................done. 04:40:29 INFO - Installing pip.................done. 04:40:29 INFO - Return code: 0 04:40:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:40:29 INFO - Reading from file tmpfile_stdout 04:40:29 INFO - Output received: 04:40:29 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) 04:40:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:40:29 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')]} 04:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:29 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')]} 04:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:29 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:29 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 04:40:29 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 04:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:29 INFO - 'HOME': '/Users/cltbld', 04:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:29 INFO - 'LOGNAME': 'cltbld', 04:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:29 INFO - 'MOZ_NO_REMOTE': '1', 04:40:29 INFO - 'NO_EM_RESTART': '1', 04:40:29 INFO - 'PAGER': '/bin/cat', 04:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:29 INFO - 'PWD': '/builds/slave/test', 04:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:29 INFO - 'SHELL': '/bin/bash', 04:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:29 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:29 INFO - 'USER': 'cltbld', 04:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:29 INFO - 'XPC_FLAGS': '0x0', 04:40:29 INFO - 'XPC_SERVICE_NAME': '0', 04:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:29 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 04:40:29 INFO - Cleaning up... 04:40:29 INFO - Return code: 0 04:40:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:40:29 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')]} 04:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:29 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')]} 04:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:29 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:29 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 04:40:29 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 04:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:29 INFO - 'HOME': '/Users/cltbld', 04:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:29 INFO - 'LOGNAME': 'cltbld', 04:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:29 INFO - 'MOZ_NO_REMOTE': '1', 04:40:29 INFO - 'NO_EM_RESTART': '1', 04:40:29 INFO - 'PAGER': '/bin/cat', 04:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:29 INFO - 'PWD': '/builds/slave/test', 04:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:29 INFO - 'SHELL': '/bin/bash', 04:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:29 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:29 INFO - 'USER': 'cltbld', 04:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:29 INFO - 'XPC_FLAGS': '0x0', 04:40:29 INFO - 'XPC_SERVICE_NAME': '0', 04:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:30 INFO - Downloading/unpacking psutil>=3.1.1 04:40:30 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 04:40:30 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 04:40:30 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 04:40:30 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 04:40:30 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 04:40:30 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 04:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:40:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:33 INFO - Installing collected packages: psutil 04:40:33 INFO - Running setup.py install for psutil 04:40:33 INFO - building 'psutil._psutil_osx' extension 04:40:33 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 04:40:33 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 04:40:33 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 04:40:34 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 04:40:34 INFO - building 'psutil._psutil_posix' extension 04:40:34 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 04:40:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:40:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:40:34 INFO - ^ 04:40:34 INFO - 1 warning generated. 04:40:34 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 04:40:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:34 INFO - Successfully installed psutil 04:40:34 INFO - Cleaning up... 04:40:34 INFO - Return code: 0 04:40:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:40:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:34 INFO - 'HOME': '/Users/cltbld', 04:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:34 INFO - 'LOGNAME': 'cltbld', 04:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:34 INFO - 'MOZ_NO_REMOTE': '1', 04:40:34 INFO - 'NO_EM_RESTART': '1', 04:40:34 INFO - 'PAGER': '/bin/cat', 04:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:34 INFO - 'PWD': '/builds/slave/test', 04:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:34 INFO - 'SHELL': '/bin/bash', 04:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:34 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:34 INFO - 'USER': 'cltbld', 04:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:34 INFO - 'XPC_FLAGS': '0x0', 04:40:34 INFO - 'XPC_SERVICE_NAME': '0', 04:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:40:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:40:37 INFO - Installing collected packages: mozsystemmonitor 04:40:37 INFO - Running setup.py install for mozsystemmonitor 04:40:37 INFO - Successfully installed mozsystemmonitor 04:40:37 INFO - Cleaning up... 04:40:37 INFO - Return code: 0 04:40:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:40: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')]} 04:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40: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')]} 04:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:40:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:38 INFO - 'HOME': '/Users/cltbld', 04:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:38 INFO - 'LOGNAME': 'cltbld', 04:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:38 INFO - 'MOZ_NO_REMOTE': '1', 04:40:38 INFO - 'NO_EM_RESTART': '1', 04:40:38 INFO - 'PAGER': '/bin/cat', 04:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:38 INFO - 'PWD': '/builds/slave/test', 04:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:38 INFO - 'SHELL': '/bin/bash', 04:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:38 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:38 INFO - 'USER': 'cltbld', 04:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:38 INFO - 'XPC_FLAGS': '0x0', 04:40:38 INFO - 'XPC_SERVICE_NAME': '0', 04:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:40:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40:41 INFO - Downloading functools32-3.2.3-2.tar.gz 04:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:40:42 INFO - warning: no files found matching '*.txt' 04:40:42 INFO - no previously-included directories found matching 'build' 04:40:42 INFO - no previously-included directories found matching 'dist' 04:40:42 INFO - no previously-included directories found matching '.git*' 04:40:42 INFO - Installing collected packages: jsonschema, functools32 04:40:42 INFO - Running setup.py install for functools32 04:40:42 INFO - warning: no files found matching '*.txt' 04:40:42 INFO - no previously-included directories found matching 'build' 04:40:42 INFO - no previously-included directories found matching 'dist' 04:40:42 INFO - no previously-included directories found matching '.git*' 04:40:42 INFO - Successfully installed jsonschema functools32 04:40:42 INFO - Cleaning up... 04:40:42 INFO - Return code: 0 04:40:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:40:42 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')]} 04:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:42 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')]} 04:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:42 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:42 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 04:40:42 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 04:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:42 INFO - 'HOME': '/Users/cltbld', 04:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:42 INFO - 'LOGNAME': 'cltbld', 04:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:42 INFO - 'MOZ_NO_REMOTE': '1', 04:40:42 INFO - 'NO_EM_RESTART': '1', 04:40:42 INFO - 'PAGER': '/bin/cat', 04:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:42 INFO - 'PWD': '/builds/slave/test', 04:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:42 INFO - 'SHELL': '/bin/bash', 04:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:42 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:42 INFO - 'USER': 'cltbld', 04:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:42 INFO - 'XPC_FLAGS': '0x0', 04:40:42 INFO - 'XPC_SERVICE_NAME': '0', 04:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:40:42 INFO - Cleaning up... 04:40:42 INFO - Return code: 0 04:40:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:40:42 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')]} 04:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:42 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')]} 04:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:42 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:42 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 04:40:42 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 04:40:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:42 INFO - 'HOME': '/Users/cltbld', 04:40:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:42 INFO - 'LOGNAME': 'cltbld', 04:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:42 INFO - 'MOZ_NO_REMOTE': '1', 04:40:42 INFO - 'NO_EM_RESTART': '1', 04:40:42 INFO - 'PAGER': '/bin/cat', 04:40:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:42 INFO - 'PWD': '/builds/slave/test', 04:40:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:42 INFO - 'SHELL': '/bin/bash', 04:40:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:42 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:42 INFO - 'USER': 'cltbld', 04:40:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:42 INFO - 'XPC_FLAGS': '0x0', 04:40:42 INFO - 'XPC_SERVICE_NAME': '0', 04:40:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:40:42 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 04:40:42 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 04:40:42 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 04:40:42 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 04:40:42 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 04:40:42 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 04:40:45 INFO - Downloading blobuploader-1.2.4.tar.gz 04:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:40:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:40:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:40:46 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 04:40:46 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 04:40:46 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 04:40:46 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 04:40:46 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 04:40:46 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 04:40:46 INFO - Downloading docopt-0.6.1.tar.gz 04:40:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:40:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:40:46 INFO - Running setup.py install for blobuploader 04:40:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:40:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:40:46 INFO - Running setup.py install for requests 04:40:47 INFO - Running setup.py install for docopt 04:40:47 INFO - Successfully installed blobuploader requests docopt 04:40:47 INFO - Cleaning up... 04:40:47 INFO - Return code: 0 04:40:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:47 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')]} 04:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:47 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')]} 04:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:47 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:47 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 04:40:47 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 04:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:47 INFO - 'HOME': '/Users/cltbld', 04:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:47 INFO - 'LOGNAME': 'cltbld', 04:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:47 INFO - 'MOZ_NO_REMOTE': '1', 04:40:47 INFO - 'NO_EM_RESTART': '1', 04:40:47 INFO - 'PAGER': '/bin/cat', 04:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:47 INFO - 'PWD': '/builds/slave/test', 04:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:47 INFO - 'SHELL': '/bin/bash', 04:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:47 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:47 INFO - 'USER': 'cltbld', 04:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:47 INFO - 'XPC_FLAGS': '0x0', 04:40:47 INFO - 'XPC_SERVICE_NAME': '0', 04:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-q1ZBBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-_m2tB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6qrHmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-zTu9ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-iR21Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-CYAR_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-QurSnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-8fpBY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-_NDtXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-PrnPjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:48 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6igdDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Es20Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-IfUX2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-p21aoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-B63GSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hWDWCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:49 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-l8FYlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:40:49 INFO - Running setup.py install for manifestparser 04:40:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:40:49 INFO - Running setup.py install for mozcrash 04:40:50 INFO - Running setup.py install for mozdebug 04:40:50 INFO - Running setup.py install for mozdevice 04:40:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozfile 04:40:50 INFO - Running setup.py install for mozhttpd 04:40:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozinfo 04:40:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozInstall 04:40:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozleak 04:40:50 INFO - Running setup.py install for mozlog 04:40:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Running setup.py install for moznetwork 04:40:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Running setup.py install for mozprocess 04:40:51 INFO - Running setup.py install for mozprofile 04:40:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Running setup.py install for mozrunner 04:40:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Running setup.py install for mozscreenshot 04:40:51 INFO - Running setup.py install for moztest 04:40:52 INFO - Running setup.py install for mozversion 04:40:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:40:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:40:52 INFO - Cleaning up... 04:40:52 INFO - Return code: 0 04:40:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:52 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')]} 04:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:52 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')]} 04:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:52 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:52 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 04:40:52 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 04:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:52 INFO - 'HOME': '/Users/cltbld', 04:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:52 INFO - 'LOGNAME': 'cltbld', 04:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:52 INFO - 'MOZ_NO_REMOTE': '1', 04:40:52 INFO - 'NO_EM_RESTART': '1', 04:40:52 INFO - 'PAGER': '/bin/cat', 04:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:52 INFO - 'PWD': '/builds/slave/test', 04:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:52 INFO - 'SHELL': '/bin/bash', 04:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:52 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:52 INFO - 'USER': 'cltbld', 04:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:52 INFO - 'XPC_FLAGS': '0x0', 04:40:52 INFO - 'XPC_SERVICE_NAME': '0', 04:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:52 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-jbqC7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:52 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-_20ehC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:52 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-TdAOOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:52 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-WYRJ2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:52 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-inh9eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-oYZnof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-LJHjXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-oMDH_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-S0KXm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-yMa091-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-JjMU0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-IPbJ5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-rDiSPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:53 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)) 04:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:53 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-4rRFy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:54 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)) 04:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:54 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-m5mtTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:54 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)) 04:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:54 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-5nhFrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:54 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)) 04:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:54 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Htw1k6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:54 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)) 04:40:54 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)) 04:40:54 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)) 04:40:54 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)) 04:40:54 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)) 04:40:54 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)) 04:40:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:40:54 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 04:40:54 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 04:40:54 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 04:40:54 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 04:40:54 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 04:40:54 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 04:40:57 INFO - Downloading blessings-1.6.tar.gz 04:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:40:57 INFO - Installing collected packages: blessings 04:40:57 INFO - Running setup.py install for blessings 04:40:57 INFO - Successfully installed blessings 04:40:57 INFO - Cleaning up... 04:40:57 INFO - Return code: 0 04:40:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:40:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:57 INFO - Reading from file tmpfile_stdout 04:40:57 INFO - Current package versions: 04:40:57 INFO - blessings == 1.6 04:40:57 INFO - blobuploader == 1.2.4 04:40:57 INFO - docopt == 0.6.1 04:40:57 INFO - functools32 == 3.2.3-2 04:40:57 INFO - jsonschema == 2.5.1 04:40:57 INFO - manifestparser == 1.1 04:40:57 INFO - mozInstall == 1.12 04:40:57 INFO - mozcrash == 1.0 04:40:57 INFO - mozdebug == 0.1 04:40:57 INFO - mozdevice == 0.49 04:40:57 INFO - mozfile == 1.2 04:40:57 INFO - mozhttpd == 0.7 04:40:57 INFO - mozinfo == 0.9 04:40:57 INFO - mozleak == 0.1 04:40:57 INFO - mozlog == 3.4 04:40:57 INFO - moznetwork == 0.27 04:40:57 INFO - mozprocess == 0.25 04:40:57 INFO - mozprofile == 0.28 04:40:57 INFO - mozrunner == 6.13 04:40:57 INFO - mozscreenshot == 0.1 04:40:57 INFO - mozsystemmonitor == 0.3 04:40:57 INFO - moztest == 0.8 04:40:57 INFO - mozversion == 1.4 04:40:57 INFO - psutil == 3.1.1 04:40:57 INFO - requests == 1.2.3 04:40:57 INFO - wsgiref == 0.1.2 04:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:57 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')]} 04:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 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')]} 04:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:57 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 04:40:57 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 04:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:57 INFO - 'HOME': '/Users/cltbld', 04:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:57 INFO - 'LOGNAME': 'cltbld', 04:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:57 INFO - 'MOZ_NO_REMOTE': '1', 04:40:57 INFO - 'NO_EM_RESTART': '1', 04:40:57 INFO - 'PAGER': '/bin/cat', 04:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:57 INFO - 'PWD': '/builds/slave/test', 04:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:57 INFO - 'SHELL': '/bin/bash', 04:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:57 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:57 INFO - 'USER': 'cltbld', 04:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:57 INFO - 'XPC_FLAGS': '0x0', 04:40:57 INFO - 'XPC_SERVICE_NAME': '0', 04:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 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)) 04:40:57 INFO - Cleaning up... 04:40:57 INFO - Return code: 0 04:40:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:40:57 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')]} 04:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 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')]} 04:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:57 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 0x10ce8bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce8e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94bc88330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, '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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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 04:40:57 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 04:40:57 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 04:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:40:57 INFO - 'HOME': '/Users/cltbld', 04:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:40:57 INFO - 'LOGNAME': 'cltbld', 04:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:57 INFO - 'MOZ_NO_REMOTE': '1', 04:40:57 INFO - 'NO_EM_RESTART': '1', 04:40:57 INFO - 'PAGER': '/bin/cat', 04:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:57 INFO - 'PWD': '/builds/slave/test', 04:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:40:57 INFO - 'SHELL': '/bin/bash', 04:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:40:57 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:40:57 INFO - 'USER': 'cltbld', 04:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:57 INFO - 'XPC_FLAGS': '0x0', 04:40:57 INFO - 'XPC_SERVICE_NAME': '0', 04:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:40:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:40:58 INFO - Cleaning up... 04:40:58 INFO - Return code: 0 04:40:58 INFO - Running post-action listener: _resource_record_post_action 04:40:58 INFO - Running post-action listener: _start_resource_monitoring 04:40:58 INFO - Starting resource monitoring. 04:40:58 INFO - [mozharness: 2017-03-31 11:40:58.230549Z] Finished create-virtualenv step (success) 04:40:58 INFO - [mozharness: 2017-03-31 11:40:58.231533Z] Running install step. 04:40:58 INFO - Running pre-action listener: _resource_record_pre_action 04:40:58 INFO - Running main action method: install 04:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:58 INFO - Reading from file tmpfile_stdout 04:40:58 INFO - Detecting whether we're running mozinstall >=1.0... 04:40:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:40:58 INFO - Reading from file tmpfile_stdout 04:40:58 INFO - Output received: 04:40:58 INFO - Usage: mozinstall [options] installer 04:40:58 INFO - Options: 04:40:58 INFO - -h, --help show this help message and exit 04:40:58 INFO - -d DEST, --destination=DEST 04:40:58 INFO - Directory to install application into. [default: 04:40:58 INFO - "/builds/slave/test"] 04:40:58 INFO - --app=APP Application being installed. [default: firefox] 04:40:58 INFO - mkdir: /builds/slave/test/build/application 04:40:58 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'] 04:40:58 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 04:41:19 INFO - Reading from file tmpfile_stdout 04:41:19 INFO - Output received: 04:41:19 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:41:19 INFO - Running post-action listener: _resource_record_post_action 04:41:19 INFO - [mozharness: 2017-03-31 11:41:19.196864Z] Finished install step (success) 04:41:19 INFO - [mozharness: 2017-03-31 11:41:19.197066Z] Running run-tests step. 04:41:19 INFO - Running pre-action listener: _resource_record_pre_action 04:41:19 INFO - Running main action method: run_tests 04:41:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:19 INFO - 2017-03-31 04:41:19.212 screenresolution[2068:17233] starting screenresolution argv=screenresolution get 04:41:19 INFO - 2017-03-31 04:41:19.214 screenresolution[2068:17233] Display 0: 1600x1200x32@60 04:41:19 INFO - 2017-03-31 04:41:19.220 screenresolution[2069:17236] starting screenresolution argv=screenresolution list 04:41:19 INFO - Available Modes on Display 0 04:41:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:41:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:41:19 INFO - Intel Iris: 04:41:19 INFO - Chipset Model: Intel Iris 04:41:19 INFO - Type: GPU 04:41:19 INFO - Bus: Built-In 04:41:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:41:19 INFO - Vendor: Intel (0x8086) 04:41:19 INFO - Device ID: 0x0a2e 04:41:19 INFO - Revision ID: 0x0009 04:41:19 INFO - Displays: 04:41:19 INFO - Display: 04:41:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:41:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:41:19 INFO - Display Serial Number: 1600x1200 60 04:41:19 INFO - Main Display: Yes 04:41:19 INFO - Mirror: Off 04:41:19 INFO - Online: Yes 04:41:19 INFO - Rotation: Supported 04:41:19 INFO - Return code: 0 04:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:41:19 INFO - Python 2.7.3 04:41:19 INFO - Return code: 0 04:41:19 INFO - grabbing minidump binary from tooltool 04:41: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')]} 04:41:19 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 0x10cc69d30>, '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 0x10cb90db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc5ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:41:19 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 04:41:19 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 04:41:19 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 04:41:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:41:19 INFO - Return code: 0 04:41:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:41:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:41:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:41:19 INFO - ENV: RUST_BACKTRACE is now 1 04:41:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:41:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 04:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0373 --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 04:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pJYaWKuOXs/Render', 04:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:41:19 INFO - 'HOME': '/Users/cltbld', 04:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:41:19 INFO - 'LOGNAME': 'cltbld', 04:41:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:41:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:19 INFO - 'MOZ_NO_REMOTE': '1', 04:41:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:41:19 INFO - 'NO_EM_RESTART': '1', 04:41:19 INFO - 'PAGER': '/bin/cat', 04:41:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:19 INFO - 'PWD': '/builds/slave/test', 04:41:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:41:19 INFO - 'RUST_BACKTRACE': '1', 04:41:19 INFO - 'SHELL': '/bin/bash', 04:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VMCjBxPyOU/Listeners', 04:41:19 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 04:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:41:19 INFO - 'USER': 'cltbld', 04:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:19 INFO - 'XPC_FLAGS': '0x0', 04:41:19 INFO - 'XPC_SERVICE_NAME': '0', 04:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:41:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--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 04:41:19 INFO - mozversion application_buildid: 20170331030216 04:41:19 INFO - mozversion application_changeset: 8df9fabf2587b7020889755acb9e75b664fe13cf 04:41:19 INFO - mozversion application_display_name: Nightly 04:41:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:41:19 INFO - mozversion application_name: Firefox 04:41:19 INFO - mozversion application_remotingname: firefox 04:41:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:41:19 INFO - mozversion application_vendor: Mozilla 04:41:19 INFO - mozversion application_version: 55.0a1 04:41:19 INFO - mozversion platform_buildid: 20170331030216 04:41:19 INFO - mozversion platform_changeset: 8df9fabf2587b7020889755acb9e75b664fe13cf 04:41:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:41:19 INFO - mozversion platform_version: 55.0a1 04:41:19 INFO - using testdate: 1490960479 04:41:19 INFO - actual date: 1490960479 04:41:19 INFO - starting webserver on 'localhost:54248' 04:41:19 INFO - SUITE-START | Running 2 tests 04:41:19 INFO - TEST-START | dromaeo_css 04:41:19 INFO - operating with platform_type : mac_ 04:41:19 INFO - Initialising browser for dromaeo_css test... 04:41:19 INFO - TEST-INFO | started process 2079 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpgm9rYH/profile http://localhost:54248/getInfo.html) 04:41:22 INFO - PID 2079 | __metrics Screen width/height:1600/1200 04:41:22 INFO - PID 2079 | colorDepth:24 04:41:22 INFO - PID 2079 | Browser inner width/height: 1024/689 04:41:22 INFO - PID 2079 | __metrics 04:41:22 ERROR - PID 2079 | 1490960482479 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:41:22 INFO - PID 2079 | 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" 04:41:22 INFO - PID 2079 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:41:23 INFO - TEST-INFO | 2079: exit 0 04:41:23 INFO - Browser initialized. 04:41:23 INFO - Running cycle 1/1 for dromaeo_css test... 04:41:23 INFO - TEST-INFO | started process 2083 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpgm9rYH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:35 INFO - PID 2083 | RSS: Main: 234409984 04:41:35 INFO - PID 2083 | 04:44:40 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54248/tests/dromaeo/cssquery-ext.html) 04:44:42 INFO - PID 2083 | RSS: Main: 422285312 04:44:42 INFO - PID 2083 | 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | A coding exception was thrown and uncaught in a Task. 04:46:25 INFO - PID 2083 | 04:46:25 ERROR - PID 2083 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:46:25 INFO - PID 2083 | Full stack: 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | A coding exception was thrown and uncaught in a Task. 04:46:25 INFO - PID 2083 | 04:46:25 ERROR - PID 2083 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:46:25 INFO - PID 2083 | Full stack: 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | A coding exception was thrown and uncaught in a Task. 04:46:25 INFO - PID 2083 | 04:46:25 ERROR - PID 2083 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:46:25 INFO - PID 2083 | Full stack: 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | ************************* 04:46:25 INFO - PID 2083 | A coding exception was thrown and uncaught in a Task. 04:46:25 INFO - PID 2083 | 04:46:25 ERROR - PID 2083 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:46:25 INFO - PID 2083 | Full stack: 04:46:25 INFO - PID 2083 | ************************* 04:47:45 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-ext.html (next: http://localhost:54248/tests/dromaeo/cssquery-jquery.html) 04:47:46 INFO - PID 2083 | RSS: Main: 372203520 04:47:46 INFO - PID 2083 | 04:50:48 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54248/tests/dromaeo/cssquery-mootools.html) 04:50:49 INFO - PID 2083 | RSS: Main: 340156416 04:50:49 INFO - PID 2083 | 04:53:51 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54248/tests/dromaeo/cssquery-prototype.html) 04:53:52 INFO - PID 2083 | RSS: Main: 360235008 04:53:52 INFO - PID 2083 | 04:56:55 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54248/tests/dromaeo/cssquery-yui.html) 04:56:56 INFO - PID 2083 | RSS: Main: 329691136 04:56:56 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | Cycle 1(1): loaded http://localhost:54248/tests/dromaeo/cssquery-yui.html (next: http://localhost:54248/tests/dromaeo/cssquery-dojo.html) 04:59:59 INFO - PID 2083 | RSS: Main: 529186816 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | __start_tp_report 04:59:59 INFO - PID 2083 | _x_x_mozilla_page_load 04:59:59 INFO - PID 2083 | _x_x_mozilla_page_load_details 04:59:59 INFO - PID 2083 | |i|pagename|runs| 04:59:59 INFO - PID 2083 | |0;dojo.html;3446.9831399737077;3447.3967055765233;3450.8792192273277;3455.447128459446;3515.4902539131826;6086.634801911889;6157.6305421674615;6183.814485565435;6227;6293.213348331458;6048.851925331858;6097.993831017888;6148.569600127993;6159.722812473438;6175.166352542406;5082.466341034194;5412.5940554458475;5536.723163841812;5537.252470916415;5648.971755141239;5510.779568817243;5578.995780759482;5623.26897503324;5625.353084395306;5629.437056294359;5374.811881584145;5450.890982180374;5459.563234941195;5465.754041068121;5542.279503664538;5477.3427188737205;5490.917636235459;5507.256520369739;5543.390227075019;5563.304586926634;5417.404085550586;5457.099578569571;5467.6719396835915;5478.178273259004;5502.5597952163735;6036.305824830157;6055.5761096723245;6066.090086487018;6155.876882462326;6157.876842463171;6414.326495717975;6447.323031081716;6489.870202595969;6491.156149700556;6517.0224466329955;6506.479481641457;6581.144451221358;6598.571092878948;6603.207615086172;6631.4031737143405;6045.637261764308;6157.907631385533;6241.9375806241815;6285.8742825143245;6301;6536.215654121535;6561.245456772437;6562.2453417856605;6564;6567.211934567787;6339.144215530937;6349.55553111287;6382.234131904202;6400.807975760641;6402.903956440658;6511.088447617338;6530.869382612416;6550.639714815731;6558.311377305409;6563.442107420923;6429.6142231466265;6430.389113034348;6433.099366088752;6444.162258906312;6461.418472337512;6272.905906411407;6356.745730170742;6367.299597044322;6437.195350581177;6468.967655161694;5253.921191182136;5290.68255904647;5308.309919710413;5316.388615309289;5327.254184414185;4508.07584445188;4641.976790116028;4655.068986202773;4655.580997710223;4682.41469816273;6102.640737559575;6168.568200226031;6188.381161883775;6313.905291420632;6329.778457753957;6435.452986496107;6486.772962946275;6506.869862602722;6545.410913017851;6563.113979612786;5545.584081193927;6059.454649081604;6340;6341.587796793194;6351.714172862232;5636.070048441961;5749.453801888898;5806;5811.331696854916;5848.795292164754;6555.2133743951035;6576.112224849775;6653.767118150841;6805.217399999064;6818.8636227276165;6340.587861788969;6406.871862562816;6525.184351956005;6562.9827376756675;6572.211334639874;6625;6694;6752.122224110834;6760.83906306947;6837.794866154023;6433.871322573429;6442.645654489048;6451.70967306463;6461.418472337512;6463.515236357198;5564.304461942257;6296.433321001132;6397.87204255903;6412.294647588855;6585.901211481927;6310.968445157745;6331.5251356147555;6337.334579869232;6341.49268058545;6345.428911397997;6326.398992095744;6332.176817013758;6340.587861788969;6347.39699728525;6369.90445143314;4819;6260.373962603703;6282;6327.778527751507;6335.651539165206;6204.565680402328;6322.652254126067;6366.681665916778;6377.521685873486;6397.424231819158;6218.9427797273665;6261.624302541862;6323.399277068671;6341.143945567289;6342.524310676626 04:59:59 INFO - PID 2083 | |1;ext.html;788.8225149341399;806.0850934189694;992.7964767222716;1143.6340371081258;1146.5585749486445;30873.84563077187;37283;37914.810425947864;38126.42810357844;38249.852504424845;40480;40893.795531022304;40957.795211023906;41057.58942410575;41151;6172.043333283348;8699.608517616722;8826.514541700204;8858.86711699325;8864.689735859247;5264.394594621629;8526.658933642648;8656.047834738174;8695.521746303952;8700.782480438009;8723.520206388646;8785.209331160195;8785.7803554911;8792.428492148021;8795.780105497382;8709.912900870973;8782.12178782123;8789.912100878973;8800.339974501938;8807.603657835349;8557.743267701979;8628.568571571397;8628.870566941501;8639.740807775714;8647.264982476434;38739.8378048659;38832.611673883184;38871.61128388737;38875.61124388777;38967;2976.4939960206816;2986.1638741152296;2987.981165332624;2988.716071973181;2998.415309014743;27558.173254802186;27620.723792762215;28096.859515702494;28657.713422865712;28711.85644071787;2738.852551297458;2755.9448811023667;2764;2818.957715634267;2825.8445785481995;321136.3943180292;324289;324852;325217;325804;40468.785936421955;41962.79018604887;51009.744951275745;51027.62154730659;51038;43593.782031090275;50983.49016509787;51690.48309516932;51839.74080129575;51968.740156298976;12368.505259789688;14296.285185740671;14401.711965760625;14436.855631443552;14445.78331325031;8469.449485783405;11320.716982075513;11543.76912461746;11588.710282243012;11614.941925290488;12698.746025079447;12702.936485317514;12721.745565088646;12737.554185603645;12852.228866267867;5443.727813609304;5451.83644490658;5453.863653408696;5457.345118585795;5464.480874316933;845.5222799118478;911.9179273865384;929.3123088914155;985.1281615769952;1004.7136566078633;21029.789702103088;22687.319380418612;23813.53580207744;23906.410466310266;24011.044602050733;1050.3960222871726;1054.1604899450508;1082.826747720361;1094.669975979189;1114.4483480676954;11937;12077.879221207852;12090.516379344728;12097.697557561132;12099.33453660057;424399;424566.8771656122;424733;424900.7509924923;425857;54644;54749.726251369284;54846.451535484135;54851;54896.45103548993;1296.9027322950817;1320.656629276395;1448.797498076594;1483.1175450607088;1489.0395694776591;21176.152953881672;22073.779262207816;22443.32670019902;22978.770212297663;23265;33101.503477447375;38126;38137.237255254186;38239.426408603336;38404.80797595994;347420;434932;434980;435284;436340;27141.05006324769;32097.716091356084;32581.34837303288;32621;32728.34543309168;40999.38500922548;41494.1701165981;41778.37332440077;42033.78983105065;42765.57234427616;23424.53150937006;28917.132486025454;28928.132156035354;29925.700742993162;29954.550681739354;26501;26887.039690107205;27721;28495.575221238607;29849.403011939205;28988.710112899444;34199.658003419645;35184.64815351916;35328.64671353356;35450.468242976895;27916.441671166056;28949.855250723613;29415.55876661809;29687;29871.402571948875;21676.132954681638;22536.66195007109;23083.769162308163;23513.41216469602;23559.882200588887 04:59:59 INFO - PID 2083 | |2;jquery.html;388.21386691948817;409.54540460089294;417.2447869356463;425.48091328579125;430.55652677741926;13594.864051359496;15541.611459713498;18109.637807243846;22043.448913777123;22117.778822211792;22575.096996120177;22617.660735088946;22759.54480910381;23057;23105.653415198787;5909.970450147744;6017.638941663492;6197.81406557804;6242.906356404657;6259.843503912393;6049.758009679607;6117.632942023471;6162.445379915806;6201.906971395432;6202.9689851550675;5894.646321220719;6103.1455596216565;6144.2319710036245;6145.692715364236;6148.815535533919;5888.262962426052;6073.969630151843;6113.908291375633;6144.815655530364;6298.055291706235;5522.3649280332875;5523.2543606613;5525.198846167324;5526.972365138189;5528.834134975917;24722.5055498889;27190.72809271902;27291.863540682367;27298.727012729818;27583.86208068967;1813.3381315819763;1826.1782198010949;1993.521554826831;2038.6024725178597;2052.415061707406;13273;16024.19879006045;16393.754093688603;16510.75233871493;17090;1615.2166199393284;1636.885418020739;1645.3994292083423;1700.9234584443607;1709.196677561544;144997.55002450052;149029;149689.25155374262;150987.49012509844;152765;11968.162228644085;11987.460564274628;12206.328651924052;12231.93884030592;12357.196782209128;16235.350585976428;16240.1067941262;16265.268062936959;16286.918565407097;16302.125255595522;8680.392372533988;8866;8880.777980550409;8953.328500362499;9046.547672616343;8494.447860889022;8504.362172837062;8527.914720852836;8672.783180420414;8677;7481.625918704043;7636.961815191;7665.578393188428;7673.884891726629;7695.807604809925;2061.5673505263635;2070.451330397446;2076.6988786625857;2078.5842831433774;2096.1405823612245;931.3434029009532;937.4422218779952;963.643451922793;970.4274478057838;991.5473830837857;18084.367047153286;19187.712184317246;20720.549561530548;20805.89597052005;21026.053827577794;952.2572393533055;964.1804466203811;981.548487695668;990.153418826907;999.3004896572428;10227.28409011377;10583.364998100138;10636.30863993838;10684.412357320422;10771.407572583426;668582;682733;704472;721719;723242;121326.54030568646;150027;153797;154438;154986;1160.1125139268465;1176.7293522489874;1196.3599474281218;1200.867904530501;1203.9036877049814;17444.73832892533;18037.639247215244;18216.635667286315;18217;18319.72520412168;60662.69668651628;60895.2218617148;61772;62424;63057.05414418879;71156;79798.00504987422;84086;84259.101862869;86641.70037449569;8658.870116948377;8773.254273386578;8801.779955500908;8825.823483530421;9293.535323233677;13879.583612491642;13897;14401.9279903604;14565.92717036408;14962.251887405368;11800.232984855957;12093.15347925672;12373;12378.319192444502;12445.191062580903;11178.832317515407;11515.654530364103;11716.391228348904;11805.822912656145;11861.703457413634;10964.396958167377;11097.722556936142;12084;12378.690532736393;12471.93764031174;6961.21551379432;8641.399301143454;8987.236084932854;9063.592138353659;9208.447493150432;11852.288862668267;11921.46353414081;12258.26450412978;12278.201916875374;12291.75416491683 04:59:59 INFO - PID 2083 | |3;mootools.html;1279.6097190356938;1394.7698629726092;1450.129922046772;1465.9727662377197;1474.1376294867503;10360.326578772383;10925.726856828564;10934.671959841198;10942.23404361695;10960.835587466185;10628.202884783634;11072.501737421815;11091.667249982513;11097.500612472439;11106.000459958603;2434.6226334918074;2484.391324125592;2489.639002344659;2491.115653942851;2492.501499700067;2081.198738485679;2315.1202543033623;2408.0728919366084;2469.49375378048;2475.344033831036;1847.8635639082001;2132.76539580646;2161.6757486377014;2392.545416370893;2474.5545801755675;2338.7427382987858;2454.214651311581;2456.1280745335325;2457.778799907999;2459.667944827443;2238.675392068141;2409.361519197414;2447.449323902126;2450.32616030593;2450.3996520852506;10748.677539673823;10997.285176463503;11028.779424411547;11038;11075.944620276927;6140.846478838065;6173.444390004876;6384.201974753156;6409.0386442033605;6410.935890641127;3673.797941113238;3918.285331547228;3949.861754838567;4087.9182416351805;4117.197146556423;1027.2655051638114;1127.7135607555635;1154.8556430446195;1200.9099317551218;1214.9574764883187;14647.926760366237;15335.30991105403;15356.692866142726;15449.227538623136;15503.767443488357;4993.925091123636;5293.894122117536;5313.893722125613;5330.307060082165;5382.461753824665;11377.829332560019;11396.544138234378;11398.316101033965;11407.714807129889;11429.428528573539;6243.687815609202;6247.406496382836;6254.6247225166635;6259.968700156561;6267.2479302484;5720.8283751487525;5756.136579513023;5791.942080579141;5852.648841069549;5888.735006924699;4023.2969566859506;4143.212789569972;4166.749995000309;4221.556736542619;4223.028703398236;1247.1457051919283;1251.6745646132072;1258.672745086284;1273.8471383434048;1277;587.6268569458425;742.1428250370723;753.1000454456871;765.8851172324084;796.9878254616721;6304.842378940563;6449.709763060673;6502.6098434093155;6638.767643132467;6696.5647232929705;558.7569407307834;580.8605934575671;591.4233622218303;593.2258402784407;619.065211530583;3453.3783918894337;3979.403089536535;4024.396340548941;4047.1500984792933;4164.6251837334175;12922.41849116792;14248.216348100746;14419.63950901215;14424.06243594163;14426.495072672406;13806.171629702048;14140.222287774272;14249.572512824632;14289.785653215211;14316.427342906376;10237;10262.207724296013;10283.53724082418;10291.74270643225;10419.635312764169;6359.236891573041;6387.137736405526;6432.517561182836;6438.3883531064475;6457.192850893638;12332.19840710351;12542.623721288375;12587.496500139892;12630;12661.620151395473;10365.896341036492;12956.481740730642;12999.96000319991;13174;13252.80120798208;8049.315808156372;8156.632951517077;8184.263416292562;8251.133630968661;8266.173382661686;9898.604055837974;10085.394876307446;10154.695359139238;10176.18590512772;10185.185185185315;8407.915920840713;8645.95677021611;8678.956605216934;8793.736187914374;8855.424397414148;8486.872696909675;8524.104968978416;8546.059933407445;8566.314694824523;8620.353473489387;7248.746293879689;7897.486663366863;7915.84168316642;7928.524288542706;7932.16712245206;5716.857078573069;5736.569757268138;5912.699206174635;5982.162497250301;6012.969935150296;5837.737301821513;6019.127226552037;6115.235595550556;6116.602420842631;6127 04:59:59 INFO - PID 2083 | |4;prototype.html;374.46825507778954;380.9142942837861;395.16225601724346;397.8249570189115;398.8723608445297;5799.797007104752;5954.612950158247;6000.609960352579;6063.211782468278;6086.269647642289;5761.193432919395;5792.362840087587;5884.882302353951;5888.5877988540815;5928.584999050057;3828.7894165820867;4683.250679891218;4686.976565117169;4700.318453824193;4704.811807527703;3832.7508711933774;4244.342126970309;4452.866414007585;4505.3242013698045;4725.929111063337;4368.060866913613;4406.008648054194;4423.690341676084;4428.667849911253;4433.982537945227;3264.755143364245;3623.9852841204506;4327.632151267146;4437.644988400953;4472.418585583886;3187.42626327261;3647.7264205184715;3821.312163810513;3833.6549710526187;3843.961560384388;5682.431756824326;5711.086226203787;5724.397168792745;5755.395683453261;5803.5357171426185;4252.574742525753;5143.202803565453;5147.948520514784;5155.0616148794825;5200.8179713710215;6136.01823708209;6249.750009999594;6312.210973628296;6338.144350512668;6346.397092276226;4227.408162857172;4246.320588705823;4249.383839343309;4252.29837076885;4257.616274710731;5436.619436639437;5513.145462453326;6075.513958883279;6652.60084394933;6730.697118629673;4075.49056367954;4162.604552567501;4257.93551612097;4303.698741088094;4306.89232769177;4349.9130017399475;4350.630196433275;4399.978000109979;4419.558044195554;4422.800973956116;5111.182210846247;5116.518488533133;5132.948670513321;5139.897202055938;5153.974230128825;6066.393360663987;6067.24159480065;6261.186045814106;6285.748570057238;6336.954402523532;1429.1782225220331;1438.3886848089521;1457.7959085728007;1479.504366037368;1498.8351281359041;957.1816097236763;977.9022097790164;981.1464026297025;991.7520619845038;1000.3647683720744;5221.399539052982;5297.549708274762;5748.45389687979;5923.111533269957;5947.434993675594;5677.37548869624;6118.724657390429;6464.547481676332;6537.477001839841;6542.247641521286;5370.731463426813;5541.944580554224;5566.304211973504;6025.19244226739;6123.938760612336;2842.408251379234;3386.2211691311136;3410.7441941853963;3412.7952322860706;3419.495422014306;6409.230892292861;6437.39285589337;6696.752075243503;6753.628550429676;6843.726250949906;6112.327643959161;6605.76879809214;6700.966495167493;6703.290660881542;6730.461563074942;5351.866203344947;6544.607323560602;6696.296888826603;6801.741677789526;6820.965895170492;5330.8933821324135;6452.93526568111;6463.579867308798;6504.739810407531;6505.186851643544;6399.168108145911;6430.614163150226;6435.356464353527;6435.742570297136;6452.257990331172;6118.60229085108;6136.417040381157;6436.485081193399;6474.773382931575;6666.366695163952;4116.670666346625;4315;4323.978380108079;4347.521772604948;4359.367891655629;6002.489788367863;6265.530085243716;6584.242812076481;6599.703013364506;6612.867742645217;5423.566114710735;5468.261784659179;6277.529185311211;6281.96859015702;6340.714667839866;5929.857175696715;6200.286966998853;6301.905471417841;6306.400891915261;6319.24169099711;4415.205263052681;6176.413240742122;6229.691764729277;6236.380720097479;6251.687145699454;5063.645544811828;6359.20509936258;6366.9044964326495;6378.872422551616;6505.837354066186;5853.385394533512;5907.350191478848;6041.818745437644;6242.687865606611;6296.874062518816 04:59:59 INFO - PID 2083 | |5;yui.html;724.7318492157902;725.9709611615535;728.7194430144394;740.8259059121108;769.8075481129717;15029.398824047039;16018.038917664933;16075.83924160758;17667.381641642514;18722.344717934873;16921.661566768656;17032.318707251725;17471.03909284989;17508.912455437738;17535.824641753577;971.9659811906585;994.5524513968707;997.5211898288826;998.9550470228858;999.0858364596386;957.3298690916351;985.6106837799065;1009.6213919780082;1009.8838633557161;1013.7414959185397;1058.3385384134915;1058.6347710040025;1058.6559368205365;1059.8887116852736;1060.867391576053;1050.6480329089763;1051.610903965534;1051.6634676903393;1053.7681710023783;1054.7784965157325;778.5601135358504;779.8908152858604;780.1769133564088;781.8123650323881;782.2334112569657;16093.275802588912;16260.186990650538;16606.83393166077;16682.58293542659;16934.3226270949;14557.490487832982;14594.854051459562;14645;14677.045992010486;14680.26598670073;11679.554531413254;11786.528538858522;11881.643550693492;12053;12536.686582835411;2932.2522756697117;3065.061679267816;3066.8466576671076;3105;3128;96092;96655.03344966531;108705;108792;113051.91266393031;9809.264305177141;11461.426928653535;11481.88518114825;11701.707457313636;12120.515179392902;24739.87630061874;25025.998960041394;25193.866276017256;25357.87321063383;25390.365240868756;7914.683412663544;7979.72070977513;8059.274665280153;8066.193380661887;8151;4030.4760381150263;4039.6970227233073;4047.210793895162;4099.528554216244;4137.937930931039;4147.751134931914;4171.833126674899;4186.058136919169;4254;4280.315149576115;819.9467034642729;825.2201669422338;827.5117680568494;853.5390888920037;855;533.0059439146042;544.8365490352958;554.464941331617;575.847400438884;596.3827438601027;9587.328886977984;9900.891478659325;10086.697399078039;10166.300907050945;10188.337758045704;538.6929450213419;540.7674699879011;565.9009673307155;585.6749504025222;597.6294697287623;4068.389741538793;4316.568343165721;4634.930476042862;4698.765061746899;4721.504242054603;90068.5496572513;90211.09788902027;90669.54665226763;90975.54512227396;91154;78088;87401;88697.55651221832;89244.6613300801;90654.54672726594;68841;69834.30165698277;71217.57564848778;78616.60691696504;80943.59528202322;9548.856767148638;10287.742806429898;10893.237473376788;10953.616623418142;10962.296673468756;36529.81735091414;36568.817155914054;36647.45028824623;36844.81577592095;37884.24231515409;40116.3982540268;40651.78044658665;47646.761766190946;48193.31323403664;48451;13197.406116724962;13333.933330333673;13342.666433339244;13501.189928604315;13662.93168534151;14141.002647960408;14341.426342946166;14355.354009069408;14450.421983120557;15120.470783522964;10942.671719848098;13303.733925321632;13343.933280333536;13376.531821386205;13663.863361366657;13118.934405327913;13176;13333.133346332457;13808.156118080446;14086.647833804236;12181.390930453264;13118.606441806762;13126.212427254395;13201.075924685563;13866.370978617251;9684.370515916444;9817.263705221994;9887.950560247153;10250.692479225636;10356.533955971849;9383.390079644802;9899.604015839574;10885.721217402699;11264.887351126712;11287.492062857027 04:59:59 INFO - PID 2083 | __end_tp_report 04:59:59 INFO - PID 2083 | __start_cc_report 04:59:59 INFO - PID 2083 | _x_x_mozilla_cycle_collect,2117 04:59:59 INFO - PID 2083 | __end_cc_report 04:59:59 INFO - PID 2083 | __startTimestamp1490961599936__endTimestamp 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | ------- Summary: start ------- 04:59:59 INFO - PID 2083 | Number of tests: 6 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#0] dojo.html Cycles:165 Average:6041.68 Median:6323.40 stddev:671.70 (10.6%) stddev-sans-first:642.18 04:59:59 INFO - PID 2083 | Values: 3447.0 3447.4 3450.9 3455.4 3515.5 6086.6 6157.6 6183.8 6227.0 6293.2 6048.9 6098.0 6148.6 6159.7 6175.2 5082.5 5412.6 5536.7 5537.3 5649.0 5510.8 5579.0 5623.3 5625.4 5629.4 5374.8 5450.9 5459.6 5465.8 5542.3 5477.3 5490.9 5507.3 5543.4 5563.3 5417.4 5457.1 5467.7 5478.2 5502.6 6036.3 6055.6 6066.1 6155.9 6157.9 6414.3 6447.3 6489.9 6491.2 6517.0 6506.5 6581.1 6598.6 6603.2 6631.4 6045.6 6157.9 6241.9 6285.9 6301.0 6536.2 6561.2 6562.2 6564.0 6567.2 6339.1 6349.6 6382.2 6400.8 6402.9 6511.1 6530.9 6550.6 6558.3 6563.4 6429.6 6430.4 6433.1 6444.2 6461.4 6272.9 6356.7 6367.3 6437.2 6469.0 5253.9 5290.7 5308.3 5316.4 5327.3 4508.1 4642.0 4655.1 4655.6 4682.4 6102.6 6168.6 6188.4 6313.9 6329.8 6435.5 6486.8 6506.9 6545.4 6563.1 5545.6 6059.5 6340.0 6341.6 6351.7 5636.1 5749.5 5806.0 5811.3 5848.8 6555.2 6576.1 6653.8 6805.2 6818.9 6340.6 6406.9 6525.2 6563.0 6572.2 6625.0 6694.0 6752.1 6760.8 6837.8 6433.9 6442.6 6451.7 6461.4 6463.5 5564.3 6296.4 6397.9 6412.3 6585.9 6311.0 6331.5 6337.3 6341.5 6345.4 6326.4 6332.2 6340.6 6347.4 6369.9 4819.0 6260.4 6282.0 6327.8 6335.7 6204.6 6322.7 6366.7 6377.5 6397.4 6218.9 6261.6 6323.4 6341.1 6342.5 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#1] ext.html Cycles:180 Average:51914.15 Median:190809.83 stddev:104082.72 (54.5%) stddev-sans-first:104303.92 04:59:59 INFO - PID 2083 | Values: 788.8 806.1 992.8 1143.6 1146.6 30873.8 37283.0 37914.8 38126.4 38249.9 40480.0 40893.8 40957.8 41057.6 41151.0 6172.0 8699.6 8826.5 8858.9 8864.7 5264.4 8526.7 8656.0 8695.5 8700.8 8723.5 8785.2 8785.8 8792.4 8795.8 8709.9 8782.1 8789.9 8800.3 8807.6 8557.7 8628.6 8628.9 8639.7 8647.3 38739.8 38832.6 38871.6 38875.6 38967.0 2976.5 2986.2 2988.0 2988.7 2998.4 27558.2 27620.7 28096.9 28657.7 28711.9 2738.9 2755.9 2764.0 2819.0 2825.8 321136.4 324289.0 324852.0 325217.0 325804.0 40468.8 41962.8 51009.7 51027.6 51038.0 43593.8 50983.5 51690.5 51839.7 51968.7 12368.5 14296.3 14401.7 14436.9 14445.8 8469.4 11320.7 11543.8 11588.7 11614.9 12698.7 12702.9 12721.7 12737.6 12852.2 5443.7 5451.8 5453.9 5457.3 5464.5 845.5 911.9 929.3 985.1 1004.7 21029.8 22687.3 23813.5 23906.4 24011.0 1050.4 1054.2 1082.8 1094.7 1114.4 11937.0 12077.9 12090.5 12097.7 12099.3 424399.0 424566.9 424733.0 424900.8 425857.0 54644.0 54749.7 54846.5 54851.0 54896.5 1296.9 1320.7 1448.8 1483.1 1489.0 21176.2 22073.8 22443.3 22978.8 23265.0 33101.5 38126.0 38137.2 38239.4 38404.8 347420.0 434932.0 434980.0 435284.0 436340.0 27141.1 32097.7 32581.3 32621.0 32728.3 40999.4 41494.2 41778.4 42033.8 42765.6 23424.5 28917.1 28928.1 29925.7 29954.6 26501.0 26887.0 27721.0 28495.6 29849.4 28988.7 34199.7 35184.6 35328.6 35450.5 27916.4 28949.9 29415.6 29687.0 29871.4 21676.1 22536.7 23083.8 23513.4 23559.9 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#2] jquery.html Cycles:180 Average:40114.92 Median:22596.38 stddev:117195.98 (518.6%) stddev-sans-first:117486.78 04:59:59 INFO - PID 2083 | Values: 388.2 409.5 417.2 425.5 430.6 13594.9 15541.6 18109.6 22043.4 22117.8 22575.1 22617.7 22759.5 23057.0 23105.7 5910.0 6017.6 6197.8 6242.9 6259.8 6049.8 6117.6 6162.4 6201.9 6203.0 5894.6 6103.1 6144.2 6145.7 6148.8 5888.3 6074.0 6113.9 6144.8 6298.1 5522.4 5523.3 5525.2 5527.0 5528.8 24722.5 27190.7 27291.9 27298.7 27583.9 1813.3 1826.2 1993.5 2038.6 2052.4 13273.0 16024.2 16393.8 16510.8 17090.0 1615.2 1636.9 1645.4 1700.9 1709.2 144997.6 149029.0 149689.3 150987.5 152765.0 11968.2 11987.5 12206.3 12231.9 12357.2 16235.4 16240.1 16265.3 16286.9 16302.1 8680.4 8866.0 8880.8 8953.3 9046.5 8494.4 8504.4 8527.9 8672.8 8677.0 7481.6 7637.0 7665.6 7673.9 7695.8 2061.6 2070.5 2076.7 2078.6 2096.1 931.3 937.4 963.6 970.4 991.5 18084.4 19187.7 20720.5 20805.9 21026.1 952.3 964.2 981.5 990.2 999.3 10227.3 10583.4 10636.3 10684.4 10771.4 668582.0 682733.0 704472.0 721719.0 723242.0 121326.5 150027.0 153797.0 154438.0 154986.0 1160.1 1176.7 1196.4 1200.9 1203.9 17444.7 18037.6 18216.6 18217.0 18319.7 60662.7 60895.2 61772.0 62424.0 63057.1 71156.0 79798.0 84086.0 84259.1 86641.7 8658.9 8773.3 8801.8 8825.8 9293.5 13879.6 13897.0 14401.9 14565.9 14962.3 11800.2 12093.2 12373.0 12378.3 12445.2 11178.8 11515.7 11716.4 11805.8 11861.7 10964.4 11097.7 12084.0 12378.7 12471.9 6961.2 8641.4 8987.2 9063.6 9208.4 11852.3 11921.5 12258.3 12278.2 12291.8 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#3] mootools.html Cycles:180 Average:6775.35 Median:2487.02 stddev:4248.73 (170.8%) stddev-sans-first:4240.58 04:59:59 INFO - PID 2083 | Values: 1279.6 1394.8 1450.1 1466.0 1474.1 10360.3 10925.7 10934.7 10942.2 10960.8 10628.2 11072.5 11091.7 11097.5 11106.0 2434.6 2484.4 2489.6 2491.1 2492.5 2081.2 2315.1 2408.1 2469.5 2475.3 1847.9 2132.8 2161.7 2392.5 2474.6 2338.7 2454.2 2456.1 2457.8 2459.7 2238.7 2409.4 2447.4 2450.3 2450.4 10748.7 10997.3 11028.8 11038.0 11075.9 6140.8 6173.4 6384.2 6409.0 6410.9 3673.8 3918.3 3949.9 4087.9 4117.2 1027.3 1127.7 1154.9 1200.9 1215.0 14647.9 15335.3 15356.7 15449.2 15503.8 4993.9 5293.9 5313.9 5330.3 5382.5 11377.8 11396.5 11398.3 11407.7 11429.4 6243.7 6247.4 6254.6 6260.0 6267.2 5720.8 5756.1 5791.9 5852.6 5888.7 4023.3 4143.2 4166.7 4221.6 4223.0 1247.1 1251.7 1258.7 1273.8 1277.0 587.6 742.1 753.1 765.9 797.0 6304.8 6449.7 6502.6 6638.8 6696.6 558.8 580.9 591.4 593.2 619.1 3453.4 3979.4 4024.4 4047.2 4164.6 12922.4 14248.2 14419.6 14424.1 14426.5 13806.2 14140.2 14249.6 14289.8 14316.4 10237.0 10262.2 10283.5 10291.7 10419.6 6359.2 6387.1 6432.5 6438.4 6457.2 12332.2 12542.6 12587.5 12630.0 12661.6 10365.9 12956.5 13000.0 13174.0 13252.8 8049.3 8156.6 8184.3 8251.1 8266.2 9898.6 10085.4 10154.7 10176.2 10185.2 8407.9 8646.0 8679.0 8793.7 8855.4 8486.9 8524.1 8546.1 8566.3 8620.4 7248.7 7897.5 7915.8 7928.5 7932.2 5716.9 5736.6 5912.7 5982.2 6013.0 5837.7 6019.1 6115.2 6116.6 6127.0 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#4] prototype.html Cycles:180 Average:5095.90 Median:5776.78 stddev:1591.08 (27.5%) stddev-sans-first:1555.57 04:59:59 INFO - PID 2083 | Values: 374.5 380.9 395.2 397.8 398.9 5799.8 5954.6 6000.6 6063.2 6086.3 5761.2 5792.4 5884.9 5888.6 5928.6 3828.8 4683.3 4687.0 4700.3 4704.8 3832.8 4244.3 4452.9 4505.3 4725.9 4368.1 4406.0 4423.7 4428.7 4434.0 3264.8 3624.0 4327.6 4437.6 4472.4 3187.4 3647.7 3821.3 3833.7 3844.0 5682.4 5711.1 5724.4 5755.4 5803.5 4252.6 5143.2 5147.9 5155.1 5200.8 6136.0 6249.8 6312.2 6338.1 6346.4 4227.4 4246.3 4249.4 4252.3 4257.6 5436.6 5513.1 6075.5 6652.6 6730.7 4075.5 4162.6 4257.9 4303.7 4306.9 4349.9 4350.6 4400.0 4419.6 4422.8 5111.2 5116.5 5132.9 5139.9 5154.0 6066.4 6067.2 6261.2 6285.7 6337.0 1429.2 1438.4 1457.8 1479.5 1498.8 957.2 977.9 981.1 991.8 1000.4 5221.4 5297.5 5748.5 5923.1 5947.4 5677.4 6118.7 6464.5 6537.5 6542.2 5370.7 5541.9 5566.3 6025.2 6123.9 2842.4 3386.2 3410.7 3412.8 3419.5 6409.2 6437.4 6696.8 6753.6 6843.7 6112.3 6605.8 6701.0 6703.3 6730.5 5351.9 6544.6 6696.3 6801.7 6821.0 5330.9 6452.9 6463.6 6504.7 6505.2 6399.2 6430.6 6435.4 6435.7 6452.3 6118.6 6136.4 6436.5 6474.8 6666.4 4116.7 4315.0 4324.0 4347.5 4359.4 6002.5 6265.5 6584.2 6599.7 6612.9 5423.6 5468.3 6277.5 6282.0 6340.7 5929.9 6200.3 6301.9 6306.4 6319.2 4415.2 6176.4 6229.7 6236.4 6251.7 5063.6 6359.2 6366.9 6378.9 6505.8 5853.4 5907.4 6041.8 6242.7 6296.9 04:59:59 INFO - PID 2083 | 04:59:59 INFO - PID 2083 | [#5] yui.html Cycles:180 Average:19205.43 Median:3116.50 stddev:26855.37 (861.7%) stddev-sans-first:26894.85 04:59:59 INFO - PID 2083 | Values: 724.7 726.0 728.7 740.8 769.8 15029.4 16018.0 16075.8 17667.4 18722.3 16921.7 17032.3 17471.0 17508.9 17535.8 972.0 994.6 997.5 999.0 999.1 957.3 985.6 1009.6 1009.9 1013.7 1058.3 1058.6 1058.7 1059.9 1060.9 1050.6 1051.6 1051.7 1053.8 1054.8 778.6 779.9 780.2 781.8 782.2 16093.3 16260.2 16606.8 16682.6 16934.3 14557.5 14594.9 14645.0 14677.0 14680.3 11679.6 11786.5 11881.6 12053.0 12536.7 2932.3 3065.1 3066.8 3105.0 3128.0 96092.0 96655.0 108705.0 108792.0 113051.9 9809.3 11461.4 11481.9 11701.7 12120.5 24739.9 25026.0 25193.9 25357.9 25390.4 7914.7 7979.7 8059.3 8066.2 8151.0 4030.5 4039.7 4047.2 4099.5 4137.9 4147.8 4171.8 4186.1 4254.0 4280.3 819.9 825.2 827.5 853.5 855.0 533.0 544.8 554.5 575.8 596.4 9587.3 9900.9 10086.7 10166.3 10188.3 538.7 540.8 565.9 585.7 597.6 4068.4 4316.6 4634.9 4698.8 4721.5 90068.5 90211.1 90669.5 90975.5 91154.0 78088.0 87401.0 88697.6 89244.7 90654.5 68841.0 69834.3 71217.6 78616.6 80943.6 9548.9 10287.7 10893.2 10953.6 10962.3 36529.8 36568.8 36647.5 36844.8 37884.2 40116.4 40651.8 47646.8 48193.3 48451.0 13197.4 13333.9 13342.7 13501.2 13662.9 14141.0 14341.4 14355.4 14450.4 15120.5 10942.7 13303.7 13343.9 13376.5 13663.9 13118.9 13176.0 13333.1 13808.2 14086.6 12181.4 13118.6 13126.2 13201.1 13866.4 9684.4 9817.3 9888.0 10250.7 10356.5 9383.4 9899.6 10885.7 11264.9 11287.5 04:59:59 INFO - PID 2083 | -------- Summary: end -------- 04:59:59 INFO - PID 2083 | 05:00:00 INFO - TEST-INFO | 2083: exit 0 05:00:00 INFO - TEST-OK | dromaeo_css | took 1120837ms 05:00:00 INFO - TEST-START | kraken 05:00:00 INFO - operating with platform_type : mac_ 05:00:00 INFO - Initialising browser for kraken test... 05:00:00 INFO - TEST-INFO | started process 2198 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpKtpG43/profile http://localhost:54248/getInfo.html) 05:00:03 INFO - PID 2198 | __metrics Screen width/height:1600/1200 05:00:03 INFO - PID 2198 | colorDepth:24 05:00:03 INFO - PID 2198 | Browser inner width/height: 1024/689 05:00:03 INFO - PID 2198 | __metrics 05:00:03 ERROR - PID 2198 | 1490961603253 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:00:03 INFO - PID 2198 | 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" 05:00:03 INFO - PID 2198 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:00:04 INFO - TEST-INFO | 2198: exit 0 05:00:04 INFO - Browser initialized. 05:00:04 INFO - Running cycle 1/1 for kraken test... 05:00:04 INFO - TEST-INFO | started process 2201 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpKtpG43/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:00:16 INFO - PID 2201 | RSS: Main: 234635264 05:00:16 INFO - PID 2201 | 05:00:19 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54248/tests/kraken/driver.html?testName=audio-beat-detection) 05:00:20 INFO - PID 2201 | RSS: Main: 286822400 05:00:20 INFO - PID 2201 | 05:00:23 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54248/tests/kraken/driver.html?testName=audio-dft) 05:00:23 INFO - PID 2201 | RSS: Main: 288415744 05:00:23 INFO - PID 2201 | 05:00:27 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54248/tests/kraken/driver.html?testName=audio-fft) 05:00:28 INFO - PID 2201 | RSS: Main: 380846080 05:00:28 INFO - PID 2201 | 05:00:30 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54248/tests/kraken/driver.html?testName=audio-oscillator) 05:00:30 INFO - PID 2201 | RSS: Main: 284000256 05:00:30 INFO - PID 2201 | 05:00:33 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:00:33 INFO - PID 2201 | RSS: Main: 287096832 05:00:33 INFO - PID 2201 | 05:00:36 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54248/tests/kraken/driver.html?testName=imaging-darkroom) 05:00:37 INFO - PID 2201 | RSS: Main: 329269248 05:00:37 INFO - PID 2201 | 05:00:40 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54248/tests/kraken/driver.html?testName=imaging-desaturate) 05:00:41 INFO - PID 2201 | RSS: Main: 335732736 05:00:41 INFO - PID 2201 | 05:00:44 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54248/tests/kraken/driver.html?testName=json-parse-financial) 05:00:45 INFO - PID 2201 | RSS: Main: 338395136 05:00:45 INFO - PID 2201 | 05:00:46 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:00:47 INFO - PID 2201 | RSS: Main: 305958912 05:00:47 INFO - PID 2201 | 05:00:49 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:00:50 INFO - PID 2201 | RSS: Main: 309133312 05:00:50 INFO - PID 2201 | 05:00:52 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:00:53 INFO - PID 2201 | RSS: Main: 311812096 05:00:53 INFO - PID 2201 | 05:00:56 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:00:57 INFO - PID 2201 | RSS: Main: 328851456 05:00:57 INFO - PID 2201 | 05:01:00 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:01:01 INFO - PID 2201 | RSS: Main: 311504896 05:01:01 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:54248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54248/tests/kraken/driver.html?testName=ai-astar) 05:01:03 INFO - PID 2201 | RSS: Main: 378200064 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | __start_tp_report 05:01:03 INFO - PID 2201 | _x_x_mozilla_page_load 05:01:03 INFO - PID 2201 | _x_x_mozilla_page_load_details 05:01:03 INFO - PID 2201 | |i|pagename|runs| 05:01:03 INFO - PID 2201 | |0;ai-astar;113;123;124;123;124;122;124;124;123;123 05:01:03 INFO - PID 2201 | |1;audio-beat-detection;164;117;119;196;156;136;117;116;142;119 05:01:03 INFO - PID 2201 | |2;audio-dft;213;215;211;217;218;210;215;213;212;210 05:01:03 INFO - PID 2201 | |3;audio-fft;86;66;66;100;76;90;90;91;87;63 05:01:03 INFO - PID 2201 | |4;audio-oscillator;92;78;99;75;79;75;89;77;73;94 05:01:03 INFO - PID 2201 | |5;imaging-gaussian-blur;94;94;95;93;93;94;93;93;92;93 05:01:03 INFO - PID 2201 | |6;imaging-darkroom;101;101;102;101;102;102;100;105;102;102 05:01:03 INFO - PID 2201 | |7;imaging-desaturate;102;103;102;104;101;103;102;101;102;102 05:01:03 INFO - PID 2201 | |8;json-parse-financial;64;63;64;64;63;65;64;101;101;86 05:01:03 INFO - PID 2201 | |9;json-stringify-tinderbox;62;63;66;76;67;64;65;66;69;66 05:01:03 INFO - PID 2201 | |10;stanford-crypto-aes;90;92;91;91;88;87;91;92;90;94 05:01:03 INFO - PID 2201 | |11;stanford-crypto-ccm;169;178;170;151;148;150;172;174;236;173 05:01:03 INFO - PID 2201 | |12;stanford-crypto-pbkdf2;221;203;206;201;199;200;212;215;201;203 05:01:03 INFO - PID 2201 | |13;stanford-crypto-sha256-iterative;71;76;67;63;81;104;109;107;105;72 05:01:03 INFO - PID 2201 | __end_tp_report 05:01:03 INFO - PID 2201 | __start_cc_report 05:01:03 INFO - PID 2201 | _x_x_mozilla_cycle_collect,2335 05:01:03 INFO - PID 2201 | __end_cc_report 05:01:03 INFO - PID 2201 | __startTimestamp1490961663453__endTimestamp 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | ------- Summary: start ------- 05:01:03 INFO - PID 2201 | Number of tests: 14 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#0] ai-astar Cycles:10 Average:122.30 Median:123.50 stddev:3.33 (2.7%) stddev-sans-first:0.71 05:01:03 INFO - PID 2201 | Values: 113.0 123.0 124.0 123.0 124.0 122.0 124.0 124.0 123.0 123.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:139.00 stddev:26.82 (19.3%) stddev-sans-first:26.77 05:01:03 INFO - PID 2201 | Values: 164.0 117.0 119.0 196.0 156.0 136.0 117.0 116.0 142.0 119.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#2] audio-dft Cycles:10 Average:213.40 Median:214.00 stddev:2.80 (1.3%) stddev-sans-first:2.96 05:01:03 INFO - PID 2201 | Values: 213.0 215.0 211.0 217.0 218.0 210.0 215.0 213.0 212.0 210.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#3] audio-fft Cycles:10 Average:81.50 Median:86.50 stddev:12.83 (14.8%) stddev-sans-first:13.50 05:01:03 INFO - PID 2201 | Values: 86.0 66.0 66.0 100.0 76.0 90.0 90.0 91.0 87.0 63.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:84.00 stddev:9.42 (11.2%) stddev-sans-first:9.43 05:01:03 INFO - PID 2201 | Values: 92.0 78.0 99.0 75.0 79.0 75.0 89.0 77.0 73.0 94.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:93.50 stddev:0.84 (0.9%) stddev-sans-first:0.87 05:01:03 INFO - PID 2201 | Values: 94.0 94.0 95.0 93.0 93.0 94.0 93.0 93.0 92.0 93.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:102.00 stddev:1.32 (1.3%) stddev-sans-first:1.36 05:01:03 INFO - PID 2201 | Values: 101.0 101.0 102.0 101.0 102.0 102.0 100.0 105.0 102.0 102.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:102.00 stddev:0.92 (0.9%) stddev-sans-first:0.97 05:01:03 INFO - PID 2201 | Values: 102.0 103.0 102.0 104.0 101.0 103.0 102.0 101.0 102.0 102.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#8] json-parse-financial Cycles:10 Average:73.50 Median:64.00 stddev:16.06 (25.1%) stddev-sans-first:16.67 05:01:03 INFO - PID 2201 | Values: 64.0 63.0 64.0 64.0 63.0 65.0 64.0 101.0 101.0 86.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:3.92 (5.9%) stddev-sans-first:3.82 05:01:03 INFO - PID 2201 | Values: 62.0 63.0 66.0 76.0 67.0 64.0 65.0 66.0 69.0 66.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:91.00 stddev:2.01 (2.2%) stddev-sans-first:2.12 05:01:03 INFO - PID 2201 | Values: 90.0 92.0 91.0 91.0 88.0 87.0 91.0 92.0 90.0 94.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#11] stanford-crypto-ccm Cycles:10 Average:172.10 Median:172.50 stddev:25.06 (14.5%) stddev-sans-first:26.55 05:01:03 INFO - PID 2201 | Values: 169.0 178.0 170.0 151.0 148.0 150.0 172.0 174.0 236.0 173.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.10 Median:204.50 stddev:7.42 (3.6%) stddev-sans-first:5.57 05:01:03 INFO - PID 2201 | Values: 221.0 203.0 206.0 201.0 199.0 200.0 212.0 215.0 201.0 203.0 05:01:03 INFO - PID 2201 | 05:01:03 INFO - PID 2201 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.50 Median:69.00 stddev:18.52 (26.8%) stddev-sans-first:18.89 05:01:03 INFO - PID 2201 | Values: 71.0 76.0 67.0 63.0 81.0 104.0 109.0 107.0 105.0 72.0 05:01:03 INFO - PID 2201 | -------- Summary: end -------- 05:01:03 INFO - PID 2201 | 05:01:04 INFO - TEST-INFO | 2201: exit 0 05:01:04 INFO - TEST-OK | kraken | took 63736ms 05:01:04 INFO - SUITE-END | took 1184s 05:01:04 INFO - Completed test suite (00:19:44) 05:01:04 INFO - javascript benchmark 05:01:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7573.709436566741, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3446.9831399737077, 3447.3967055765233, 3450.8792192273277, 3455.447128459446, 3515.4902539131826, 6086.634801911889, 6157.6305421674615, 6183.814485565435, 6227.0, 6293.213348331458, 6048.851925331858, 6097.993831017888, 6148.569600127993, 6159.722812473438, 6175.166352542406, 5082.466341034194, 5412.5940554458475, 5536.723163841812, 5537.252470916415, 5648.971755141239, 5510.779568817243, 5578.995780759482, 5623.26897503324, 5625.353084395306, 5629.437056294359, 5374.811881584145, 5450.890982180374, 5459.563234941195, 5465.754041068121, 5542.279503664538, 5477.3427188737205, 5490.917636235459, 5507.256520369739, 5543.390227075019, 5563.304586926634, 5417.404085550586, 5457.099578569571, 5467.6719396835915, 5478.178273259004, 5502.5597952163735, 6036.305824830157, 6055.5761096723245, 6066.090086487018, 6155.876882462326, 6157.876842463171, 6414.326495717975, 6447.323031081716, 6489.870202595969, 6491.156149700556, 6517.0224466329955, 6506.479481641457, 6581.144451221358, 6598.571092878948, 6603.207615086172, 6631.4031737143405, 6045.637261764308, 6157.907631385533, 6241.9375806241815, 6285.8742825143245, 6301.0, 6536.215654121535, 6561.245456772437, 6562.2453417856605, 6564.0, 6567.211934567787, 6339.144215530937, 6349.55553111287, 6382.234131904202, 6400.807975760641, 6402.903956440658, 6511.088447617338, 6530.869382612416, 6550.639714815731, 6558.311377305409, 6563.442107420923, 6429.6142231466265, 6430.389113034348, 6433.099366088752, 6444.162258906312, 6461.418472337512, 6272.905906411407, 6356.745730170742, 6367.299597044322, 6437.195350581177, 6468.967655161694, 5253.921191182136, 5290.68255904647, 5308.309919710413, 5316.388615309289, 5327.254184414185, 4508.07584445188, 4641.976790116028, 4655.068986202773, 4655.580997710223, 4682.41469816273, 6102.640737559575, 6168.568200226031, 6188.381161883775, 6313.905291420632, 6329.778457753957, 6435.452986496107, 6486.772962946275, 6506.869862602722, 6545.410913017851, 6563.113979612786, 5545.584081193927, 6059.454649081604, 6340.0, 6341.587796793194, 6351.714172862232, 5636.070048441961, 5749.453801888898, 5806.0, 5811.331696854916, 5848.795292164754, 6555.2133743951035, 6576.112224849775, 6653.767118150841, 6805.217399999064, 6818.8636227276165, 6340.587861788969, 6406.871862562816, 6525.184351956005, 6562.9827376756675, 6572.211334639874, 6625.0, 6694.0, 6752.122224110834, 6760.83906306947, 6837.794866154023, 6433.871322573429, 6442.645654489048, 6451.70967306463, 6461.418472337512, 6463.515236357198, 5564.304461942257, 6296.433321001132, 6397.87204255903, 6412.294647588855, 6585.901211481927, 6310.968445157745, 6331.5251356147555, 6337.334579869232, 6341.49268058545, 6345.428911397997, 6326.398992095744, 6332.176817013758, 6340.587861788969, 6347.39699728525, 6369.90445143314, 4819.0, 6260.373962603703, 6282.0, 6327.778527751507, 6335.651539165206, 6204.565680402328, 6322.652254126067, 6366.681665916778, 6377.521685873486, 6397.424231819158, 6218.9427797273665, 6261.624302541862, 6323.399277068671, 6341.143945567289, 6342.524310676626], "value": 5998.507972180295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.8225149341399, 806.0850934189694, 992.7964767222716, 1143.6340371081258, 1146.5585749486445, 30873.84563077187, 37283.0, 37914.810425947864, 38126.42810357844, 38249.852504424845, 40480.0, 40893.795531022304, 40957.795211023906, 41057.58942410575, 41151.0, 6172.043333283348, 8699.608517616722, 8826.514541700204, 8858.86711699325, 8864.689735859247, 5264.394594621629, 8526.658933642648, 8656.047834738174, 8695.521746303952, 8700.782480438009, 8723.520206388646, 8785.209331160195, 8785.7803554911, 8792.428492148021, 8795.780105497382, 8709.912900870973, 8782.12178782123, 8789.912100878973, 8800.339974501938, 8807.603657835349, 8557.743267701979, 8628.568571571397, 8628.870566941501, 8639.740807775714, 8647.264982476434, 38739.8378048659, 38832.611673883184, 38871.61128388737, 38875.61124388777, 38967.0, 2976.4939960206816, 2986.1638741152296, 2987.981165332624, 2988.716071973181, 2998.415309014743, 27558.173254802186, 27620.723792762215, 28096.859515702494, 28657.713422865712, 28711.85644071787, 2738.852551297458, 2755.9448811023667, 2764.0, 2818.957715634267, 2825.8445785481995, 321136.3943180292, 324289.0, 324852.0, 325217.0, 325804.0, 40468.785936421955, 41962.79018604887, 51009.744951275745, 51027.62154730659, 51038.0, 43593.782031090275, 50983.49016509787, 51690.48309516932, 51839.74080129575, 51968.740156298976, 12368.505259789688, 14296.285185740671, 14401.711965760625, 14436.855631443552, 14445.78331325031, 8469.449485783405, 11320.716982075513, 11543.76912461746, 11588.710282243012, 11614.941925290488, 12698.746025079447, 12702.936485317514, 12721.745565088646, 12737.554185603645, 12852.228866267867, 5443.727813609304, 5451.83644490658, 5453.863653408696, 5457.345118585795, 5464.480874316933, 845.5222799118478, 911.9179273865384, 929.3123088914155, 985.1281615769952, 1004.7136566078633, 21029.789702103088, 22687.319380418612, 23813.53580207744, 23906.410466310266, 24011.044602050733, 1050.3960222871726, 1054.1604899450508, 1082.826747720361, 1094.669975979189, 1114.4483480676954, 11937.0, 12077.879221207852, 12090.516379344728, 12097.697557561132, 12099.33453660057, 424399.0, 424566.8771656122, 424733.0, 424900.7509924923, 425857.0, 54644.0, 54749.726251369284, 54846.451535484135, 54851.0, 54896.45103548993, 1296.9027322950817, 1320.656629276395, 1448.797498076594, 1483.1175450607088, 1489.0395694776591, 21176.152953881672, 22073.779262207816, 22443.32670019902, 22978.770212297663, 23265.0, 33101.503477447375, 38126.0, 38137.237255254186, 38239.426408603336, 38404.80797595994, 347420.0, 434932.0, 434980.0, 435284.0, 436340.0, 27141.05006324769, 32097.716091356084, 32581.34837303288, 32621.0, 32728.34543309168, 40999.38500922548, 41494.1701165981, 41778.37332440077, 42033.78983105065, 42765.57234427616, 23424.53150937006, 28917.132486025454, 28928.132156035354, 29925.700742993162, 29954.550681739354, 26501.0, 26887.039690107205, 27721.0, 28495.575221238607, 29849.403011939205, 28988.710112899444, 34199.658003419645, 35184.64815351916, 35328.64671353356, 35450.468242976895, 27916.441671166056, 28949.855250723613, 29415.55876661809, 29687.0, 29871.402571948875, 21676.132954681638, 22536.66195007109, 23083.769162308163, 23513.41216469602, 23559.882200588887], "value": 17275.972211981687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.21386691948817, 409.54540460089294, 417.2447869356463, 425.48091328579125, 430.55652677741926, 13594.864051359496, 15541.611459713498, 18109.637807243846, 22043.448913777123, 22117.778822211792, 22575.096996120177, 22617.660735088946, 22759.54480910381, 23057.0, 23105.653415198787, 5909.970450147744, 6017.638941663492, 6197.81406557804, 6242.906356404657, 6259.843503912393, 6049.758009679607, 6117.632942023471, 6162.445379915806, 6201.906971395432, 6202.9689851550675, 5894.646321220719, 6103.1455596216565, 6144.2319710036245, 6145.692715364236, 6148.815535533919, 5888.262962426052, 6073.969630151843, 6113.908291375633, 6144.815655530364, 6298.055291706235, 5522.3649280332875, 5523.2543606613, 5525.198846167324, 5526.972365138189, 5528.834134975917, 24722.5055498889, 27190.72809271902, 27291.863540682367, 27298.727012729818, 27583.86208068967, 1813.3381315819763, 1826.1782198010949, 1993.521554826831, 2038.6024725178597, 2052.415061707406, 13273.0, 16024.19879006045, 16393.754093688603, 16510.75233871493, 17090.0, 1615.2166199393284, 1636.885418020739, 1645.3994292083423, 1700.9234584443607, 1709.196677561544, 144997.55002450052, 149029.0, 149689.25155374262, 150987.49012509844, 152765.0, 11968.162228644085, 11987.460564274628, 12206.328651924052, 12231.93884030592, 12357.196782209128, 16235.350585976428, 16240.1067941262, 16265.268062936959, 16286.918565407097, 16302.125255595522, 8680.392372533988, 8866.0, 8880.777980550409, 8953.328500362499, 9046.547672616343, 8494.447860889022, 8504.362172837062, 8527.914720852836, 8672.783180420414, 8677.0, 7481.625918704043, 7636.961815191, 7665.578393188428, 7673.884891726629, 7695.807604809925, 2061.5673505263635, 2070.451330397446, 2076.6988786625857, 2078.5842831433774, 2096.1405823612245, 931.3434029009532, 937.4422218779952, 963.643451922793, 970.4274478057838, 991.5473830837857, 18084.367047153286, 19187.712184317246, 20720.549561530548, 20805.89597052005, 21026.053827577794, 952.2572393533055, 964.1804466203811, 981.548487695668, 990.153418826907, 999.3004896572428, 10227.28409011377, 10583.364998100138, 10636.30863993838, 10684.412357320422, 10771.407572583426, 668582.0, 682733.0, 704472.0, 721719.0, 723242.0, 121326.54030568646, 150027.0, 153797.0, 154438.0, 154986.0, 1160.1125139268465, 1176.7293522489874, 1196.3599474281218, 1200.867904530501, 1203.9036877049814, 17444.73832892533, 18037.639247215244, 18216.635667286315, 18217.0, 18319.72520412168, 60662.69668651628, 60895.2218617148, 61772.0, 62424.0, 63057.05414418879, 71156.0, 79798.00504987422, 84086.0, 84259.101862869, 86641.70037449569, 8658.870116948377, 8773.254273386578, 8801.779955500908, 8825.823483530421, 9293.535323233677, 13879.583612491642, 13897.0, 14401.9279903604, 14565.92717036408, 14962.251887405368, 11800.232984855957, 12093.15347925672, 12373.0, 12378.319192444502, 12445.191062580903, 11178.832317515407, 11515.654530364103, 11716.391228348904, 11805.822912656145, 11861.703457413634, 10964.396958167377, 11097.722556936142, 12084.0, 12378.690532736393, 12471.93764031174, 6961.21551379432, 8641.399301143454, 8987.236084932854, 9063.592138353659, 9208.447493150432, 11852.288862668267, 11921.46353414081, 12258.26450412978, 12278.201916875374, 12291.75416491683], "value": 10327.62377755124, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.6097190356938, 1394.7698629726092, 1450.129922046772, 1465.9727662377197, 1474.1376294867503, 10360.326578772383, 10925.726856828564, 10934.671959841198, 10942.23404361695, 10960.835587466185, 10628.202884783634, 11072.501737421815, 11091.667249982513, 11097.500612472439, 11106.000459958603, 2434.6226334918074, 2484.391324125592, 2489.639002344659, 2491.115653942851, 2492.501499700067, 2081.198738485679, 2315.1202543033623, 2408.0728919366084, 2469.49375378048, 2475.344033831036, 1847.8635639082001, 2132.76539580646, 2161.6757486377014, 2392.545416370893, 2474.5545801755675, 2338.7427382987858, 2454.214651311581, 2456.1280745335325, 2457.778799907999, 2459.667944827443, 2238.675392068141, 2409.361519197414, 2447.449323902126, 2450.32616030593, 2450.3996520852506, 10748.677539673823, 10997.285176463503, 11028.779424411547, 11038.0, 11075.944620276927, 6140.846478838065, 6173.444390004876, 6384.201974753156, 6409.0386442033605, 6410.935890641127, 3673.797941113238, 3918.285331547228, 3949.861754838567, 4087.9182416351805, 4117.197146556423, 1027.2655051638114, 1127.7135607555635, 1154.8556430446195, 1200.9099317551218, 1214.9574764883187, 14647.926760366237, 15335.30991105403, 15356.692866142726, 15449.227538623136, 15503.767443488357, 4993.925091123636, 5293.894122117536, 5313.893722125613, 5330.307060082165, 5382.461753824665, 11377.829332560019, 11396.544138234378, 11398.316101033965, 11407.714807129889, 11429.428528573539, 6243.687815609202, 6247.406496382836, 6254.6247225166635, 6259.968700156561, 6267.2479302484, 5720.8283751487525, 5756.136579513023, 5791.942080579141, 5852.648841069549, 5888.735006924699, 4023.2969566859506, 4143.212789569972, 4166.749995000309, 4221.556736542619, 4223.028703398236, 1247.1457051919283, 1251.6745646132072, 1258.672745086284, 1273.8471383434048, 1277.0, 587.6268569458425, 742.1428250370723, 753.1000454456871, 765.8851172324084, 796.9878254616721, 6304.842378940563, 6449.709763060673, 6502.6098434093155, 6638.767643132467, 6696.5647232929705, 558.7569407307834, 580.8605934575671, 591.4233622218303, 593.2258402784407, 619.065211530583, 3453.3783918894337, 3979.403089536535, 4024.396340548941, 4047.1500984792933, 4164.6251837334175, 12922.41849116792, 14248.216348100746, 14419.63950901215, 14424.06243594163, 14426.495072672406, 13806.171629702048, 14140.222287774272, 14249.572512824632, 14289.785653215211, 14316.427342906376, 10237.0, 10262.207724296013, 10283.53724082418, 10291.74270643225, 10419.635312764169, 6359.236891573041, 6387.137736405526, 6432.517561182836, 6438.3883531064475, 6457.192850893638, 12332.19840710351, 12542.623721288375, 12587.496500139892, 12630.0, 12661.620151395473, 10365.896341036492, 12956.481740730642, 12999.96000319991, 13174.0, 13252.80120798208, 8049.315808156372, 8156.632951517077, 8184.263416292562, 8251.133630968661, 8266.173382661686, 9898.604055837974, 10085.394876307446, 10154.695359139238, 10176.18590512772, 10185.185185185315, 8407.915920840713, 8645.95677021611, 8678.956605216934, 8793.736187914374, 8855.424397414148, 8486.872696909675, 8524.104968978416, 8546.059933407445, 8566.314694824523, 8620.353473489387, 7248.746293879689, 7897.486663366863, 7915.84168316642, 7928.524288542706, 7932.16712245206, 5716.857078573069, 5736.569757268138, 5912.699206174635, 5982.162497250301, 6012.969935150296, 5837.737301821513, 6019.127226552037, 6115.235595550556, 6116.602420842631, 6127.0], "value": 5084.856149300935, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.46825507778954, 380.9142942837861, 395.16225601724346, 397.8249570189115, 398.8723608445297, 5799.797007104752, 5954.612950158247, 6000.609960352579, 6063.211782468278, 6086.269647642289, 5761.193432919395, 5792.362840087587, 5884.882302353951, 5888.5877988540815, 5928.584999050057, 3828.7894165820867, 4683.250679891218, 4686.976565117169, 4700.318453824193, 4704.811807527703, 3832.7508711933774, 4244.342126970309, 4452.866414007585, 4505.3242013698045, 4725.929111063337, 4368.060866913613, 4406.008648054194, 4423.690341676084, 4428.667849911253, 4433.982537945227, 3264.755143364245, 3623.9852841204506, 4327.632151267146, 4437.644988400953, 4472.418585583886, 3187.42626327261, 3647.7264205184715, 3821.312163810513, 3833.6549710526187, 3843.961560384388, 5682.431756824326, 5711.086226203787, 5724.397168792745, 5755.395683453261, 5803.5357171426185, 4252.574742525753, 5143.202803565453, 5147.948520514784, 5155.0616148794825, 5200.8179713710215, 6136.01823708209, 6249.750009999594, 6312.210973628296, 6338.144350512668, 6346.397092276226, 4227.408162857172, 4246.320588705823, 4249.383839343309, 4252.29837076885, 4257.616274710731, 5436.619436639437, 5513.145462453326, 6075.513958883279, 6652.60084394933, 6730.697118629673, 4075.49056367954, 4162.604552567501, 4257.93551612097, 4303.698741088094, 4306.89232769177, 4349.9130017399475, 4350.630196433275, 4399.978000109979, 4419.558044195554, 4422.800973956116, 5111.182210846247, 5116.518488533133, 5132.948670513321, 5139.897202055938, 5153.974230128825, 6066.393360663987, 6067.24159480065, 6261.186045814106, 6285.748570057238, 6336.954402523532, 1429.1782225220331, 1438.3886848089521, 1457.7959085728007, 1479.504366037368, 1498.8351281359041, 957.1816097236763, 977.9022097790164, 981.1464026297025, 991.7520619845038, 1000.3647683720744, 5221.399539052982, 5297.549708274762, 5748.45389687979, 5923.111533269957, 5947.434993675594, 5677.37548869624, 6118.724657390429, 6464.547481676332, 6537.477001839841, 6542.247641521286, 5370.731463426813, 5541.944580554224, 5566.304211973504, 6025.19244226739, 6123.938760612336, 2842.408251379234, 3386.2211691311136, 3410.7441941853963, 3412.7952322860706, 3419.495422014306, 6409.230892292861, 6437.39285589337, 6696.752075243503, 6753.628550429676, 6843.726250949906, 6112.327643959161, 6605.76879809214, 6700.966495167493, 6703.290660881542, 6730.461563074942, 5351.866203344947, 6544.607323560602, 6696.296888826603, 6801.741677789526, 6820.965895170492, 5330.8933821324135, 6452.93526568111, 6463.579867308798, 6504.739810407531, 6505.186851643544, 6399.168108145911, 6430.614163150226, 6435.356464353527, 6435.742570297136, 6452.257990331172, 6118.60229085108, 6136.417040381157, 6436.485081193399, 6474.773382931575, 6666.366695163952, 4116.670666346625, 4315.0, 4323.978380108079, 4347.521772604948, 4359.367891655629, 6002.489788367863, 6265.530085243716, 6584.242812076481, 6599.703013364506, 6612.867742645217, 5423.566114710735, 5468.261784659179, 6277.529185311211, 6281.96859015702, 6340.714667839866, 5929.857175696715, 6200.286966998853, 6301.905471417841, 6306.400891915261, 6319.24169099711, 4415.205263052681, 6176.413240742122, 6229.691764729277, 6236.380720097479, 6251.687145699454, 5063.645544811828, 6359.20509936258, 6366.9044964326495, 6378.872422551616, 6505.837354066186, 5853.385394533512, 5907.350191478848, 6041.818745437644, 6242.687865606611, 6296.874062518816], "value": 4600.885752952125, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.7318492157902, 725.9709611615535, 728.7194430144394, 740.8259059121108, 769.8075481129717, 15029.398824047039, 16018.038917664933, 16075.83924160758, 17667.381641642514, 18722.344717934873, 16921.661566768656, 17032.318707251725, 17471.03909284989, 17508.912455437738, 17535.824641753577, 971.9659811906585, 994.5524513968707, 997.5211898288826, 998.9550470228858, 999.0858364596386, 957.3298690916351, 985.6106837799065, 1009.6213919780082, 1009.8838633557161, 1013.7414959185397, 1058.3385384134915, 1058.6347710040025, 1058.6559368205365, 1059.8887116852736, 1060.867391576053, 1050.6480329089763, 1051.610903965534, 1051.6634676903393, 1053.7681710023783, 1054.7784965157325, 778.5601135358504, 779.8908152858604, 780.1769133564088, 781.8123650323881, 782.2334112569657, 16093.275802588912, 16260.186990650538, 16606.83393166077, 16682.58293542659, 16934.3226270949, 14557.490487832982, 14594.854051459562, 14645.0, 14677.045992010486, 14680.26598670073, 11679.554531413254, 11786.528538858522, 11881.643550693492, 12053.0, 12536.686582835411, 2932.2522756697117, 3065.061679267816, 3066.8466576671076, 3105.0, 3128.0, 96092.0, 96655.03344966531, 108705.0, 108792.0, 113051.91266393031, 9809.264305177141, 11461.426928653535, 11481.88518114825, 11701.707457313636, 12120.515179392902, 24739.87630061874, 25025.998960041394, 25193.866276017256, 25357.87321063383, 25390.365240868756, 7914.683412663544, 7979.72070977513, 8059.274665280153, 8066.193380661887, 8151.0, 4030.4760381150263, 4039.6970227233073, 4047.210793895162, 4099.528554216244, 4137.937930931039, 4147.751134931914, 4171.833126674899, 4186.058136919169, 4254.0, 4280.315149576115, 819.9467034642729, 825.2201669422338, 827.5117680568494, 853.5390888920037, 855.0, 533.0059439146042, 544.8365490352958, 554.464941331617, 575.847400438884, 596.3827438601027, 9587.328886977984, 9900.891478659325, 10086.697399078039, 10166.300907050945, 10188.337758045704, 538.6929450213419, 540.7674699879011, 565.9009673307155, 585.6749504025222, 597.6294697287623, 4068.389741538793, 4316.568343165721, 4634.930476042862, 4698.765061746899, 4721.504242054603, 90068.5496572513, 90211.09788902027, 90669.54665226763, 90975.54512227396, 91154.0, 78088.0, 87401.0, 88697.55651221832, 89244.6613300801, 90654.54672726594, 68841.0, 69834.30165698277, 71217.57564848778, 78616.60691696504, 80943.59528202322, 9548.856767148638, 10287.742806429898, 10893.237473376788, 10953.616623418142, 10962.296673468756, 36529.81735091414, 36568.817155914054, 36647.45028824623, 36844.81577592095, 37884.24231515409, 40116.3982540268, 40651.78044658665, 47646.761766190946, 48193.31323403664, 48451.0, 13197.406116724962, 13333.933330333673, 13342.666433339244, 13501.189928604315, 13662.93168534151, 14141.002647960408, 14341.426342946166, 14355.354009069408, 14450.421983120557, 15120.470783522964, 10942.671719848098, 13303.733925321632, 13343.933280333536, 13376.531821386205, 13663.863361366657, 13118.934405327913, 13176.0, 13333.133346332457, 13808.156118080446, 14086.647833804236, 12181.390930453264, 13118.606441806762, 13126.212427254395, 13201.075924685563, 13866.370978617251, 9684.370515916444, 9817.263705221994, 9887.950560247153, 10250.692479225636, 10356.533955971849, 9383.390079644802, 9899.604015839574, 10885.721217402699, 11264.887351126712, 11287.492062857027], "value": 7537.244782702684, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 123.0, 124.0, 123.0, 124.0, 122.0, 124.0, 124.0, 123.0, 123.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 117.0, 119.0, 196.0, 156.0, 136.0, 117.0, 116.0, 142.0, 119.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 215.0, 211.0, 217.0, 218.0, 210.0, 215.0, 213.0, 212.0, 210.0], "value": 213.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 66.0, 66.0, 100.0, 76.0, 90.0, 90.0, 91.0, 87.0, 63.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 78.0, 99.0, 75.0, 79.0, 75.0, 89.0, 77.0, 73.0, 94.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 102.0, 101.0, 102.0, 102.0, 100.0, 105.0, 102.0, 102.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 102.0, 104.0, 101.0, 103.0, 102.0, 101.0, 102.0, 102.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 64.0, 63.0, 65.0, 64.0, 101.0, 101.0, 86.0], "value": 73.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 66.0, 76.0, 67.0, 64.0, 65.0, 66.0, 69.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 91.0, 88.0, 87.0, 91.0, 92.0, 90.0, 94.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 178.0, 170.0, 151.0, 148.0, 150.0, 172.0, 174.0, 236.0, 173.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 203.0, 206.0, 201.0, 199.0, 200.0, 212.0, 215.0, 201.0, 203.0], "value": 206.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 67.0, 63.0, 81.0, 104.0, 109.0, 107.0, 105.0, 72.0], "value": 85.5, "unit": "score"}]}]} 05:01:04 INFO - Return code: 0 05:01:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:01:04 ERROR - # TBPL SUCCESS # 05:01:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:01:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:01:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:01:04 INFO - 2017-03-31 05:01:04.752 screenresolution[2211:19460] starting screenresolution argv=screenresolution get 05:01:04 INFO - 2017-03-31 05:01:04.755 screenresolution[2211:19460] Display 0: 1600x1200x32@60 05:01:04 INFO - 2017-03-31 05:01:04.762 screenresolution[2212:19463] starting screenresolution argv=screenresolution list 05:01:04 INFO - Available Modes on Display 0 05:01:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:01:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:01:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:01:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:01:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:01:04 INFO - Intel Iris: 05:01:04 INFO - Chipset Model: Intel Iris 05:01:04 INFO - Type: GPU 05:01:04 INFO - Bus: Built-In 05:01:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:01:04 INFO - Vendor: Intel (0x8086) 05:01:04 INFO - Device ID: 0x0a2e 05:01:04 INFO - Revision ID: 0x0009 05:01:04 INFO - Displays: 05:01:04 INFO - Display: 05:01:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:01:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:01:04 INFO - Display Serial Number: 1600x1200 60 05:01:04 INFO - Main Display: Yes 05:01:04 INFO - Mirror: Off 05:01:04 INFO - Online: Yes 05:01:04 INFO - Rotation: Supported 05:01:05 INFO - Return code: 0 05:01:05 INFO - Running post-action listener: _resource_record_post_action 05:01:05 INFO - [mozharness: 2017-03-31 12:01:05.000938Z] Finished run-tests step (success) 05:01:05 INFO - Running post-run listener: _resource_record_post_run 05:01:05 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 643072; Write bytes: 517331968; Read time: 9; Write time: 1675 05:01:05 INFO - TinderboxPrint: CPU usage
53.5% 05:01:05 INFO - TinderboxPrint: I/O read bytes / time
643,072 / 9 05:01:05 INFO - TinderboxPrint: I/O write bytes / time
517,331,968 / 1,675 05:01:05 INFO - TinderboxPrint: CPU idle
2,242.3 (46.5%) 05:01:05 INFO - TinderboxPrint: CPU system
966.6 (20.0%) 05:01:05 INFO - TinderboxPrint: CPU user
1,616.7 (33.5%) 05:01:05 INFO - TinderboxPrint: Swap in / out
212,590,592 / 0 05:01:05 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200217088; Write bytes: 194250240; Read time: 15510; Write time: 438 05:01:05 INFO - run-tests - Wall time: 1186s; CPU: 54.0%; Read bytes: 643072; Write bytes: 303248896; Read time: 9; Write time: 1175 05:01:05 INFO - Running post-run listener: _upload_blobber_files 05:01:05 INFO - Blob upload gear active. 05:01:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:01:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:01:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:01:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:01:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:01:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:07 INFO - (blobuploader) - INFO - Done attempting. 05:01:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:01:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:01:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:08 INFO - (blobuploader) - INFO - Done attempting. 05:01:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:01:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:01:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:09 INFO - (blobuploader) - INFO - Done attempting. 05:01:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:01:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:01:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:01:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:01:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:01:11 INFO - (blobuploader) - INFO - Done attempting. 05:01:11 INFO - (blobuploader) - INFO - Iteration through files over. 05:01:11 INFO - Return code: 0 05:01:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:01:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:01:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8851ab50196c745c219f61f44d72f82bc5c5efdf2a30819978f006ee3008c8710b193fd2ea0a35281fd0c0aaa2641978c19c86bfebeef759b1da47643e5e29a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0f300abc85ede33894d6b31081bfb304de4ad81c04c5023c15b9a325ee170743956aab7c0b3a33c1c22a4d6bd6e01ef8f5c0e791c310249986ea8dce3d635be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d049aa029d2db0e197a4e22ff2d77e857edd9d97a3b6fee3ab1eced1bee3541128c23a95a7f5933420e34703a8302443dae46952ed3744ceff029e1c7fd78da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e75e56ef0b92ff008d725536961144c429557040287dd1be2a3e030179cde11fec42daebe288f87a5459c059b7244e1a39ea3e41746e523ed7ecd84e40682034"} 05:01:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:01:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:01:11 INFO - Contents: 05:01:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8851ab50196c745c219f61f44d72f82bc5c5efdf2a30819978f006ee3008c8710b193fd2ea0a35281fd0c0aaa2641978c19c86bfebeef759b1da47643e5e29a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0f300abc85ede33894d6b31081bfb304de4ad81c04c5023c15b9a325ee170743956aab7c0b3a33c1c22a4d6bd6e01ef8f5c0e791c310249986ea8dce3d635be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d049aa029d2db0e197a4e22ff2d77e857edd9d97a3b6fee3ab1eced1bee3541128c23a95a7f5933420e34703a8302443dae46952ed3744ceff029e1c7fd78da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e75e56ef0b92ff008d725536961144c429557040287dd1be2a3e030179cde11fec42daebe288f87a5459c059b7244e1a39ea3e41746e523ed7ecd84e40682034"} 05:01:11 INFO - Running post-run listener: copy_logs_to_upload_dir 05:01:11 INFO - Copying logs to upload dir... 05:01:11 INFO - mkdir: /builds/slave/test/build/upload/logs 05:01:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.348745 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-31 05:01:11.137212) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 05:01:11.141426) ========= 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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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/8851ab50196c745c219f61f44d72f82bc5c5efdf2a30819978f006ee3008c8710b193fd2ea0a35281fd0c0aaa2641978c19c86bfebeef759b1da47643e5e29a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0f300abc85ede33894d6b31081bfb304de4ad81c04c5023c15b9a325ee170743956aab7c0b3a33c1c22a4d6bd6e01ef8f5c0e791c310249986ea8dce3d635be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d049aa029d2db0e197a4e22ff2d77e857edd9d97a3b6fee3ab1eced1bee3541128c23a95a7f5933420e34703a8302443dae46952ed3744ceff029e1c7fd78da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e75e56ef0b92ff008d725536961144c429557040287dd1be2a3e030179cde11fec42daebe288f87a5459c059b7244e1a39ea3e41746e523ed7ecd84e40682034"} build_url:https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010794 build_url: 'https://queue.taskcluster.net/v1/task/XGI4z9MDTq2HTOpw8l9PfA/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/8851ab50196c745c219f61f44d72f82bc5c5efdf2a30819978f006ee3008c8710b193fd2ea0a35281fd0c0aaa2641978c19c86bfebeef759b1da47643e5e29a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0f300abc85ede33894d6b31081bfb304de4ad81c04c5023c15b9a325ee170743956aab7c0b3a33c1c22a4d6bd6e01ef8f5c0e791c310249986ea8dce3d635be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d049aa029d2db0e197a4e22ff2d77e857edd9d97a3b6fee3ab1eced1bee3541128c23a95a7f5933420e34703a8302443dae46952ed3744ceff029e1c7fd78da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e75e56ef0b92ff008d725536961144c429557040287dd1be2a3e030179cde11fec42daebe288f87a5459c059b7244e1a39ea3e41746e523ed7ecd84e40682034"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 05:01:11.179837) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 05:01:11.180185) ========= 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.pJYaWKuOXs/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.VMCjBxPyOU/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.005369 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 05:01:11.228725) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 05:01:11.229057) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 05:01:11.229427) ========= ========= Total master_lag: 0.25 =========