builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1490357515.31 results: success (0) buildid: 20170324032516 builduid: 94554793ffc74ef29c123aa38b240091 revision: 72bc265f157f3937f25d18be68b60fb982df695a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.309568) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.310448) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.310759) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.351749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.352006) ========= 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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-24 05:11:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-03-24 05:11:55 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126080 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.501047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.501357) ========= 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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.022739 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:55.550286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 05:11:55.550628) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 72bc265f157f3937f25d18be68b60fb982df695a --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 72bc265f157f3937f25d18be68b60fb982df695a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-24 05:11:55,632 truncating revision to first 12 chars 2017-03-24 05:11:55,633 Setting DEBUG logging. 2017-03-24 05:11:55,633 attempt 1/10 2017-03-24 05:11:55,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/72bc265f157f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 05:11:56,994 unpacking tar archive at: mozilla-central-72bc265f157f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.619253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 05:11:57.193035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:57.193311) ========= script_repo_revision: 72bc265f157f3937f25d18be68b60fb982df695a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:57.193723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:57.193982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 05:11:57.213357) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-24 05:11:57.213680) ========= /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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:11:57 INFO - MultiFileLogger online at 20170324 05:11:57 in /builds/slave/test 05:11:57 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 05:11:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:57 INFO - {'append_to_log': False, 05:11:57 INFO - 'base_work_dir': '/builds/slave/test', 05:11:57 INFO - 'blob_upload_branch': 'Firefox', 05:11:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:57 INFO - 'branch': 'Firefox', 05:11:57 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:57 INFO - 'config_files': ('talos/mac_config.py',), 05:11:57 INFO - 'default_actions': ('clobber', 05:11:57 INFO - 'read-buildbot-config', 05:11:57 INFO - 'download-and-extract', 05:11:57 INFO - 'populate-webroot', 05:11:57 INFO - 'create-virtualenv', 05:11:57 INFO - 'install', 05:11:57 INFO - 'run-tests'), 05:11:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:57 INFO - 'download_minidump_stackwalk': True, 05:11:57 INFO - 'download_symbols': 'ondemand', 05:11:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:57 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:57 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:57 INFO - 'gecko_profile': False, 05:11:57 INFO - 'gecko_profile_interval': 0, 05:11:57 INFO - 'installer_path': 'installer.exe', 05:11:57 INFO - 'log_level': 'info', 05:11:57 INFO - 'log_name': 'talos', 05:11:57 INFO - 'log_to_console': True, 05:11:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:11:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:11:57 INFO - 'opt_config_files': (), 05:11:57 INFO - 'pip_index': False, 05:11:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:57 INFO - 'cmd': ('bash', 05:11:57 INFO - '-c', 05:11:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:57 INFO - 'enabled': True, 05:11:57 INFO - 'halt_on_failure': False, 05:11:57 INFO - 'name': 'check_screen_resolution'},), 05:11:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:57 INFO - 'cmd': ('bash', 05:11:57 INFO - '-c', 05:11:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:57 INFO - 'enabled': True, 05:11:57 INFO - 'halt_on_failure': False, 05:11:57 INFO - 'name': 'check_screen_resolution'},), 05:11:57 INFO - 'run_cmd_checks_enabled': True, 05:11:57 INFO - 'suite': 'dromaeojs', 05:11:57 INFO - 'system_bits': '32', 05:11:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:57 INFO - 'title': 't-yosemite-r7-0185', 05:11:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:57 INFO - 'use_talos_json': True, 05:11:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:57 INFO - 'work_dir': 'build'} 05:11:57 INFO - [mozharness: 2017-03-24 12:11:57.424702Z] Running clobber step. 05:11:57 INFO - Running pre-action listener: _resource_record_pre_action 05:11:57 INFO - Running main action method: clobber 05:11:57 INFO - rmtree: /builds/slave/test/build 05:11:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:59 INFO - Running post-action listener: _resource_record_post_action 05:11:59 INFO - [mozharness: 2017-03-24 12:11:59.238722Z] Finished clobber step (success) 05:11:59 INFO - [mozharness: 2017-03-24 12:11:59.238890Z] Running read-buildbot-config step. 05:11:59 INFO - Running pre-action listener: _resource_record_pre_action 05:11:59 INFO - Running main action method: read_buildbot_config 05:11:59 INFO - Using buildbot properties: 05:11:59 INFO - { 05:11:59 INFO - "project": "", 05:11:59 INFO - "product": "firefox", 05:11:59 INFO - "script_repo_revision": "production", 05:11:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:11:59 INFO - "repository": "", 05:11:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:11:59 INFO - "buildid": "20170324032516", 05:11:59 INFO - "pgo_build": "False", 05:11:59 INFO - "basedir": "/builds/slave/test", 05:11:59 INFO - "buildnumber": 10, 05:11:59 INFO - "slavename": "t-yosemite-r7-0185", 05:11:59 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:11:59 INFO - "platform": "yosemite_r7", 05:11:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:11:59 INFO - "branch": "mozilla-central", 05:11:59 INFO - "repo_path": "mozilla-central", 05:11:59 INFO - "revision": "72bc265f157f3937f25d18be68b60fb982df695a", 05:11:59 INFO - "stage_platform": "macosx64", 05:11:59 INFO - "builduid": "94554793ffc74ef29c123aa38b240091", 05:11:59 INFO - "slavebuilddir": "test" 05:11:59 INFO - } 05:11:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 05:11:59 INFO - Running post-action listener: _resource_record_post_action 05:11:59 INFO - [mozharness: 2017-03-24 12:11:59.241955Z] Finished read-buildbot-config step (success) 05:11:59 INFO - [mozharness: 2017-03-24 12:11:59.242102Z] Running download-and-extract step. 05:11:59 INFO - Running pre-action listener: _resource_record_pre_action 05:11:59 INFO - Running main action method: download_and_extract 05:11:59 INFO - mkdir: /builds/slave/test/build/tests 05:11:59 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')]} 05:11:59 INFO - trying https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/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 05:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/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 05:12:00 INFO - Downloaded 1325 bytes. 05:12:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:12:00 INFO - Using the following test package requirements: 05:12:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:12:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:12:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:12:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:12:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'jsshell-mac.zip'], 05:12:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:12:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:12:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:12:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:12:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 05:12:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:12:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:12:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:12:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:12:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:12:00 INFO - Fetch https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:12:02 INFO - Expected file size: 52410617 05:12:02 INFO - Obtained file size: 52410617 05:12:19 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 05:12:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:12:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:12:19 INFO - Fetch https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:12:20 INFO - Expected file size: 52410617 05:12:20 INFO - Obtained file size: 52410617 05:12:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:12:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:12:36 INFO - Fetch https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:12:37 INFO - Expected file size: 14404171 05:12:37 INFO - Obtained file size: 14404171 05:12: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')]} 05:12:37 INFO - trying https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:37 INFO - Downloading https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/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 05:12:40 INFO - Downloaded 75463806 bytes. 05:12:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:40 INFO - mkdir: /builds/slave/test/properties 05:12:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:40 INFO - Contents: 05:12:40 INFO - build_url:https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - Running post-action listener: set_extra_try_arguments 05:12:40 INFO - [mozharness: 2017-03-24 12:12:40.812587Z] Finished download-and-extract step (success) 05:12:40 INFO - [mozharness: 2017-03-24 12:12:40.812743Z] Running populate-webroot step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: populate_webroot 05:12:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:40 INFO - '--tppagecycles', 05:12:40 INFO - '7'], 05:12:40 INFO - 'tests': ['tsvgm']}}, 05:12:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['damp', 'tps']}, 05:12:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'tests': ['damp', 'tps']}, 05:12:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['dromaeo_dom']}, 05:12:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['basic_compositor_video']}, 05:12:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:12:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['a11yr', 05:12:40 INFO - 'ts_paint', 05:12:40 INFO - 'tpaint', 05:12:40 INFO - 'sessionrestore', 05:12:40 INFO - 'sessionrestore_no_auto_restore', 05:12:40 INFO - 'tabpaint']}, 05:12:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['tsvgx', 05:12:40 INFO - 'tsvgr_opacity', 05:12:40 INFO - 'tart', 05:12:40 INFO - 'tscrollx', 05:12:40 INFO - 'cart', 05:12:40 INFO - 'tsvg_static']}, 05:12:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:40 INFO - 'tsvgr_opacity', 05:12:40 INFO - 'tart', 05:12:40 INFO - 'tscrollx', 05:12:40 INFO - 'cart', 05:12:40 INFO - 'tsvg_static']}, 05:12:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'talos_options': ['--disable-e10s'], 05:12:40 INFO - 'tests': ['tp5o']}, 05:12:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:12:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'talos_options': ['--disable-e10s', 05:12:40 INFO - '--xperf_path', 05:12:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:40 INFO - 'tests': ['tp5n']}, 05:12:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:12:40 INFO - 'talos_options': ['--xperf_path', 05:12:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:40 INFO - 'tests': ['tp5n']}}, 05:12:40 INFO - 'talos.zip': {'path': '', 05:12:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - [mozharness: 2017-03-24 12:12:40.831616Z] Finished populate-webroot step (success) 05:12:40 INFO - [mozharness: 2017-03-24 12:12:40.831731Z] Running create-virtualenv step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: create_virtualenv 05:12:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12: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 05:12:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:40 INFO - Using real prefix '/tools/python27' 05:12:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:41 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:44 INFO - Installing pip.................done. 05:12:44 INFO - Return code: 0 05:12:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:12:44 INFO - Reading from file tmpfile_stdout 05:12:44 INFO - Output received: 05:12: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) 05:12:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12: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 05:12: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 05:12:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:44 INFO - 'HOME': '/Users/cltbld', 05:12:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:44 INFO - 'LOGNAME': 'cltbld', 05:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:44 INFO - 'MOZ_NO_REMOTE': '1', 05:12:44 INFO - 'NO_EM_RESTART': '1', 05:12:44 INFO - 'PAGER': '/bin/cat', 05:12:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:44 INFO - 'PWD': '/builds/slave/test', 05:12:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:44 INFO - 'SHELL': '/bin/bash', 05:12:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:44 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:44 INFO - 'USER': 'cltbld', 05:12:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:44 INFO - 'XPC_FLAGS': '0x0', 05:12:44 INFO - 'XPC_SERVICE_NAME': '0', 05:12:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:45 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 05:12:45 INFO - Cleaning up... 05:12:45 INFO - Return code: 0 05:12:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:45 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12:45 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 05:12:45 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 05:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:45 INFO - 'HOME': '/Users/cltbld', 05:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:45 INFO - 'LOGNAME': 'cltbld', 05:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:45 INFO - 'MOZ_NO_REMOTE': '1', 05:12:45 INFO - 'NO_EM_RESTART': '1', 05:12:45 INFO - 'PAGER': '/bin/cat', 05:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:45 INFO - 'PWD': '/builds/slave/test', 05:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:45 INFO - 'SHELL': '/bin/bash', 05:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:45 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:45 INFO - 'USER': 'cltbld', 05:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:45 INFO - 'XPC_FLAGS': '0x0', 05:12:45 INFO - 'XPC_SERVICE_NAME': '0', 05:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:45 INFO - Downloading/unpacking psutil>=3.1.1 05:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:49 INFO - Installing collected packages: psutil 05:12:49 INFO - Running setup.py install for psutil 05:12:49 INFO - building 'psutil._psutil_osx' extension 05:12: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/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:12: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/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:12: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 05:12: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 05:12:49 INFO - building 'psutil._psutil_posix' extension 05:12: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 05:12:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:49 INFO - ^ 05:12:50 INFO - 1 warning generated. 05:12:50 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 05:12:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:50 INFO - Successfully installed psutil 05:12:50 INFO - Cleaning up... 05:12:50 INFO - Return code: 0 05:12:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:50 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12:50 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 05:12:50 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 05:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:50 INFO - 'HOME': '/Users/cltbld', 05:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:50 INFO - 'LOGNAME': 'cltbld', 05:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:50 INFO - 'MOZ_NO_REMOTE': '1', 05:12:50 INFO - 'NO_EM_RESTART': '1', 05:12:50 INFO - 'PAGER': '/bin/cat', 05:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:50 INFO - 'PWD': '/builds/slave/test', 05:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:50 INFO - 'SHELL': '/bin/bash', 05:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:50 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:50 INFO - 'USER': 'cltbld', 05:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:50 INFO - 'XPC_FLAGS': '0x0', 05:12:50 INFO - 'XPC_SERVICE_NAME': '0', 05:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:12:50 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 05:12:50 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 05:12:50 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 05:12:50 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 05:12:50 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 05:12:50 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 05:12:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:12:53 INFO - Installing collected packages: mozsystemmonitor 05:12:53 INFO - Running setup.py install for mozsystemmonitor 05:12:53 INFO - Successfully installed mozsystemmonitor 05:12:53 INFO - Cleaning up... 05:12:53 INFO - Return code: 0 05:12:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:12:53 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')]} 05:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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')]} 05:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12:53 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 05:12:53 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 05:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:53 INFO - 'HOME': '/Users/cltbld', 05:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:53 INFO - 'LOGNAME': 'cltbld', 05:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:53 INFO - 'MOZ_NO_REMOTE': '1', 05:12:53 INFO - 'NO_EM_RESTART': '1', 05:12:53 INFO - 'PAGER': '/bin/cat', 05:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:53 INFO - 'PWD': '/builds/slave/test', 05:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:53 INFO - 'SHELL': '/bin/bash', 05:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:53 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:53 INFO - 'USER': 'cltbld', 05:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:53 INFO - 'XPC_FLAGS': '0x0', 05:12:53 INFO - 'XPC_SERVICE_NAME': '0', 05:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:53 INFO - Downloading/unpacking jsonschema==2.5.1 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:12:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12:56 INFO - Downloading functools32-3.2.3-2.tar.gz 05:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:12:56 INFO - warning: no files found matching '*.txt' 05:12:56 INFO - no previously-included directories found matching 'build' 05:12:56 INFO - no previously-included directories found matching 'dist' 05:12:56 INFO - no previously-included directories found matching '.git*' 05:12:56 INFO - Installing collected packages: jsonschema, functools32 05:12:56 INFO - Running setup.py install for functools32 05:12:57 INFO - warning: no files found matching '*.txt' 05:12:57 INFO - no previously-included directories found matching 'build' 05:12:57 INFO - no previously-included directories found matching 'dist' 05:12:57 INFO - no previously-included directories found matching '.git*' 05:12:57 INFO - Successfully installed jsonschema functools32 05:12:57 INFO - Cleaning up... 05:12:57 INFO - Return code: 0 05:12:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:57 INFO - 'HOME': '/Users/cltbld', 05:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:57 INFO - 'LOGNAME': 'cltbld', 05:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:57 INFO - 'MOZ_NO_REMOTE': '1', 05:12:57 INFO - 'NO_EM_RESTART': '1', 05:12:57 INFO - 'PAGER': '/bin/cat', 05:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:57 INFO - 'PWD': '/builds/slave/test', 05:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:57 INFO - 'SHELL': '/bin/bash', 05:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:57 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:57 INFO - 'USER': 'cltbld', 05:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:57 INFO - 'XPC_FLAGS': '0x0', 05:12:57 INFO - 'XPC_SERVICE_NAME': '0', 05:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:12:57 INFO - Cleaning up... 05:12:57 INFO - Return code: 0 05:12:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:57 INFO - 'HOME': '/Users/cltbld', 05:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:57 INFO - 'LOGNAME': 'cltbld', 05:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:57 INFO - 'MOZ_NO_REMOTE': '1', 05:12:57 INFO - 'NO_EM_RESTART': '1', 05:12:57 INFO - 'PAGER': '/bin/cat', 05:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:57 INFO - 'PWD': '/builds/slave/test', 05:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:57 INFO - 'SHELL': '/bin/bash', 05:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:12:57 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:57 INFO - 'USER': 'cltbld', 05:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:57 INFO - 'XPC_FLAGS': '0x0', 05:12:57 INFO - 'XPC_SERVICE_NAME': '0', 05:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:12:57 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 05:12:57 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 05:12:57 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 05:12:57 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 05:12:57 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 05:12:57 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 05:13:00 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13: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 05:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:01 INFO - Downloading docopt-0.6.1.tar.gz 05:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:01 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:01 INFO - Running setup.py install for blobuploader 05:13:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:01 INFO - Running setup.py install for requests 05:13:02 INFO - Running setup.py install for docopt 05:13:02 INFO - Successfully installed blobuploader requests docopt 05:13:02 INFO - Cleaning up... 05:13:02 INFO - Return code: 0 05:13:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:02 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')]} 05:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:02 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')]} 05:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:02 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:13:02 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 05:13:02 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 05:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:02 INFO - 'HOME': '/Users/cltbld', 05:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:02 INFO - 'LOGNAME': 'cltbld', 05:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:02 INFO - 'MOZ_NO_REMOTE': '1', 05:13:02 INFO - 'NO_EM_RESTART': '1', 05:13:02 INFO - 'PAGER': '/bin/cat', 05:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:02 INFO - 'PWD': '/builds/slave/test', 05:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:02 INFO - 'SHELL': '/bin/bash', 05:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:13:02 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:02 INFO - 'USER': 'cltbld', 05:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:02 INFO - 'XPC_FLAGS': '0x0', 05:13:02 INFO - 'XPC_SERVICE_NAME': '0', 05:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-lqN2ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:02 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-_MqgvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-SLA0JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-HwuJ75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-TVNmsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-1jUMtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-qs6QU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-qWvKIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-uqcBhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-86LZeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-VJEuOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:03 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-CAC_Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:04 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-YcMMYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:04 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-lzoaip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:04 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-W9thqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:04 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-U4u_nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:04 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-47_h5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:04 INFO - Running setup.py install for manifestparser 05:13:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:04 INFO - Running setup.py install for mozcrash 05:13:04 INFO - Running setup.py install for mozdebug 05:13:05 INFO - Running setup.py install for mozdevice 05:13:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Running setup.py install for mozfile 05:13:05 INFO - Running setup.py install for mozhttpd 05:13:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Running setup.py install for mozinfo 05:13:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Running setup.py install for mozInstall 05:13:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:05 INFO - Running setup.py install for mozleak 05:13:05 INFO - Running setup.py install for mozlog 05:13:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Running setup.py install for moznetwork 05:13:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Running setup.py install for mozprocess 05:13:06 INFO - Running setup.py install for mozprofile 05:13:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Running setup.py install for mozrunner 05:13:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:06 INFO - Running setup.py install for mozscreenshot 05:13:06 INFO - Running setup.py install for moztest 05:13:07 INFO - Running setup.py install for mozversion 05:13:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:07 INFO - Cleaning up... 05:13:07 INFO - Return code: 0 05:13:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:07 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')]} 05:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:07 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')]} 05:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:07 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:13:07 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 05:13:07 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 05:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:07 INFO - 'HOME': '/Users/cltbld', 05:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:07 INFO - 'LOGNAME': 'cltbld', 05:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:07 INFO - 'MOZ_NO_REMOTE': '1', 05:13:07 INFO - 'NO_EM_RESTART': '1', 05:13:07 INFO - 'PAGER': '/bin/cat', 05:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:07 INFO - 'PWD': '/builds/slave/test', 05:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:07 INFO - 'SHELL': '/bin/bash', 05:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:13:07 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:07 INFO - 'USER': 'cltbld', 05:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:07 INFO - 'XPC_FLAGS': '0x0', 05:13:07 INFO - 'XPC_SERVICE_NAME': '0', 05:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:07 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-SYgSC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:07 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)) 05:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:07 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-7ikV1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:07 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)) 05:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:07 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-I6Ua3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:07 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)) 05:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:07 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-HliUcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13: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)) 05:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:07 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-d4TEPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-fLgWm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nFT9h_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-jx1htw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-H_XmU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ONGnDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-EbeGAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-dTzccU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:08 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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-AU4lmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13: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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-5P7xHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13: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)) 05:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-8853Yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:09 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)) 05:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-NvPZAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:09 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)) 05:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-A5Q9ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:09 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)) 05:13:09 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)) 05:13:09 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)) 05:13:09 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)) 05:13:09 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)) 05:13:09 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)) 05:13:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:13:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:11 INFO - Downloading blessings-1.6.tar.gz 05:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:12 INFO - Installing collected packages: blessings 05:13:12 INFO - Running setup.py install for blessings 05:13:12 INFO - Successfully installed blessings 05:13:12 INFO - Cleaning up... 05:13:12 INFO - Return code: 0 05:13:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:12 INFO - Reading from file tmpfile_stdout 05:13:12 INFO - Current package versions: 05:13:12 INFO - blessings == 1.6 05:13:12 INFO - blobuploader == 1.2.4 05:13:12 INFO - docopt == 0.6.1 05:13:12 INFO - functools32 == 3.2.3-2 05:13:12 INFO - jsonschema == 2.5.1 05:13:12 INFO - manifestparser == 1.1 05:13:12 INFO - mozInstall == 1.12 05:13:12 INFO - mozcrash == 1.0 05:13:12 INFO - mozdebug == 0.1 05:13:12 INFO - mozdevice == 0.49 05:13:12 INFO - mozfile == 1.2 05:13:12 INFO - mozhttpd == 0.7 05:13:12 INFO - mozinfo == 0.9 05:13:12 INFO - mozleak == 0.1 05:13:12 INFO - mozlog == 3.4 05:13:12 INFO - moznetwork == 0.27 05:13:12 INFO - mozprocess == 0.25 05:13:12 INFO - mozprofile == 0.28 05:13:12 INFO - mozrunner == 6.13 05:13:12 INFO - mozscreenshot == 0.1 05:13:12 INFO - mozsystemmonitor == 0.3 05:13:12 INFO - moztest == 0.8 05:13:12 INFO - mozversion == 1.4 05:13:12 INFO - psutil == 3.1.1 05:13:12 INFO - requests == 1.2.3 05:13:12 INFO - wsgiref == 0.1.2 05:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:12 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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:13:12 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 05:13:12 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 05:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:12 INFO - 'HOME': '/Users/cltbld', 05:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:12 INFO - 'LOGNAME': 'cltbld', 05:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:12 INFO - 'MOZ_NO_REMOTE': '1', 05:13:12 INFO - 'NO_EM_RESTART': '1', 05:13:12 INFO - 'PAGER': '/bin/cat', 05:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:12 INFO - 'PWD': '/builds/slave/test', 05:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:12 INFO - 'SHELL': '/bin/bash', 05:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:13:12 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:12 INFO - 'USER': 'cltbld', 05:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:12 INFO - 'XPC_FLAGS': '0x0', 05:13:12 INFO - 'XPC_SERVICE_NAME': '0', 05:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13: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)) 05:13:12 INFO - Cleaning up... 05:13:12 INFO - Return code: 0 05:13:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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 0x10314db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103150768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9783d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, '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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:13: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 05:13: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 05:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:12 INFO - 'HOME': '/Users/cltbld', 05:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:12 INFO - 'LOGNAME': 'cltbld', 05:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:12 INFO - 'MOZ_NO_REMOTE': '1', 05:13:12 INFO - 'NO_EM_RESTART': '1', 05:13:12 INFO - 'PAGER': '/bin/cat', 05:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:12 INFO - 'PWD': '/builds/slave/test', 05:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:12 INFO - 'SHELL': '/bin/bash', 05:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:13:12 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:12 INFO - 'USER': 'cltbld', 05:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:12 INFO - 'XPC_FLAGS': '0x0', 05:13:12 INFO - 'XPC_SERVICE_NAME': '0', 05:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:13:13 INFO - Cleaning up... 05:13:13 INFO - Return code: 0 05:13:13 INFO - Running post-action listener: _resource_record_post_action 05:13:13 INFO - Running post-action listener: _start_resource_monitoring 05:13:13 INFO - Starting resource monitoring. 05:13:13 INFO - [mozharness: 2017-03-24 12:13:13.122118Z] Finished create-virtualenv step (success) 05:13:13 INFO - [mozharness: 2017-03-24 12:13:13.122975Z] Running install step. 05:13:13 INFO - Running pre-action listener: _resource_record_pre_action 05:13:13 INFO - Running main action method: install 05:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:13 INFO - Reading from file tmpfile_stdout 05:13:13 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:13 INFO - Reading from file tmpfile_stdout 05:13:13 INFO - Output received: 05:13:13 INFO - Usage: mozinstall [options] installer 05:13:13 INFO - Options: 05:13:13 INFO - -h, --help show this help message and exit 05:13:13 INFO - -d DEST, --destination=DEST 05:13:13 INFO - Directory to install application into. [default: 05:13:13 INFO - "/builds/slave/test"] 05:13:13 INFO - --app=APP Application being installed. [default: firefox] 05:13:13 INFO - mkdir: /builds/slave/test/build/application 05:13:13 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'] 05:13:13 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 05:13:33 INFO - Reading from file tmpfile_stdout 05:13:33 INFO - Output received: 05:13:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:13:33 INFO - Running post-action listener: _resource_record_post_action 05:13:33 INFO - [mozharness: 2017-03-24 12:13:33.596834Z] Finished install step (success) 05:13:33 INFO - [mozharness: 2017-03-24 12:13:33.596966Z] Running run-tests step. 05:13:33 INFO - Running pre-action listener: _resource_record_pre_action 05:13:33 INFO - Running main action method: run_tests 05:13:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:33 INFO - 2017-03-24 05:13:33.612 screenresolution[2740:42248] starting screenresolution argv=screenresolution get 05:13:33 INFO - 2017-03-24 05:13:33.615 screenresolution[2740:42248] Display 0: 1600x1200x32@60 05:13:33 INFO - 2017-03-24 05:13:33.621 screenresolution[2741:42251] starting screenresolution argv=screenresolution list 05:13:33 INFO - Available Modes on Display 0 05:13:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:33 INFO - Intel Iris: 05:13:33 INFO - Chipset Model: Intel Iris 05:13:33 INFO - Type: GPU 05:13:33 INFO - Bus: Built-In 05:13:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:33 INFO - Vendor: Intel (0x8086) 05:13:33 INFO - Device ID: 0x0a2e 05:13:33 INFO - Revision ID: 0x0009 05:13:33 INFO - Displays: 05:13:33 INFO - Display: 05:13:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:33 INFO - Display Serial Number: 1600x1200 60 05:13:33 INFO - Main Display: Yes 05:13:33 INFO - Mirror: Off 05:13:33 INFO - Online: Yes 05:13:33 INFO - Rotation: Supported 05:13:33 INFO - Return code: 0 05:13:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:33 INFO - Python 2.7.3 05:13:33 INFO - Return code: 0 05:13:33 INFO - grabbing minidump binary from tooltool 05:13: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')]} 05:13: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 0x102f2bd30>, '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 0x102e52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f20be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13: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 05:13: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 05:13: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 05:13:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:33 INFO - Return code: 0 05:13:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:13:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:33 INFO - ENV: RUST_BACKTRACE is now 1 05:13:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:13:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13: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/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 05:13: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/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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 05:13:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0nYgqDv4Gs/Render', 05:13:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:33 INFO - 'HOME': '/Users/cltbld', 05:13:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:33 INFO - 'LOGNAME': 'cltbld', 05:13:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:33 INFO - 'MOZ_NO_REMOTE': '1', 05:13:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:33 INFO - 'NO_EM_RESTART': '1', 05:13:33 INFO - 'PAGER': '/bin/cat', 05:13:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:33 INFO - 'PWD': '/builds/slave/test', 05:13:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:33 INFO - 'RUST_BACKTRACE': '1', 05:13:33 INFO - 'SHELL': '/bin/bash', 05:13:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70Q4TCEYS9/Listeners', 05:13:33 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:13:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:33 INFO - 'USER': 'cltbld', 05:13:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:33 INFO - 'XPC_FLAGS': '0x0', 05:13:33 INFO - 'XPC_SERVICE_NAME': '0', 05:13:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13: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/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 05:13:34 INFO - mozversion application_buildid: 20170324032516 05:13:34 INFO - mozversion application_changeset: 72bc265f157f3937f25d18be68b60fb982df695a 05:13:34 INFO - mozversion application_display_name: Nightly 05:13:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:34 INFO - mozversion application_name: Firefox 05:13:34 INFO - mozversion application_remotingname: firefox 05:13:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:13:34 INFO - mozversion application_vendor: Mozilla 05:13:34 INFO - mozversion application_version: 55.0a1 05:13:34 INFO - mozversion platform_buildid: 20170324032516 05:13:34 INFO - mozversion platform_changeset: 72bc265f157f3937f25d18be68b60fb982df695a 05:13:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:13:34 INFO - mozversion platform_version: 55.0a1 05:13:34 INFO - using testdate: 1490357614 05:13:34 INFO - actual date: 1490357614 05:13:34 INFO - starting webserver on 'localhost:49243' 05:13:34 INFO - SUITE-START | Running 2 tests 05:13:34 INFO - TEST-START | dromaeo_css 05:13:34 INFO - operating with platform_type : mac_ 05:13:34 INFO - Initialising browser for dromaeo_css test... 05:13:34 INFO - TEST-INFO | started process 2751 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpExno_A/profile http://localhost:49243/getInfo.html) 05:13:36 INFO - PID 2751 | __metrics Screen width/height:1600/1200 05:13:36 INFO - PID 2751 | colorDepth:24 05:13:36 INFO - PID 2751 | Browser inner width/height: 1024/689 05:13:36 INFO - PID 2751 | __metrics 05:13:36 INFO - PID 2751 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:13:36 INFO - PID 2751 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:13:37 INFO - TEST-INFO | 2751: exit 0 05:13:37 INFO - Browser initialized. 05:13:37 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:37 INFO - TEST-INFO | started process 2753 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpExno_A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:49 INFO - PID 2753 | RSS: Main: 234598400 05:13:49 INFO - PID 2753 | 05:16:55 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 05:16:56 INFO - PID 2753 | RSS: Main: 363036672 05:16:56 INFO - PID 2753 | 05:18:39 INFO - PID 2753 | ************************* 05:18:39 INFO - PID 2753 | A coding exception was thrown and uncaught in a Task. 05:18:39 INFO - PID 2753 | 05:18:39 ERROR - PID 2753 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:39 INFO - PID 2753 | Full stack: 05:18:39 INFO - PID 2753 | ************************* 05:19:59 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 05:20:00 INFO - PID 2753 | RSS: Main: 364896256 05:20:00 INFO - PID 2753 | 05:23:02 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 05:23:03 INFO - PID 2753 | RSS: Main: 333168640 05:23:03 INFO - PID 2753 | 05:26:06 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:26:06 INFO - PID 2753 | RSS: Main: 323182592 05:26:06 INFO - PID 2753 | 05:29:10 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:29:10 INFO - PID 2753 | RSS: Main: 333201408 05:29:10 INFO - PID 2753 | 05:32:14 INFO - PID 2753 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:32:15 INFO - PID 2753 | RSS: Main: 535597056 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | __start_tp_report 05:32:15 INFO - PID 2753 | _x_x_mozilla_page_load 05:32:15 INFO - PID 2753 | _x_x_mozilla_page_load_details 05:32:15 INFO - PID 2753 | |i|pagename|runs| 05:32:15 INFO - PID 2753 | |0;dojo.html;3386.102682789061;3417.6411476794938;3438.1404648837793;3450.309938012397;3488.197714525659;5979.611325263855;6140.98673718837;6165.229346331708;6194.070889366597;6208.720607572659;5078.415982162052;6109.1752613397175;6143.139960405536;6173.753049878;6245.594036387632;5331.600129990247;5483.232347471338;5516.007118718629;5536.916946245809;5545.390007099216;5628.887422251552;5648.378678345379;5649.265595472582;5650.463205995424;5686;5444.156155795858;5503.229547863302;5516.7517461714315;5588.86027849307;5592.3009623796825;4481.820727170909;5523.6409633373705;5534.861628459281;5537.086380747212;5542.057850165441;4555.430571178603;5417.647852889589;5467.17992301154;5497.47773961473;5498.752556134944;6103.542234332398;6111.448149658388;6151.169592105053;6175.5985860919345;6186.1648677428875;6541.542092053558;6552.410283074546;6556.9016464752585;6563.671816409161;6563.868722625521;6441.774537891199;6666.200055993263;6704.5306828522025;6766.3233676632435;6769.966150169218;6133.101782541855;6210.31686514483;6219.502439804804;6298.842528936813;6312.715927783261;6810.182778066664;6859.039734437183;6859.279775623488;6860.931390686029;6861.4853885957755;6288.968555157195;6383.71273292703;6387.393197646216;6491.578047426902;6506.544541882023;6629;6696.497762667819;6705.530612857053;6706.731730730716;6719.42884854783;6353.904691429632;6370;6372.362763723683;6546.312637173029;6551.377619126175;6498.317676643885;6550.672466376662;6560.573562718408;6570.474362051024;6577.704003319862;5324;5325.600579956519;5332.253484512094;5333.57331413486;5351.411344752074;4731.668783185213;4734.479207287196;4738.336632871401;4742.762861856962;4771.093492236464;6055.333913269536;6141.271221159771;6151.7539298428455;6172.4136207060255;6274;6639.701213445406;6699.330066993261;6708.060871477997;6710.530262881649;6716.832079197961;6211.192544969125;6226.221722284715;6259.1550140730415;6300.527460440394;6307.022411526221;5652.406497317805;5712.885742285214;5735.225744524436;5828.417158284223;5867.618604790675;6719.294474080151;6792.592444453348;6795.728170873307;6810.693518791567;6812.046313516013;6687.799366019027;6700.631465269457;6709.530332876847;6731.932680673326;6735.393814556714;6669.266380698216;6735.360140786617;6794.3545363190415;6794.796156115324;6797.456203503807;6367.299597044414;6512.967435162794;6536.542442029012;6557.508186886098;6605.04226887089;6408.102865598913;6651.235107962452;6661.233958094687;6661.367170118831;6682.398584127452;6229.2524897012645;6316.62100273985;6421.518386120966;6456.612603243821;6469.870602587827;6433.678316084271;6444.581102228341;6464;6467.547271690936;6472.093906853139;6127.556056270569;6211.440970312694;6213.720382582705;6247.0629405589525;6285.5600107992;6422.165118534561;6446.548741588043;6475.190601174853;6483.059956306403;6487.059376390492;6327.462165715966;6328.114064030948;6334.239891213084;6337.302896681454;6350.7459701611415 05:32:15 INFO - PID 2753 | |1;ext.html;665.8002599220234;741.280957471253;801.4550105927966;1126.8760436352004;1144.5936692474165;23009.424764380878;26123.693815309252;27171;27761.444771104565;36842.63157368425;41593.96015099624;41932.790336048354;41984.790076049656;42022.78988605053;42149.78925105378;8653.96152461704;8855.42439741418;8869.37914345996;8886.155815197546;8905;6068.57519973602;6153.630782153063;8363.992641765963;8791.780205494882;8860.911390886105;8614.396992210548;8648.956755216215;8679.088695686925;8700.651973921036;8715.607797649121;8543.231109200171;8609.39734218605;8615.65537378504;8626.137386261387;8632.136786321349;5990.161377407167;6114.2357205349335;8338.583070846433;8397.160283971614;8484;36734.63265367339;37081.443778343346;37196.88409347724;37218.62781372179;37245.813770930974;3022.561728549348;3027.1070034339923;3059.8011129276747;3075.5847960525266;3152.448321543721;27276.31809204766;27308.590371144448;27522.449551009067;27640.308992275153;28430.573541396894;2783.805133640646;2786.3452088759127;2813.170114816134;2828.78784091194;2836.5319722245804;321525;321691;324648.3767581147;324950.9999999976;326899.36550317093;48869.266960995614;48904;48947.265791012454;49096.75451622719;49143.75428122908;49966.75016624894;50006.74996625067;50012;50015.74992125089;50021.249681254085;14241.287935603179;14524.709505810037;14536.781948270784;14547.27263636835;14552.927235363968;11907.166498345206;11963.162578619587;11974.341411222467;11975.820362694567;11982.101342399355;9222.801035865294;9773.169280611228;10177.54201060954;10200.846987295197;10204.43875586835;4630;5130.948690513122;5455.399906010336;5498.780048798003;5504.366997795305;883.6377085394957;981.9558119884622;988.9406635601887;1000.1881542072294;1019.5513973851481;21115.52191346591;24459.755402446102;24466.021359145434;24508.26475205747;24548.754512455005;1022.7136401807505;1080.6920027792198;1122.337820685783;1133.6825688807148;1141.2809929744374;9777.120059194707;11478.622565292051;11940.522379104912;11975.221610595283;12301.575438701399;341260.29369852995;343332;344920;345678;423757;51476;51606.22590661143;51672;51835.22247166296;51874.48125518772;1460.6202387379358;1489.0470099136778;1505.1345476351141;1509.65277986066;1510.61479322772;26058.60912086358;26539.601905971813;27756.028539001847;28378.85810570934;28814.423711526073;38115.61884381121;38139.665111720955;38157.80921095377;38236;38237;371420;374882;375388;376910.115449421;378700;31832.46805810286;32110.357792843544;32153.35693286168;32236.03291901247;32324.95938007749;30420.543691844196;30860.84569577138;32165.035048948568;39116;42710.95269280658;25040.37399065038;25525.61711574291;26614.201573952814;27126.593101103106;27596.31009224705;26320.73679263183;27392;28348.716512834606;29104.7089529102;29231;33344.66655333416;33818.66181338155;33933.15167120743;33974.64101435915;34007.659923400446;24160.275191744277;25106.995720170988;27095.72904271011;28576.14271571856;28933.855330724055;22888.427789305402;22909.54180916339;22939.78108542225;23207.88396058009;23369.41576460602 05:32:15 INFO - PID 2753 | |2;jquery.html;419.4377267230955;420.67818119138883;428.2014043808297;436.8274531560033;446.0209839402511;18861.81138188618;19974.90012549937;20822.89588552057;21230.36308910731;21382;20624;21119.472013199676;22017.66973495399;22171.113155473802;22266.22068227612;6086.969565152168;6344.809655710336;6380.553361264713;6387.425131738142;6389.8402539936405;6012.248468941382;6204.844878878019;6222.408871157256;6273.309935907047;6301.495880329563;6235.594686345373;6245.313015568284;6247.937520624781;6251.12484252205;6256;5957.851053723648;6204.6587437690905;6263.029230469285;6264.906026409608;6272.090546870701;4652.185867473178;5390.703511306877;5403.891922161574;5512.118061110203;5559.916601250984;27060.45879082427;27189.776460059344;27605.757740901707;27909;28014.579781303295;2008.1365013044385;2014.113789932425;2030.4314791858303;2053.16846677096;2102.043570175582;12087.879121208764;15538;16184.75722864158;16324;17027.574310642332;1664.8418400251958;1681.3946979087518;1704.6675898199856;1720.1657196259805;1723.896566206019;119439;126849;156295.2185239078;158076.20961895233;163436;11825.88174118265;11984.06788639629;12018.87981120195;12057.51769929193;12311.630651080482;16263.756043659354;16272.51182464528;16296.918515407584;16305.510834674978;16335.18324083791;8717.30261579072;8907.421017633833;8964.327675424369;9011.774705632411;9080.364374493854;6953.192170035827;7082.716691332289;7130.358267755927;8677.262432693165;8721;6461;6697.866042679217;7066.505344625827;7186.245444228385;7556.88407245972;1729.9394521191698;1818.1727314072066;1830.8099735172245;2056.352249041547;2210.602091623491;901.1889299630254;907.8592818113203;926.8146370725882;945.9716208513755;965.8358079126565;18985;20803.95980200984;23445;25408.872955635103;25974.83113259908;933.3419938943028;955.527014128011;964.8022155458115;977.750673578233;988.3822610868208;8025.357971362277;10519.737006574744;10714.839277410845;10752.677419677422;10782.353058816496;601594;705120;722600.3869980617;735598;750331;133104;162567;165112;167469;168279;1155.9897485845195;1193.6359410379916;1216.8357271768375;1223.9326837024228;1224.5224362498632;17663.293468261127;18198.636027279117;18242;18269.08654567293;18278.268869245083;52986.20520692116;54811.72594137004;59074.704626476596;59796.80406391935;62012.75974480576;87376.563117184;88265.55867220623;89126.55436722774;89703;91502;8100.432969692065;8225.83548329042;8230.897875529266;8734.257588105082;8792.164744349277;12272.447739851907;13141.671458213621;13390.799138013132;13771.104878182887;13824.930875345559;10816;11562.19064665499;11715.414229288672;11813.586524471602;11925.463354149259;10619.840702389316;10634.202434817265;10828.025477707044;10964.616238431936;10997.61508347204;11365.31808091517;11702.206665049122;11911.583094591648;12033.338166400932;12088.274703517816;8308.12764659702;8347.874781878398;8601.956990215009;8655.394122411371;8796.208341249318;11317.434128293717;11433.828492572451;11715.355655439032;11731.472083756422;11760.647180584596 05:32:15 INFO - PID 2753 | |3;mootools.html;1204.4640135139866;1281.9871801281988;1437.7843323501475;1451.6661167931368;1452.0343971259113;9279.489628070454;9692.806143877118;9697.660581879645;9705.70882873513;9706.951465242673;9436.52817359131;10474.947625261862;10803.5678572857;10806.621768238114;10808.621698240564;2421.418859473727;2451.178855083549;2463.1502131764514;2469.7036355637347;2472.3571871313497;2380.6609247462397;2416.359664688859;2461.5569197544437;2473.282748003077;2479.256223133053;2032.1668116072412;2135.4447843560706;2358.7759162879584;2446.156076153724;2450.2036838027625;2434.75652434756;2438.500107477963;2441.9633705494252;2445.2419749877417;2453.521563295158;2399.8920048597856;2421.5278020785954;2459.594166962449;2463.7043554773363;2471.4933438645035;10853.186011049203;11002.559897604086;11002.899710029013;11007.63335882781;11073.335599864035;5067.505085999639;6390.329015453357;6400.487960963112;6406.327335629739;6416.48668106555;3732;3830.6552410283207;4056.4523789288364;4084.346504559256;4084.9387259191135;1127.9830802537967;1184.1533303687904;1204.3255776764952;1211.4185191108318;1213.3690480949856;15338.539843804703;15408.691826163526;15437.150956697376;15445.30496127677;15455.613609659737;4836.903261934742;5151.0470562945975;5626.746796394172;5702.144678298206;5718.885622287531;12753.808692869614;12764.489420423264;12771.936140319425;12785.360731963365;12787.744245115045;6423.164988551458;6447.129637498878;6454.483641308684;6456.289808121103;6456.773213089498;5225.9738701307015;5661.1508273758445;5730.627509211971;5734.713264336767;5885.911711324334;4019.638692019192;4171.91656166875;4201.957980420218;4212.220739163265;4235.957640423618;1062.3997441445558;1244.2969722107161;1257.787299007647;1266.6326765237977;1272.8790764877322;753.3596443023387;758.9127250366279;775.25962705616;776.6000509737489;790.8917952446824;5208.5051920067535;5849.64902105875;5873.678422354936;5935.228420305419;6079.939200607937;573.2862586080273;577.3504807092022;582.7238348017827;588.9175515427844;599.7690889007763;3357.529945807589;3555.6088830228655;3986.5614782373914;4046.2716710991717;4137.565555616678;11706.297622142529;13316.467341306434;13849.653758656113;14141.929290353482;14206.289685515683;13476.921846252277;14029;14045.578632641038;14049.22729249901;14053.437862485385;10091.949540252252;10331.793364132674;10341.586336546605;10342.276040677081;10354.275200735876;6520.771772987922;6565.507586931095;6728.428083612947;6748.0552722617895;6760.628165450948;12115.273083615011;12175.57385491539;12202.877971220529;12463.127581069239;12477.126601137834;11125.109991200523;11531.884681153417;12490.562830300896;12744.49022039108;12821.102522823312;8918.420302680304;9004.41471304363;9010.189082982562;9027.322950778587;9133.908660913306;10126.544305506417;10153.898461015295;10251.94874025655;10253.589856405959;10260.897391025994;8606.311495080254;8656.307495400228;8705.216530512283;8768.123187681182;8774.956125219333;8413.537255451009;8482.703105391281;8487.066422693622;8499.95750021246;8589.914100858912;7665.808354791175;7844.254795794391;7844.450888437755;7850.568218748023;7865.213478652089;5480.890382192413;5524.563613460449;5616.354119276172;5704.828855134353;5732.455416735404;5011.373578302712;5013.39839219296;5830.154627579062;5866.67733274674;6169.907451388143 05:32:15 INFO - PID 2753 | |4;prototype.html;332.2606156769313;402.63360342088686;403.7516927089837;407.72682302857083;408.9550149483558;5978.491828194603;6012.428819262163;6049.2136022317145;6120.938790612098;6133.417325354089;5185.481451854813;5657.094864821619;5751.884962300752;5784;5963.16515687803;4640.283095254752;4642.65180111493;4679.906401871961;4690.835820746274;4693.88265293367;4061.715679902408;4670.626349892001;4673.742944138071;4682.672212945095;4702.90594188116;4250.404943307939;4493.3484644726495;4508.063670281037;4562.84030058948;4662.2773470112015;3798.955287295988;4493.123840851035;4500.212462818994;4518.051209246029;4525.502194758574;3894.5338648602765;3901.941470877939;3924.2151569685893;3941.9014524636827;3943.369060950263;5588.413216612238;5879.470847623692;5933.910991335133;5985.34161242263;5993.160957465958;4606.470255920579;5049.671771334888;5145.279660847485;5295.576353891679;5315;5768.3654797972185;6319.778807741753;6457.709403076873;6533.901991470132;6539.678414162999;3417.79493230407;4294.7852607369505;4351.630111440531;4353.651707863395;4381.123775244963;5651.886962260814;6531.869362612769;6686.231083425371;6835.350641689031;6903.413209877166;3643.4899114124046;4261.595148460891;4294.981358761377;4304.870853874389;4322.113966636832;3934.498296516103;4363.6072753452345;4457.621102206349;4492.303692927601;4493.73037617744;4784.473707892129;4928.619986403813;4984.451710311862;5311.990721762852;5325.227841962894;5319.601029922694;5585.972070139624;6269.8746025079245;6531.804045878631;6536.934630653633;1497.1466264229502;1515.4241388272385;1532.1726267815477;1537.8231503377256;1541.6916616676485;962.8026254617787;998.5072316886301;1012.7569383347943;1013.4324778124127;1018.1447584029451;4935.037667654773;4992.625553083533;6042.244719410074;6070.666113363719;6082.239720034996;5402.567794576425;6236.875262494725;6389.361063893573;6749.190097188369;6789.6605169741315;5041.470645582161;5602.607817452739;5756.445030545544;6105;6170.969145154246;2949.0388891387415;3444.397230484655;3526.0606666333415;3526.2242306692488;3529.841157147883;6476.481881449473;6527.294191485205;6661.2006559213205;6752;6817.5568588041615;5098.388193416814;6249.7187626556915;6839.487038472035;6874.900015997517;6887.5178737487895;6405.391487808651;6784;6806.421454176351;6819.567890742999;6914.654267286615;5109.591232701449;5564.777408903598;6675.833104172434;6780.491218249279;6826.368246196808;6080.72636731357;6659.800205993828;6679;6700.631465269457;6725.720309556974;5416.127468859784;6607.306232845482;6831.248562658005;6913.723451062102;7051.942208668741;4388.222769778245;4414.183376075436;4423.894026493376;4425.380446737395;4461;6586.562971700501;6648.867022659617;6660.167479065117;6760.256371799196;6835.316468353125;5920;6291.842703932438;6356.110144579855;6549.574277671936;6554.705038273194;5399.865003374947;5827.59206855516;6226.377362263737;6489.351064893662;6526.967365163144;6315.336889626523;6338.58799178052;6353.96823015882;6361.491080713625;6382.32985536512;5280.313559237428;5436.836894893159;6231.53263505248;6516.609003459807;6539.836504087436;5496.972515137399;5690.041178587194;5839.766409343749;6369.49044076482;6396.520260980538 05:32:15 INFO - PID 2753 | |5;yui.html;711.6022977995985;726.4116065986548;738.3613174603969;744.6053591596452;774.1368374262695;14989.400423983056;15396.615084622876;15505.224738763045;15707.528774136767;15775.763363549542;14733;15048.699026019473;15269.465568705098;15327.540173794803;15377.538673839803;934.4066517761237;934.7709811096291;955.5938726041439;959.4291396619001;962.1340793286064;679.9014142949269;698.8567343694556;704.2323866984985;843.9745708963608;961.5721004153155;1018.7300365403137;1034.4672493665769;1034.6171916390947;1035.8912314207014;1035.9689209323733;1026.8870424253325;1028.8353863381824;1029.3103620574195;1030.7732298894268;1032.2258306270298;526.210683974039;541.6506353402034;543.7471575717271;547.84386449862;762.6453699029922;12320.445579948835;12341.382930853422;15572.688546229125;16170.353185872551;16189.595260118473;12309.446074926649;14552.126872387691;14666.266686665624;14695.632609184855;14718.92640536801;10245.64140255095;11527.538898444052;11563.30620162793;11849.763004739856;11963.64109076729;2998.31038861061;3026.8940587079346;3059.877604895824;3121.890733824328;3157.842107894619;90946;104728;109480;112849;113171.43414282959;9136.269098472107;11038.006579407891;11297.943510282395;11476.655700329004;11783.234089784137;24965.750342496707;24983;24992.00031998736;24994.750052499607;24999.62500562493;7149.106361704787;7612.543247405063;7702.730404435818;7788;7942.166072562298;4017.1764788218347;4068.389741538793;4077.327241005201;4201.894952626148;4206.26390381682;4040.4343391925154;4163.604457576525;4165.10450253197;4170.207660544487;4176.081262122329;803.3452736020125;828.1387357148618;865.6191275838737;892.7544925145637;897.5198268926093;522.636767446621;528.6907159311769;537.7391964897022;563.706872426336;576.2393640394639;9385.343025988252;9474.289428292768;10140.239482038876;10212.285140040125;10236.948815256024;556.4379976223962;577.6765011593521;582.408855012163;609;614.536025300895;4540.762644672575;4658.161530924398;4664.160451118832;4754.359745887663;4774.9522504775205;81986.18013819786;87488.68766968501;94686;97414;101198.49400752949;74449;74580;75449.24550754532;75523.24476755272;75688;73179.82763102587;74418.62790686011;74742.62628686822;74914.12714682067;76360.61819690977;8532.744017679479;9146.588403521724;9236.676716314896;9310.301727370339;9511.809763804547;29153.85423072871;36557.45163822491;36950.26099477942;38038.23923521459;38223;48453.75773121112;49026;49161.754191228814;49476.50523494863;49766;11392.658220253406;11520.596779112691;11562.94218528902;11829.17195796286;12136.878631213927;12584.559540416041;12646.304453254792;12655.430505627453;13081.084324097223;13153.473861045451;11808.763824723286;11873.465694043616;12379.257244565355;12492.43784029739;12514.624561263177;11764.529418823151;11910.642898947555;11967.521299147937;12078.396080196131;12345.444454999726;11367.658970230907;11576.768464630828;11577.536898523964;11827.586034488751;12293.262404255773;8546.78633034179;8788;8802.207801297915;9031.412958157698;9352.625894964125;8668.97388467881;9529.761755956157;9684.56419461112;9693.6122555097;9696.806063878825 05:32:15 INFO - PID 2753 | __end_tp_report 05:32:15 INFO - PID 2753 | __start_cc_report 05:32:15 INFO - PID 2753 | _x_x_mozilla_cycle_collect,1491 05:32:15 INFO - PID 2753 | __end_cc_report 05:32:15 INFO - PID 2753 | __startTimestamp1490358735111__endTimestamp 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | ------- Summary: start ------- 05:32:15 INFO - PID 2753 | Number of tests: 6 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#0] dojo.html Cycles:165 Average:6107.17 Median:6316.62 stddev:716.16 (11.3%) stddev-sans-first:685.81 05:32:15 INFO - PID 2753 | Values: 3386.1 3417.6 3438.1 3450.3 3488.2 5979.6 6141.0 6165.2 6194.1 6208.7 5078.4 6109.2 6143.1 6173.8 6245.6 5331.6 5483.2 5516.0 5536.9 5545.4 5628.9 5648.4 5649.3 5650.5 5686.0 5444.2 5503.2 5516.8 5588.9 5592.3 4481.8 5523.6 5534.9 5537.1 5542.1 4555.4 5417.6 5467.2 5497.5 5498.8 6103.5 6111.4 6151.2 6175.6 6186.2 6541.5 6552.4 6556.9 6563.7 6563.9 6441.8 6666.2 6704.5 6766.3 6770.0 6133.1 6210.3 6219.5 6298.8 6312.7 6810.2 6859.0 6859.3 6860.9 6861.5 6289.0 6383.7 6387.4 6491.6 6506.5 6629.0 6696.5 6705.5 6706.7 6719.4 6353.9 6370.0 6372.4 6546.3 6551.4 6498.3 6550.7 6560.6 6570.5 6577.7 5324.0 5325.6 5332.3 5333.6 5351.4 4731.7 4734.5 4738.3 4742.8 4771.1 6055.3 6141.3 6151.8 6172.4 6274.0 6639.7 6699.3 6708.1 6710.5 6716.8 6211.2 6226.2 6259.2 6300.5 6307.0 5652.4 5712.9 5735.2 5828.4 5867.6 6719.3 6792.6 6795.7 6810.7 6812.0 6687.8 6700.6 6709.5 6731.9 6735.4 6669.3 6735.4 6794.4 6794.8 6797.5 6367.3 6513.0 6536.5 6557.5 6605.0 6408.1 6651.2 6661.2 6661.4 6682.4 6229.3 6316.6 6421.5 6456.6 6469.9 6433.7 6444.6 6464.0 6467.5 6472.1 6127.6 6211.4 6213.7 6247.1 6285.6 6422.2 6446.5 6475.2 6483.1 6487.1 6327.5 6328.1 6334.2 6337.3 6350.7 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#1] ext.html Cycles:180 Average:48449.99 Median:324799.69 stddev:93669.07 (28.8%) stddev-sans-first:93863.13 05:32:15 INFO - PID 2753 | Values: 665.8 741.3 801.5 1126.9 1144.6 23009.4 26123.7 27171.0 27761.4 36842.6 41594.0 41932.8 41984.8 42022.8 42149.8 8654.0 8855.4 8869.4 8886.2 8905.0 6068.6 6153.6 8364.0 8791.8 8860.9 8614.4 8649.0 8679.1 8700.7 8715.6 8543.2 8609.4 8615.7 8626.1 8632.1 5990.2 6114.2 8338.6 8397.2 8484.0 36734.6 37081.4 37196.9 37218.6 37245.8 3022.6 3027.1 3059.8 3075.6 3152.4 27276.3 27308.6 27522.4 27640.3 28430.6 2783.8 2786.3 2813.2 2828.8 2836.5 321525.0 321691.0 324648.4 324951.0 326899.4 48869.3 48904.0 48947.3 49096.8 49143.8 49966.8 50006.7 50012.0 50015.7 50021.2 14241.3 14524.7 14536.8 14547.3 14552.9 11907.2 11963.2 11974.3 11975.8 11982.1 9222.8 9773.2 10177.5 10200.8 10204.4 4630.0 5130.9 5455.4 5498.8 5504.4 883.6 982.0 988.9 1000.2 1019.6 21115.5 24459.8 24466.0 24508.3 24548.8 1022.7 1080.7 1122.3 1133.7 1141.3 9777.1 11478.6 11940.5 11975.2 12301.6 341260.3 343332.0 344920.0 345678.0 423757.0 51476.0 51606.2 51672.0 51835.2 51874.5 1460.6 1489.0 1505.1 1509.7 1510.6 26058.6 26539.6 27756.0 28378.9 28814.4 38115.6 38139.7 38157.8 38236.0 38237.0 371420.0 374882.0 375388.0 376910.1 378700.0 31832.5 32110.4 32153.4 32236.0 32325.0 30420.5 30860.8 32165.0 39116.0 42711.0 25040.4 25525.6 26614.2 27126.6 27596.3 26320.7 27392.0 28348.7 29104.7 29231.0 33344.7 33818.7 33933.2 33974.6 34007.7 24160.3 25107.0 27095.7 28576.1 28933.9 22888.4 22909.5 22939.8 23207.9 23369.4 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#2] jquery.html Cycles:180 Average:40496.23 Median:22855.61 stddev:118260.82 (517.4%) stddev-sans-first:118554.29 05:32:15 INFO - PID 2753 | Values: 419.4 420.7 428.2 436.8 446.0 18861.8 19974.9 20822.9 21230.4 21382.0 20624.0 21119.5 22017.7 22171.1 22266.2 6087.0 6344.8 6380.6 6387.4 6389.8 6012.2 6204.8 6222.4 6273.3 6301.5 6235.6 6245.3 6247.9 6251.1 6256.0 5957.9 6204.7 6263.0 6264.9 6272.1 4652.2 5390.7 5403.9 5512.1 5559.9 27060.5 27189.8 27605.8 27909.0 28014.6 2008.1 2014.1 2030.4 2053.2 2102.0 12087.9 15538.0 16184.8 16324.0 17027.6 1664.8 1681.4 1704.7 1720.2 1723.9 119439.0 126849.0 156295.2 158076.2 163436.0 11825.9 11984.1 12018.9 12057.5 12311.6 16263.8 16272.5 16296.9 16305.5 16335.2 8717.3 8907.4 8964.3 9011.8 9080.4 6953.2 7082.7 7130.4 8677.3 8721.0 6461.0 6697.9 7066.5 7186.2 7556.9 1729.9 1818.2 1830.8 2056.4 2210.6 901.2 907.9 926.8 946.0 965.8 18985.0 20804.0 23445.0 25408.9 25974.8 933.3 955.5 964.8 977.8 988.4 8025.4 10519.7 10714.8 10752.7 10782.4 601594.0 705120.0 722600.4 735598.0 750331.0 133104.0 162567.0 165112.0 167469.0 168279.0 1156.0 1193.6 1216.8 1223.9 1224.5 17663.3 18198.6 18242.0 18269.1 18278.3 52986.2 54811.7 59074.7 59796.8 62012.8 87376.6 88265.6 89126.6 89703.0 91502.0 8100.4 8225.8 8230.9 8734.3 8792.2 12272.4 13141.7 13390.8 13771.1 13824.9 10816.0 11562.2 11715.4 11813.6 11925.5 10619.8 10634.2 10828.0 10964.6 10997.6 11365.3 11702.2 11911.6 12033.3 12088.3 8308.1 8347.9 8602.0 8655.4 8796.2 11317.4 11433.8 11715.4 11731.5 11760.6 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#3] mootools.html Cycles:180 Average:6722.78 Median:3643.80 stddev:4215.82 (115.7%) stddev-sans-first:4207.25 05:32:15 INFO - PID 2753 | Values: 1204.5 1282.0 1437.8 1451.7 1452.0 9279.5 9692.8 9697.7 9705.7 9707.0 9436.5 10474.9 10803.6 10806.6 10808.6 2421.4 2451.2 2463.2 2469.7 2472.4 2380.7 2416.4 2461.6 2473.3 2479.3 2032.2 2135.4 2358.8 2446.2 2450.2 2434.8 2438.5 2442.0 2445.2 2453.5 2399.9 2421.5 2459.6 2463.7 2471.5 10853.2 11002.6 11002.9 11007.6 11073.3 5067.5 6390.3 6400.5 6406.3 6416.5 3732.0 3830.7 4056.5 4084.3 4084.9 1128.0 1184.2 1204.3 1211.4 1213.4 15338.5 15408.7 15437.2 15445.3 15455.6 4836.9 5151.0 5626.7 5702.1 5718.9 12753.8 12764.5 12771.9 12785.4 12787.7 6423.2 6447.1 6454.5 6456.3 6456.8 5226.0 5661.2 5730.6 5734.7 5885.9 4019.6 4171.9 4202.0 4212.2 4236.0 1062.4 1244.3 1257.8 1266.6 1272.9 753.4 758.9 775.3 776.6 790.9 5208.5 5849.6 5873.7 5935.2 6079.9 573.3 577.4 582.7 588.9 599.8 3357.5 3555.6 3986.6 4046.3 4137.6 11706.3 13316.5 13849.7 14141.9 14206.3 13476.9 14029.0 14045.6 14049.2 14053.4 10091.9 10331.8 10341.6 10342.3 10354.3 6520.8 6565.5 6728.4 6748.1 6760.6 12115.3 12175.6 12202.9 12463.1 12477.1 11125.1 11531.9 12490.6 12744.5 12821.1 8918.4 9004.4 9010.2 9027.3 9133.9 10126.5 10153.9 10251.9 10253.6 10260.9 8606.3 8656.3 8705.2 8768.1 8775.0 8413.5 8482.7 8487.1 8500.0 8589.9 7665.8 7844.3 7844.5 7850.6 7865.2 5480.9 5524.6 5616.4 5704.8 5732.5 5011.4 5013.4 5830.2 5866.7 6169.9 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#4] prototype.html Cycles:180 Average:5174.57 Median:5654.49 stddev:1610.92 (28.5%) stddev-sans-first:1573.91 05:32:15 INFO - PID 2753 | Values: 332.3 402.6 403.8 407.7 409.0 5978.5 6012.4 6049.2 6120.9 6133.4 5185.5 5657.1 5751.9 5784.0 5963.2 4640.3 4642.7 4679.9 4690.8 4693.9 4061.7 4670.6 4673.7 4682.7 4702.9 4250.4 4493.3 4508.1 4562.8 4662.3 3799.0 4493.1 4500.2 4518.1 4525.5 3894.5 3901.9 3924.2 3941.9 3943.4 5588.4 5879.5 5933.9 5985.3 5993.2 4606.5 5049.7 5145.3 5295.6 5315.0 5768.4 6319.8 6457.7 6533.9 6539.7 3417.8 4294.8 4351.6 4353.7 4381.1 5651.9 6531.9 6686.2 6835.4 6903.4 3643.5 4261.6 4295.0 4304.9 4322.1 3934.5 4363.6 4457.6 4492.3 4493.7 4784.5 4928.6 4984.5 5312.0 5325.2 5319.6 5586.0 6269.9 6531.8 6536.9 1497.1 1515.4 1532.2 1537.8 1541.7 962.8 998.5 1012.8 1013.4 1018.1 4935.0 4992.6 6042.2 6070.7 6082.2 5402.6 6236.9 6389.4 6749.2 6789.7 5041.5 5602.6 5756.4 6105.0 6171.0 2949.0 3444.4 3526.1 3526.2 3529.8 6476.5 6527.3 6661.2 6752.0 6817.6 5098.4 6249.7 6839.5 6874.9 6887.5 6405.4 6784.0 6806.4 6819.6 6914.7 5109.6 5564.8 6675.8 6780.5 6826.4 6080.7 6659.8 6679.0 6700.6 6725.7 5416.1 6607.3 6831.2 6913.7 7051.9 4388.2 4414.2 4423.9 4425.4 4461.0 6586.6 6648.9 6660.2 6760.3 6835.3 5920.0 6291.8 6356.1 6549.6 6554.7 5399.9 5827.6 6226.4 6489.4 6527.0 6315.3 6338.6 6354.0 6361.5 6382.3 5280.3 5436.8 6231.5 6516.6 6539.8 5497.0 5690.0 5839.8 6369.5 6396.5 05:32:15 INFO - PID 2753 | 05:32:15 INFO - PID 2753 | [#5] yui.html Cycles:180 Average:18581.23 Median:4120.47 stddev:26664.67 (647.1%) stddev-sans-first:26705.71 05:32:15 INFO - PID 2753 | Values: 711.6 726.4 738.4 744.6 774.1 14989.4 15396.6 15505.2 15707.5 15775.8 14733.0 15048.7 15269.5 15327.5 15377.5 934.4 934.8 955.6 959.4 962.1 679.9 698.9 704.2 844.0 961.6 1018.7 1034.5 1034.6 1035.9 1036.0 1026.9 1028.8 1029.3 1030.8 1032.2 526.2 541.7 543.7 547.8 762.6 12320.4 12341.4 15572.7 16170.4 16189.6 12309.4 14552.1 14666.3 14695.6 14718.9 10245.6 11527.5 11563.3 11849.8 11963.6 2998.3 3026.9 3059.9 3121.9 3157.8 90946.0 104728.0 109480.0 112849.0 113171.4 9136.3 11038.0 11297.9 11476.7 11783.2 24965.8 24983.0 24992.0 24994.8 24999.6 7149.1 7612.5 7702.7 7788.0 7942.2 4017.2 4068.4 4077.3 4201.9 4206.3 4040.4 4163.6 4165.1 4170.2 4176.1 803.3 828.1 865.6 892.8 897.5 522.6 528.7 537.7 563.7 576.2 9385.3 9474.3 10140.2 10212.3 10236.9 556.4 577.7 582.4 609.0 614.5 4540.8 4658.2 4664.2 4754.4 4775.0 81986.2 87488.7 94686.0 97414.0 101198.5 74449.0 74580.0 75449.2 75523.2 75688.0 73179.8 74418.6 74742.6 74914.1 76360.6 8532.7 9146.6 9236.7 9310.3 9511.8 29153.9 36557.5 36950.3 38038.2 38223.0 48453.8 49026.0 49161.8 49476.5 49766.0 11392.7 11520.6 11562.9 11829.2 12136.9 12584.6 12646.3 12655.4 13081.1 13153.5 11808.8 11873.5 12379.3 12492.4 12514.6 11764.5 11910.6 11967.5 12078.4 12345.4 11367.7 11576.8 11577.5 11827.6 12293.3 8546.8 8788.0 8802.2 9031.4 9352.6 8669.0 9529.8 9684.6 9693.6 9696.8 05:32:15 INFO - PID 2753 | -------- Summary: end -------- 05:32:15 INFO - PID 2753 | 05:32:15 INFO - TEST-INFO | 2753: exit 0 05:32:15 INFO - TEST-OK | dromaeo_css | took 1121597ms 05:32:15 INFO - TEST-START | kraken 05:32:15 INFO - operating with platform_type : mac_ 05:32:15 INFO - Initialising browser for kraken test... 05:32:15 INFO - TEST-INFO | started process 2757 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpGwIptT/profile http://localhost:49243/getInfo.html) 05:32:18 INFO - PID 2757 | __metrics Screen width/height:1600/1200 05:32:18 INFO - PID 2757 | colorDepth:24 05:32:18 INFO - PID 2757 | Browser inner width/height: 1024/689 05:32:18 INFO - PID 2757 | __metrics 05:32:18 INFO - PID 2757 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:32:18 INFO - PID 2757 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:19 INFO - TEST-INFO | 2757: exit 0 05:32:19 INFO - Browser initialized. 05:32:19 INFO - Running cycle 1/1 for kraken test... 05:32:19 INFO - TEST-INFO | started process 2758 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpGwIptT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:31 INFO - PID 2758 | RSS: Main: 233902080 05:32:31 INFO - PID 2758 | 05:32:34 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:34 INFO - PID 2758 | RSS: Main: 284942336 05:32:34 INFO - PID 2758 | 05:32:37 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:32:38 INFO - PID 2758 | RSS: Main: 285773824 05:32:38 INFO - PID 2758 | 05:32:41 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:32:42 INFO - PID 2758 | RSS: Main: 377102336 05:32:42 INFO - PID 2758 | 05:32:44 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:32:44 INFO - PID 2758 | RSS: Main: 282411008 05:32:44 INFO - PID 2758 | 05:32:46 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:47 INFO - PID 2758 | RSS: Main: 284651520 05:32:47 INFO - PID 2758 | 05:32:50 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:51 INFO - PID 2758 | RSS: Main: 327122944 05:32:51 INFO - PID 2758 | 05:32:54 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:32:54 INFO - PID 2758 | RSS: Main: 338018304 05:32:54 INFO - PID 2758 | 05:32:57 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:32:58 INFO - PID 2758 | RSS: Main: 347602944 05:32:58 INFO - PID 2758 | 05:32:59 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:00 INFO - PID 2758 | RSS: Main: 301395968 05:33:00 INFO - PID 2758 | 05:33:02 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:03 INFO - PID 2758 | RSS: Main: 304730112 05:33:03 INFO - PID 2758 | 05:33:05 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:06 INFO - PID 2758 | RSS: Main: 306372608 05:33:06 INFO - PID 2758 | 05:33:08 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:09 INFO - PID 2758 | RSS: Main: 326832128 05:33:09 INFO - PID 2758 | 05:33:12 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:13 INFO - PID 2758 | RSS: Main: 320147456 05:33:13 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:33:15 INFO - PID 2758 | RSS: Main: 382775296 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | __start_tp_report 05:33:15 INFO - PID 2758 | _x_x_mozilla_page_load 05:33:15 INFO - PID 2758 | _x_x_mozilla_page_load_details 05:33:15 INFO - PID 2758 | |i|pagename|runs| 05:33:15 INFO - PID 2758 | |0;ai-astar;113;112;110;111;112;111;113;112;111;112 05:33:15 INFO - PID 2758 | |1;audio-beat-detection;151;124;146;181;112;109;112;114;132;112 05:33:15 INFO - PID 2758 | |2;audio-dft;186;183;186;182;187;184;184;184;183;185 05:33:15 INFO - PID 2758 | |3;audio-fft;80;60;60;94;61;59;62;59;79;59 05:33:15 INFO - PID 2758 | |4;audio-oscillator;91;70;91;69;69;69;83;69;70;85 05:33:15 INFO - PID 2758 | |5;imaging-gaussian-blur;87;87;86;87;87;86;87;86;87;86 05:33:15 INFO - PID 2758 | |6;imaging-darkroom;94;94;94;95;95;95;98;95;94;96 05:33:15 INFO - PID 2758 | |7;imaging-desaturate;83;83;83;84;87;84;86;83;87;84 05:33:15 INFO - PID 2758 | |8;json-parse-financial;61;61;61;60;61;62;65;62;61;60 05:33:15 INFO - PID 2758 | |9;json-stringify-tinderbox;54;58;64;71;90;50;55;84;83;84 05:33:15 INFO - PID 2758 | |10;stanford-crypto-aes;83;84;98;109;105;82;82;81;82;81 05:33:15 INFO - PID 2758 | |11;stanford-crypto-ccm;146;147;137;148;136;141;142;137;187;132 05:33:15 INFO - PID 2758 | |12;stanford-crypto-pbkdf2;186;217;243;185;188;185;181;181;185;183 05:33:15 INFO - PID 2758 | |13;stanford-crypto-sha256-iterative;68;67;66;67;67;67;68;67;67;82 05:33:15 INFO - PID 2758 | __end_tp_report 05:33:15 INFO - PID 2758 | __start_cc_report 05:33:15 INFO - PID 2758 | _x_x_mozilla_cycle_collect,2162 05:33:15 INFO - PID 2758 | __end_cc_report 05:33:15 INFO - PID 2758 | __startTimestamp1490358795580__endTimestamp 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | ------- Summary: start ------- 05:33:15 INFO - PID 2758 | Number of tests: 14 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:0.95 (0.8%) stddev-sans-first:0.88 05:33:15 INFO - PID 2758 | Values: 113.0 112.0 110.0 111.0 112.0 111.0 113.0 112.0 111.0 112.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:128.00 stddev:23.58 (18.4%) stddev-sans-first:23.66 05:33:15 INFO - PID 2758 | Values: 151.0 124.0 146.0 181.0 112.0 109.0 112.0 114.0 132.0 112.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.50 stddev:1.58 (0.9%) stddev-sans-first:1.56 05:33:15 INFO - PID 2758 | Values: 186.0 183.0 186.0 182.0 187.0 184.0 184.0 184.0 183.0 185.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#3] audio-fft Cycles:10 Average:67.30 Median:61.50 stddev:12.44 (20.2%) stddev-sans-first:12.31 05:33:15 INFO - PID 2758 | Values: 80.0 60.0 60.0 94.0 61.0 59.0 62.0 59.0 79.0 59.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:76.50 stddev:9.69 (12.7%) stddev-sans-first:8.76 05:33:15 INFO - PID 2758 | Values: 91.0 70.0 91.0 69.0 69.0 69.0 83.0 69.0 70.0 85.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 05:33:15 INFO - PID 2758 | Values: 87.0 87.0 86.0 87.0 87.0 86.0 87.0 86.0 87.0 86.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.25 (1.3%) stddev-sans-first:1.27 05:33:15 INFO - PID 2758 | Values: 94.0 94.0 94.0 95.0 95.0 95.0 98.0 95.0 94.0 96.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.65 (2.0%) stddev-sans-first:1.67 05:33:15 INFO - PID 2758 | Values: 83.0 83.0 83.0 84.0 87.0 84.0 86.0 83.0 87.0 84.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:1.43 (2.3%) stddev-sans-first:1.51 05:33:15 INFO - PID 2758 | Values: 61.0 61.0 61.0 60.0 61.0 62.0 65.0 62.0 61.0 60.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:77.00 stddev:14.97 (19.4%) stddev-sans-first:14.82 05:33:15 INFO - PID 2758 | Values: 54.0 58.0 64.0 71.0 90.0 50.0 55.0 84.0 83.0 84.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:82.00 stddev:10.91 (13.3%) stddev-sans-first:11.38 05:33:15 INFO - PID 2758 | Values: 83.0 84.0 98.0 109.0 105.0 82.0 82.0 81.0 82.0 81.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:144.00 stddev:15.56 (10.8%) stddev-sans-first:16.51 05:33:15 INFO - PID 2758 | Values: 146.0 147.0 137.0 148.0 136.0 141.0 142.0 137.0 187.0 132.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:185.50 stddev:20.35 (11.0%) stddev-sans-first:21.41 05:33:15 INFO - PID 2758 | Values: 186.0 217.0 243.0 185.0 188.0 185.0 181.0 181.0 185.0 183.0 05:33:15 INFO - PID 2758 | 05:33:15 INFO - PID 2758 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:67.00 stddev:4.74 (7.1%) stddev-sans-first:5.02 05:33:15 INFO - PID 2758 | Values: 68.0 67.0 66.0 67.0 67.0 67.0 68.0 67.0 67.0 82.0 05:33:15 INFO - PID 2758 | -------- Summary: end -------- 05:33:15 INFO - PID 2758 | 05:33:16 INFO - TEST-INFO | 2758: exit 0 05:33:16 INFO - TEST-OK | kraken | took 60839ms 05:33:16 INFO - SUITE-END | took 1182s 05:33:16 INFO - Completed test suite (00:19:42) 05:33:16 INFO - javascript benchmark 05:33:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7491.310572874266, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.102682789061, 3417.6411476794938, 3438.1404648837793, 3450.309938012397, 3488.197714525659, 5979.611325263855, 6140.98673718837, 6165.229346331708, 6194.070889366597, 6208.720607572659, 5078.415982162052, 6109.1752613397175, 6143.139960405536, 6173.753049878, 6245.594036387632, 5331.600129990247, 5483.232347471338, 5516.007118718629, 5536.916946245809, 5545.390007099216, 5628.887422251552, 5648.378678345379, 5649.265595472582, 5650.463205995424, 5686.0, 5444.156155795858, 5503.229547863302, 5516.7517461714315, 5588.86027849307, 5592.3009623796825, 4481.820727170909, 5523.6409633373705, 5534.861628459281, 5537.086380747212, 5542.057850165441, 4555.430571178603, 5417.647852889589, 5467.17992301154, 5497.47773961473, 5498.752556134944, 6103.542234332398, 6111.448149658388, 6151.169592105053, 6175.5985860919345, 6186.1648677428875, 6541.542092053558, 6552.410283074546, 6556.9016464752585, 6563.671816409161, 6563.868722625521, 6441.774537891199, 6666.200055993263, 6704.5306828522025, 6766.3233676632435, 6769.966150169218, 6133.101782541855, 6210.31686514483, 6219.502439804804, 6298.842528936813, 6312.715927783261, 6810.182778066664, 6859.039734437183, 6859.279775623488, 6860.931390686029, 6861.4853885957755, 6288.968555157195, 6383.71273292703, 6387.393197646216, 6491.578047426902, 6506.544541882023, 6629.0, 6696.497762667819, 6705.530612857053, 6706.731730730716, 6719.42884854783, 6353.904691429632, 6370.0, 6372.362763723683, 6546.312637173029, 6551.377619126175, 6498.317676643885, 6550.672466376662, 6560.573562718408, 6570.474362051024, 6577.704003319862, 5324.0, 5325.600579956519, 5332.253484512094, 5333.57331413486, 5351.411344752074, 4731.668783185213, 4734.479207287196, 4738.336632871401, 4742.762861856962, 4771.093492236464, 6055.333913269536, 6141.271221159771, 6151.7539298428455, 6172.4136207060255, 6274.0, 6639.701213445406, 6699.330066993261, 6708.060871477997, 6710.530262881649, 6716.832079197961, 6211.192544969125, 6226.221722284715, 6259.1550140730415, 6300.527460440394, 6307.022411526221, 5652.406497317805, 5712.885742285214, 5735.225744524436, 5828.417158284223, 5867.618604790675, 6719.294474080151, 6792.592444453348, 6795.728170873307, 6810.693518791567, 6812.046313516013, 6687.799366019027, 6700.631465269457, 6709.530332876847, 6731.932680673326, 6735.393814556714, 6669.266380698216, 6735.360140786617, 6794.3545363190415, 6794.796156115324, 6797.456203503807, 6367.299597044414, 6512.967435162794, 6536.542442029012, 6557.508186886098, 6605.04226887089, 6408.102865598913, 6651.235107962452, 6661.233958094687, 6661.367170118831, 6682.398584127452, 6229.2524897012645, 6316.62100273985, 6421.518386120966, 6456.612603243821, 6469.870602587827, 6433.678316084271, 6444.581102228341, 6464.0, 6467.547271690936, 6472.093906853139, 6127.556056270569, 6211.440970312694, 6213.720382582705, 6247.0629405589525, 6285.5600107992, 6422.165118534561, 6446.548741588043, 6475.190601174853, 6483.059956306403, 6487.059376390492, 6327.462165715966, 6328.114064030948, 6334.239891213084, 6337.302896681454, 6350.7459701611415], "value": 6058.498621007175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8002599220234, 741.280957471253, 801.4550105927966, 1126.8760436352004, 1144.5936692474165, 23009.424764380878, 26123.693815309252, 27171.0, 27761.444771104565, 36842.63157368425, 41593.96015099624, 41932.790336048354, 41984.790076049656, 42022.78988605053, 42149.78925105378, 8653.96152461704, 8855.42439741418, 8869.37914345996, 8886.155815197546, 8905.0, 6068.57519973602, 6153.630782153063, 8363.992641765963, 8791.780205494882, 8860.911390886105, 8614.396992210548, 8648.956755216215, 8679.088695686925, 8700.651973921036, 8715.607797649121, 8543.231109200171, 8609.39734218605, 8615.65537378504, 8626.137386261387, 8632.136786321349, 5990.161377407167, 6114.2357205349335, 8338.583070846433, 8397.160283971614, 8484.0, 36734.63265367339, 37081.443778343346, 37196.88409347724, 37218.62781372179, 37245.813770930974, 3022.561728549348, 3027.1070034339923, 3059.8011129276747, 3075.5847960525266, 3152.448321543721, 27276.31809204766, 27308.590371144448, 27522.449551009067, 27640.308992275153, 28430.573541396894, 2783.805133640646, 2786.3452088759127, 2813.170114816134, 2828.78784091194, 2836.5319722245804, 321525.0, 321691.0, 324648.3767581147, 324950.9999999976, 326899.36550317093, 48869.266960995614, 48904.0, 48947.265791012454, 49096.75451622719, 49143.75428122908, 49966.75016624894, 50006.74996625067, 50012.0, 50015.74992125089, 50021.249681254085, 14241.287935603179, 14524.709505810037, 14536.781948270784, 14547.27263636835, 14552.927235363968, 11907.166498345206, 11963.162578619587, 11974.341411222467, 11975.820362694567, 11982.101342399355, 9222.801035865294, 9773.169280611228, 10177.54201060954, 10200.846987295197, 10204.43875586835, 4630.0, 5130.948690513122, 5455.399906010336, 5498.780048798003, 5504.366997795305, 883.6377085394957, 981.9558119884622, 988.9406635601887, 1000.1881542072294, 1019.5513973851481, 21115.52191346591, 24459.755402446102, 24466.021359145434, 24508.26475205747, 24548.754512455005, 1022.7136401807505, 1080.6920027792198, 1122.337820685783, 1133.6825688807148, 1141.2809929744374, 9777.120059194707, 11478.622565292051, 11940.522379104912, 11975.221610595283, 12301.575438701399, 341260.29369852995, 343332.0, 344920.0, 345678.0, 423757.0, 51476.0, 51606.22590661143, 51672.0, 51835.22247166296, 51874.48125518772, 1460.6202387379358, 1489.0470099136778, 1505.1345476351141, 1509.65277986066, 1510.61479322772, 26058.60912086358, 26539.601905971813, 27756.028539001847, 28378.85810570934, 28814.423711526073, 38115.61884381121, 38139.665111720955, 38157.80921095377, 38236.0, 38237.0, 371420.0, 374882.0, 375388.0, 376910.115449421, 378700.0, 31832.46805810286, 32110.357792843544, 32153.35693286168, 32236.03291901247, 32324.95938007749, 30420.543691844196, 30860.84569577138, 32165.035048948568, 39116.0, 42710.95269280658, 25040.37399065038, 25525.61711574291, 26614.201573952814, 27126.593101103106, 27596.31009224705, 26320.73679263183, 27392.0, 28348.716512834606, 29104.7089529102, 29231.0, 33344.66655333416, 33818.66181338155, 33933.15167120743, 33974.64101435915, 34007.659923400446, 24160.275191744277, 25106.995720170988, 27095.72904271011, 28576.14271571856, 28933.855330724055, 22888.427789305402, 22909.54180916339, 22939.78108542225, 23207.88396058009, 23369.41576460602], "value": 16843.353192837792, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.4377267230955, 420.67818119138883, 428.2014043808297, 436.8274531560033, 446.0209839402511, 18861.81138188618, 19974.90012549937, 20822.89588552057, 21230.36308910731, 21382.0, 20624.0, 21119.472013199676, 22017.66973495399, 22171.113155473802, 22266.22068227612, 6086.969565152168, 6344.809655710336, 6380.553361264713, 6387.425131738142, 6389.8402539936405, 6012.248468941382, 6204.844878878019, 6222.408871157256, 6273.309935907047, 6301.495880329563, 6235.594686345373, 6245.313015568284, 6247.937520624781, 6251.12484252205, 6256.0, 5957.851053723648, 6204.6587437690905, 6263.029230469285, 6264.906026409608, 6272.090546870701, 4652.185867473178, 5390.703511306877, 5403.891922161574, 5512.118061110203, 5559.916601250984, 27060.45879082427, 27189.776460059344, 27605.757740901707, 27909.0, 28014.579781303295, 2008.1365013044385, 2014.113789932425, 2030.4314791858303, 2053.16846677096, 2102.043570175582, 12087.879121208764, 15538.0, 16184.75722864158, 16324.0, 17027.574310642332, 1664.8418400251958, 1681.3946979087518, 1704.6675898199856, 1720.1657196259805, 1723.896566206019, 119439.0, 126849.0, 156295.2185239078, 158076.20961895233, 163436.0, 11825.88174118265, 11984.06788639629, 12018.87981120195, 12057.51769929193, 12311.630651080482, 16263.756043659354, 16272.51182464528, 16296.918515407584, 16305.510834674978, 16335.18324083791, 8717.30261579072, 8907.421017633833, 8964.327675424369, 9011.774705632411, 9080.364374493854, 6953.192170035827, 7082.716691332289, 7130.358267755927, 8677.262432693165, 8721.0, 6461.0, 6697.866042679217, 7066.505344625827, 7186.245444228385, 7556.88407245972, 1729.9394521191698, 1818.1727314072066, 1830.8099735172245, 2056.352249041547, 2210.602091623491, 901.1889299630254, 907.8592818113203, 926.8146370725882, 945.9716208513755, 965.8358079126565, 18985.0, 20803.95980200984, 23445.0, 25408.872955635103, 25974.83113259908, 933.3419938943028, 955.527014128011, 964.8022155458115, 977.750673578233, 988.3822610868208, 8025.357971362277, 10519.737006574744, 10714.839277410845, 10752.677419677422, 10782.353058816496, 601594.0, 705120.0, 722600.3869980617, 735598.0, 750331.0, 133104.0, 162567.0, 165112.0, 167469.0, 168279.0, 1155.9897485845195, 1193.6359410379916, 1216.8357271768375, 1223.9326837024228, 1224.5224362498632, 17663.293468261127, 18198.636027279117, 18242.0, 18269.08654567293, 18278.268869245083, 52986.20520692116, 54811.72594137004, 59074.704626476596, 59796.80406391935, 62012.75974480576, 87376.563117184, 88265.55867220623, 89126.55436722774, 89703.0, 91502.0, 8100.432969692065, 8225.83548329042, 8230.897875529266, 8734.257588105082, 8792.164744349277, 12272.447739851907, 13141.671458213621, 13390.799138013132, 13771.104878182887, 13824.930875345559, 10816.0, 11562.19064665499, 11715.414229288672, 11813.586524471602, 11925.463354149259, 10619.840702389316, 10634.202434817265, 10828.025477707044, 10964.616238431936, 10997.61508347204, 11365.31808091517, 11702.206665049122, 11911.583094591648, 12033.338166400932, 12088.274703517816, 8308.12764659702, 8347.874781878398, 8601.956990215009, 8655.394122411371, 8796.208341249318, 11317.434128293717, 11433.828492572451, 11715.355655439032, 11731.472083756422, 11760.647180584596], "value": 10266.427517010618, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1204.4640135139866, 1281.9871801281988, 1437.7843323501475, 1451.6661167931368, 1452.0343971259113, 9279.489628070454, 9692.806143877118, 9697.660581879645, 9705.70882873513, 9706.951465242673, 9436.52817359131, 10474.947625261862, 10803.5678572857, 10806.621768238114, 10808.621698240564, 2421.418859473727, 2451.178855083549, 2463.1502131764514, 2469.7036355637347, 2472.3571871313497, 2380.6609247462397, 2416.359664688859, 2461.5569197544437, 2473.282748003077, 2479.256223133053, 2032.1668116072412, 2135.4447843560706, 2358.7759162879584, 2446.156076153724, 2450.2036838027625, 2434.75652434756, 2438.500107477963, 2441.9633705494252, 2445.2419749877417, 2453.521563295158, 2399.8920048597856, 2421.5278020785954, 2459.594166962449, 2463.7043554773363, 2471.4933438645035, 10853.186011049203, 11002.559897604086, 11002.899710029013, 11007.63335882781, 11073.335599864035, 5067.505085999639, 6390.329015453357, 6400.487960963112, 6406.327335629739, 6416.48668106555, 3732.0, 3830.6552410283207, 4056.4523789288364, 4084.346504559256, 4084.9387259191135, 1127.9830802537967, 1184.1533303687904, 1204.3255776764952, 1211.4185191108318, 1213.3690480949856, 15338.539843804703, 15408.691826163526, 15437.150956697376, 15445.30496127677, 15455.613609659737, 4836.903261934742, 5151.0470562945975, 5626.746796394172, 5702.144678298206, 5718.885622287531, 12753.808692869614, 12764.489420423264, 12771.936140319425, 12785.360731963365, 12787.744245115045, 6423.164988551458, 6447.129637498878, 6454.483641308684, 6456.289808121103, 6456.773213089498, 5225.9738701307015, 5661.1508273758445, 5730.627509211971, 5734.713264336767, 5885.911711324334, 4019.638692019192, 4171.91656166875, 4201.957980420218, 4212.220739163265, 4235.957640423618, 1062.3997441445558, 1244.2969722107161, 1257.787299007647, 1266.6326765237977, 1272.8790764877322, 753.3596443023387, 758.9127250366279, 775.25962705616, 776.6000509737489, 790.8917952446824, 5208.5051920067535, 5849.64902105875, 5873.678422354936, 5935.228420305419, 6079.939200607937, 573.2862586080273, 577.3504807092022, 582.7238348017827, 588.9175515427844, 599.7690889007763, 3357.529945807589, 3555.6088830228655, 3986.5614782373914, 4046.2716710991717, 4137.565555616678, 11706.297622142529, 13316.467341306434, 13849.653758656113, 14141.929290353482, 14206.289685515683, 13476.921846252277, 14029.0, 14045.578632641038, 14049.22729249901, 14053.437862485385, 10091.949540252252, 10331.793364132674, 10341.586336546605, 10342.276040677081, 10354.275200735876, 6520.771772987922, 6565.507586931095, 6728.428083612947, 6748.0552722617895, 6760.628165450948, 12115.273083615011, 12175.57385491539, 12202.877971220529, 12463.127581069239, 12477.126601137834, 11125.109991200523, 11531.884681153417, 12490.562830300896, 12744.49022039108, 12821.102522823312, 8918.420302680304, 9004.41471304363, 9010.189082982562, 9027.322950778587, 9133.908660913306, 10126.544305506417, 10153.898461015295, 10251.94874025655, 10253.589856405959, 10260.897391025994, 8606.311495080254, 8656.307495400228, 8705.216530512283, 8768.123187681182, 8774.956125219333, 8413.537255451009, 8482.703105391281, 8487.066422693622, 8499.95750021246, 8589.914100858912, 7665.808354791175, 7844.254795794391, 7844.450888437755, 7850.568218748023, 7865.213478652089, 5480.890382192413, 5524.563613460449, 5616.354119276172, 5704.828855134353, 5732.455416735404, 5011.373578302712, 5013.39839219296, 5830.154627579062, 5866.67733274674, 6169.907451388143], "value": 5060.429326271565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.2606156769313, 402.63360342088686, 403.7516927089837, 407.72682302857083, 408.9550149483558, 5978.491828194603, 6012.428819262163, 6049.2136022317145, 6120.938790612098, 6133.417325354089, 5185.481451854813, 5657.094864821619, 5751.884962300752, 5784.0, 5963.16515687803, 4640.283095254752, 4642.65180111493, 4679.906401871961, 4690.835820746274, 4693.88265293367, 4061.715679902408, 4670.626349892001, 4673.742944138071, 4682.672212945095, 4702.90594188116, 4250.404943307939, 4493.3484644726495, 4508.063670281037, 4562.84030058948, 4662.2773470112015, 3798.955287295988, 4493.123840851035, 4500.212462818994, 4518.051209246029, 4525.502194758574, 3894.5338648602765, 3901.941470877939, 3924.2151569685893, 3941.9014524636827, 3943.369060950263, 5588.413216612238, 5879.470847623692, 5933.910991335133, 5985.34161242263, 5993.160957465958, 4606.470255920579, 5049.671771334888, 5145.279660847485, 5295.576353891679, 5315.0, 5768.3654797972185, 6319.778807741753, 6457.709403076873, 6533.901991470132, 6539.678414162999, 3417.79493230407, 4294.7852607369505, 4351.630111440531, 4353.651707863395, 4381.123775244963, 5651.886962260814, 6531.869362612769, 6686.231083425371, 6835.350641689031, 6903.413209877166, 3643.4899114124046, 4261.595148460891, 4294.981358761377, 4304.870853874389, 4322.113966636832, 3934.498296516103, 4363.6072753452345, 4457.621102206349, 4492.303692927601, 4493.73037617744, 4784.473707892129, 4928.619986403813, 4984.451710311862, 5311.990721762852, 5325.227841962894, 5319.601029922694, 5585.972070139624, 6269.8746025079245, 6531.804045878631, 6536.934630653633, 1497.1466264229502, 1515.4241388272385, 1532.1726267815477, 1537.8231503377256, 1541.6916616676485, 962.8026254617787, 998.5072316886301, 1012.7569383347943, 1013.4324778124127, 1018.1447584029451, 4935.037667654773, 4992.625553083533, 6042.244719410074, 6070.666113363719, 6082.239720034996, 5402.567794576425, 6236.875262494725, 6389.361063893573, 6749.190097188369, 6789.6605169741315, 5041.470645582161, 5602.607817452739, 5756.445030545544, 6105.0, 6170.969145154246, 2949.0388891387415, 3444.397230484655, 3526.0606666333415, 3526.2242306692488, 3529.841157147883, 6476.481881449473, 6527.294191485205, 6661.2006559213205, 6752.0, 6817.5568588041615, 5098.388193416814, 6249.7187626556915, 6839.487038472035, 6874.900015997517, 6887.5178737487895, 6405.391487808651, 6784.0, 6806.421454176351, 6819.567890742999, 6914.654267286615, 5109.591232701449, 5564.777408903598, 6675.833104172434, 6780.491218249279, 6826.368246196808, 6080.72636731357, 6659.800205993828, 6679.0, 6700.631465269457, 6725.720309556974, 5416.127468859784, 6607.306232845482, 6831.248562658005, 6913.723451062102, 7051.942208668741, 4388.222769778245, 4414.183376075436, 4423.894026493376, 4425.380446737395, 4461.0, 6586.562971700501, 6648.867022659617, 6660.167479065117, 6760.256371799196, 6835.316468353125, 5920.0, 6291.842703932438, 6356.110144579855, 6549.574277671936, 6554.705038273194, 5399.865003374947, 5827.59206855516, 6226.377362263737, 6489.351064893662, 6526.967365163144, 6315.336889626523, 6338.58799178052, 6353.96823015882, 6361.491080713625, 6382.32985536512, 5280.313559237428, 5436.836894893159, 6231.53263505248, 6516.609003459807, 6539.836504087436, 5496.972515137399, 5690.041178587194, 5839.766409343749, 6369.49044076482, 6396.520260980538], "value": 4677.121426767782, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.6022977995985, 726.4116065986548, 738.3613174603969, 744.6053591596452, 774.1368374262695, 14989.400423983056, 15396.615084622876, 15505.224738763045, 15707.528774136767, 15775.763363549542, 14733.0, 15048.699026019473, 15269.465568705098, 15327.540173794803, 15377.538673839803, 934.4066517761237, 934.7709811096291, 955.5938726041439, 959.4291396619001, 962.1340793286064, 679.9014142949269, 698.8567343694556, 704.2323866984985, 843.9745708963608, 961.5721004153155, 1018.7300365403137, 1034.4672493665769, 1034.6171916390947, 1035.8912314207014, 1035.9689209323733, 1026.8870424253325, 1028.8353863381824, 1029.3103620574195, 1030.7732298894268, 1032.2258306270298, 526.210683974039, 541.6506353402034, 543.7471575717271, 547.84386449862, 762.6453699029922, 12320.445579948835, 12341.382930853422, 15572.688546229125, 16170.353185872551, 16189.595260118473, 12309.446074926649, 14552.126872387691, 14666.266686665624, 14695.632609184855, 14718.92640536801, 10245.64140255095, 11527.538898444052, 11563.30620162793, 11849.763004739856, 11963.64109076729, 2998.31038861061, 3026.8940587079346, 3059.877604895824, 3121.890733824328, 3157.842107894619, 90946.0, 104728.0, 109480.0, 112849.0, 113171.43414282959, 9136.269098472107, 11038.006579407891, 11297.943510282395, 11476.655700329004, 11783.234089784137, 24965.750342496707, 24983.0, 24992.00031998736, 24994.750052499607, 24999.62500562493, 7149.106361704787, 7612.543247405063, 7702.730404435818, 7788.0, 7942.166072562298, 4017.1764788218347, 4068.389741538793, 4077.327241005201, 4201.894952626148, 4206.26390381682, 4040.4343391925154, 4163.604457576525, 4165.10450253197, 4170.207660544487, 4176.081262122329, 803.3452736020125, 828.1387357148618, 865.6191275838737, 892.7544925145637, 897.5198268926093, 522.636767446621, 528.6907159311769, 537.7391964897022, 563.706872426336, 576.2393640394639, 9385.343025988252, 9474.289428292768, 10140.239482038876, 10212.285140040125, 10236.948815256024, 556.4379976223962, 577.6765011593521, 582.408855012163, 609.0, 614.536025300895, 4540.762644672575, 4658.161530924398, 4664.160451118832, 4754.359745887663, 4774.9522504775205, 81986.18013819786, 87488.68766968501, 94686.0, 97414.0, 101198.49400752949, 74449.0, 74580.0, 75449.24550754532, 75523.24476755272, 75688.0, 73179.82763102587, 74418.62790686011, 74742.62628686822, 74914.12714682067, 76360.61819690977, 8532.744017679479, 9146.588403521724, 9236.676716314896, 9310.301727370339, 9511.809763804547, 29153.85423072871, 36557.45163822491, 36950.26099477942, 38038.23923521459, 38223.0, 48453.75773121112, 49026.0, 49161.754191228814, 49476.50523494863, 49766.0, 11392.658220253406, 11520.596779112691, 11562.94218528902, 11829.17195796286, 12136.878631213927, 12584.559540416041, 12646.304453254792, 12655.430505627453, 13081.084324097223, 13153.473861045451, 11808.763824723286, 11873.465694043616, 12379.257244565355, 12492.43784029739, 12514.624561263177, 11764.529418823151, 11910.642898947555, 11967.521299147937, 12078.396080196131, 12345.444454999726, 11367.658970230907, 11576.768464630828, 11577.536898523964, 11827.586034488751, 12293.262404255773, 8546.78633034179, 8788.0, 8802.207801297915, 9031.412958157698, 9352.625894964125, 8668.97388467881, 9529.761755956157, 9684.56419461112, 9693.6122555097, 9696.806063878825], "value": 7127.485755844958, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1461.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 110.0, 111.0, 112.0, 111.0, 113.0, 112.0, 111.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 146.0, 181.0, 112.0, 109.0, 112.0, 114.0, 132.0, 112.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 186.0, 182.0, 187.0, 184.0, 184.0, 184.0, 183.0, 185.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 94.0, 61.0, 59.0, 62.0, 59.0, 79.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 91.0, 69.0, 69.0, 69.0, 83.0, 69.0, 70.0, 85.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 87.0, 87.0, 86.0, 87.0, 86.0, 87.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 98.0, 95.0, 94.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 84.0, 87.0, 84.0, 86.0, 83.0, 87.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 61.0, 62.0, 65.0, 62.0, 61.0, 60.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 64.0, 71.0, 90.0, 50.0, 55.0, 84.0, 83.0, 84.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 98.0, 109.0, 105.0, 82.0, 82.0, 81.0, 82.0, 81.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 137.0, 148.0, 136.0, 141.0, 142.0, 137.0, 187.0, 132.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 217.0, 243.0, 185.0, 188.0, 185.0, 181.0, 181.0, 185.0, 183.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 82.0], "value": 68.6, "unit": "score"}]}]} 05:33:16 INFO - Return code: 0 05:33:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:33:16 ERROR - # TBPL SUCCESS # 05:33:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:16 INFO - 2017-03-24 05:33:16.862 screenresolution[2762:44411] starting screenresolution argv=screenresolution get 05:33:16 INFO - 2017-03-24 05:33:16.865 screenresolution[2762:44411] Display 0: 1600x1200x32@60 05:33:16 INFO - 2017-03-24 05:33:16.871 screenresolution[2763:44414] starting screenresolution argv=screenresolution list 05:33:16 INFO - Available Modes on Display 0 05:33:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:17 INFO - Intel Iris: 05:33:17 INFO - Chipset Model: Intel Iris 05:33:17 INFO - Type: GPU 05:33:17 INFO - Bus: Built-In 05:33:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:17 INFO - Vendor: Intel (0x8086) 05:33:17 INFO - Device ID: 0x0a2e 05:33:17 INFO - Revision ID: 0x0009 05:33:17 INFO - Displays: 05:33:17 INFO - Display: 05:33:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:17 INFO - Display Serial Number: 1600x1200 60 05:33:17 INFO - Main Display: Yes 05:33:17 INFO - Mirror: Off 05:33:17 INFO - Online: Yes 05:33:17 INFO - Rotation: Supported 05:33:17 INFO - Return code: 0 05:33:17 INFO - Running post-action listener: _resource_record_post_action 05:33:17 INFO - [mozharness: 2017-03-24 12:33:17.121668Z] Finished run-tests step (success) 05:33:17 INFO - Running post-run listener: _resource_record_post_run 05:33:17 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 16384; Write bytes: 515474432; Read time: 1; Write time: 1617 05:33:17 INFO - TinderboxPrint: CPU usage
53.3% 05:33:17 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 1 05:33:17 INFO - TinderboxPrint: I/O write bytes / time
515,474,432 / 1,617 05:33:17 INFO - TinderboxPrint: CPU idle
2,248.2 (46.7%) 05:33:17 INFO - TinderboxPrint: CPU system
958.9 (19.9%) 05:33:17 INFO - TinderboxPrint: CPU user
1,606.5 (33.4%) 05:33:17 INFO - TinderboxPrint: Swap in / out
210,399,232 / 0 05:33:17 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206297600; Write bytes: 204892160; Read time: 15758; Write time: 412 05:33:17 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 16384; Write bytes: 299740160; Read time: 1; Write time: 1174 05:33:17 INFO - Running post-run listener: _upload_blobber_files 05:33:17 INFO - Blob upload gear active. 05:33:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:33:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:33:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:33:18 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:19 INFO - (blobuploader) - INFO - Done attempting. 05:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:20 INFO - (blobuploader) - INFO - Done attempting. 05:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:22 INFO - (blobuploader) - INFO - Done attempting. 05:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:33:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:24 INFO - (blobuploader) - INFO - Done attempting. 05:33:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:24 INFO - Return code: 0 05:33:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/524d89a15f81f91b143ffc2d4ac6ace6d50bea0fb2ef55636b60a85a53469d2e080542002ee607b1b1cbd814f9b3b9f9bddd2fe9fa617e51245072f99f3d7375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0472db747d4fc72c4a712bded08e548a8a9c9c86efc615571d3fad34802a95b1c156197a77e8b6871c0808c1a26cbf76c7366c150801702424a65b73db3642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5e450d554dd3166549d14a497a21372d08834444dbba7e136329a8faf7aa96496b3a348caa5f2dd32d9e723df606d1282fbdf5b8a02dd061b3e3991d387b08b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9585959482c2d8ebc3a0ee23982614646d421614b5e0645f4c6b440c43a5ef358b27ff5ce6e372bfd4b518939db46868a00a56d0a0d75ce15835f234829ad5af"} 05:33:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:24 INFO - Contents: 05:33:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/524d89a15f81f91b143ffc2d4ac6ace6d50bea0fb2ef55636b60a85a53469d2e080542002ee607b1b1cbd814f9b3b9f9bddd2fe9fa617e51245072f99f3d7375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0472db747d4fc72c4a712bded08e548a8a9c9c86efc615571d3fad34802a95b1c156197a77e8b6871c0808c1a26cbf76c7366c150801702424a65b73db3642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5e450d554dd3166549d14a497a21372d08834444dbba7e136329a8faf7aa96496b3a348caa5f2dd32d9e723df606d1282fbdf5b8a02dd061b3e3991d387b08b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9585959482c2d8ebc3a0ee23982614646d421614b5e0645f4c6b440c43a5ef358b27ff5ce6e372bfd4b518939db46868a00a56d0a0d75ce15835f234829ad5af"} 05:33:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:24 INFO - Copying logs to upload dir... 05:33:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.325482 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-24 05:33:24.583520) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 05:33:24.586966) ========= 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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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/524d89a15f81f91b143ffc2d4ac6ace6d50bea0fb2ef55636b60a85a53469d2e080542002ee607b1b1cbd814f9b3b9f9bddd2fe9fa617e51245072f99f3d7375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0472db747d4fc72c4a712bded08e548a8a9c9c86efc615571d3fad34802a95b1c156197a77e8b6871c0808c1a26cbf76c7366c150801702424a65b73db3642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5e450d554dd3166549d14a497a21372d08834444dbba7e136329a8faf7aa96496b3a348caa5f2dd32d9e723df606d1282fbdf5b8a02dd061b3e3991d387b08b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9585959482c2d8ebc3a0ee23982614646d421614b5e0645f4c6b440c43a5ef358b27ff5ce6e372bfd4b518939db46868a00a56d0a0d75ce15835f234829ad5af"} build_url:https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011503 build_url: 'https://queue.taskcluster.net/v1/task/QGnuRMBfRDuWi8LtdgiexA/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/524d89a15f81f91b143ffc2d4ac6ace6d50bea0fb2ef55636b60a85a53469d2e080542002ee607b1b1cbd814f9b3b9f9bddd2fe9fa617e51245072f99f3d7375", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c0472db747d4fc72c4a712bded08e548a8a9c9c86efc615571d3fad34802a95b1c156197a77e8b6871c0808c1a26cbf76c7366c150801702424a65b73db3642", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5e450d554dd3166549d14a497a21372d08834444dbba7e136329a8faf7aa96496b3a348caa5f2dd32d9e723df606d1282fbdf5b8a02dd061b3e3991d387b08b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9585959482c2d8ebc3a0ee23982614646d421614b5e0645f4c6b440c43a5ef358b27ff5ce6e372bfd4b518939db46868a00a56d0a0d75ce15835f234829ad5af"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 05:33:24.623646) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:33:24.623987) ========= 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.0nYgqDv4Gs/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.70Q4TCEYS9/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.004922 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 05:33:24.672828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 05:33:24.673203) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 05:33:24.673576) ========= ========= Total master_lag: 0.19 =========