builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0133 starttime: 1490016893.73 results: success (0) buildid: 20170320051141 builduid: 878a77b8e60344409fa5aca429be4510 revision: 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:53.733570) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:53.733939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:53.734204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:53.772580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 06:34:53.772871) ========= 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.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-20 06:34:53-- 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% 37.8M=0s 2017-03-20 06:34:53 (37.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149008 ========= master_lag: 1.37 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 06:34:55.291318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:55.291663) ========= 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.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.020679 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:55.377274) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 06:34:55.377725) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 --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 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-20 06:34:55,466 truncating revision to first 12 chars 2017-03-20 06:34:55,466 Setting DEBUG logging. 2017-03-20 06:34:55,466 attempt 1/10 2017-03-20 06:34:55,466 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8d967436d696?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 06:34:56,493 unpacking tar archive at: mozilla-central-8d967436d696/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288902 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-20 06:34:56.694317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:56.694643) ========= script_repo_revision: 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:56.698217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:56.698506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 06:34:56.718879) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-20 06:34:56.719272) ========= /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.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:34:56 INFO - MultiFileLogger online at 20170320 06:34:56 in /builds/slave/test 06:34:56 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 06:34:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:34:56 INFO - {'append_to_log': False, 06:34:56 INFO - 'base_work_dir': '/builds/slave/test', 06:34:56 INFO - 'blob_upload_branch': 'Firefox', 06:34:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:34:56 INFO - 'branch': 'Firefox', 06:34:56 INFO - 'buildbot_json_path': 'buildprops.json', 06:34:56 INFO - 'config_files': ('talos/mac_config.py',), 06:34:56 INFO - 'default_actions': ('clobber', 06:34:56 INFO - 'read-buildbot-config', 06:34:56 INFO - 'download-and-extract', 06:34:56 INFO - 'populate-webroot', 06:34:56 INFO - 'create-virtualenv', 06:34:56 INFO - 'install', 06:34:56 INFO - 'run-tests'), 06:34:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:34:56 INFO - 'download_minidump_stackwalk': True, 06:34:56 INFO - 'download_symbols': 'ondemand', 06:34:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:34:56 INFO - 'tooltool.py': '/tools/tooltool.py', 06:34:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:34:56 INFO - '/tools/misc-python/virtualenv.py')}, 06:34:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:34:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:34:56 INFO - 'gecko_profile': False, 06:34:56 INFO - 'gecko_profile_interval': 0, 06:34:56 INFO - 'installer_path': 'installer.exe', 06:34:56 INFO - 'log_level': 'info', 06:34:56 INFO - 'log_name': 'talos', 06:34:56 INFO - 'log_to_console': True, 06:34:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:34:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:34:56 INFO - 'opt_config_files': (), 06:34:56 INFO - 'pip_index': False, 06:34:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:34:56 INFO - 'cmd': ('bash', 06:34:56 INFO - '-c', 06:34:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:34:56 INFO - 'enabled': True, 06:34:56 INFO - 'halt_on_failure': False, 06:34:56 INFO - 'name': 'check_screen_resolution'},), 06:34:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:34:56 INFO - 'cmd': ('bash', 06:34:56 INFO - '-c', 06:34:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:34:56 INFO - 'enabled': True, 06:34:56 INFO - 'halt_on_failure': False, 06:34:56 INFO - 'name': 'check_screen_resolution'},), 06:34:56 INFO - 'run_cmd_checks_enabled': True, 06:34:56 INFO - 'suite': 'dromaeojs', 06:34:56 INFO - 'system_bits': '32', 06:34:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:34:56 INFO - 'title': 't-yosemite-r7-0133', 06:34:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:34:56 INFO - 'use_talos_json': True, 06:34:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:34:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:34:56 INFO - 'work_dir': 'build'} 06:34:56 INFO - [mozharness: 2017-03-20 13:34:56.928030Z] Running clobber step. 06:34:56 INFO - Running pre-action listener: _resource_record_pre_action 06:34:56 INFO - Running main action method: clobber 06:34:56 INFO - rmtree: /builds/slave/test/build 06:34:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:34:58 INFO - Running post-action listener: _resource_record_post_action 06:34:58 INFO - [mozharness: 2017-03-20 13:34:58.637318Z] Finished clobber step (success) 06:34:58 INFO - [mozharness: 2017-03-20 13:34:58.637395Z] Running read-buildbot-config step. 06:34:58 INFO - Running pre-action listener: _resource_record_pre_action 06:34:58 INFO - Running main action method: read_buildbot_config 06:34:58 INFO - Using buildbot properties: 06:34:58 INFO - { 06:34:58 INFO - "project": "", 06:34:58 INFO - "product": "firefox", 06:34:58 INFO - "script_repo_revision": "production", 06:34:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:34:58 INFO - "repository": "", 06:34:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:34:58 INFO - "buildid": "20170320051141", 06:34:58 INFO - "pgo_build": "False", 06:34:58 INFO - "basedir": "/builds/slave/test", 06:34:58 INFO - "buildnumber": 25, 06:34:58 INFO - "slavename": "t-yosemite-r7-0133", 06:34:58 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:34:58 INFO - "platform": "yosemite_r7", 06:34:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:34:58 INFO - "branch": "mozilla-central", 06:34:58 INFO - "repo_path": "mozilla-central", 06:34:58 INFO - "revision": "8d967436d696d1f8e3fb33cf7e3d32a72457ffa6", 06:34:58 INFO - "stage_platform": "macosx64", 06:34:58 INFO - "builduid": "878a77b8e60344409fa5aca429be4510", 06:34:58 INFO - "slavebuilddir": "test" 06:34:58 INFO - } 06:34:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 06:34:58 INFO - Running post-action listener: _resource_record_post_action 06:34:58 INFO - [mozharness: 2017-03-20 13:34:58.640577Z] Finished read-buildbot-config step (success) 06:34:58 INFO - [mozharness: 2017-03-20 13:34:58.640684Z] Running download-and-extract step. 06:34:58 INFO - Running pre-action listener: _resource_record_pre_action 06:34:58 INFO - Running main action method: download_and_extract 06:34:58 INFO - mkdir: /builds/slave/test/build/tests 06:34:58 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')]} 06:34:58 INFO - trying https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 06:34:58 INFO - Downloading https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/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 06:34:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/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 06:35:00 INFO - Downloaded 1325 bytes. 06:35:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 06:35:00 INFO - Using the following test package requirements: 06:35:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 06:35:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:35:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 06:35:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 06:35:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'jsshell-mac.zip'], 06:35:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 06:35:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 06:35:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 06:35:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 06:35:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 06:35:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 06:35:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 06:35:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:35:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:35:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:35:00 INFO - Fetch https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:35:02 INFO - Expected file size: 52473537 06:35:02 INFO - Obtained file size: 52473537 06:35:18 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 06:35:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 06:35:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:35:18 INFO - Fetch https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 06:35:20 INFO - Expected file size: 52473537 06:35:20 INFO - Obtained file size: 52473537 06:35:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 06:35:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:35:36 INFO - Fetch https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 06:35:37 INFO - Expected file size: 14404171 06:35:37 INFO - Obtained file size: 14404171 06:35: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')]} 06:35:37 INFO - trying https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:35:37 INFO - Downloading https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 06:35:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/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 06:35:39 INFO - Downloaded 75191358 bytes. 06:35:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:35:39 INFO - mkdir: /builds/slave/test/properties 06:35:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:35:39 INFO - Writing to file /builds/slave/test/properties/build_url 06:35:39 INFO - Contents: 06:35:39 INFO - build_url:https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 06:35:40 INFO - Running post-action listener: _resource_record_post_action 06:35:40 INFO - Running post-action listener: set_extra_try_arguments 06:35:40 INFO - [mozharness: 2017-03-20 13:35:40.231366Z] Finished download-and-extract step (success) 06:35:40 INFO - [mozharness: 2017-03-20 13:35:40.231514Z] Running populate-webroot step. 06:35:40 INFO - Running pre-action listener: _resource_record_pre_action 06:35:40 INFO - Running main action method: populate_webroot 06:35:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:35:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:35:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:35:40 INFO - '--tppagecycles', 06:35:40 INFO - '7'], 06:35:40 INFO - 'tests': ['tsvgm']}}, 06:35:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:35:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:35:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:35:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:35:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['damp', 'tps']}, 06:35:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'tests': ['damp', 'tps']}, 06:35:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['dromaeo_dom']}, 06:35:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:35:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['basic_compositor_video']}, 06:35:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:35:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'other-e10s': {'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['a11yr', 06:35:40 INFO - 'ts_paint', 06:35:40 INFO - 'tpaint', 06:35:40 INFO - 'sessionrestore', 06:35:40 INFO - 'sessionrestore_no_auto_restore', 06:35:40 INFO - 'tabpaint']}, 06:35:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['tsvgx', 06:35:40 INFO - 'tsvgr_opacity', 06:35:40 INFO - 'tart', 06:35:40 INFO - 'tscrollx', 06:35:40 INFO - 'cart', 06:35:40 INFO - 'tsvg_static']}, 06:35:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:35:40 INFO - 'tsvgr_opacity', 06:35:40 INFO - 'tart', 06:35:40 INFO - 'tscrollx', 06:35:40 INFO - 'cart', 06:35:40 INFO - 'tsvg_static']}, 06:35:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'talos_options': ['--disable-e10s'], 06:35:40 INFO - 'tests': ['tp5o']}, 06:35:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:35:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'talos_options': ['--disable-e10s', 06:35:40 INFO - '--xperf_path', 06:35:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:40 INFO - 'tests': ['tp5n']}, 06:35:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:35:40 INFO - 'talos_options': ['--xperf_path', 06:35:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:40 INFO - 'tests': ['tp5n']}}, 06:35:40 INFO - 'talos.zip': {'path': '', 06:35:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:35:40 INFO - Running post-action listener: _resource_record_post_action 06:35:40 INFO - [mozharness: 2017-03-20 13:35:40.249835Z] Finished populate-webroot step (success) 06:35:40 INFO - [mozharness: 2017-03-20 13:35:40.249952Z] Running create-virtualenv step. 06:35:40 INFO - Running pre-action listener: _resource_record_pre_action 06:35:40 INFO - Running main action method: create_virtualenv 06:35:40 INFO - Creating virtualenv /builds/slave/test/build/venv 06:35:40 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 06:35:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:35:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:35:40 INFO - Using real prefix '/tools/python27' 06:35:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:35:41 INFO - Installing distribute.............................................................................................................................................................................................done. 06:35:43 INFO - Installing pip.................done. 06:35:43 INFO - Return code: 0 06:35:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:35:44 INFO - Reading from file tmpfile_stdout 06:35:44 INFO - Output received: 06:35:44 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) 06:35:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:44 INFO - 'HOME': '/Users/cltbld', 06:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:44 INFO - 'LOGNAME': 'cltbld', 06:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:44 INFO - 'MOZ_NO_REMOTE': '1', 06:35:44 INFO - 'NO_EM_RESTART': '1', 06:35:44 INFO - 'PAGER': '/bin/cat', 06:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:44 INFO - 'PWD': '/builds/slave/test', 06:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:44 INFO - 'SHELL': '/bin/bash', 06:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:44 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:44 INFO - 'USER': 'cltbld', 06:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:44 INFO - 'XPC_FLAGS': '0x0', 06:35:44 INFO - 'XPC_SERVICE_NAME': '0', 06:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:44 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 06:35:44 INFO - Cleaning up... 06:35:44 INFO - Return code: 0 06:35:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:44 INFO - 'HOME': '/Users/cltbld', 06:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:44 INFO - 'LOGNAME': 'cltbld', 06:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:44 INFO - 'MOZ_NO_REMOTE': '1', 06:35:44 INFO - 'NO_EM_RESTART': '1', 06:35:44 INFO - 'PAGER': '/bin/cat', 06:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:44 INFO - 'PWD': '/builds/slave/test', 06:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:44 INFO - 'SHELL': '/bin/bash', 06:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:44 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:44 INFO - 'USER': 'cltbld', 06:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:44 INFO - 'XPC_FLAGS': '0x0', 06:35:44 INFO - 'XPC_SERVICE_NAME': '0', 06:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:44 INFO - Downloading/unpacking psutil>=3.1.1 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:35:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:48 INFO - Installing collected packages: psutil 06:35:48 INFO - Running setup.py install for psutil 06:35:48 INFO - building 'psutil._psutil_osx' extension 06:35:48 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 06:35:48 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 06:35:49 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 06:35:49 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 06:35:49 INFO - building 'psutil._psutil_posix' extension 06:35:49 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 06:35:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:35:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:35:49 INFO - ^ 06:35:49 INFO - 1 warning generated. 06:35:49 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 06:35:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:49 INFO - Successfully installed psutil 06:35:49 INFO - Cleaning up... 06:35:49 INFO - Return code: 0 06:35:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:49 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:49 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 06:35:49 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 06:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:49 INFO - 'HOME': '/Users/cltbld', 06:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:49 INFO - 'LOGNAME': 'cltbld', 06:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:49 INFO - 'MOZ_NO_REMOTE': '1', 06:35:49 INFO - 'NO_EM_RESTART': '1', 06:35:49 INFO - 'PAGER': '/bin/cat', 06:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:49 INFO - 'PWD': '/builds/slave/test', 06:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:49 INFO - 'SHELL': '/bin/bash', 06:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:49 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:49 INFO - 'USER': 'cltbld', 06:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:49 INFO - 'XPC_FLAGS': '0x0', 06:35:49 INFO - 'XPC_SERVICE_NAME': '0', 06:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:35:49 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 06:35:49 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 06:35:49 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 06:35:49 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 06:35:49 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 06:35:49 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 06:35:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:35:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:35:52 INFO - Installing collected packages: mozsystemmonitor 06:35:52 INFO - Running setup.py install for mozsystemmonitor 06:35:52 INFO - Successfully installed mozsystemmonitor 06:35:52 INFO - Cleaning up... 06:35:52 INFO - Return code: 0 06:35:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:35: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')]} 06:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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')]} 06:35:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:52 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:52 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 06:35:52 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 06:35:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:52 INFO - 'HOME': '/Users/cltbld', 06:35:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:52 INFO - 'LOGNAME': 'cltbld', 06:35:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:52 INFO - 'MOZ_NO_REMOTE': '1', 06:35:52 INFO - 'NO_EM_RESTART': '1', 06:35:52 INFO - 'PAGER': '/bin/cat', 06:35:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:52 INFO - 'PWD': '/builds/slave/test', 06:35:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:52 INFO - 'SHELL': '/bin/bash', 06:35:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:52 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:52 INFO - 'USER': 'cltbld', 06:35:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:52 INFO - 'XPC_FLAGS': '0x0', 06:35:52 INFO - 'XPC_SERVICE_NAME': '0', 06:35:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:52 INFO - Downloading/unpacking jsonschema==2.5.1 06:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:35:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:35:55 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 06:35:55 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 06:35:55 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 06:35:55 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 06:35:55 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 06:35:55 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 06:35:55 INFO - Downloading functools32-3.2.3-2.tar.gz 06:35:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:35:56 INFO - warning: no files found matching '*.txt' 06:35:56 INFO - no previously-included directories found matching 'build' 06:35:56 INFO - no previously-included directories found matching 'dist' 06:35:56 INFO - no previously-included directories found matching '.git*' 06:35:56 INFO - Installing collected packages: jsonschema, functools32 06:35:56 INFO - Running setup.py install for functools32 06:35:56 INFO - warning: no files found matching '*.txt' 06:35:56 INFO - no previously-included directories found matching 'build' 06:35:56 INFO - no previously-included directories found matching 'dist' 06:35:56 INFO - no previously-included directories found matching '.git*' 06:35:56 INFO - Successfully installed jsonschema functools32 06:35:56 INFO - Cleaning up... 06:35:56 INFO - Return code: 0 06:35:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:35:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:56 INFO - 'HOME': '/Users/cltbld', 06:35:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:56 INFO - 'LOGNAME': 'cltbld', 06:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:56 INFO - 'MOZ_NO_REMOTE': '1', 06:35:56 INFO - 'NO_EM_RESTART': '1', 06:35:56 INFO - 'PAGER': '/bin/cat', 06:35:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:56 INFO - 'PWD': '/builds/slave/test', 06:35:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:56 INFO - 'SHELL': '/bin/bash', 06:35:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:56 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:56 INFO - 'USER': 'cltbld', 06:35:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:56 INFO - 'XPC_FLAGS': '0x0', 06:35:56 INFO - 'XPC_SERVICE_NAME': '0', 06:35:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:35:56 INFO - Cleaning up... 06:35:56 INFO - Return code: 0 06:35:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:35:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:35:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:35:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:56 INFO - 'HOME': '/Users/cltbld', 06:35:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:56 INFO - 'LOGNAME': 'cltbld', 06:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:56 INFO - 'MOZ_NO_REMOTE': '1', 06:35:56 INFO - 'NO_EM_RESTART': '1', 06:35:56 INFO - 'PAGER': '/bin/cat', 06:35:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:56 INFO - 'PWD': '/builds/slave/test', 06:35:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:56 INFO - 'SHELL': '/bin/bash', 06:35:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:35:56 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:35:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:56 INFO - 'USER': 'cltbld', 06:35:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:56 INFO - 'XPC_FLAGS': '0x0', 06:35:56 INFO - 'XPC_SERVICE_NAME': '0', 06:35:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:56 INFO - Downloading/unpacking blobuploader==1.2.4 06:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:59 INFO - Downloading blobuploader-1.2.4.tar.gz 06:35:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:35:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:35:59 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 06:35:59 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 06:35:59 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 06:35:59 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 06:35:59 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 06:35:59 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 06:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:36:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:36:00 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 06:36:00 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 06:36:00 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 06:36:00 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 06:36:00 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 06:36:00 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 06:36:00 INFO - Downloading docopt-0.6.1.tar.gz 06:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:36:00 INFO - Installing collected packages: blobuploader, requests, docopt 06:36:00 INFO - Running setup.py install for blobuploader 06:36:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:36:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:36:01 INFO - Running setup.py install for requests 06:36:01 INFO - Running setup.py install for docopt 06:36:01 INFO - Successfully installed blobuploader requests docopt 06:36:01 INFO - Cleaning up... 06:36:01 INFO - Return code: 0 06:36:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:36:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:36:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:01 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:36:01 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 06:36:01 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 06:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:01 INFO - 'HOME': '/Users/cltbld', 06:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:01 INFO - 'LOGNAME': 'cltbld', 06:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:01 INFO - 'MOZ_NO_REMOTE': '1', 06:36:01 INFO - 'NO_EM_RESTART': '1', 06:36:01 INFO - 'PAGER': '/bin/cat', 06:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:01 INFO - 'PWD': '/builds/slave/test', 06:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:01 INFO - 'SHELL': '/bin/bash', 06:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:36:01 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:01 INFO - 'USER': 'cltbld', 06:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:01 INFO - 'XPC_FLAGS': '0x0', 06:36:01 INFO - 'XPC_SERVICE_NAME': '0', 06:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-doraUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Zv5hyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-WuIO_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TGSsgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SgQPwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-_QyHw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-waUT5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-udVlnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ug_rOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:36:02 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Pw2AkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sEVNSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xhnqwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-BLD_CW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-zDig_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-aGgZSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Knf_4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:36:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:36:03 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-QxpQ0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:36:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:36:03 INFO - Running setup.py install for manifestparser 06:36:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:36:03 INFO - Running setup.py install for mozcrash 06:36:04 INFO - Running setup.py install for mozdebug 06:36:04 INFO - Running setup.py install for mozdevice 06:36:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Running setup.py install for mozfile 06:36:04 INFO - Running setup.py install for mozhttpd 06:36:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Running setup.py install for mozinfo 06:36:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Running setup.py install for mozInstall 06:36:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:36:04 INFO - Running setup.py install for mozleak 06:36:05 INFO - Running setup.py install for mozlog 06:36:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Running setup.py install for moznetwork 06:36:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Running setup.py install for mozprocess 06:36:05 INFO - Running setup.py install for mozprofile 06:36:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Running setup.py install for mozrunner 06:36:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:36:05 INFO - Running setup.py install for mozscreenshot 06:36:06 INFO - Running setup.py install for moztest 06:36:06 INFO - Running setup.py install for mozversion 06:36:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:36:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:36:06 INFO - Cleaning up... 06:36:06 INFO - Return code: 0 06:36:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:06 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:36:06 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 06:36:06 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 06:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:06 INFO - 'HOME': '/Users/cltbld', 06:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:06 INFO - 'LOGNAME': 'cltbld', 06:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:06 INFO - 'MOZ_NO_REMOTE': '1', 06:36:06 INFO - 'NO_EM_RESTART': '1', 06:36:06 INFO - 'PAGER': '/bin/cat', 06:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:06 INFO - 'PWD': '/builds/slave/test', 06:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:06 INFO - 'SHELL': '/bin/bash', 06:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:36:06 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:06 INFO - 'USER': 'cltbld', 06:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:06 INFO - 'XPC_FLAGS': '0x0', 06:36:06 INFO - 'XPC_SERVICE_NAME': '0', 06:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:36:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-aJA_yC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:36:06 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)) 06:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:36:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Jm4xu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:36:06 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)) 06:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:36:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-_60XSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:36:06 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)) 06:36:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:36:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-kcIIsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-FpiHkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-fEaLeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Hbu1vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-8S2lmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-670FQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xJp7Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7cRyVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-2k6NkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:36:07 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)) 06:36:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:36:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-12q4pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:36:08 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)) 06:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:36:08 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-lB18ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:36:08 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)) 06:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:36:08 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-X7XTL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:36:08 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)) 06:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:36:08 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-29Ohog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:36:08 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)) 06:36:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:36:08 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-5cY11A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:36:08 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)) 06:36:08 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)) 06:36:08 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)) 06:36:08 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)) 06:36:08 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)) 06:36:08 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)) 06:36:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:36:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:36:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:36:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:36:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:36:11 INFO - Downloading blessings-1.6.tar.gz 06:36:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:36:11 INFO - Installing collected packages: blessings 06:36:11 INFO - Running setup.py install for blessings 06:36:11 INFO - Successfully installed blessings 06:36:11 INFO - Cleaning up... 06:36:11 INFO - Return code: 0 06:36:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:36:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:36:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:36:11 INFO - Reading from file tmpfile_stdout 06:36:11 INFO - Current package versions: 06:36:11 INFO - blessings == 1.6 06:36:11 INFO - blobuploader == 1.2.4 06:36:11 INFO - docopt == 0.6.1 06:36:11 INFO - functools32 == 3.2.3-2 06:36:11 INFO - jsonschema == 2.5.1 06:36:11 INFO - manifestparser == 1.1 06:36:11 INFO - mozInstall == 1.12 06:36:11 INFO - mozcrash == 1.0 06:36:11 INFO - mozdebug == 0.1 06:36:11 INFO - mozdevice == 0.49 06:36:11 INFO - mozfile == 1.2 06:36:11 INFO - mozhttpd == 0.7 06:36:11 INFO - mozinfo == 0.9 06:36:11 INFO - mozleak == 0.1 06:36:11 INFO - mozlog == 3.4 06:36:11 INFO - moznetwork == 0.27 06:36:11 INFO - mozprocess == 0.25 06:36:11 INFO - mozprofile == 0.28 06:36:11 INFO - mozrunner == 6.13 06:36:11 INFO - mozscreenshot == 0.1 06:36:11 INFO - mozsystemmonitor == 0.3 06:36:11 INFO - moztest == 0.8 06:36:11 INFO - mozversion == 1.4 06:36:11 INFO - psutil == 3.1.1 06:36:11 INFO - requests == 1.2.3 06:36:11 INFO - wsgiref == 0.1.2 06:36:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:36:11 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')]} 06:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:36:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:11 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')]} 06:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:36:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:11 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:36:11 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 06:36:11 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 06:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:11 INFO - 'HOME': '/Users/cltbld', 06:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:11 INFO - 'LOGNAME': 'cltbld', 06:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:11 INFO - 'MOZ_NO_REMOTE': '1', 06:36:11 INFO - 'NO_EM_RESTART': '1', 06:36:11 INFO - 'PAGER': '/bin/cat', 06:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:11 INFO - 'PWD': '/builds/slave/test', 06:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:11 INFO - 'SHELL': '/bin/bash', 06:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:36:11 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:11 INFO - 'USER': 'cltbld', 06:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:11 INFO - 'XPC_FLAGS': '0x0', 06:36:11 INFO - 'XPC_SERVICE_NAME': '0', 06:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 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)) 06:36:12 INFO - Cleaning up... 06:36:12 INFO - Return code: 0 06:36:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:36:12 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')]} 06:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:12 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')]} 06:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:36:12 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 0x1098fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febd8585c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, '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.zWelwXCHXp/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.Xzyo481xYr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 06:36:12 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 06:36:12 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 06:36:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:36:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:12 INFO - 'HOME': '/Users/cltbld', 06:36:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:12 INFO - 'LOGNAME': 'cltbld', 06:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:12 INFO - 'MOZ_NO_REMOTE': '1', 06:36:12 INFO - 'NO_EM_RESTART': '1', 06:36:12 INFO - 'PAGER': '/bin/cat', 06:36:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:12 INFO - 'PWD': '/builds/slave/test', 06:36:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:12 INFO - 'SHELL': '/bin/bash', 06:36:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:36:12 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:36:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:12 INFO - 'USER': 'cltbld', 06:36:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:12 INFO - 'XPC_FLAGS': '0x0', 06:36:12 INFO - 'XPC_SERVICE_NAME': '0', 06:36:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:36:12 INFO - Cleaning up... 06:36:12 INFO - Return code: 0 06:36:12 INFO - Running post-action listener: _resource_record_post_action 06:36:12 INFO - Running post-action listener: _start_resource_monitoring 06:36:12 INFO - Starting resource monitoring. 06:36:12 INFO - [mozharness: 2017-03-20 13:36:12.336427Z] Finished create-virtualenv step (success) 06:36:12 INFO - [mozharness: 2017-03-20 13:36:12.337455Z] Running install step. 06:36:12 INFO - Running pre-action listener: _resource_record_pre_action 06:36:12 INFO - Running main action method: install 06:36:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:36:12 INFO - Reading from file tmpfile_stdout 06:36:12 INFO - Detecting whether we're running mozinstall >=1.0... 06:36:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:36:12 INFO - Reading from file tmpfile_stdout 06:36:12 INFO - Output received: 06:36:12 INFO - Usage: mozinstall [options] installer 06:36:12 INFO - Options: 06:36:12 INFO - -h, --help show this help message and exit 06:36:12 INFO - -d DEST, --destination=DEST 06:36:12 INFO - Directory to install application into. [default: 06:36:12 INFO - "/builds/slave/test"] 06:36:12 INFO - --app=APP Application being installed. [default: firefox] 06:36:12 INFO - mkdir: /builds/slave/test/build/application 06:36:12 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'] 06:36:12 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 06:36:32 INFO - Reading from file tmpfile_stdout 06:36:32 INFO - Output received: 06:36:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:36:32 INFO - Running post-action listener: _resource_record_post_action 06:36:32 INFO - [mozharness: 2017-03-20 13:36:32.990171Z] Finished install step (success) 06:36:32 INFO - [mozharness: 2017-03-20 13:36:32.990353Z] Running run-tests step. 06:36:32 INFO - Running pre-action listener: _resource_record_pre_action 06:36:32 INFO - Running main action method: run_tests 06:36:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:36:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:36:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:36:33 INFO - 2017-03-20 06:36:33.004 screenresolution[4473:76514] starting screenresolution argv=screenresolution get 06:36:33 INFO - 2017-03-20 06:36:33.007 screenresolution[4473:76514] Display 0: 1600x1200x32@60 06:36:33 INFO - 2017-03-20 06:36:33.013 screenresolution[4474:76517] starting screenresolution argv=screenresolution list 06:36:33 INFO - Available Modes on Display 0 06:36:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:36:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:36:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:36:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:36:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:36:33 INFO - Intel Iris: 06:36:33 INFO - Chipset Model: Intel Iris 06:36:33 INFO - Type: GPU 06:36:33 INFO - Bus: Built-In 06:36:33 INFO - VRAM (Dynamic, Max): 1536 MB 06:36:33 INFO - Vendor: Intel (0x8086) 06:36:33 INFO - Device ID: 0x0a2e 06:36:33 INFO - Revision ID: 0x0009 06:36:33 INFO - Displays: 06:36:33 INFO - Display: 06:36:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:36:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:36:33 INFO - Display Serial Number: 1600x1200 60 06:36:33 INFO - Main Display: Yes 06:36:33 INFO - Mirror: Off 06:36:33 INFO - Online: Yes 06:36:33 INFO - Rotation: Supported 06:36:33 INFO - Return code: 0 06:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:36:33 INFO - Python 2.7.3 06:36:33 INFO - Return code: 0 06:36:33 INFO - grabbing minidump binary from tooltool 06:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:36:33 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 0x1096dad30>, '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 0x109601db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096cfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:36:33 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 06:36:33 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 06:36:33 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 06:36:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:36:33 INFO - Return code: 0 06:36:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:36:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:36:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:36:33 INFO - ENV: RUST_BACKTRACE is now 1 06:36:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:36:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 06:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --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 06:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWelwXCHXp/Render', 06:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:33 INFO - 'HOME': '/Users/cltbld', 06:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:33 INFO - 'LOGNAME': 'cltbld', 06:36:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:36:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:33 INFO - 'MOZ_NO_REMOTE': '1', 06:36:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:36:33 INFO - 'NO_EM_RESTART': '1', 06:36:33 INFO - 'PAGER': '/bin/cat', 06:36:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:33 INFO - 'PWD': '/builds/slave/test', 06:36:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:33 INFO - 'RUST_BACKTRACE': '1', 06:36:33 INFO - 'SHELL': '/bin/bash', 06:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xzyo481xYr/Listeners', 06:36:33 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 06:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:33 INFO - 'USER': 'cltbld', 06:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:33 INFO - 'XPC_FLAGS': '0x0', 06:36:33 INFO - 'XPC_SERVICE_NAME': '0', 06:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 06:36:33 INFO - mozversion application_buildid: 20170320051141 06:36:33 INFO - mozversion application_changeset: 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 06:36:33 INFO - mozversion application_display_name: Nightly 06:36:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:36:33 INFO - mozversion application_name: Firefox 06:36:33 INFO - mozversion application_remotingname: firefox 06:36:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:36:33 INFO - mozversion application_vendor: Mozilla 06:36:33 INFO - mozversion application_version: 55.0a1 06:36:33 INFO - mozversion platform_buildid: 20170320051141 06:36:33 INFO - mozversion platform_changeset: 8d967436d696d1f8e3fb33cf7e3d32a72457ffa6 06:36:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:36:33 INFO - mozversion platform_version: 55.0a1 06:36:33 INFO - using testdate: 1490016993 06:36:33 INFO - actual date: 1490016993 06:36:33 INFO - starting webserver on 'localhost:54915' 06:36:33 INFO - SUITE-START | Running 2 tests 06:36:33 INFO - TEST-START | dromaeo_css 06:36:33 INFO - operating with platform_type : mac_ 06:36:33 INFO - Initialising browser for dromaeo_css test... 06:36:33 INFO - TEST-INFO | started process 4484 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpOZA42G/profile http://localhost:54915/getInfo.html) 06:36:35 INFO - PID 4484 | __metrics Screen width/height:1600/1200 06:36:35 INFO - PID 4484 | colorDepth:24 06:36:35 INFO - PID 4484 | Browser inner width/height: 1024/689 06:36:35 INFO - PID 4484 | __metrics 06:36:36 INFO - PID 4484 | 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" 06:36:36 INFO - PID 4484 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:36:36 INFO - TEST-INFO | 4484: exit 0 06:36:36 INFO - Browser initialized. 06:36:36 INFO - Running cycle 1/1 for dromaeo_css test... 06:36:36 INFO - TEST-INFO | started process 4486 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpOZA42G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:36:49 INFO - PID 4486 | RSS: Main: 233873408 06:36:49 INFO - PID 4486 | 06:39:53 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54915/tests/dromaeo/cssquery-ext.html) 06:39:55 INFO - PID 4486 | RSS: Main: 435777536 06:39:55 INFO - PID 4486 | 06:41:38 INFO - PID 4486 | ************************* 06:41:38 INFO - PID 4486 | A coding exception was thrown and uncaught in a Task. 06:41:38 INFO - PID 4486 | 06:41:38 ERROR - PID 4486 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:41:38 INFO - PID 4486 | Full stack: 06:41:38 INFO - PID 4486 | ************************* 06:42:58 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-ext.html (next: http://localhost:54915/tests/dromaeo/cssquery-jquery.html) 06:42:59 INFO - PID 4486 | RSS: Main: 376426496 06:42:59 INFO - PID 4486 | 06:46:02 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54915/tests/dromaeo/cssquery-mootools.html) 06:46:02 INFO - PID 4486 | RSS: Main: 351002624 06:46:02 INFO - PID 4486 | 06:49:05 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54915/tests/dromaeo/cssquery-prototype.html) 06:49:05 INFO - PID 4486 | RSS: Main: 336814080 06:49:05 INFO - PID 4486 | 06:52:09 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54915/tests/dromaeo/cssquery-yui.html) 06:52:10 INFO - PID 4486 | RSS: Main: 338599936 06:52:10 INFO - PID 4486 | 06:55:13 INFO - PID 4486 | Cycle 1(1): loaded http://localhost:54915/tests/dromaeo/cssquery-yui.html (next: http://localhost:54915/tests/dromaeo/cssquery-dojo.html) 06:55:14 INFO - PID 4486 | RSS: Main: 393625600 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | __start_tp_report 06:55:14 INFO - PID 4486 | _x_x_mozilla_page_load 06:55:14 INFO - PID 4486 | _x_x_mozilla_page_load_details 06:55:14 INFO - PID 4486 | |i|pagename|runs| 06:55:14 INFO - PID 4486 | |0;dojo.html;3369.0903456066876;3374.6287908330078;3376.4260075787097;3405.114670185752;3445;5716.628419152752;5787.971060144704;5889.293284805829;5938.703064846756;5956.047032474805;5838.591298609099;5874.618149820259;5891.116332550119;5920.052791553352;5937.703114844262;4893.559579637832;4937.5556199942;5304.946950530503;5339.41266460689;5339.519443250107;5452.263944367519;5494;5494.423085576017;5516.972415137919;5551.1118221084635;4380.34294855771;5222.947770522284;5262.894742105136;5274.314339135888;5418.729063546826;5336.146216605363;5346.705931173784;5346.786128554853;5392.325959255093;5418.376886658046;5253.9737301313635;5270.051390749663;5303.284056652341;5313.0967735485065;5378.542823859977;5691.9146212806845;5743.368229494752;5850;5880.117982302646;5900.616459930134;6269;6276.121343011936;6315.652639104847;6317.14718513004;6320.399562041599;6265.686715664198;6292.87414251717;6303.527235457359;6324.7470101195895;6330.461910737593;5992.700364981733;5993.940060599425;6037.45662890342;6073.39266073398;6086.3609321021095;6257.843553911189;6291.559590828598;6321.778737744157;6366.936330636727;6446.161998940108;6289.559730818799;6318.304986451487;6322.968385158045;6335.176427064452;6338;6187;6291.5281353898645;6305;6313.147725057151;6330.113784070234;6178.351273116348;6228.34602366754;6296.653684047421;6319.526035547352;6322.620642761449;6173.166622505994;6233.283172435137;6289.433950944345;6289.654069026156;6305.14880491137;5098.1842905135;5098.59211263098;5100.336956195745;5113;5131.7177555234075;4477.261251893401;4521.796519156645;4523;4529.818807247673;4533.32000199966;5789.421057894177;5864.9443100241515;5865.4134586541;5870.853228669232;5969.79105731306;6286.905696414466;6294.118823364733;6303.117563541108;6303.180586523723;6306.211723534559;5873.295204575393;5873.735681894325;5878.5297176225795;5890.322612899486;5891.057430811135;5474.507294343449;5519.31008623922;5559.8888022239325;5574.16387541872;5576.749046292926;6381.29805721361;6390.8721825563225;6393.648349340738;6396.264429590564;6400.199975003125;6282.057691346335;6283.214598175228;6289.433950944437;6300.149479820166;6312.621242725451;5868.589198756046;6108.6945652716295;6505.381988711065;6505.967470162619;6535.673216339259;6296.5592408530965;6307.148534947723;6325.7153428094925;6332.398422149889;6338.334474880071;6125.356837531995;6308.27454842699;6312.368763123651;6313.968430158004;6322.841928951629;6160.3223645398075;6180.196574445294;6180.81457556274;6189.319174890848;6195.535334849814;6122.265328160649;6140.718981675666;6148.385161483816;6217.751289948352;6278.4035516625845;6182.969085154546;6200.100985357189;6201.193844800147;6205.968970155121;6229.283632382334;6261.592996455216;6275.995840665471;6278.7488500461295;6294.559380843297;6298.590591611531;4988.5260900214425;5983.850403739942;6015.157877897185;6052.757889684363;6056.0915862619195 06:55:14 INFO - PID 4486 | |1;ext.html;712.1347562711306;774.5662429039736;847.8855354527137;852.9275011624012;855.4653341661461;26865.597016044776;35789;36263.99999999996;36414.817925910334;36584;37844.621553784455;38043.61956380435;38162;38167.809160954224;38190.809045954804;8872.467651940888;9107.954460227722;9112.407693499934;9120.498372589505;9122;7154.427645788324;7335.266473352649;10650.25448218625;10839.566417343296;10856.34861908288;10800.51397687102;10905.127589792797;10915.508802103885;10919.39943303118;10924.050506363277;10778.353298802096;10850.348979061282;10855.782884342309;10960.397178155199;10981.945090274578;7060.505764596481;7247.057882475297;7250.383717383976;7256.818579535501;7257.963710181415;35230.82384588061;35808;35825.775680188905;35879.92360229171;37011;3241.465158248886;3749.568799588055;3792.412176112707;3800.619938006205;3839.212961342946;27266.727332726616;27399;27438.176854694193;28120.296992575146;28323.575146372616;3108.9895783870106;3116.626004879406;3121.1884909923356;3143.1513491357205;3177.205698575356;309022;314190;314864;315712.84287156834;316531;37417;44551.331730024074;50156.996860062594;50269.74865125651;50270;39901;40477.595224047385;47286.5271347289;47344.28983565249;47470;13664.316784160752;13813.171209727449;13841.515546955809;13849.86150138506;13921.095128816594;11232.438378081226;11253.943730281297;11280.097592192606;11285.435728213557;11286.717832054264;12818.359082045861;12850.807237891438;12855.292958887165;12878;12996.285204313665;4828.37231159947;4976.129177393942;5353.2773075634295;5353.812616558402;5364.517193452608;978.2418625565244;1107.5791199344199;1110.5280255891214;1124.6794663520857;1179.2099293473393;21929.70713786395;26047.739522604912;26075.838966084666;26240.8687956559;26393.868030659723;1216.2823933879054;1225.9570915017932;1229.391451231646;1269.320913311374;1279.226068228727;12919.418626161845;13376.130551514121;13659.15100139456;13705.794413083811;15261.236938153048;298869;298945;299355;299691.5015424909;299857;50170.498295017314;50190.24714629284;50314;50370;50372.244416333786;1326.4694122351136;1396.6648004479055;1403.1651167555287;1406.3460490871794;1416.9574912752428;23629.645555317027;27201.143879892326;27835.44329113447;28390.858045710465;28473.572896406156;39180;39238.01904952399;39281.607183927794;43695.90760230892;46494.767526162155;356198;358510;359337;359405;359998;32244.19389515281;32270.677293226767;32281.838590806896;32452.675473244966;32459.513107303883;40344;40574;40575.797121014206;41823.7908810454;41898;25087;27225.72774272232;27666.03168889079;33413.832930836164;34223.4866477008;33222;33453.66546334505;33522.32955340928;34328.313433730684;34627;33691.49462758011;34394.82802585971;34428;34537;34542.48186277258;25815.354616134748;27241.31896702598;29703.960361387246;29729.702702972692;30344.84827575848;22753.658695119917;22897.882988442125;22922.656160157276;23273;23356.53286934218 06:55:14 INFO - PID 4486 | |2;jquery.html;329.8977317031722;383.4133775323756;391.43437732476565;391.6983922379767;392.30757712637194;13308.534201302957;13758.931205343972;14984.026038307502;21558.676619850714;21583.24458643948;21090.789092109073;22359.993800278986;22893.656595151086;23485.882570587124;23736.643950340716;6522.347765223475;6813.114295141625;6827.078344423514;7466.328030477256;7849.882251766228;6886.380225779679;7939.603019849013;8003.839923201533;8024.638891249879;8027.478213916106;7926.8810967835525;7993.600319984007;8001.879971800398;8010.5594192319395;8016.839663206732;7813.726519571787;7832.451728378987;7873.881891771628;7929.524228546276;7958.761237162837;7480.962595187044;7713.34436572892;7792.142864284924;7805.765827025199;7811.414143939171;27019.45961080787;27198;27374.589381159298;28058.859705701547;28430.715692843012;2793.7485626293733;2859.656841179051;2870.354170311663;2896.435195136949;2914.1694617034045;16183.757243641356;16848.83151168485;17058;17519.605344049458;17522.824771752246;2040.4796776821815;2107.262458139654;2107.8102970732552;2110.1137522240697;2110.5884352551257;134563.32718336346;147109;154897.6377952767;156966;156987;17346.82653173452;21406.143754249966;21762.782372176393;21828.068486829954;22095.44761380978;30159.69840301569;37131.62868371336;37317.44023839645;37353.252934941695;37436.06409839721;10066.798664026679;10344.37933723964;10528.157747380192;10542.947285263677;10710.196735244888;6900.3444672756;8381.916180838114;8410.747677569683;8586.914130858737;8607.837941877891;10539.578416863393;10636.361818290772;10641.72299324086;10790.244682872124;10827.350358978487;4114.403411505315;4214.4310518079665;4252.893677658083;4265.7440553566885;4290.656747460196;951.4434056077134;987.195435719886;1003.7189586915675;1011.5144730529387;1034.5448002878709;22385.328440146823;22976;22988;23105.653415198787;23132.3060308191;956.4882787708542;1005.3063386263455;1007.9143272821892;1035.316690983947;1043.5304113149114;11047.613333533287;11808.645740627622;11931.522739090513;11983.064149283482;12469.68070301016;612378;623984;645428;646797;651236;106595;136311;140090;140365;143059.38104545424;1256.0956111599633;1278.1053262716136;1325.5095614622458;1326.8407791065133;1330.9267990260435;19064.33274835374;20468.79531204669;20522.897385512977;21674.458138546033;22055.22806701758;50028;60266.39733602783;61153.08270376037;61895;63516;76125.619371905;87284;89141.55429222812;89389;90685.54657226671;11792.70518237051;12392.876071239172;12640.546337171343;12809.03932205107;12860.549880754128;14112.717745645235;14187.290635467978;14207.715845682822;14524.346404411615;14632.634184145481;9617.615295388387;11110.277831940897;11414.714632134263;11450.82823757661;11451.59919402817;9902.801943960936;11139.944300278446;11245.325280483197;11679.76640467203;12060.939695301468;11143.2199746017;11926.522939082339;12198.75602487963;12337.814932775836;12456.626301210978;12427.502899883903;12430.254184748945;12510.437030333838;12833.358332083171;12875.227486350848;11631.302121872715;11898.107641926717;11949.103817213918;12020.519179233082;12037.013892707955 06:55:14 INFO - PID 4486 | |3;mootools.html;1363.0799210532887;1490.0016988617624;1580.3678528588564;1603.6391811842336;1608.035178892664;9210.355275130743;9649.22806175506;9652.469114198717;9663.468509231998;9664.420134791917;9473.289503287246;9569.904300957005;10618.469076546162;10796.946015269932;10955.34267943924;2009.577988622391;2312.7455979842202;2349.835511514195;2350.894209760565;2353.9293821185306;1727.6631056943902;2027.0067666843288;2237.6084185267596;2289.107248173214;2319.82601304902;2012.667909794882;2039.8470114741378;2241.2491815241915;2330.382448651109;2331.720193576773;2307.849989750661;2312.3409828198965;2312.6415405612156;2317.945334872629;2319.5297008685866;2239.5632851593946;2316.3514216019544;2319.6404557293645;2321.1643808229023;2322.674825524428;11025.724356891062;11151.442427878574;11152.721181970515;11165.38590377529;11179.385133817637;5705.144228365737;5974.880502389928;5980.1328807323125;5984.3417224105315;5988.790392336247;3348.514465402503;3397.133730898605;3532.487789270567;3586.4261718124976;3595.7842529448317;1154.8152295632742;1159.3507635724022;1202.140469564257;1218.7318789866215;1225.3811825028392;12662.81005784914;15660.138692371915;15716.764248536281;15730.056196628124;15744.448944286894;4741.193997020446;4852.660313778072;4894.290327902434;4963.10664080469;4972.925406118911;13033.218006919426;13036.869631303754;13066.216027038408;13066.54267100647;13067.803982940264;5990.311114221833;5991.221141251696;6006.039033754579;6014.699265036731;6017.789377371771;5140.280360749498;5156.793728250903;5181.689098654093;5217.452167522431;5293.100172970604;3911.9804400977814;3974.7813870236837;4004.1190937993597;4047.210793895221;4051.817668204938;1141.8686851012073;1142.1890457775073;1150.0626989003936;1162.6686394377562;1162.8720840707515;651.7099890548756;742.8708363287773;797.8803179523118;811.3306522119275;826.9669213231404;6263.968680156661;6458.515611329169;6630.1049358336;6687.598744075371;6716.429103526158;1037.818381783185;1040.6149724601946;1053.4100903494068;1057.5822550092823;1098.9670309890716;4057.4522439470884;4118.8352465901025;4267.295896177158;4293.763842988666;4311.202427550913;12656.493740250471;13093.476260949456;13106.934465327613;13132.671683207805;13134.277614731282;12754.553590624284;12848.29334386599;12856.228626282453;13023.153495022792;13030.283334416508;9897.950510247547;9899.703008909744;10066.597336106473;10087.092161705481;10107.494625268708;6408.166938297992;6494.545381823227;6676.732930682912;6704.195496540447;6745.200568454693;11977.461014254206;12238.694032649255;12286.877131228573;12326.32205228721;12338.136330456782;9999.800004000024;12429.37853107323;12641.747165056831;12642.367881606067;12863.935680321913;8399.412041157304;8418.87371689413;8578.78553036179;8605.95697021511;8609.569521523774;10153.136983356497;10165.745856353651;10285.588576456857;10299.3820370778;10318.174546036149;8777.55372015471;9021.503817290111;9064.184223419923;9111.589978451118;9171.40385874916;8901.198892099483;8913.688020919237;9025.007249202452;9027.052159523155;9055.592498337459;7820.648070836713;7844.097928738268;8013;8021.197880211932;8025.23760242776;4239.300515414922;5937.287525496968;5953.196318496947;6009.338972713085;6011.7595296187665;5752.597318187687;6008.128821320796;6050.42520960508;6075.149479073022;6163.229596300463 06:55:14 INFO - PID 4486 | |4;prototype.html;378.08692008798755;381.62600651361663;385.6278691063125;387.5620548779878;392.41726036835297;5899.498542623876;5917.763289468416;5963.761449542024;5977.850553736159;6001.069834175699;5522.475364840344;5587.357453892793;5742.971285143569;5766.106253530709;5823.2138661280715;4551.954480455186;4570.771461426932;4578.496365399803;4587.334836448721;4589.426321709786;3628.4375921732176;3771.4908487354132;4572.5884670379655;4578.313253012042;4582.404287442628;3626.601073881871;4491.955080449203;4526.569975852289;4538.047010127877;4564;4440.378347031418;4501.459824821042;4512.977435112836;4517.006258623114;4517.186906356887;3965.2466031453932;3984;3991.560928297885;3995.20095980805;4001.239764444746;5433.7283135843445;5710.057840456319;5763.682997435139;5780.826575202751;5796.449337312949;4711.575958163782;5615.887682246333;5632.802851900205;5645.447501937;5662.292213473316;5532.419095994943;6335.936640633581;6350.33321501245;6353.237611486605;6426.710798014101;4151.667866570667;4828.324034635154;4855.271709243606;4911.91243721417;4943.876403089916;5109.795608175668;6501.739930402731;6580.269188286855;6627.469802415843;6707.155532228628;3880.5731369549326;4278.229773802188;4836.347093142451;4853.185152611477;4862.5380588844;3745.4943582615997;5011.949880501148;5216.843494695089;5219.791208351699;5221.269022336875;5567.884204002453;5601.6358936668985;5602.439756024365;5615.691136987505;5654.3497497788285;5589.496945274864;5717;6058.879107365152;6088.033726947616;6327.525435592349;1480.748272793606;1573.4728865830075;1763.6384541169286;1784.5717027913468;1797.3529529369416;1247.5882958623633;1284.7730417451312;1304.7651422743998;1310.3644732304824;1319.8812106910425;5515.806946756844;5868.530517558585;5871.148683440877;5889.911651325234;5911.7635294587735;5686.545076393879;5981.37195594465;6517.967410162919;6544.640044797487;6555.803325900135;5587.580931430159;5792.594518383757;5843.853903652358;5867.735951882176;5876.706164691748;3240.659730728287;3414.7438942079443;3454.29187016661;3455.585329760445;3471.9305613888087;5096.362954630671;5246.3704355477585;5968.522518198533;6231.8159549685415;6725.596464212163;6168.537359698041;6345.777897773556;6811.693473793691;6815.42068924147;6832.248452670202;6542.672866356758;6746.898796517958;6833.5216534843075;6880.935719284194;6929.965350173217;6572.4084832365315;6574.605523668404;6598.67006649656;6653.567518111307;6690.665466726741;5658.603897727119;5727.079189613411;6263.4362907338555;6489.837754056186;6509.414152726277;6792.694328755317;6808.217055038734;6817.693203805741;6825.385715285648;6862.348076932683;5064.873378165576;5136.486351364833;5158.704376552399;5186.2479940409185;5199.168133098686;5685.573581981451;6370.203724534434;6499.252585952676;6568.573042752206;6606.339366063356;5008.874778130576;5804.564657650609;6337.493000559852;6350.047492876106;6380.298167201697;5514.6691198528215;5617.269754931996;5830.416958304136;6332.427463831798;6354.777582784581;5937.881242375042;6217.974034284292;6241.815364622345;6315.27374351937;6337;6393.328700486568;6397.840053998501;6405.967970160305;6422.133012043314;6422.518311126591;5528.921860237215;5727.255456790757;5860.355360910211;6217.264403662374;6220.222472190976 06:55:14 INFO - PID 4486 | |5;yui.html;766.7623036858574;771.2326235395778;827.2265431821246;831.1646794971053;838.1743982177555;15465.226738663065;16281.267342969566;16311.184440777974;16316.510504684878;16322.836771632295;14750.778738318906;14824.036437631574;14948.925255373735;15007.249637518134;15097.698046039073;893.6827426263663;919.6229545886188;922.0410772796283;923.2706162131908;938.061938061938;942.6842007927353;945.3713280668347;960.788626502172;963.3882484622245;964.0889359555223;1028.8302430099025;1029.613894789454;1030.752619371353;1030.8247597908373;1031.2059714020238;1015.3197357770312;1016.4206402350663;1016.9626980479943;1018.7707765752721;1021.9795604087914;755.9017327747413;756.4931495897762;757.2730179028084;757.5038349880837;758.5145506875604;16279.83720162795;16414.917925410417;17000.404985825437;17070.146492675318;17199.22603482846;14550.781738273934;14550.927245363811;14607.123572585679;14668.046576972463;14720.705585888329;11619.941900290529;12032.51869925202;12294.139410241287;12312.876871231263;12332.691682707915;3640.7269454791;3642.4354225095153;3840.135969406889;3931.2747568424707;3951.940720889189;88002.5599872003;102310.48844755803;103133.96866031394;103798.44302335396;105603.47198264036;10436.63471778484;11291.604793832177;11303.208775385809;11580.36308003068;11826.349550774617;24129.155479558132;24281.39296517601;24326.513469730507;24506.387340316636;24603.384915377257;7762.961185194038;7815.726449574237;7918.3665306776475;7982.4412291140225;8053.275205231557;4149.03385499841;4300;4446.5553444655925;4486.327050942319;4489.775511224426;3474.2877710069374;3572.982135089308;3574.5889222739197;3578.9642103579154;3581.391163502211;619.5972617798467;651.205529254309;661.0447902780436;673.5352606701458;674.814426032845;658.1145019519043;680.614948579638;683.2689022745714;704.5103652961143;724.4132252875188;8779.780505487413;9102.90897091034;9428.10433008863;10264.384136951807;10581;638.3584497579996;686.4268335939447;693.7641201991347;701.0990876723386;702.7926761605339;4144.295469770146;4759;4917.852464426073;4960.255961605716;4974;93895;97802;98837.50581247048;99417;99601;85187.14812851792;86372.27255454981;88693;88903;90429.64355534672;76692.24808139412;77727;79443.60278198456;79975.00062498367;80363.19636803557;9081.636734530544;9264.675736349196;9611.39689755565;9647.662331818352;9773.12041916231;44296.77851610721;44590.554094459934;50955.49044509508;52567;52626.47373526216;58676.4132358671;62976;63446.36553634589;66400.33599664134;66487.66756166382;13388.263645499592;13535.458581657018;13542.796858047333;14112.435502580192;14298.642533936734;14557.85442145565;14681.559553213421;14777.335019923914;14935.850641493445;14958.326875290855;13637.90152449929;13677.24775137377;13776.380062896993;13902.443902243796;14820.40718371253;12353.876461235273;13901.443942242602;13948.511802086878;14041.719165616427;14401;10748.785024299314;13078.738425231251;13619.322782848378;13634.35499967747;13774.173549587056;10904.78190436171;10998.780024399628;11187.457273593878;11192.160587955774;11817.586384476503;8753.145163299421;10990.560377584807;11261.71845703864;11289.7742045157;11476.4261786912 06:55:14 INFO - PID 4486 | __end_tp_report 06:55:14 INFO - PID 4486 | __start_cc_report 06:55:14 INFO - PID 4486 | _x_x_mozilla_cycle_collect,2320 06:55:14 INFO - PID 4486 | __end_cc_report 06:55:14 INFO - PID 4486 | __startTimestamp1490018114021__endTimestamp 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | ------- Summary: start ------- 06:55:14 INFO - PID 4486 | Number of tests: 6 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#0] dojo.html Cycles:165 Average:5848.17 Median:6122.27 stddev:650.32 (10.6%) stddev-sans-first:622.56 06:55:14 INFO - PID 4486 | Values: 3369.1 3374.6 3376.4 3405.1 3445.0 5716.6 5788.0 5889.3 5938.7 5956.0 5838.6 5874.6 5891.1 5920.1 5937.7 4893.6 4937.6 5304.9 5339.4 5339.5 5452.3 5494.0 5494.4 5517.0 5551.1 4380.3 5222.9 5262.9 5274.3 5418.7 5336.1 5346.7 5346.8 5392.3 5418.4 5254.0 5270.1 5303.3 5313.1 5378.5 5691.9 5743.4 5850.0 5880.1 5900.6 6269.0 6276.1 6315.7 6317.1 6320.4 6265.7 6292.9 6303.5 6324.7 6330.5 5992.7 5993.9 6037.5 6073.4 6086.4 6257.8 6291.6 6321.8 6366.9 6446.2 6289.6 6318.3 6323.0 6335.2 6338.0 6187.0 6291.5 6305.0 6313.1 6330.1 6178.4 6228.3 6296.7 6319.5 6322.6 6173.2 6233.3 6289.4 6289.7 6305.1 5098.2 5098.6 5100.3 5113.0 5131.7 4477.3 4521.8 4523.0 4529.8 4533.3 5789.4 5864.9 5865.4 5870.9 5969.8 6286.9 6294.1 6303.1 6303.2 6306.2 5873.3 5873.7 5878.5 5890.3 5891.1 5474.5 5519.3 5559.9 5574.2 5576.7 6381.3 6390.9 6393.6 6396.3 6400.2 6282.1 6283.2 6289.4 6300.1 6312.6 5868.6 6108.7 6505.4 6506.0 6535.7 6296.6 6307.1 6325.7 6332.4 6338.3 6125.4 6308.3 6312.4 6314.0 6322.8 6160.3 6180.2 6180.8 6189.3 6195.5 6122.3 6140.7 6148.4 6217.8 6278.4 6183.0 6200.1 6201.2 6206.0 6229.3 6261.6 6276.0 6278.7 6294.6 6298.6 4988.5 5983.9 6015.2 6052.8 6056.1 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#1] ext.html Cycles:180 Average:46720.42 Median:17710.70 stddev:85441.61 (482.4%) stddev-sans-first:85611.47 06:55:14 INFO - PID 4486 | Values: 712.1 774.6 847.9 852.9 855.5 26865.6 35789.0 36264.0 36414.8 36584.0 37844.6 38043.6 38162.0 38167.8 38190.8 8872.5 9108.0 9112.4 9120.5 9122.0 7154.4 7335.3 10650.3 10839.6 10856.3 10800.5 10905.1 10915.5 10919.4 10924.1 10778.4 10850.3 10855.8 10960.4 10981.9 7060.5 7247.1 7250.4 7256.8 7258.0 35230.8 35808.0 35825.8 35879.9 37011.0 3241.5 3749.6 3792.4 3800.6 3839.2 27266.7 27399.0 27438.2 28120.3 28323.6 3109.0 3116.6 3121.2 3143.2 3177.2 309022.0 314190.0 314864.0 315712.8 316531.0 37417.0 44551.3 50157.0 50269.7 50270.0 39901.0 40477.6 47286.5 47344.3 47470.0 13664.3 13813.2 13841.5 13849.9 13921.1 11232.4 11253.9 11280.1 11285.4 11286.7 12818.4 12850.8 12855.3 12878.0 12996.3 4828.4 4976.1 5353.3 5353.8 5364.5 978.2 1107.6 1110.5 1124.7 1179.2 21929.7 26047.7 26075.8 26240.9 26393.9 1216.3 1226.0 1229.4 1269.3 1279.2 12919.4 13376.1 13659.2 13705.8 15261.2 298869.0 298945.0 299355.0 299691.5 299857.0 50170.5 50190.2 50314.0 50370.0 50372.2 1326.5 1396.7 1403.2 1406.3 1417.0 23629.6 27201.1 27835.4 28390.9 28473.6 39180.0 39238.0 39281.6 43695.9 46494.8 356198.0 358510.0 359337.0 359405.0 359998.0 32244.2 32270.7 32281.8 32452.7 32459.5 40344.0 40574.0 40575.8 41823.8 41898.0 25087.0 27225.7 27666.0 33413.8 34223.5 33222.0 33453.7 33522.3 34328.3 34627.0 33691.5 34394.8 34428.0 34537.0 34542.5 25815.4 27241.3 29704.0 29729.7 30344.8 22753.7 22897.9 22922.7 23273.0 23356.5 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#2] jquery.html Cycles:180 Average:39671.20 Median:21628.85 stddev:106495.87 (492.4%) stddev-sans-first:106753.65 06:55:14 INFO - PID 4486 | Values: 329.9 383.4 391.4 391.7 392.3 13308.5 13758.9 14984.0 21558.7 21583.2 21090.8 22360.0 22893.7 23485.9 23736.6 6522.3 6813.1 6827.1 7466.3 7849.9 6886.4 7939.6 8003.8 8024.6 8027.5 7926.9 7993.6 8001.9 8010.6 8016.8 7813.7 7832.5 7873.9 7929.5 7958.8 7481.0 7713.3 7792.1 7805.8 7811.4 27019.5 27198.0 27374.6 28058.9 28430.7 2793.7 2859.7 2870.4 2896.4 2914.2 16183.8 16848.8 17058.0 17519.6 17522.8 2040.5 2107.3 2107.8 2110.1 2110.6 134563.3 147109.0 154897.6 156966.0 156987.0 17346.8 21406.1 21762.8 21828.1 22095.4 30159.7 37131.6 37317.4 37353.3 37436.1 10066.8 10344.4 10528.2 10542.9 10710.2 6900.3 8381.9 8410.7 8586.9 8607.8 10539.6 10636.4 10641.7 10790.2 10827.4 4114.4 4214.4 4252.9 4265.7 4290.7 951.4 987.2 1003.7 1011.5 1034.5 22385.3 22976.0 22988.0 23105.7 23132.3 956.5 1005.3 1007.9 1035.3 1043.5 11047.6 11808.6 11931.5 11983.1 12469.7 612378.0 623984.0 645428.0 646797.0 651236.0 106595.0 136311.0 140090.0 140365.0 143059.4 1256.1 1278.1 1325.5 1326.8 1330.9 19064.3 20468.8 20522.9 21674.5 22055.2 50028.0 60266.4 61153.1 61895.0 63516.0 76125.6 87284.0 89141.6 89389.0 90685.5 11792.7 12392.9 12640.5 12809.0 12860.5 14112.7 14187.3 14207.7 14524.3 14632.6 9617.6 11110.3 11414.7 11450.8 11451.6 9902.8 11139.9 11245.3 11679.8 12060.9 11143.2 11926.5 12198.8 12337.8 12456.6 12427.5 12430.3 12510.4 12833.4 12875.2 11631.3 11898.1 11949.1 12020.5 12037.0 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#3] mootools.html Cycles:180 Average:6654.67 Median:3372.82 stddev:4159.55 (123.3%) stddev-sans-first:4152.21 06:55:14 INFO - PID 4486 | Values: 1363.1 1490.0 1580.4 1603.6 1608.0 9210.4 9649.2 9652.5 9663.5 9664.4 9473.3 9569.9 10618.5 10796.9 10955.3 2009.6 2312.7 2349.8 2350.9 2353.9 1727.7 2027.0 2237.6 2289.1 2319.8 2012.7 2039.8 2241.2 2330.4 2331.7 2307.8 2312.3 2312.6 2317.9 2319.5 2239.6 2316.4 2319.6 2321.2 2322.7 11025.7 11151.4 11152.7 11165.4 11179.4 5705.1 5974.9 5980.1 5984.3 5988.8 3348.5 3397.1 3532.5 3586.4 3595.8 1154.8 1159.4 1202.1 1218.7 1225.4 12662.8 15660.1 15716.8 15730.1 15744.4 4741.2 4852.7 4894.3 4963.1 4972.9 13033.2 13036.9 13066.2 13066.5 13067.8 5990.3 5991.2 6006.0 6014.7 6017.8 5140.3 5156.8 5181.7 5217.5 5293.1 3912.0 3974.8 4004.1 4047.2 4051.8 1141.9 1142.2 1150.1 1162.7 1162.9 651.7 742.9 797.9 811.3 827.0 6264.0 6458.5 6630.1 6687.6 6716.4 1037.8 1040.6 1053.4 1057.6 1099.0 4057.5 4118.8 4267.3 4293.8 4311.2 12656.5 13093.5 13106.9 13132.7 13134.3 12754.6 12848.3 12856.2 13023.2 13030.3 9898.0 9899.7 10066.6 10087.1 10107.5 6408.2 6494.5 6676.7 6704.2 6745.2 11977.5 12238.7 12286.9 12326.3 12338.1 9999.8 12429.4 12641.7 12642.4 12863.9 8399.4 8418.9 8578.8 8606.0 8609.6 10153.1 10165.7 10285.6 10299.4 10318.2 8777.6 9021.5 9064.2 9111.6 9171.4 8901.2 8913.7 9025.0 9027.1 9055.6 7820.6 7844.1 8013.0 8021.2 8025.2 4239.3 5937.3 5953.2 6009.3 6011.8 5752.6 6008.1 6050.4 6075.1 6163.2 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#4] prototype.html Cycles:180 Average:5222.89 Median:5686.06 stddev:1527.89 (26.9%) stddev-sans-first:1488.27 06:55:14 INFO - PID 4486 | Values: 378.1 381.6 385.6 387.6 392.4 5899.5 5917.8 5963.8 5977.9 6001.1 5522.5 5587.4 5743.0 5766.1 5823.2 4552.0 4570.8 4578.5 4587.3 4589.4 3628.4 3771.5 4572.6 4578.3 4582.4 3626.6 4492.0 4526.6 4538.0 4564.0 4440.4 4501.5 4513.0 4517.0 4517.2 3965.2 3984.0 3991.6 3995.2 4001.2 5433.7 5710.1 5763.7 5780.8 5796.4 4711.6 5615.9 5632.8 5645.4 5662.3 5532.4 6335.9 6350.3 6353.2 6426.7 4151.7 4828.3 4855.3 4911.9 4943.9 5109.8 6501.7 6580.3 6627.5 6707.2 3880.6 4278.2 4836.3 4853.2 4862.5 3745.5 5011.9 5216.8 5219.8 5221.3 5567.9 5601.6 5602.4 5615.7 5654.3 5589.5 5717.0 6058.9 6088.0 6327.5 1480.7 1573.5 1763.6 1784.6 1797.4 1247.6 1284.8 1304.8 1310.4 1319.9 5515.8 5868.5 5871.1 5889.9 5911.8 5686.5 5981.4 6518.0 6544.6 6555.8 5587.6 5792.6 5843.9 5867.7 5876.7 3240.7 3414.7 3454.3 3455.6 3471.9 5096.4 5246.4 5968.5 6231.8 6725.6 6168.5 6345.8 6811.7 6815.4 6832.2 6542.7 6746.9 6833.5 6880.9 6930.0 6572.4 6574.6 6598.7 6653.6 6690.7 5658.6 5727.1 6263.4 6489.8 6509.4 6792.7 6808.2 6817.7 6825.4 6862.3 5064.9 5136.5 5158.7 5186.2 5199.2 5685.6 6370.2 6499.3 6568.6 6606.3 5008.9 5804.6 6337.5 6350.0 6380.3 5514.7 5617.3 5830.4 6332.4 6354.8 5937.9 6218.0 6241.8 6315.3 6337.0 6393.3 6397.8 6406.0 6422.1 6422.5 5528.9 5727.3 5860.4 6217.3 6220.2 06:55:14 INFO - PID 4486 | 06:55:14 INFO - PID 4486 | [#5] yui.html Cycles:180 Average:20286.24 Median:3641.58 stddev:28325.55 (777.8%) stddev-sans-first:28367.09 06:55:14 INFO - PID 4486 | Values: 766.8 771.2 827.2 831.2 838.2 15465.2 16281.3 16311.2 16316.5 16322.8 14750.8 14824.0 14948.9 15007.2 15097.7 893.7 919.6 922.0 923.3 938.1 942.7 945.4 960.8 963.4 964.1 1028.8 1029.6 1030.8 1030.8 1031.2 1015.3 1016.4 1017.0 1018.8 1022.0 755.9 756.5 757.3 757.5 758.5 16279.8 16414.9 17000.4 17070.1 17199.2 14550.8 14550.9 14607.1 14668.0 14720.7 11619.9 12032.5 12294.1 12312.9 12332.7 3640.7 3642.4 3840.1 3931.3 3951.9 88002.6 102310.5 103134.0 103798.4 105603.5 10436.6 11291.6 11303.2 11580.4 11826.3 24129.2 24281.4 24326.5 24506.4 24603.4 7763.0 7815.7 7918.4 7982.4 8053.3 4149.0 4300.0 4446.6 4486.3 4489.8 3474.3 3573.0 3574.6 3579.0 3581.4 619.6 651.2 661.0 673.5 674.8 658.1 680.6 683.3 704.5 724.4 8779.8 9102.9 9428.1 10264.4 10581.0 638.4 686.4 693.8 701.1 702.8 4144.3 4759.0 4917.9 4960.3 4974.0 93895.0 97802.0 98837.5 99417.0 99601.0 85187.1 86372.3 88693.0 88903.0 90429.6 76692.2 77727.0 79443.6 79975.0 80363.2 9081.6 9264.7 9611.4 9647.7 9773.1 44296.8 44590.6 50955.5 52567.0 52626.5 58676.4 62976.0 63446.4 66400.3 66487.7 13388.3 13535.5 13542.8 14112.4 14298.6 14557.9 14681.6 14777.3 14935.9 14958.3 13637.9 13677.2 13776.4 13902.4 14820.4 12353.9 13901.4 13948.5 14041.7 14401.0 10748.8 13078.7 13619.3 13634.4 13774.2 10904.8 10998.8 11187.5 11192.2 11817.6 8753.1 10990.6 11261.7 11289.8 11476.4 06:55:14 INFO - PID 4486 | -------- Summary: end -------- 06:55:14 INFO - PID 4486 | 06:55:14 INFO - TEST-INFO | 4486: exit 0 06:55:14 INFO - TEST-OK | dromaeo_css | took 1121115ms 06:55:14 INFO - TEST-START | kraken 06:55:14 INFO - operating with platform_type : mac_ 06:55:14 INFO - Initialising browser for kraken test... 06:55:14 INFO - TEST-INFO | started process 4489 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpDmOEum/profile http://localhost:54915/getInfo.html) 06:55:17 INFO - PID 4489 | __metrics Screen width/height:1600/1200 06:55:17 INFO - PID 4489 | colorDepth:24 06:55:17 INFO - PID 4489 | Browser inner width/height: 1024/689 06:55:17 INFO - PID 4489 | __metrics 06:55:17 INFO - PID 4489 | 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" 06:55:17 INFO - PID 4489 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:55:18 INFO - TEST-INFO | 4489: exit 0 06:55:18 INFO - Browser initialized. 06:55:18 INFO - Running cycle 1/1 for kraken test... 06:55:18 INFO - TEST-INFO | started process 4490 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpDmOEum/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:55:30 INFO - PID 4490 | RSS: Main: 233410560 06:55:30 INFO - PID 4490 | 06:55:32 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54915/tests/kraken/driver.html?testName=audio-beat-detection) 06:55:33 INFO - PID 4490 | RSS: Main: 284168192 06:55:33 INFO - PID 4490 | 06:55:36 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54915/tests/kraken/driver.html?testName=audio-dft) 06:55:37 INFO - PID 4490 | RSS: Main: 285274112 06:55:37 INFO - PID 4490 | 06:55:40 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54915/tests/kraken/driver.html?testName=audio-fft) 06:55:41 INFO - PID 4490 | RSS: Main: 377266176 06:55:41 INFO - PID 4490 | 06:55:43 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54915/tests/kraken/driver.html?testName=audio-oscillator) 06:55:43 INFO - PID 4490 | RSS: Main: 280453120 06:55:43 INFO - PID 4490 | 06:55:45 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:55:46 INFO - PID 4490 | RSS: Main: 283136000 06:55:46 INFO - PID 4490 | 06:55:49 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54915/tests/kraken/driver.html?testName=imaging-darkroom) 06:55:50 INFO - PID 4490 | RSS: Main: 325312512 06:55:50 INFO - PID 4490 | 06:55:53 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54915/tests/kraken/driver.html?testName=imaging-desaturate) 06:55:53 INFO - PID 4490 | RSS: Main: 335085568 06:55:53 INFO - PID 4490 | 06:55:56 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54915/tests/kraken/driver.html?testName=json-parse-financial) 06:55:57 INFO - PID 4490 | RSS: Main: 340279296 06:55:57 INFO - PID 4490 | 06:55:58 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:55:59 INFO - PID 4490 | RSS: Main: 300056576 06:55:59 INFO - PID 4490 | 06:56:01 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:56:01 INFO - PID 4490 | RSS: Main: 304291840 06:56:01 INFO - PID 4490 | 06:56:04 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:56:04 INFO - PID 4490 | RSS: Main: 306905088 06:56:04 INFO - PID 4490 | 06:56:07 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:56:08 INFO - PID 4490 | RSS: Main: 323530752 06:56:08 INFO - PID 4490 | 06:56:11 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:56:12 INFO - PID 4490 | RSS: Main: 317616128 06:56:12 INFO - PID 4490 | 06:56:13 INFO - PID 4490 | Cycle 1(1): loaded http://localhost:54915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54915/tests/kraken/driver.html?testName=ai-astar) 06:56:14 INFO - PID 4490 | RSS: Main: 379465728 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | __start_tp_report 06:56:14 INFO - PID 4490 | _x_x_mozilla_page_load 06:56:14 INFO - PID 4490 | _x_x_mozilla_page_load_details 06:56:14 INFO - PID 4490 | |i|pagename|runs| 06:56:14 INFO - PID 4490 | |0;ai-astar;118;118;118;124;116;113;119;118;119;119 06:56:14 INFO - PID 4490 | |1;audio-beat-detection;149;112;111;154;117;112;109;110;135;112 06:56:14 INFO - PID 4490 | |2;audio-dft;185;188;256;218;185;186;183;185;185;185 06:56:14 INFO - PID 4490 | |3;audio-fft;75;61;59;94;66;60;64;57;77;58 06:56:14 INFO - PID 4490 | |4;audio-oscillator;90;71;88;70;70;70;84;68;71;106 06:56:14 INFO - PID 4490 | |5;imaging-gaussian-blur;90;89;89;88;89;90;89;89;90;89 06:56:14 INFO - PID 4490 | |6;imaging-darkroom;95;96;95;95;95;96;97;97;97;97 06:56:14 INFO - PID 4490 | |7;imaging-desaturate;86;86;86;84;84;85;86;86;83;85 06:56:14 INFO - PID 4490 | |8;json-parse-financial;64;61;61;61;61;61;60;64;63;63 06:56:14 INFO - PID 4490 | |9;json-stringify-tinderbox;60;55;58;64;61;58;59;59;58;59 06:56:14 INFO - PID 4490 | |10;stanford-crypto-aes;85;82;83;81;82;83;82;85;97;103 06:56:14 INFO - PID 4490 | |11;stanford-crypto-ccm;127;127;146;141;147;143;140;138;182;144 06:56:14 INFO - PID 4490 | |12;stanford-crypto-pbkdf2;188;215;189;182;185;180;190;185;186;181 06:56:14 INFO - PID 4490 | |13;stanford-crypto-sha256-iterative;70;68;68;68;68;68;69;68;68;69 06:56:14 INFO - PID 4490 | __end_tp_report 06:56:14 INFO - PID 4490 | __start_cc_report 06:56:14 INFO - PID 4490 | _x_x_mozilla_cycle_collect,2163 06:56:14 INFO - PID 4490 | __end_cc_report 06:56:14 INFO - PID 4490 | __startTimestamp1490018174154__endTimestamp 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | ------- Summary: start ------- 06:56:14 INFO - PID 4490 | Number of tests: 14 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#0] ai-astar Cycles:10 Average:118.20 Median:118.50 stddev:2.74 (2.3%) stddev-sans-first:2.91 06:56:14 INFO - PID 4490 | Values: 118.0 118.0 118.0 124.0 116.0 113.0 119.0 118.0 119.0 119.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:114.50 stddev:17.26 (15.1%) stddev-sans-first:15.32 06:56:14 INFO - PID 4490 | Values: 149.0 112.0 111.0 154.0 117.0 112.0 109.0 110.0 135.0 112.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#2] audio-dft Cycles:10 Average:195.60 Median:185.50 stddev:23.62 (12.7%) stddev-sans-first:24.74 06:56:14 INFO - PID 4490 | Values: 185.0 188.0 256.0 218.0 185.0 186.0 183.0 185.0 185.0 185.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#3] audio-fft Cycles:10 Average:67.10 Median:65.00 stddev:11.70 (18.0%) stddev-sans-first:12.06 06:56:14 INFO - PID 4490 | Values: 75.0 61.0 59.0 94.0 66.0 60.0 64.0 57.0 77.0 58.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:71.00 stddev:12.68 (17.9%) stddev-sans-first:12.79 06:56:14 INFO - PID 4490 | Values: 90.0 71.0 88.0 70.0 70.0 70.0 84.0 68.0 71.0 106.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 06:56:14 INFO - PID 4490 | Values: 90.0 89.0 89.0 88.0 89.0 90.0 89.0 89.0 90.0 89.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.50 stddev:0.94 (1.0%) stddev-sans-first:0.93 06:56:14 INFO - PID 4490 | Values: 95.0 96.0 95.0 95.0 95.0 96.0 97.0 97.0 97.0 97.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.10 (1.3%) stddev-sans-first:1.12 06:56:14 INFO - PID 4490 | Values: 86.0 86.0 86.0 84.0 84.0 85.0 86.0 86.0 83.0 85.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:1.45 (2.3%) stddev-sans-first:1.32 06:56:14 INFO - PID 4490 | Values: 64.0 61.0 61.0 61.0 61.0 61.0 60.0 64.0 63.0 63.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:2.33 (4.0%) stddev-sans-first:2.45 06:56:14 INFO - PID 4490 | Values: 60.0 55.0 58.0 64.0 61.0 58.0 59.0 59.0 58.0 59.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:83.00 stddev:7.47 (9.0%) stddev-sans-first:7.91 06:56:14 INFO - PID 4490 | Values: 85.0 82.0 83.0 81.0 82.0 83.0 82.0 85.0 97.0 103.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:143.50 stddev:15.26 (10.6%) stddev-sans-first:14.97 06:56:14 INFO - PID 4490 | Values: 127.0 127.0 146.0 141.0 147.0 143.0 140.0 138.0 182.0 144.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.10 Median:187.00 stddev:10.03 (5.4%) stddev-sans-first:10.64 06:56:14 INFO - PID 4490 | Values: 188.0 215.0 189.0 182.0 185.0 180.0 190.0 185.0 186.0 181.0 06:56:14 INFO - PID 4490 | 06:56:14 INFO - PID 4490 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.44 06:56:14 INFO - PID 4490 | Values: 70.0 68.0 68.0 68.0 68.0 68.0 69.0 68.0 68.0 69.0 06:56:14 INFO - PID 4490 | -------- Summary: end -------- 06:56:14 INFO - PID 4490 | 06:56:15 INFO - TEST-INFO | 4490: exit 0 06:56:15 INFO - TEST-OK | kraken | took 60402ms 06:56:15 INFO - SUITE-END | took 1181s 06:56:15 INFO - Completed test suite (00:19:41) 06:56:15 INFO - javascript benchmark 06:56:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7816.032548358275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.0903456066876, 3374.6287908330078, 3376.4260075787097, 3405.114670185752, 3445.0, 5716.628419152752, 5787.971060144704, 5889.293284805829, 5938.703064846756, 5956.047032474805, 5838.591298609099, 5874.618149820259, 5891.116332550119, 5920.052791553352, 5937.703114844262, 4893.559579637832, 4937.5556199942, 5304.946950530503, 5339.41266460689, 5339.519443250107, 5452.263944367519, 5494.0, 5494.423085576017, 5516.972415137919, 5551.1118221084635, 4380.34294855771, 5222.947770522284, 5262.894742105136, 5274.314339135888, 5418.729063546826, 5336.146216605363, 5346.705931173784, 5346.786128554853, 5392.325959255093, 5418.376886658046, 5253.9737301313635, 5270.051390749663, 5303.284056652341, 5313.0967735485065, 5378.542823859977, 5691.9146212806845, 5743.368229494752, 5850.0, 5880.117982302646, 5900.616459930134, 6269.0, 6276.121343011936, 6315.652639104847, 6317.14718513004, 6320.399562041599, 6265.686715664198, 6292.87414251717, 6303.527235457359, 6324.7470101195895, 6330.461910737593, 5992.700364981733, 5993.940060599425, 6037.45662890342, 6073.39266073398, 6086.3609321021095, 6257.843553911189, 6291.559590828598, 6321.778737744157, 6366.936330636727, 6446.161998940108, 6289.559730818799, 6318.304986451487, 6322.968385158045, 6335.176427064452, 6338.0, 6187.0, 6291.5281353898645, 6305.0, 6313.147725057151, 6330.113784070234, 6178.351273116348, 6228.34602366754, 6296.653684047421, 6319.526035547352, 6322.620642761449, 6173.166622505994, 6233.283172435137, 6289.433950944345, 6289.654069026156, 6305.14880491137, 5098.1842905135, 5098.59211263098, 5100.336956195745, 5113.0, 5131.7177555234075, 4477.261251893401, 4521.796519156645, 4523.0, 4529.818807247673, 4533.32000199966, 5789.421057894177, 5864.9443100241515, 5865.4134586541, 5870.853228669232, 5969.79105731306, 6286.905696414466, 6294.118823364733, 6303.117563541108, 6303.180586523723, 6306.211723534559, 5873.295204575393, 5873.735681894325, 5878.5297176225795, 5890.322612899486, 5891.057430811135, 5474.507294343449, 5519.31008623922, 5559.8888022239325, 5574.16387541872, 5576.749046292926, 6381.29805721361, 6390.8721825563225, 6393.648349340738, 6396.264429590564, 6400.199975003125, 6282.057691346335, 6283.214598175228, 6289.433950944437, 6300.149479820166, 6312.621242725451, 5868.589198756046, 6108.6945652716295, 6505.381988711065, 6505.967470162619, 6535.673216339259, 6296.5592408530965, 6307.148534947723, 6325.7153428094925, 6332.398422149889, 6338.334474880071, 6125.356837531995, 6308.27454842699, 6312.368763123651, 6313.968430158004, 6322.841928951629, 6160.3223645398075, 6180.196574445294, 6180.81457556274, 6189.319174890848, 6195.535334849814, 6122.265328160649, 6140.718981675666, 6148.385161483816, 6217.751289948352, 6278.4035516625845, 6182.969085154546, 6200.100985357189, 6201.193844800147, 6205.968970155121, 6229.283632382334, 6261.592996455216, 6275.995840665471, 6278.7488500461295, 6294.559380843297, 6298.590591611531, 4988.5260900214425, 5983.850403739942, 6015.157877897185, 6052.757889684363, 6056.0915862619195], "value": 5806.268090778593, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.1347562711306, 774.5662429039736, 847.8855354527137, 852.9275011624012, 855.4653341661461, 26865.597016044776, 35789.0, 36263.99999999996, 36414.817925910334, 36584.0, 37844.621553784455, 38043.61956380435, 38162.0, 38167.809160954224, 38190.809045954804, 8872.467651940888, 9107.954460227722, 9112.407693499934, 9120.498372589505, 9122.0, 7154.427645788324, 7335.266473352649, 10650.25448218625, 10839.566417343296, 10856.34861908288, 10800.51397687102, 10905.127589792797, 10915.508802103885, 10919.39943303118, 10924.050506363277, 10778.353298802096, 10850.348979061282, 10855.782884342309, 10960.397178155199, 10981.945090274578, 7060.505764596481, 7247.057882475297, 7250.383717383976, 7256.818579535501, 7257.963710181415, 35230.82384588061, 35808.0, 35825.775680188905, 35879.92360229171, 37011.0, 3241.465158248886, 3749.568799588055, 3792.412176112707, 3800.619938006205, 3839.212961342946, 27266.727332726616, 27399.0, 27438.176854694193, 28120.296992575146, 28323.575146372616, 3108.9895783870106, 3116.626004879406, 3121.1884909923356, 3143.1513491357205, 3177.205698575356, 309022.0, 314190.0, 314864.0, 315712.84287156834, 316531.0, 37417.0, 44551.331730024074, 50156.996860062594, 50269.74865125651, 50270.0, 39901.0, 40477.595224047385, 47286.5271347289, 47344.28983565249, 47470.0, 13664.316784160752, 13813.171209727449, 13841.515546955809, 13849.86150138506, 13921.095128816594, 11232.438378081226, 11253.943730281297, 11280.097592192606, 11285.435728213557, 11286.717832054264, 12818.359082045861, 12850.807237891438, 12855.292958887165, 12878.0, 12996.285204313665, 4828.37231159947, 4976.129177393942, 5353.2773075634295, 5353.812616558402, 5364.517193452608, 978.2418625565244, 1107.5791199344199, 1110.5280255891214, 1124.6794663520857, 1179.2099293473393, 21929.70713786395, 26047.739522604912, 26075.838966084666, 26240.8687956559, 26393.868030659723, 1216.2823933879054, 1225.9570915017932, 1229.391451231646, 1269.320913311374, 1279.226068228727, 12919.418626161845, 13376.130551514121, 13659.15100139456, 13705.794413083811, 15261.236938153048, 298869.0, 298945.0, 299355.0, 299691.5015424909, 299857.0, 50170.498295017314, 50190.24714629284, 50314.0, 50370.0, 50372.244416333786, 1326.4694122351136, 1396.6648004479055, 1403.1651167555287, 1406.3460490871794, 1416.9574912752428, 23629.645555317027, 27201.143879892326, 27835.44329113447, 28390.858045710465, 28473.572896406156, 39180.0, 39238.01904952399, 39281.607183927794, 43695.90760230892, 46494.767526162155, 356198.0, 358510.0, 359337.0, 359405.0, 359998.0, 32244.19389515281, 32270.677293226767, 32281.838590806896, 32452.675473244966, 32459.513107303883, 40344.0, 40574.0, 40575.797121014206, 41823.7908810454, 41898.0, 25087.0, 27225.72774272232, 27666.03168889079, 33413.832930836164, 34223.4866477008, 33222.0, 33453.66546334505, 33522.32955340928, 34328.313433730684, 34627.0, 33691.49462758011, 34394.82802585971, 34428.0, 34537.0, 34542.48186277258, 25815.354616134748, 27241.31896702598, 29703.960361387246, 29729.702702972692, 30344.84827575848, 22753.658695119917, 22897.882988442125, 22922.656160157276, 23273.0, 23356.53286934218], "value": 17625.046224729536, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.8977317031722, 383.4133775323756, 391.43437732476565, 391.6983922379767, 392.30757712637194, 13308.534201302957, 13758.931205343972, 14984.026038307502, 21558.676619850714, 21583.24458643948, 21090.789092109073, 22359.993800278986, 22893.656595151086, 23485.882570587124, 23736.643950340716, 6522.347765223475, 6813.114295141625, 6827.078344423514, 7466.328030477256, 7849.882251766228, 6886.380225779679, 7939.603019849013, 8003.839923201533, 8024.638891249879, 8027.478213916106, 7926.8810967835525, 7993.600319984007, 8001.879971800398, 8010.5594192319395, 8016.839663206732, 7813.726519571787, 7832.451728378987, 7873.881891771628, 7929.524228546276, 7958.761237162837, 7480.962595187044, 7713.34436572892, 7792.142864284924, 7805.765827025199, 7811.414143939171, 27019.45961080787, 27198.0, 27374.589381159298, 28058.859705701547, 28430.715692843012, 2793.7485626293733, 2859.656841179051, 2870.354170311663, 2896.435195136949, 2914.1694617034045, 16183.757243641356, 16848.83151168485, 17058.0, 17519.605344049458, 17522.824771752246, 2040.4796776821815, 2107.262458139654, 2107.8102970732552, 2110.1137522240697, 2110.5884352551257, 134563.32718336346, 147109.0, 154897.6377952767, 156966.0, 156987.0, 17346.82653173452, 21406.143754249966, 21762.782372176393, 21828.068486829954, 22095.44761380978, 30159.69840301569, 37131.62868371336, 37317.44023839645, 37353.252934941695, 37436.06409839721, 10066.798664026679, 10344.37933723964, 10528.157747380192, 10542.947285263677, 10710.196735244888, 6900.3444672756, 8381.916180838114, 8410.747677569683, 8586.914130858737, 8607.837941877891, 10539.578416863393, 10636.361818290772, 10641.72299324086, 10790.244682872124, 10827.350358978487, 4114.403411505315, 4214.4310518079665, 4252.893677658083, 4265.7440553566885, 4290.656747460196, 951.4434056077134, 987.195435719886, 1003.7189586915675, 1011.5144730529387, 1034.5448002878709, 22385.328440146823, 22976.0, 22988.0, 23105.653415198787, 23132.3060308191, 956.4882787708542, 1005.3063386263455, 1007.9143272821892, 1035.316690983947, 1043.5304113149114, 11047.613333533287, 11808.645740627622, 11931.522739090513, 11983.064149283482, 12469.68070301016, 612378.0, 623984.0, 645428.0, 646797.0, 651236.0, 106595.0, 136311.0, 140090.0, 140365.0, 143059.38104545424, 1256.0956111599633, 1278.1053262716136, 1325.5095614622458, 1326.8407791065133, 1330.9267990260435, 19064.33274835374, 20468.79531204669, 20522.897385512977, 21674.458138546033, 22055.22806701758, 50028.0, 60266.39733602783, 61153.08270376037, 61895.0, 63516.0, 76125.619371905, 87284.0, 89141.55429222812, 89389.0, 90685.54657226671, 11792.70518237051, 12392.876071239172, 12640.546337171343, 12809.03932205107, 12860.549880754128, 14112.717745645235, 14187.290635467978, 14207.715845682822, 14524.346404411615, 14632.634184145481, 9617.615295388387, 11110.277831940897, 11414.714632134263, 11450.82823757661, 11451.59919402817, 9902.801943960936, 11139.944300278446, 11245.325280483197, 11679.76640467203, 12060.939695301468, 11143.2199746017, 11926.522939082339, 12198.75602487963, 12337.814932775836, 12456.626301210978, 12427.502899883903, 12430.254184748945, 12510.437030333838, 12833.358332083171, 12875.227486350848, 11631.302121872715, 11898.107641926717, 11949.103817213918, 12020.519179233082, 12037.013892707955], "value": 11956.050298610437, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.0799210532887, 1490.0016988617624, 1580.3678528588564, 1603.6391811842336, 1608.035178892664, 9210.355275130743, 9649.22806175506, 9652.469114198717, 9663.468509231998, 9664.420134791917, 9473.289503287246, 9569.904300957005, 10618.469076546162, 10796.946015269932, 10955.34267943924, 2009.577988622391, 2312.7455979842202, 2349.835511514195, 2350.894209760565, 2353.9293821185306, 1727.6631056943902, 2027.0067666843288, 2237.6084185267596, 2289.107248173214, 2319.82601304902, 2012.667909794882, 2039.8470114741378, 2241.2491815241915, 2330.382448651109, 2331.720193576773, 2307.849989750661, 2312.3409828198965, 2312.6415405612156, 2317.945334872629, 2319.5297008685866, 2239.5632851593946, 2316.3514216019544, 2319.6404557293645, 2321.1643808229023, 2322.674825524428, 11025.724356891062, 11151.442427878574, 11152.721181970515, 11165.38590377529, 11179.385133817637, 5705.144228365737, 5974.880502389928, 5980.1328807323125, 5984.3417224105315, 5988.790392336247, 3348.514465402503, 3397.133730898605, 3532.487789270567, 3586.4261718124976, 3595.7842529448317, 1154.8152295632742, 1159.3507635724022, 1202.140469564257, 1218.7318789866215, 1225.3811825028392, 12662.81005784914, 15660.138692371915, 15716.764248536281, 15730.056196628124, 15744.448944286894, 4741.193997020446, 4852.660313778072, 4894.290327902434, 4963.10664080469, 4972.925406118911, 13033.218006919426, 13036.869631303754, 13066.216027038408, 13066.54267100647, 13067.803982940264, 5990.311114221833, 5991.221141251696, 6006.039033754579, 6014.699265036731, 6017.789377371771, 5140.280360749498, 5156.793728250903, 5181.689098654093, 5217.452167522431, 5293.100172970604, 3911.9804400977814, 3974.7813870236837, 4004.1190937993597, 4047.210793895221, 4051.817668204938, 1141.8686851012073, 1142.1890457775073, 1150.0626989003936, 1162.6686394377562, 1162.8720840707515, 651.7099890548756, 742.8708363287773, 797.8803179523118, 811.3306522119275, 826.9669213231404, 6263.968680156661, 6458.515611329169, 6630.1049358336, 6687.598744075371, 6716.429103526158, 1037.818381783185, 1040.6149724601946, 1053.4100903494068, 1057.5822550092823, 1098.9670309890716, 4057.4522439470884, 4118.8352465901025, 4267.295896177158, 4293.763842988666, 4311.202427550913, 12656.493740250471, 13093.476260949456, 13106.934465327613, 13132.671683207805, 13134.277614731282, 12754.553590624284, 12848.29334386599, 12856.228626282453, 13023.153495022792, 13030.283334416508, 9897.950510247547, 9899.703008909744, 10066.597336106473, 10087.092161705481, 10107.494625268708, 6408.166938297992, 6494.545381823227, 6676.732930682912, 6704.195496540447, 6745.200568454693, 11977.461014254206, 12238.694032649255, 12286.877131228573, 12326.32205228721, 12338.136330456782, 9999.800004000024, 12429.37853107323, 12641.747165056831, 12642.367881606067, 12863.935680321913, 8399.412041157304, 8418.87371689413, 8578.78553036179, 8605.95697021511, 8609.569521523774, 10153.136983356497, 10165.745856353651, 10285.588576456857, 10299.3820370778, 10318.174546036149, 8777.55372015471, 9021.503817290111, 9064.184223419923, 9111.589978451118, 9171.40385874916, 8901.198892099483, 8913.688020919237, 9025.007249202452, 9027.052159523155, 9055.592498337459, 7820.648070836713, 7844.097928738268, 8013.0, 8021.197880211932, 8025.23760242776, 4239.300515414922, 5937.287525496968, 5953.196318496947, 6009.338972713085, 6011.7595296187665, 5752.597318187687, 6008.128821320796, 6050.42520960508, 6075.149479073022, 6163.229596300463], "value": 5061.9701035015905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.08692008798755, 381.62600651361663, 385.6278691063125, 387.5620548779878, 392.41726036835297, 5899.498542623876, 5917.763289468416, 5963.761449542024, 5977.850553736159, 6001.069834175699, 5522.475364840344, 5587.357453892793, 5742.971285143569, 5766.106253530709, 5823.2138661280715, 4551.954480455186, 4570.771461426932, 4578.496365399803, 4587.334836448721, 4589.426321709786, 3628.4375921732176, 3771.4908487354132, 4572.5884670379655, 4578.313253012042, 4582.404287442628, 3626.601073881871, 4491.955080449203, 4526.569975852289, 4538.047010127877, 4564.0, 4440.378347031418, 4501.459824821042, 4512.977435112836, 4517.006258623114, 4517.186906356887, 3965.2466031453932, 3984.0, 3991.560928297885, 3995.20095980805, 4001.239764444746, 5433.7283135843445, 5710.057840456319, 5763.682997435139, 5780.826575202751, 5796.449337312949, 4711.575958163782, 5615.887682246333, 5632.802851900205, 5645.447501937, 5662.292213473316, 5532.419095994943, 6335.936640633581, 6350.33321501245, 6353.237611486605, 6426.710798014101, 4151.667866570667, 4828.324034635154, 4855.271709243606, 4911.91243721417, 4943.876403089916, 5109.795608175668, 6501.739930402731, 6580.269188286855, 6627.469802415843, 6707.155532228628, 3880.5731369549326, 4278.229773802188, 4836.347093142451, 4853.185152611477, 4862.5380588844, 3745.4943582615997, 5011.949880501148, 5216.843494695089, 5219.791208351699, 5221.269022336875, 5567.884204002453, 5601.6358936668985, 5602.439756024365, 5615.691136987505, 5654.3497497788285, 5589.496945274864, 5717.0, 6058.879107365152, 6088.033726947616, 6327.525435592349, 1480.748272793606, 1573.4728865830075, 1763.6384541169286, 1784.5717027913468, 1797.3529529369416, 1247.5882958623633, 1284.7730417451312, 1304.7651422743998, 1310.3644732304824, 1319.8812106910425, 5515.806946756844, 5868.530517558585, 5871.148683440877, 5889.911651325234, 5911.7635294587735, 5686.545076393879, 5981.37195594465, 6517.967410162919, 6544.640044797487, 6555.803325900135, 5587.580931430159, 5792.594518383757, 5843.853903652358, 5867.735951882176, 5876.706164691748, 3240.659730728287, 3414.7438942079443, 3454.29187016661, 3455.585329760445, 3471.9305613888087, 5096.362954630671, 5246.3704355477585, 5968.522518198533, 6231.8159549685415, 6725.596464212163, 6168.537359698041, 6345.777897773556, 6811.693473793691, 6815.42068924147, 6832.248452670202, 6542.672866356758, 6746.898796517958, 6833.5216534843075, 6880.935719284194, 6929.965350173217, 6572.4084832365315, 6574.605523668404, 6598.67006649656, 6653.567518111307, 6690.665466726741, 5658.603897727119, 5727.079189613411, 6263.4362907338555, 6489.837754056186, 6509.414152726277, 6792.694328755317, 6808.217055038734, 6817.693203805741, 6825.385715285648, 6862.348076932683, 5064.873378165576, 5136.486351364833, 5158.704376552399, 5186.2479940409185, 5199.168133098686, 5685.573581981451, 6370.203724534434, 6499.252585952676, 6568.573042752206, 6606.339366063356, 5008.874778130576, 5804.564657650609, 6337.493000559852, 6350.047492876106, 6380.298167201697, 5514.6691198528215, 5617.269754931996, 5830.416958304136, 6332.427463831798, 6354.777582784581, 5937.881242375042, 6217.974034284292, 6241.815364622345, 6315.27374351937, 6337.0, 6393.328700486568, 6397.840053998501, 6405.967970160305, 6422.133012043314, 6422.518311126591, 5528.921860237215, 5727.255456790757, 5860.355360910211, 6217.264403662374, 6220.222472190976], "value": 4765.906315979439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.7623036858574, 771.2326235395778, 827.2265431821246, 831.1646794971053, 838.1743982177555, 15465.226738663065, 16281.267342969566, 16311.184440777974, 16316.510504684878, 16322.836771632295, 14750.778738318906, 14824.036437631574, 14948.925255373735, 15007.249637518134, 15097.698046039073, 893.6827426263663, 919.6229545886188, 922.0410772796283, 923.2706162131908, 938.061938061938, 942.6842007927353, 945.3713280668347, 960.788626502172, 963.3882484622245, 964.0889359555223, 1028.8302430099025, 1029.613894789454, 1030.752619371353, 1030.8247597908373, 1031.2059714020238, 1015.3197357770312, 1016.4206402350663, 1016.9626980479943, 1018.7707765752721, 1021.9795604087914, 755.9017327747413, 756.4931495897762, 757.2730179028084, 757.5038349880837, 758.5145506875604, 16279.83720162795, 16414.917925410417, 17000.404985825437, 17070.146492675318, 17199.22603482846, 14550.781738273934, 14550.927245363811, 14607.123572585679, 14668.046576972463, 14720.705585888329, 11619.941900290529, 12032.51869925202, 12294.139410241287, 12312.876871231263, 12332.691682707915, 3640.7269454791, 3642.4354225095153, 3840.135969406889, 3931.2747568424707, 3951.940720889189, 88002.5599872003, 102310.48844755803, 103133.96866031394, 103798.44302335396, 105603.47198264036, 10436.63471778484, 11291.604793832177, 11303.208775385809, 11580.36308003068, 11826.349550774617, 24129.155479558132, 24281.39296517601, 24326.513469730507, 24506.387340316636, 24603.384915377257, 7762.961185194038, 7815.726449574237, 7918.3665306776475, 7982.4412291140225, 8053.275205231557, 4149.03385499841, 4300.0, 4446.5553444655925, 4486.327050942319, 4489.775511224426, 3474.2877710069374, 3572.982135089308, 3574.5889222739197, 3578.9642103579154, 3581.391163502211, 619.5972617798467, 651.205529254309, 661.0447902780436, 673.5352606701458, 674.814426032845, 658.1145019519043, 680.614948579638, 683.2689022745714, 704.5103652961143, 724.4132252875188, 8779.780505487413, 9102.90897091034, 9428.10433008863, 10264.384136951807, 10581.0, 638.3584497579996, 686.4268335939447, 693.7641201991347, 701.0990876723386, 702.7926761605339, 4144.295469770146, 4759.0, 4917.852464426073, 4960.255961605716, 4974.0, 93895.0, 97802.0, 98837.50581247048, 99417.0, 99601.0, 85187.14812851792, 86372.27255454981, 88693.0, 88903.0, 90429.64355534672, 76692.24808139412, 77727.0, 79443.60278198456, 79975.00062498367, 80363.19636803557, 9081.636734530544, 9264.675736349196, 9611.39689755565, 9647.662331818352, 9773.12041916231, 44296.77851610721, 44590.554094459934, 50955.49044509508, 52567.0, 52626.47373526216, 58676.4132358671, 62976.0, 63446.36553634589, 66400.33599664134, 66487.66756166382, 13388.263645499592, 13535.458581657018, 13542.796858047333, 14112.435502580192, 14298.642533936734, 14557.85442145565, 14681.559553213421, 14777.335019923914, 14935.850641493445, 14958.326875290855, 13637.90152449929, 13677.24775137377, 13776.380062896993, 13902.443902243796, 14820.40718371253, 12353.876461235273, 13901.443942242602, 13948.511802086878, 14041.719165616427, 14401.0, 10748.785024299314, 13078.738425231251, 13619.322782848378, 13634.35499967747, 13774.173549587056, 10904.78190436171, 10998.780024399628, 11187.457273593878, 11192.160587955774, 11817.586384476503, 8753.145163299421, 10990.560377584807, 11261.71845703864, 11289.7742045157, 11476.4261786912], "value": 7723.292398807274, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 124.0, 116.0, 113.0, 119.0, 118.0, 119.0, 119.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 111.0, 154.0, 117.0, 112.0, 109.0, 110.0, 135.0, 112.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 256.0, 218.0, 185.0, 186.0, 183.0, 185.0, 185.0, 185.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 61.0, 59.0, 94.0, 66.0, 60.0, 64.0, 57.0, 77.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 88.0, 70.0, 70.0, 70.0, 84.0, 68.0, 71.0, 106.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 88.0, 89.0, 90.0, 89.0, 89.0, 90.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 97.0, 97.0, 97.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 84.0, 85.0, 86.0, 86.0, 83.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 61.0, 61.0, 61.0, 60.0, 64.0, 63.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 58.0, 64.0, 61.0, 58.0, 59.0, 59.0, 58.0, 59.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 81.0, 82.0, 83.0, 82.0, 85.0, 97.0, 103.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 146.0, 141.0, 147.0, 143.0, 140.0, 138.0, 182.0, 144.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 215.0, 189.0, 182.0, 185.0, 180.0, 190.0, 185.0, 186.0, 181.0], "value": 188.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 69.0], "value": 68.4, "unit": "score"}]}]} 06:56:15 INFO - Return code: 0 06:56:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:56:15 ERROR - # TBPL SUCCESS # 06:56:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:56:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:56:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:56:15 INFO - 2017-03-20 06:56:15.478 screenresolution[4494:78556] starting screenresolution argv=screenresolution get 06:56:15 INFO - 2017-03-20 06:56:15.480 screenresolution[4494:78556] Display 0: 1600x1200x32@60 06:56:15 INFO - 2017-03-20 06:56:15.487 screenresolution[4495:78560] starting screenresolution argv=screenresolution list 06:56:15 INFO - Available Modes on Display 0 06:56:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:56:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:56:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:56:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:56:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:56:15 INFO - Intel Iris: 06:56:15 INFO - Chipset Model: Intel Iris 06:56:15 INFO - Type: GPU 06:56:15 INFO - Bus: Built-In 06:56:15 INFO - VRAM (Dynamic, Max): 1536 MB 06:56:15 INFO - Vendor: Intel (0x8086) 06:56:15 INFO - Device ID: 0x0a2e 06:56:15 INFO - Revision ID: 0x0009 06:56:15 INFO - Displays: 06:56:15 INFO - Display: 06:56:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:56:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:56:15 INFO - Display Serial Number: 1600x1200 60 06:56:15 INFO - Main Display: Yes 06:56:15 INFO - Mirror: Off 06:56:15 INFO - Online: Yes 06:56:15 INFO - Rotation: Supported 06:56:15 INFO - Return code: 0 06:56:15 INFO - Running post-action listener: _resource_record_post_action 06:56:15 INFO - [mozharness: 2017-03-20 13:56:15.721299Z] Finished run-tests step (success) 06:56:15 INFO - Running post-run listener: _resource_record_post_run 06:56:15 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 438272; Write bytes: 511992320; Read time: 5; Write time: 1715 06:56:15 INFO - TinderboxPrint: CPU usage
53.4% 06:56:15 INFO - TinderboxPrint: I/O read bytes / time
438,272 / 5 06:56:15 INFO - TinderboxPrint: I/O write bytes / time
511,992,320 / 1,715 06:56:15 INFO - TinderboxPrint: CPU idle
2,239.0 (46.6%) 06:56:15 INFO - TinderboxPrint: CPU system
957.3 (19.9%) 06:56:15 INFO - TinderboxPrint: CPU user
1,613.3 (33.5%) 06:56:15 INFO - TinderboxPrint: Swap in / out
210,444,288 / 0 06:56:15 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203547136; Write bytes: 203634176; Read time: 15655; Write time: 401 06:56:15 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 438272; Write bytes: 297544704; Read time: 5; Write time: 1229 06:56:16 INFO - Running post-run listener: _upload_blobber_files 06:56:16 INFO - Blob upload gear active. 06:56:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:56:16 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 06:56:16 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'] 06:56:16 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 06:56:16 INFO - (blobuploader) - INFO - Open directory for files ... 06:56:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:56:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:56:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:17 INFO - (blobuploader) - INFO - Done attempting. 06:56:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:56:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:56:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:18 INFO - (blobuploader) - INFO - Done attempting. 06:56:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:56:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:56:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:19 INFO - (blobuploader) - INFO - Done attempting. 06:56:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:56:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:56:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:21 INFO - (blobuploader) - INFO - Done attempting. 06:56:21 INFO - (blobuploader) - INFO - Iteration through files over. 06:56:21 INFO - Return code: 0 06:56:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:56:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:56:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adfad3bad3161d98d7b97243fa67b426aba02269a7320de349ab2ac62372312caf3c8e2d042af8a53e668f838e0eba9b8dce7ef6863e541eb280ae68da7a1450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e023f1949326d461d4cb6782400926560323323956b236e8c960506489de8d1e92e7f08b726ff51b46a4be4f5db88f4c59b6552f6903b4ada59863b4359eb2d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d0a7b98c03323cc11bdce2faa7683504afd7e00945af3f3333741d05478e05a1b1e24187df8f5741e2d06abedf9f09f83a9255ffa742de14c9325f9d363787a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b230c738edf2c8430bd01b6daf914177eb094dc25eb50a7ea06f6b87e34e9cf660da34a1f09d0a0d2f60a79c1a71ce0ca82704ead879f450f13ce8028155a158"} 06:56:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:56:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:56:21 INFO - Contents: 06:56:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adfad3bad3161d98d7b97243fa67b426aba02269a7320de349ab2ac62372312caf3c8e2d042af8a53e668f838e0eba9b8dce7ef6863e541eb280ae68da7a1450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e023f1949326d461d4cb6782400926560323323956b236e8c960506489de8d1e92e7f08b726ff51b46a4be4f5db88f4c59b6552f6903b4ada59863b4359eb2d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d0a7b98c03323cc11bdce2faa7683504afd7e00945af3f3333741d05478e05a1b1e24187df8f5741e2d06abedf9f09f83a9255ffa742de14c9325f9d363787a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b230c738edf2c8430bd01b6daf914177eb094dc25eb50a7ea06f6b87e34e9cf660da34a1f09d0a0d2f60a79c1a71ce0ca82704ead879f450f13ce8028155a158"} 06:56:21 INFO - Running post-run listener: copy_logs_to_upload_dir 06:56:21 INFO - Copying logs to upload dir... 06:56:21 INFO - mkdir: /builds/slave/test/build/upload/logs 06:56:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.942635 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-20 06:56:21.699463) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:56:21.703112) ========= 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.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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/adfad3bad3161d98d7b97243fa67b426aba02269a7320de349ab2ac62372312caf3c8e2d042af8a53e668f838e0eba9b8dce7ef6863e541eb280ae68da7a1450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e023f1949326d461d4cb6782400926560323323956b236e8c960506489de8d1e92e7f08b726ff51b46a4be4f5db88f4c59b6552f6903b4ada59863b4359eb2d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d0a7b98c03323cc11bdce2faa7683504afd7e00945af3f3333741d05478e05a1b1e24187df8f5741e2d06abedf9f09f83a9255ffa742de14c9325f9d363787a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b230c738edf2c8430bd01b6daf914177eb094dc25eb50a7ea06f6b87e34e9cf660da34a1f09d0a0d2f60a79c1a71ce0ca82704ead879f450f13ce8028155a158"} build_url:https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/aeKJ1BqjQxKR-W18XkX32w/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/adfad3bad3161d98d7b97243fa67b426aba02269a7320de349ab2ac62372312caf3c8e2d042af8a53e668f838e0eba9b8dce7ef6863e541eb280ae68da7a1450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e023f1949326d461d4cb6782400926560323323956b236e8c960506489de8d1e92e7f08b726ff51b46a4be4f5db88f4c59b6552f6903b4ada59863b4359eb2d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d0a7b98c03323cc11bdce2faa7683504afd7e00945af3f3333741d05478e05a1b1e24187df8f5741e2d06abedf9f09f83a9255ffa742de14c9325f9d363787a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b230c738edf2c8430bd01b6daf914177eb094dc25eb50a7ea06f6b87e34e9cf660da34a1f09d0a0d2f60a79c1a71ce0ca82704ead879f450f13ce8028155a158"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:56:21.739334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:56:21.739882) ========= 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.zWelwXCHXp/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.Xzyo481xYr/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005259 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:56:21.792174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:56:21.792964) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:56:21.793848) ========= ========= Total master_lag: 1.57 =========