builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0187 starttime: 1491220101.58 results: success (0) buildid: 20170403030207 builduid: 0a78a9416f614dc2bd8538e21f3f728d revision: 38894655c89e68bcd8f45d31a0d3005f2c2b53db ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.583737) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.584204) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.584484) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.611837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.612120) ========= 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.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-04-03 04:48:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 55.3M=0s 2017-04-03 04:48:21 (55.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082594 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.927775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.928108) ========= 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.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.028539 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.978083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:21.978429) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 38894655c89e68bcd8f45d31a0d3005f2c2b53db --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 38894655c89e68bcd8f45d31a0d3005f2c2b53db --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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-04-03 04:48:22,039 truncating revision to first 12 chars 2017-04-03 04:48:22,039 Setting DEBUG logging. 2017-04-03 04:48:22,039 attempt 1/10 2017-04-03 04:48:22,039 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/38894655c89e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 04:48:22,349 unpacking tar archive at: mozilla-central-38894655c89e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.580620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:22.580318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:22.580644) ========= script_repo_revision: 38894655c89e68bcd8f45d31a0d3005f2c2b53db ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:22.581046) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:22.581311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 04:48:22.599406) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-03 04:48:22.599735) ========= /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.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:48:22 INFO - MultiFileLogger online at 20170403 04:48:22 in /builds/slave/test 04:48:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:48:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:48:22 INFO - {'append_to_log': False, 04:48:22 INFO - 'base_work_dir': '/builds/slave/test', 04:48:22 INFO - 'blob_upload_branch': 'Firefox', 04:48:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:48:22 INFO - 'branch': 'Firefox', 04:48:22 INFO - 'buildbot_json_path': 'buildprops.json', 04:48:22 INFO - 'config_files': ('talos/mac_config.py',), 04:48:22 INFO - 'default_actions': ('clobber', 04:48:22 INFO - 'read-buildbot-config', 04:48:22 INFO - 'download-and-extract', 04:48:22 INFO - 'populate-webroot', 04:48:22 INFO - 'create-virtualenv', 04:48:22 INFO - 'install', 04:48:22 INFO - 'run-tests'), 04:48:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:48:22 INFO - 'download_minidump_stackwalk': True, 04:48:22 INFO - 'download_symbols': 'ondemand', 04:48:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:48:22 INFO - 'tooltool.py': '/tools/tooltool.py', 04:48:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:48:22 INFO - '/tools/misc-python/virtualenv.py')}, 04:48:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:48:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:48:22 INFO - 'gecko_profile': False, 04:48:22 INFO - 'gecko_profile_interval': 0, 04:48:22 INFO - 'installer_path': 'installer.exe', 04:48:22 INFO - 'log_level': 'info', 04:48:22 INFO - 'log_name': 'talos', 04:48:22 INFO - 'log_to_console': True, 04:48:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:48:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:48:22 INFO - 'opt_config_files': (), 04:48:22 INFO - 'pip_index': False, 04:48:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:22 INFO - 'cmd': ('bash', 04:48:22 INFO - '-c', 04:48:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:22 INFO - 'enabled': True, 04:48:22 INFO - 'halt_on_failure': False, 04:48:22 INFO - 'name': 'check_screen_resolution'},), 04:48:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:22 INFO - 'cmd': ('bash', 04:48:22 INFO - '-c', 04:48:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:22 INFO - 'enabled': True, 04:48:22 INFO - 'halt_on_failure': False, 04:48:22 INFO - 'name': 'check_screen_resolution'},), 04:48:22 INFO - 'run_cmd_checks_enabled': True, 04:48:22 INFO - 'suite': 'dromaeojs', 04:48:22 INFO - 'system_bits': '32', 04:48:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:48:22 INFO - 'title': 't-yosemite-r7-0187', 04:48:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:48:22 INFO - 'use_talos_json': True, 04:48:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:48:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:48:22 INFO - 'work_dir': 'build'} 04:48:22 INFO - [mozharness: 2017-04-03 11:48:22.768569Z] Running clobber step. 04:48:22 INFO - Running pre-action listener: _resource_record_pre_action 04:48:22 INFO - Running main action method: clobber 04:48:22 INFO - rmtree: /builds/slave/test/build 04:48:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:48:25 INFO - Running post-action listener: _resource_record_post_action 04:48:25 INFO - [mozharness: 2017-04-03 11:48:25.280730Z] Finished clobber step (success) 04:48:25 INFO - [mozharness: 2017-04-03 11:48:25.280912Z] Running read-buildbot-config step. 04:48:25 INFO - Running pre-action listener: _resource_record_pre_action 04:48:25 INFO - Running main action method: read_buildbot_config 04:48:25 INFO - Using buildbot properties: 04:48:25 INFO - { 04:48:25 INFO - "project": "", 04:48:25 INFO - "product": "firefox", 04:48:25 INFO - "script_repo_revision": "production", 04:48:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:48:25 INFO - "repository": "", 04:48:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:48:25 INFO - "buildid": "20170403030207", 04:48:25 INFO - "stage_platform": "macosx64", 04:48:25 INFO - "basedir": "/builds/slave/test", 04:48:25 INFO - "nightly_build": "True", 04:48:25 INFO - "buildnumber": 92, 04:48:25 INFO - "slavename": "t-yosemite-r7-0187", 04:48:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:48:25 INFO - "platform": "yosemite_r7", 04:48:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:48:25 INFO - "branch": "mozilla-central", 04:48:25 INFO - "repo_path": "mozilla-central", 04:48:25 INFO - "revision": "38894655c89e68bcd8f45d31a0d3005f2c2b53db", 04:48:25 INFO - "pgo_build": "False", 04:48:25 INFO - "builduid": "0a78a9416f614dc2bd8538e21f3f728d", 04:48:25 INFO - "slavebuilddir": "test" 04:48:25 INFO - } 04:48:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:48:25 INFO - Running post-action listener: _resource_record_post_action 04:48:25 INFO - [mozharness: 2017-04-03 11:48:25.284446Z] Finished read-buildbot-config step (success) 04:48:25 INFO - [mozharness: 2017-04-03 11:48:25.284563Z] Running download-and-extract step. 04:48:25 INFO - Running pre-action listener: _resource_record_pre_action 04:48:25 INFO - Running main action method: download_and_extract 04:48:25 INFO - mkdir: /builds/slave/test/build/tests 04:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:25 INFO - trying https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:25 INFO - Downloading https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:48:26 INFO - Downloaded 1325 bytes. 04:48:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:48:26 INFO - Using the following test package requirements: 04:48:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:48:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:48:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:48:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'jsshell-mac.zip'], 04:48:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:48:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:48:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:48:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:48:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:48:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:48:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:48:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:48:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:48:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:26 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:48:27 INFO - Expected file size: 52516287 04:48:27 INFO - Obtained file size: 52516287 04:48:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:48:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:48:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:44 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:48:45 INFO - Expected file size: 52516287 04:48:45 INFO - Obtained file size: 52516287 04:49:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:49:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:49:00 INFO - Fetch https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:49:02 INFO - Expected file size: 14404152 04:49:02 INFO - Obtained file size: 14404152 04:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:03 INFO - trying https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:03 INFO - Downloading https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:49:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:49:05 INFO - Downloaded 75641450 bytes. 04:49:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:05 INFO - mkdir: /builds/slave/test/properties 04:49:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:49:05 INFO - Writing to file /builds/slave/test/properties/build_url 04:49:05 INFO - Contents: 04:49:05 INFO - build_url:https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:49:05 INFO - Running post-action listener: _resource_record_post_action 04:49:05 INFO - Running post-action listener: set_extra_try_arguments 04:49:05 INFO - [mozharness: 2017-04-03 11:49:05.962961Z] Finished download-and-extract step (success) 04:49:05 INFO - [mozharness: 2017-04-03 11:49:05.963118Z] Running populate-webroot step. 04:49:05 INFO - Running pre-action listener: _resource_record_pre_action 04:49:05 INFO - Running main action method: populate_webroot 04:49:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:49:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:49:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:49:05 INFO - '--tppagecycles', 04:49:05 INFO - '7'], 04:49:05 INFO - 'tests': ['tsvgm']}}, 04:49:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:49:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:49:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:49:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:49:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:49:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['damp', 'tps']}, 04:49:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'tests': ['damp', 'tps']}, 04:49:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['dromaeo_dom']}, 04:49:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:49:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['basic_compositor_video']}, 04:49:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:49:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['a11yr', 04:49:05 INFO - 'ts_paint', 04:49:05 INFO - 'tpaint', 04:49:05 INFO - 'sessionrestore', 04:49:05 INFO - 'sessionrestore_no_auto_restore', 04:49:05 INFO - 'tabpaint']}, 04:49:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['tsvgx', 04:49:05 INFO - 'tsvgr_opacity', 04:49:05 INFO - 'tart', 04:49:05 INFO - 'tscrollx', 04:49:05 INFO - 'cart', 04:49:05 INFO - 'tsvg_static']}, 04:49:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:49:05 INFO - 'tsvgr_opacity', 04:49:05 INFO - 'tart', 04:49:05 INFO - 'tscrollx', 04:49:05 INFO - 'cart', 04:49:05 INFO - 'tsvg_static']}, 04:49:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'talos_options': ['--disable-e10s'], 04:49:05 INFO - 'tests': ['tp5o']}, 04:49:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:49:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'talos_options': ['--disable-e10s', 04:49:05 INFO - '--xperf_path', 04:49:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:05 INFO - 'tests': ['tp5n']}, 04:49:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:49:05 INFO - 'talos_options': ['--xperf_path', 04:49:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:49:05 INFO - 'tests': ['tp5n']}}, 04:49:05 INFO - 'talos.zip': {'path': '', 04:49:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:49:05 INFO - Running post-action listener: _resource_record_post_action 04:49:05 INFO - [mozharness: 2017-04-03 11:49:05.981556Z] Finished populate-webroot step (success) 04:49:05 INFO - [mozharness: 2017-04-03 11:49:05.981688Z] Running create-virtualenv step. 04:49:05 INFO - Running pre-action listener: _resource_record_pre_action 04:49:05 INFO - Running main action method: create_virtualenv 04:49:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:49:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:49:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:49:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:49:06 INFO - Using real prefix '/tools/python27' 04:49:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:49:06 INFO - Installing distribute.............................................................................................................................................................................................done. 04:49:09 INFO - Installing pip.................done. 04:49:09 INFO - Return code: 0 04:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:49:09 INFO - Reading from file tmpfile_stdout 04:49:09 INFO - Output received: 04:49:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:49:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:09 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:09 INFO - 'HOME': '/Users/cltbld', 04:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:09 INFO - 'LOGNAME': 'cltbld', 04:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:09 INFO - 'MOZ_NO_REMOTE': '1', 04:49:09 INFO - 'NO_EM_RESTART': '1', 04:49:09 INFO - 'PAGER': '/bin/cat', 04:49:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:09 INFO - 'PWD': '/builds/slave/test', 04:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:09 INFO - 'SHELL': '/bin/bash', 04:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:09 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:09 INFO - 'USER': 'cltbld', 04:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:10 INFO - 'XPC_FLAGS': '0x0', 04:49:10 INFO - 'XPC_SERVICE_NAME': '0', 04:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:49:10 INFO - Cleaning up... 04:49:10 INFO - Return code: 0 04:49:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:10 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:10 INFO - 'HOME': '/Users/cltbld', 04:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:10 INFO - 'LOGNAME': 'cltbld', 04:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:10 INFO - 'MOZ_NO_REMOTE': '1', 04:49:10 INFO - 'NO_EM_RESTART': '1', 04:49:10 INFO - 'PAGER': '/bin/cat', 04:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:10 INFO - 'PWD': '/builds/slave/test', 04:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:10 INFO - 'SHELL': '/bin/bash', 04:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:10 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:10 INFO - 'USER': 'cltbld', 04:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:10 INFO - 'XPC_FLAGS': '0x0', 04:49:10 INFO - 'XPC_SERVICE_NAME': '0', 04:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:10 INFO - Downloading/unpacking psutil>=3.1.1 04:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:49:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:13 INFO - Installing collected packages: psutil 04:49:13 INFO - Running setup.py install for psutil 04:49:14 INFO - building 'psutil._psutil_osx' extension 04:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:49:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:49:14 INFO - building 'psutil._psutil_posix' extension 04:49:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:49:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:49:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:49:14 INFO - ^ 04:49:14 INFO - 1 warning generated. 04:49:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:49:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:49:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:49:15 INFO - Successfully installed psutil 04:49:15 INFO - Cleaning up... 04:49:15 INFO - Return code: 0 04:49:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:16 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:16 INFO - 'HOME': '/Users/cltbld', 04:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:16 INFO - 'LOGNAME': 'cltbld', 04:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:16 INFO - 'MOZ_NO_REMOTE': '1', 04:49:16 INFO - 'NO_EM_RESTART': '1', 04:49:16 INFO - 'PAGER': '/bin/cat', 04:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:16 INFO - 'PWD': '/builds/slave/test', 04:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:16 INFO - 'SHELL': '/bin/bash', 04:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:16 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:16 INFO - 'USER': 'cltbld', 04:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:16 INFO - 'XPC_FLAGS': '0x0', 04:49:16 INFO - 'XPC_SERVICE_NAME': '0', 04:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:49:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:49:19 INFO - Installing collected packages: mozsystemmonitor 04:49:19 INFO - Running setup.py install for mozsystemmonitor 04:49:19 INFO - Successfully installed mozsystemmonitor 04:49:19 INFO - Cleaning up... 04:49:19 INFO - Return code: 0 04:49:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:19 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:19 INFO - 'HOME': '/Users/cltbld', 04:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:19 INFO - 'LOGNAME': 'cltbld', 04:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:19 INFO - 'MOZ_NO_REMOTE': '1', 04:49:19 INFO - 'NO_EM_RESTART': '1', 04:49:19 INFO - 'PAGER': '/bin/cat', 04:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:19 INFO - 'PWD': '/builds/slave/test', 04:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:19 INFO - 'SHELL': '/bin/bash', 04:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:19 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:19 INFO - 'USER': 'cltbld', 04:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:19 INFO - 'XPC_FLAGS': '0x0', 04:49:19 INFO - 'XPC_SERVICE_NAME': '0', 04:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:19 INFO - Downloading/unpacking jsonschema==2.5.1 04:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:22 INFO - warning: no files found matching '*.txt' 04:49:22 INFO - no previously-included directories found matching 'build' 04:49:22 INFO - no previously-included directories found matching 'dist' 04:49:22 INFO - no previously-included directories found matching '.git*' 04:49:22 INFO - Installing collected packages: jsonschema, functools32 04:49:23 INFO - Running setup.py install for functools32 04:49:23 INFO - warning: no files found matching '*.txt' 04:49:23 INFO - no previously-included directories found matching 'build' 04:49:23 INFO - no previously-included directories found matching 'dist' 04:49:23 INFO - no previously-included directories found matching '.git*' 04:49:23 INFO - Successfully installed jsonschema functools32 04:49:23 INFO - Cleaning up... 04:49:23 INFO - Return code: 0 04:49:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:23 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:49:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:23 INFO - 'HOME': '/Users/cltbld', 04:49:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:23 INFO - 'LOGNAME': 'cltbld', 04:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:23 INFO - 'MOZ_NO_REMOTE': '1', 04:49:23 INFO - 'NO_EM_RESTART': '1', 04:49:23 INFO - 'PAGER': '/bin/cat', 04:49:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:23 INFO - 'PWD': '/builds/slave/test', 04:49:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:23 INFO - 'SHELL': '/bin/bash', 04:49:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:23 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:23 INFO - 'USER': 'cltbld', 04:49:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:23 INFO - 'XPC_FLAGS': '0x0', 04:49:23 INFO - 'XPC_SERVICE_NAME': '0', 04:49:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:49:23 INFO - Cleaning up... 04:49:23 INFO - Return code: 0 04:49:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:23 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:49:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:23 INFO - 'HOME': '/Users/cltbld', 04:49:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:23 INFO - 'LOGNAME': 'cltbld', 04:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:23 INFO - 'MOZ_NO_REMOTE': '1', 04:49:23 INFO - 'NO_EM_RESTART': '1', 04:49:23 INFO - 'PAGER': '/bin/cat', 04:49:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:23 INFO - 'PWD': '/builds/slave/test', 04:49:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:23 INFO - 'SHELL': '/bin/bash', 04:49:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:23 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:23 INFO - 'USER': 'cltbld', 04:49:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:23 INFO - 'XPC_FLAGS': '0x0', 04:49:23 INFO - 'XPC_SERVICE_NAME': '0', 04:49:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:26 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:49:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:27 INFO - Downloading docopt-0.6.1.tar.gz 04:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:27 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:27 INFO - Running setup.py install for blobuploader 04:49:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:49:27 INFO - Running setup.py install for requests 04:49:28 INFO - Running setup.py install for docopt 04:49:28 INFO - Successfully installed blobuploader requests docopt 04:49:28 INFO - Cleaning up... 04:49:28 INFO - Return code: 0 04:49:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:28 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:28 INFO - 'HOME': '/Users/cltbld', 04:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:28 INFO - 'LOGNAME': 'cltbld', 04:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:28 INFO - 'MOZ_NO_REMOTE': '1', 04:49:28 INFO - 'NO_EM_RESTART': '1', 04:49:28 INFO - 'PAGER': '/bin/cat', 04:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:28 INFO - 'PWD': '/builds/slave/test', 04:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:28 INFO - 'SHELL': '/bin/bash', 04:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:28 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:28 INFO - 'USER': 'cltbld', 04:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:28 INFO - 'XPC_FLAGS': '0x0', 04:49:28 INFO - 'XPC_SERVICE_NAME': '0', 04:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-RCMIqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-tLRAh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-3W5glB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-PFwmXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-ivsfZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-S6ts_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-rhDQj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-uG7UbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Lawn91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-fjKMsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-UJbg6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-QTIHQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:30 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-wBUgG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:30 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-GiulVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:30 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-pGDjaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:30 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-mtkyeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:30 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-u2b8So-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:30 INFO - Running setup.py install for manifestparser 04:49:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:49:30 INFO - Running setup.py install for mozcrash 04:49:30 INFO - Running setup.py install for mozdebug 04:49:30 INFO - Running setup.py install for mozdevice 04:49:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Running setup.py install for mozfile 04:49:31 INFO - Running setup.py install for mozhttpd 04:49:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Running setup.py install for mozinfo 04:49:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Running setup.py install for mozInstall 04:49:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:49:31 INFO - Running setup.py install for mozleak 04:49:31 INFO - Running setup.py install for mozlog 04:49:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Running setup.py install for moznetwork 04:49:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Running setup.py install for mozprocess 04:49:32 INFO - Running setup.py install for mozprofile 04:49:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Running setup.py install for mozrunner 04:49:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:49:32 INFO - Running setup.py install for mozscreenshot 04:49:32 INFO - Running setup.py install for moztest 04:49:32 INFO - Running setup.py install for mozversion 04:49:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:49:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:49:33 INFO - Cleaning up... 04:49:33 INFO - Return code: 0 04:49:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49: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')]} 04:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49: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')]} 04:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:33 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:33 INFO - 'HOME': '/Users/cltbld', 04:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:33 INFO - 'LOGNAME': 'cltbld', 04:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:33 INFO - 'MOZ_NO_REMOTE': '1', 04:49:33 INFO - 'NO_EM_RESTART': '1', 04:49:33 INFO - 'PAGER': '/bin/cat', 04:49:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:33 INFO - 'PWD': '/builds/slave/test', 04:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:33 INFO - 'SHELL': '/bin/bash', 04:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:33 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:33 INFO - 'USER': 'cltbld', 04:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:33 INFO - 'XPC_FLAGS': '0x0', 04:49:33 INFO - 'XPC_SERVICE_NAME': '0', 04:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-TAtlaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-MGz9Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-RvfK4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-PnuBMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-tetLJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Fa_uI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:33 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-xDogdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Kb0EmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-L706JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-meDyGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-F2aBaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-8kUKqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-r2M0KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-WEp6bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-efZ3ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:34 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-ykplnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:35 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-mGu34U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:37 INFO - Downloading blessings-1.6.tar.gz 04:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:49:37 INFO - Installing collected packages: blessings 04:49:37 INFO - Running setup.py install for blessings 04:49:38 INFO - Successfully installed blessings 04:49:38 INFO - Cleaning up... 04:49:38 INFO - Return code: 0 04:49:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:38 INFO - Reading from file tmpfile_stdout 04:49:38 INFO - Current package versions: 04:49:38 INFO - blessings == 1.6 04:49:38 INFO - blobuploader == 1.2.4 04:49:38 INFO - docopt == 0.6.1 04:49:38 INFO - functools32 == 3.2.3-2 04:49:38 INFO - jsonschema == 2.5.1 04:49:38 INFO - manifestparser == 1.1 04:49:38 INFO - mozInstall == 1.12 04:49:38 INFO - mozcrash == 1.0 04:49:38 INFO - mozdebug == 0.1 04:49:38 INFO - mozdevice == 0.49 04:49:38 INFO - mozfile == 1.2 04:49:38 INFO - mozhttpd == 0.7 04:49:38 INFO - mozinfo == 0.9 04:49:38 INFO - mozleak == 0.1 04:49:38 INFO - mozlog == 3.4 04:49:38 INFO - moznetwork == 0.27 04:49:38 INFO - mozprocess == 0.25 04:49:38 INFO - mozprofile == 0.28 04:49:38 INFO - mozrunner == 6.13 04:49:38 INFO - mozscreenshot == 0.1 04:49:38 INFO - mozsystemmonitor == 0.3 04:49:38 INFO - moztest == 0.8 04:49:38 INFO - mozversion == 1.4 04:49:38 INFO - psutil == 3.1.1 04:49:38 INFO - requests == 1.2.3 04:49:38 INFO - wsgiref == 0.1.2 04:49:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 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 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:38 INFO - 'HOME': '/Users/cltbld', 04:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PAGER': '/bin/cat', 04:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test', 04:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:38 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - 'XPC_FLAGS': '0x0', 04:49:38 INFO - 'XPC_SERVICE_NAME': '0', 04:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:49:38 INFO - Cleaning up... 04:49:38 INFO - Return code: 0 04:49:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0cf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0f1571670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, '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.rnrvbORler/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.IOgH7UWBSu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:38 INFO - 'HOME': '/Users/cltbld', 04:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:38 INFO - 'LOGNAME': 'cltbld', 04:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:38 INFO - 'MOZ_NO_REMOTE': '1', 04:49:38 INFO - 'NO_EM_RESTART': '1', 04:49:38 INFO - 'PAGER': '/bin/cat', 04:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:38 INFO - 'PWD': '/builds/slave/test', 04:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:38 INFO - 'SHELL': '/bin/bash', 04:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:49:38 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:38 INFO - 'USER': 'cltbld', 04:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:38 INFO - 'XPC_FLAGS': '0x0', 04:49:38 INFO - 'XPC_SERVICE_NAME': '0', 04:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:49:38 INFO - Cleaning up... 04:49:38 INFO - Return code: 0 04:49:38 INFO - Running post-action listener: _resource_record_post_action 04:49:38 INFO - Running post-action listener: _start_resource_monitoring 04:49:38 INFO - Starting resource monitoring. 04:49:38 INFO - [mozharness: 2017-04-03 11:49:38.987421Z] Finished create-virtualenv step (success) 04:49:38 INFO - [mozharness: 2017-04-03 11:49:38.988373Z] Running install step. 04:49:38 INFO - Running pre-action listener: _resource_record_pre_action 04:49:38 INFO - Running main action method: install 04:49:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:39 INFO - Reading from file tmpfile_stdout 04:49:39 INFO - Detecting whether we're running mozinstall >=1.0... 04:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:49:39 INFO - Reading from file tmpfile_stdout 04:49:39 INFO - Output received: 04:49:39 INFO - Usage: mozinstall [options] installer 04:49:39 INFO - Options: 04:49:39 INFO - -h, --help show this help message and exit 04:49:39 INFO - -d DEST, --destination=DEST 04:49:39 INFO - Directory to install application into. [default: 04:49:39 INFO - "/builds/slave/test"] 04:49:39 INFO - --app=APP Application being installed. [default: firefox] 04:49:39 INFO - mkdir: /builds/slave/test/build/application 04:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:50:00 INFO - Reading from file tmpfile_stdout 04:50:00 INFO - Output received: 04:50:00 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:50:00 INFO - Running post-action listener: _resource_record_post_action 04:50:00 INFO - [mozharness: 2017-04-03 11:50:00.250525Z] Finished install step (success) 04:50:00 INFO - [mozharness: 2017-04-03 11:50:00.250689Z] Running run-tests step. 04:50:00 INFO - Running pre-action listener: _resource_record_pre_action 04:50:00 INFO - Running main action method: run_tests 04:50:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:00 INFO - 2017-04-03 04:50:00.265 screenresolution[1622:7447] starting screenresolution argv=screenresolution get 04:50:00 INFO - 2017-04-03 04:50:00.268 screenresolution[1622:7447] Display 0: 1600x1200x32@60 04:50:00 INFO - 2017-04-03 04:50:00.274 screenresolution[1623:7449] starting screenresolution argv=screenresolution list 04:50:00 INFO - Available Modes on Display 0 04:50:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:00 INFO - Intel Iris: 04:50:00 INFO - Chipset Model: Intel Iris 04:50:00 INFO - Type: GPU 04:50:00 INFO - Bus: Built-In 04:50:00 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:00 INFO - Vendor: Intel (0x8086) 04:50:00 INFO - Device ID: 0x0a2e 04:50:00 INFO - Revision ID: 0x0009 04:50:00 INFO - Displays: 04:50:00 INFO - Display: 04:50:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:00 INFO - Display Serial Number: 1600x1200 60 04:50:00 INFO - Main Display: Yes 04:50:00 INFO - Mirror: Off 04:50:00 INFO - Online: Yes 04:50:00 INFO - Rotation: Supported 04:50:00 INFO - Return code: 0 04:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:50:00 INFO - Python 2.7.3 04:50:00 INFO - Return code: 0 04:50:00 INFO - grabbing minidump binary from tooltool 04:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:50:00 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 0x10aeaad30>, '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 0x10add1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae9fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:50:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:50:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:50:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:50:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:50:00 INFO - Return code: 0 04:50:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:50:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:50:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:50:00 INFO - ENV: RUST_BACKTRACE is now 1 04:50:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:50:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:50:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0187 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:50:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rnrvbORler/Render', 04:50:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:50:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:50:00 INFO - 'HOME': '/Users/cltbld', 04:50:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:50:00 INFO - 'LOGNAME': 'cltbld', 04:50:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:50:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:50:00 INFO - 'MOZ_NO_REMOTE': '1', 04:50:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:50:00 INFO - 'NO_EM_RESTART': '1', 04:50:00 INFO - 'PAGER': '/bin/cat', 04:50:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:50:00 INFO - 'PWD': '/builds/slave/test', 04:50:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:50:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:50:00 INFO - 'RUST_BACKTRACE': '1', 04:50:00 INFO - 'SHELL': '/bin/bash', 04:50:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IOgH7UWBSu/Listeners', 04:50:00 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 04:50:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:50:00 INFO - 'USER': 'cltbld', 04:50:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:50:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:50:00 INFO - 'XPC_FLAGS': '0x0', 04:50:00 INFO - 'XPC_SERVICE_NAME': '0', 04:50:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:50:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:50:00 INFO - mozversion application_buildid: 20170403030207 04:50:00 INFO - mozversion application_changeset: 38894655c89e68bcd8f45d31a0d3005f2c2b53db 04:50:00 INFO - mozversion application_display_name: Nightly 04:50:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:50:00 INFO - mozversion application_name: Firefox 04:50:00 INFO - mozversion application_remotingname: firefox 04:50:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:50:00 INFO - mozversion application_vendor: Mozilla 04:50:00 INFO - mozversion application_version: 55.0a1 04:50:00 INFO - mozversion platform_buildid: 20170403030207 04:50:00 INFO - mozversion platform_changeset: 38894655c89e68bcd8f45d31a0d3005f2c2b53db 04:50:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:50:00 INFO - mozversion platform_version: 55.0a1 04:50:00 INFO - using testdate: 1491220200 04:50:00 INFO - actual date: 1491220200 04:50:00 INFO - starting webserver on 'localhost:49241' 04:50:00 INFO - SUITE-START | Running 2 tests 04:50:00 INFO - TEST-START | dromaeo_css 04:50:00 INFO - operating with platform_type : mac_ 04:50:00 INFO - Initialising browser for dromaeo_css test... 04:50:00 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpg1brqC/profile http://localhost:49241/getInfo.html) 04:50:03 INFO - PID 1633 | __metrics Screen width/height:1600/1200 04:50:03 INFO - PID 1633 | colorDepth:24 04:50:03 INFO - PID 1633 | Browser inner width/height: 1024/689 04:50:03 INFO - PID 1633 | __metrics 04:50:03 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:50:03 INFO - PID 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:50:04 INFO - TEST-INFO | 1633: exit 0 04:50:04 INFO - Browser initialized. 04:50:04 INFO - Running cycle 1/1 for dromaeo_css test... 04:50:04 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpg1brqC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:16 INFO - PID 1636 | RSS: Main: 232390656 04:50:16 INFO - PID 1636 | 04:53:21 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:53:23 INFO - PID 1636 | RSS: Main: 363204608 04:53:23 INFO - PID 1636 | 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 04:55:05 INFO - PID 1636 | 04:55:05 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:05 INFO - PID 1636 | Full stack: 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 04:55:05 INFO - PID 1636 | 04:55:05 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:05 INFO - PID 1636 | Full stack: 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 04:55:05 INFO - PID 1636 | 04:55:05 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:05 INFO - PID 1636 | Full stack: 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | ************************* 04:55:05 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 04:55:05 INFO - PID 1636 | 04:55:05 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:55:05 INFO - PID 1636 | Full stack: 04:55:05 INFO - PID 1636 | ************************* 04:56:26 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:56:27 INFO - PID 1636 | RSS: Main: 371757056 04:56:27 INFO - PID 1636 | 04:59:29 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:59:30 INFO - PID 1636 | RSS: Main: 360214528 04:59:30 INFO - PID 1636 | 05:02:32 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:02:33 INFO - PID 1636 | RSS: Main: 359636992 05:02:33 INFO - PID 1636 | 05:05:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:05:37 INFO - PID 1636 | RSS: Main: 339705856 05:05:37 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:08:41 INFO - PID 1636 | RSS: Main: 428920832 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | __start_tp_report 05:08:41 INFO - PID 1636 | _x_x_mozilla_page_load 05:08:41 INFO - PID 1636 | _x_x_mozilla_page_load_details 05:08:41 INFO - PID 1636 | |i|pagename|runs| 05:08:41 INFO - PID 1636 | |0;dojo.html;3505.193805424752;3525.506429099928;3544.6809787119155;3555.9466608000903;3597.5503062117236;6206.286277078138;6227.190465239518;6262.5616206865425;6277.937220627792;6319.05214217867;5155.4586768389345;6241.625502469855;6251.71867265973;6258.62448253104;6259.530535209855;4917.188663870457;5241.999999999991;5423.593230507708;5448.972755136218;5547.55619550435;5298.36419629645;5355.98236335097;5469.835904922858;5493.725313734298;5545.77816887324;5316.415194328621;5321.2284218788445;5325.174597937326;5335.573154147659;5338.893222135574;5298.523132918017;5312.256284120226;5321.361436627591;5324.334458192726;5333.093374126408;5219.373675159005;5296.470352964711;5307.548858347045;5317.654352467116;5325.041492531382;5583.022970980103;5627.943720562824;6054.727537260834;6078.361772013964;6090.786822461193;6370.5540612157165;6391.904121438182;6397.9360206397805;6399.552031357807;6554.80335589933;6647.202335719696;6693.364130407604;6735.427488663469;6750.9873518972545;6754.425873800733;6334.556581039329;6341.143945567335;6351.936480635134;6352.205974253218;6352.428281454645;6513.869722605521;6581.3747693969;6872.518923675295;6884.311568843075;6892.862142757117;5559.471850174227;6384.106225128486;6405.935940640627;6448.161738973903;6460.676966151674;6659.4006539411685;6684.799456016327;6703.765368212089;6704.631245281459;6711.395974362234;6478.222613286436;6511.0558968949235;6520.902186467207;6565.048068030109;6574.76988305407;5520.66875987442;6291.622502649856;6359.1733074700915;6364.809055728336;6471.870562588816;5315.867103322448;5332.813351532678;5342.946570534323;5358.035553600312;5376.193570964387;4689.132510485571;4709.105269998689;4733.313669517969;4736.052789442126;4771.427428708508;6152;6248.625082495065;6286.402791734778;6308.085327627468;6368.10846481493;6675.098861653614;6684.364985326386;6705.094812200388;6706.128203333536;6706.5640733352175;6345.555811093272;6350.110984462179;6361.14124593174;6368.840778980562;6373.745050198033;5594.496495315441;5712.37163911961;5821.854453638693;5842.094475356326;5849.766009359663;6714.52998290115;6813.557118787461;6834.350736680008;6880.552764070319;6907.585544867324;6382.170317858649;6454.064160696579;6460.709268082854;6693.196816382066;6718.361755633207;6862.245153033063;6881;6957.652117394211;6959.40845028158;6962.965185174042;6507.934920650733;6525.2822189558165;6530.869382612416;6546.770863019771;6552.508561857975;6377.808665740035;6460.612363258219;6506.902396463962;6538.574992625464;6617;6357.650329231937;6423.839404014937;6467.967660161669;6473.741050357933;6481.4814814815645;6454.354564543508;6456.096146539394;6459.741610335534;6459.9031014533875;6479.708413121327;6189.628622282497;6268.059791031382;6281.057841323655;6311.684415779285;6368.076628888878;6373.808785736435;6393.8721225576155;6414.230292364946;6435.4529864962005;6456.12842266288;6339.778107766206;6342.714577843916;6345.809625711236;6358.841028974312;6359.109724638462 05:08:41 INFO - PID 1636 | |1;ext.html;719.4028955966546;797.6889013284818;1139.9544018239271;1155.058627218817;1162.238733629473;37993;38811.02972425687;38853.80573097138;38880.61119388805;38917.832465026026;40789.38815917764;41142.794286028606;41248.968775780624;41314.58685413145;41486.58513414865;6112.724927378256;8558.229759321675;8711;8723.869141962876;8732.956335218316;6275.2469703635625;8522.488650680947;8729.738107856774;8750.86873696895;8790.51652159131;8790.47257164569;8815.823683526325;8823.514706691129;8838.734837954871;8848.778780530474;8721.171488708562;8788.956055219747;8795.824083518357;8812.250958668521;8817.162369574913;8502.44734092282;8559.614817333173;8565.957170214171;8567.657293708244;8569.100244474303;38446.231075378615;38623.8068809657;38706.419403708685;38750.41874371887;38754.093442991885;3007.9248018799485;3018.7886847920654;3032.9241768955953;3045.6954304569585;3083.861226244802;27692.446151076867;27698;27797.722022779715;27946.860265698746;28163.577546336615;2771.3764403009163;2786.6098746175553;2810.971890281112;2870.468963241807;2882.610847535577;302741;303028;304095;304401;304859.47570262005;39475.80262098671;40761.59238407637;48592;48696.7565162179;48825.5117448821;50382.49617503778;51090.23364649533;51123.098381717995;51134.48865511372;51241;14215.076020058663;14648.926755366156;14661.706765864623;14705.852941470448;14706.705865882837;10181.898181018243;10304.896951030543;11653.766924661459;11818.645440636794;11924.582639607572;11654.417279136009;12251.448684809206;13462.528811491551;13940.721185576233;13960;5320;5534.972325138348;5805.564582656318;5864.765409383577;5882.764689412461;966.5167416291854;990.346371394876;992.712113487095;997.665781963036;1026.2098184397971;21677.78322216789;23362.532749345257;24571.14001009956;24594.877025614758;24615.63076553853;1086.6359769477315;1088.314361951965;1100.9229353945307;1129.3111202166672;1136.6362763915467;12496.87503124957;12575.559855405018;12615.990720742502;12623.116381853366;12733.61799146027;468363;470541;471051.6447417741;471540;472222;55363;55679;55713;55817.441825582035;55924.72037639867;1467.5597320803715;1493.6639256167275;1531.2956040221623;1541.7301972154828;1553.0138362140121;24002.3198376112;29689.812407503458;29975.55036674408;29996.654165564014;30215.51099540828;54724;55444;55477.722611386685;55480.44519554753;55561;355454;490235;490691;491919;493664;28972.49522590701;29308.97418590339;29325.26686832846;34213.31573368568;34840.12899677528;33017.66982330146;42953.78523107364;43465.78267108644;43642.69071927847;43837;25650.358741031625;28589;29120.980765673947;30341.544876826425;30825.691743082283;29260.56109158407;29642;30083.849580751958;30275.848620756755;30293;35125.47311790376;35162.648373515935;35582;36302;36415;25056.498870022133;29253.122406327846;29970.70029299679;30627.846860765552;30738.231544211572;22604.434889127904;22620.434489137904;24060.759392405853;24488;24541.75458245395 05:08:41 INFO - PID 1636 | |2;jquery.html;387.08840680198114;418.4664552695313;429.66271476890626;429.7786639880461;446.45532450410485;15998;21654.89172554137;22170.1131954722;22877.911481949952;23189.768102318973;19364;22641.660375094387;23327;24103.638445423334;24104.156354527597;6055.636661800284;6207.224096987865;6235.781747638834;6356.36436356363;6441;6236.812895613139;6334;6343.397377249154;6349.42855143037;6355.364463553631;6109.236345456818;6176.72204750785;6212.534059945522;6264.373562643722;6300.21247344082;6117.847053823645;6204.78283260086;6212.87574248517;6261.7182226799905;6266.5300102492265;5534.0315444797;5592.5246354059545;5592.713675854577;5605.80379686709;5609.831705048855;22462.213822516296;27099.91600336004;27415.903363865422;27630.309242268904;27647;2000.7999200080021;2008.126464987735;2013.5368865161095;2022.1911235505768;2086;15540.844591554165;16727.080010599413;16815.41146059894;17177.65644687112;17820.841645293014;1831.111910723298;1896.2582494699425;1898.6107847891149;1914.9085021537728;1917.9712304315447;149291;155806;156734;156824;158863.82272354604;10003.899961000297;10809;11239.044681202025;12555.93722031384;12795.296258705861;13440.462381504825;16652.333906643842;16706.749398759028;16778.41275555374;16793.328266869434;8942.86585701215;8959.641614335354;9090.40912340709;9216.63133474667;9242.953785231031;8644.567771611395;8658.220760131619;8724.476531408136;8820.91179088201;8856.557172141367;7875.448718589644;8059.194080592011;8075.878861817077;8092.878606820902;8102.149274326229;2215.8670479771267;2217.9021384414496;2218.234709025384;2221.500162463433;2224.5439684864887;892.9866052009226;934.9859752103724;943.9244860411151;974.2254907348674;996.6860438961849;20059.899700501403;21463.248786292406;21500.89249553742;21527.56944861094;21536.0308786105;871.9564021799011;989.366805244644;1005.4218824175982;1017.6794309792392;1028.7428142964259;10805.675829724963;11088.944555277334;11126.998570128728;11146.832797508045;11202.94398528002;641630;704860;715795;717062;717855;155932;162723;165123;165445;172432;1197.874223206568;1199.4602428907026;1259.420666493405;1259.4269607328642;1262.3246563088703;18653.227943345362;21191.364259071637;22113.336599902028;22240.44398889976;22656.886715566317;46381;58514.4148558526;65111;66631.66736665336;67116.66441667761;78092.50519916786;90986.54506727424;92579;93966;94894;8600.913990860012;8637.352198585006;8684.348673849612;9042.547872606476;9212.723618291462;11333.376664283214;13812.723745525234;13877.375518101508;14315.234644066033;14566.980311378102;11696.35670038122;11737.471813768572;12008.39958002114;12056.698582535157;12105.031597472005;11342.20604557673;11354.602588909678;11397.658070257905;11539.769204616028;11716.23844450108;11988.520459181535;12071.39643017863;12348.259104453762;12361.567345142877;12483.12618116723;8599.656013759379;8822.161894620001;8887.911120888966;8900.109989001048;9118.544072796201;9955.153811926068;11722.531098755955;11988.340641264815;12003.699907502383;12013.459394327101 05:08:41 INFO - PID 1636 | |3;mootools.html;1381.0884816021426;1419.0147982971819;1447.1461837515862;1466.2009204983283;1476.9113853168815;10570.78858422831;11000.119990400779;11011.504482298296;11019.779604407917;11059.72350691233;10716.41059741714;10799.190060745455;11147.498362573684;11152.442377881114;11174.441277936112;2422.39440139965;2471.7281099079178;2471.9617760540527;2478.3680161558777;2481.751824817513;2418.9274321770376;2436.9634455483183;2475.5915273979863;2478.5166892455977;2478.566250906093;2391.85648861068;2424.9757502425014;2439.060961529815;2442.9511409771794;2446.5106978604263;2356.7996720278798;2411.4815314707334;2422.5033868057008;2425.672534207877;2449.106076282162;2076.345951025422;2118.597466481379;2381.0475809676095;2392.9401764956015;2421.9273421797375;10814.72963175919;11062.557497700082;11081.722956926142;11084.778304433947;11087.279326843729;6097.115918191882;6337.778177763802;6340.461060809836;6345.42891139795;6365.745370185187;3985.342418500944;3987.6211759882763;4052.2705912935658;4072.1855628874346;4093.692973027005;1177.2995067934564;1194.1939191046008;1229.4098832560333;1239.4732238798474;1239.73965467252;14149.29253537319;14571.41714331436;14579.781303280459;14855.925720371437;15047.398104075824;4474.373587697725;4769.546893045155;4960.90078198434;5457.999999999921;5515.007298686195;9215.354925155309;11559.826602600968;11564.884351156548;11570.247933884271;11585.942070289595;6287.591306565058;6301.086342480315;6305.842353941188;6308.369163083747;6312.936870631235;5566;5870.236869206976;5895.380984996599;5965.821025369246;6018.337982821886;4003.039783662166;4130.112025914443;4146.911187316722;4203.957960420356;4214.0518383364115;1265.4811527273773;1268.6764874956827;1269.6254604891578;1287.3756228229302;1288.2528133682442;743.4832791210165;766.7929658992041;770.3952397368039;776.2858170483169;786.3591571026112;5846.386129456346;5881.823545293648;6051.122587224828;6070.119832624333;6082.817515474543;565.9320881494165;581.5928849805068;596.8537708261504;597.1639704413856;601.1823919469518;3433.7939723616164;3782.243551289753;3788.9431658525145;3802.657760801541;3811.0853395184954;13541.86458135406;14226.288685565887;14229.644258893404;14237.85762142386;14239.928800355932;11558.826617600742;12053.0960177987;14056.367463464168;14106.294685265695;14141.646458838406;8860.8670869937;9174.082591740773;9184.357095003419;9787.510624468749;10014.54934527948;6594.802155935329;6664.766733164316;6843.589384636938;6855.931440685529;6970.825729356807;11220.38287894141;12176.208546444454;12931.28877911724;12935.353232338157;12938.741225175632;12702.618921432371;12892.871071289166;13065.673358166121;13169.47322107105;13211.867881321064;9005.95497022537;9215.354925155174;9264.490453025148;9281.860772088288;9296.72109836706;10103.49482525856;10160.237982151517;10231;10253.436061016715;10259.948700256451;7216.278372162742;8535.61589728476;8537.658493660185;8624.921884764404;8960.283177345927;8317.459365141247;8413.663453462037;8446;8503.447275927045;8558.914410855812;7586.468947173866;7743.341815945708;7810;7942.722004729807;7962.761117166494;5772.5959182856795;5940.287165540163;5946.197263369563;6050.5764596479585;6067.211262535842;5833.358330583547;5846.707664616837;6012.098185272069;6047.395260473917;6176.969115154396 05:08:41 INFO - PID 1636 | |4;prototype.html;378.8389934277933;382.5887171290862;398.69898226838734;399.86404622428375;402.14142805111095;5592.356878958921;6019.067044608088;6101.2678478582575;6115.266168059828;6158.784442544517;4820.638452116088;4887.633427492934;5635.604867931133;5924.614900031495;5931.288245410546;4389.692721509504;4420.756858372788;4435.866923992269;4441.711288766236;4446.6877223843585;4202.222588821062;4432.756198409086;4434.911301773978;4453.198424283628;4462.68350836504;4116.526599441057;4305.3541968704785;4321.956780432187;4355.368471571636;4367.388565600819;4305.870823875289;4326.091520780608;4328.329108988096;4335.458067741517;4359.237133501656;3772.2266935278203;3777.2067865748227;3786.715996300261;3786.734928555002;3817.48463957365;5594.488833340762;5728.198052272685;5801.969310741207;5848.181254624356;5851.970740146272;5133.230015497668;5141.845744627667;5164.406093299281;5164.509371609691;5177.249298851645;5490.258815059956;6041.667708276043;6070.605410648293;6277.959663109507;6299.622022678654;4221.303484925014;4227.393590435646;4241.745495270294;4245.702800803945;4268.59448352406;6399.9360006400275;6596.86806263872;6599.901001484982;6632.900506492407;6699.095622091004;4249.426327445755;4357.694961352675;4359.760213188241;4363.5418281080665;4384.758838263926;3958.940615890764;4197.950512371907;4411.808811620845;4444.355568442557;4453.688241823042;4582.908341833145;4985.401751789808;5068.873278168076;5151.2530683050745;5158.228638509364;6428.164338636041;6430.517711171681;6439.645819479973;6441.838954026187;6470.352964703586;1356.3015047250674;1470.9705805884037;1493.6706331365094;1497.10922001409;1497.5058230783816;969.1810420194925;979.4221409368507;1002.2983911262146;1003.247564326755;1005.0452070533023;5988.341282458926;5988.490978266809;5989.2812862456785;6008.519318454513;6051.757929682852;5543.390227075019;6072.656062664827;6124.540659450559;6571.3757193066585;6586.769463068769;5069.366329208849;5237.9476205238225;5936.1986131871645;5955.553333500005;6001.939980600138;3395.388830010622;3413.8634454622033;3433.3819912415506;3439.7592168547963;3439.9312013759586;6415.903761443488;6724.394804467622;6736.608503981114;6739.460843132538;6781.593104413751;5928.7332070056955;5950.96500784603;6189.442950134509;6707.564008339442;6737.764178253738;5864.354920958691;5892.499137573354;6296.968515157395;6415.358464153547;6796.864062718718;5116.437191908813;5432.511074003359;6524.249711283073;6528.575642583217;6664.234707390309;5727.541796656341;6163.630182189083;6236.545175743266;6462.063000864942;6482.546221764431;5657.943420565742;5671.546276297969;6378;6434.774782882577;6733.293004234681;3828.0812604975163;4093.9969707420682;4094.955786274481;4290.249206388932;4300.602524006;5007.874803129951;5579.637323573955;5620.819627878028;5738.885222295447;6214.40963108504;5503.147012213113;6025.337212906665;6298.748050077946;6319.715612797343;6323.4625056868845;6139.662318572343;6291.245050593958;6320.988641817287;6324.905126423016;6345.968270158804;6101.237345331833;6145.1285690015;6165.784197553064;6186.319504854552;6202.162708034538;5273.499017593323;5339.813106541253;5544.251526044094;6107.267127944675;6257.030160325156;5206.5314121727715;5865.765369385177;6093.573449858467;6177.505799536116;6200.968995154995 05:08:41 INFO - PID 1636 | |5;yui.html;731.7073170731703;733.3876213361842;740.5371642723298;748.2069006852738;773.4276635289883;15413.922930385346;16383.09892955887;16497.50507484776;16808.075555844425;19096.33162839301;17612.383566575172;17811.287548498043;18086.638267234648;18276.268949242014;18375.90812045941;994.3834822410116;1012.1093437774748;1019.0980981831059;1020.036065917708;1020.5101551255365;994.2394068537575;1000.2048371544639;1025.9846102308472;1027.3117011602208;1035.6219979707391;1062.750253690386;1070.063694267516;1072.072657151566;1073.849661047454;1075.2634445404883;986.9013098690145;1008.188408331293;1059.5761695321855;1067.524951396629;1069.2942657845863;782.792559971608;788.5308241596241;788.9782731362842;789.4316092413453;790.1940021178423;16606.335746570123;16932.322707091702;16973.575660608585;17121.486355409357;17259.827401725946;12397;14858.554243372717;14876.776848347283;14990;15052.54842354731;11623.6512904613;11645.883541164649;11872.52509899603;12059.758804823941;12062;2960.5411161270035;3023.4104349651825;3046.01004673482;3123.437781199383;3174.4763312722857;107004;112905;113257.43371283173;113983.43008284905;116477;10252.692419227435;11605.6518304451;11650.88349116498;11692.298462092302;11975.820362694567;24584;25543.105991290588;25572;25577.488450230892;25631.48737025249;8120.553369564613;8203.138670439637;8284.917150828414;8294.294984926228;8294.502329860228;4002.7598344099447;4060.127072679388;4105.220008198433;4121.979390103031;4125.195586860534;4217.304144816132;4272.636825869774;4286.54991225923;4309.396684464177;4328.783560821947;843.3211264931789;853.3002937591116;860.9440386374865;862.607513581319;886.3618194899661;535.6598559914482;553.2254843219445;554.581291125206;577.151587166864;589.2693060604819;8889.333300002525;9719.757006074904;10392;10590.682279531626;10604.787904241872;535.3843080457427;543.9510444060054;584.8654809393863;588.6438704583751;593.7743657410243;4598.770061496912;4616.499637617788;4706.270528068155;4782.569568738831;4787.329773831666;96570.51714741382;97246;98270;99832;102998;53744;72853;88343.55828220946;88394.55802721073;96005;78419.21580784234;79719.99999999884;80068.59965700134;82007;82116;10246.743831404274;10497.42264175447;10921.617743378943;10945.398003109905;11136.721581960515;37245.68857785554;39330;39740;40210.597894020684;41023.179536409705;56567.43432565621;66200.33799662134;69837.65081174561;70063;72496.55006899727;13846.515371962336;13991.58025259244;14007.509737159151;14020.439182432588;14472.782908256595;15152.343691743212;15187.848121519086;15232.543023709306;15251;15664.865079680194;13846.515371961934;14145.717085658023;14151.929240354144;14258.928705356408;14479;13646.385912633757;13919.2465127371;14065.296735162996;14385.784213237019;14684.706305874037;13381.598552043455;13607.591772246848;13626.591202263948;13769;14235.430582776988;10079.395236285847;10180;10423.322484038514;10439.217058720475;10475.633352832614;11112.499937502993;11175.832362514406;11341.432928353714;11624.476898539755;11632.651020469399 05:08:41 INFO - PID 1636 | __end_tp_report 05:08:41 INFO - PID 1636 | __start_cc_report 05:08:41 INFO - PID 1636 | _x_x_mozilla_cycle_collect,1854 05:08:41 INFO - PID 1636 | __end_cc_report 05:08:41 INFO - PID 1636 | __startTimestamp1491221321666__endTimestamp 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | ------- Summary: start ------- 05:08:41 INFO - PID 1636 | Number of tests: 6 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6083.87 Median:6358.84 stddev:717.77 (11.3%) stddev-sans-first:690.88 05:08:41 INFO - PID 1636 | Values: 3505.2 3525.5 3544.7 3555.9 3597.6 6206.3 6227.2 6262.6 6277.9 6319.1 5155.5 6241.6 6251.7 6258.6 6259.5 4917.2 5242.0 5423.6 5449.0 5547.6 5298.4 5356.0 5469.8 5493.7 5545.8 5316.4 5321.2 5325.2 5335.6 5338.9 5298.5 5312.3 5321.4 5324.3 5333.1 5219.4 5296.5 5307.5 5317.7 5325.0 5583.0 5627.9 6054.7 6078.4 6090.8 6370.6 6391.9 6397.9 6399.6 6554.8 6647.2 6693.4 6735.4 6751.0 6754.4 6334.6 6341.1 6351.9 6352.2 6352.4 6513.9 6581.4 6872.5 6884.3 6892.9 5559.5 6384.1 6405.9 6448.2 6460.7 6659.4 6684.8 6703.8 6704.6 6711.4 6478.2 6511.1 6520.9 6565.0 6574.8 5520.7 6291.6 6359.2 6364.8 6471.9 5315.9 5332.8 5342.9 5358.0 5376.2 4689.1 4709.1 4733.3 4736.1 4771.4 6152.0 6248.6 6286.4 6308.1 6368.1 6675.1 6684.4 6705.1 6706.1 6706.6 6345.6 6350.1 6361.1 6368.8 6373.7 5594.5 5712.4 5821.9 5842.1 5849.8 6714.5 6813.6 6834.4 6880.6 6907.6 6382.2 6454.1 6460.7 6693.2 6718.4 6862.2 6881.0 6957.7 6959.4 6963.0 6507.9 6525.3 6530.9 6546.8 6552.5 6377.8 6460.6 6506.9 6538.6 6617.0 6357.7 6423.8 6468.0 6473.7 6481.5 6454.4 6456.1 6459.7 6459.9 6479.7 6189.6 6268.1 6281.1 6311.7 6368.1 6373.8 6393.9 6414.2 6435.5 6456.1 6339.8 6342.7 6345.8 6358.8 6359.1 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:54871.66 Median:35144.06 stddev:112124.64 (319.0%) stddev-sans-first:112365.46 05:08:41 INFO - PID 1636 | Values: 719.4 797.7 1140.0 1155.1 1162.2 37993.0 38811.0 38853.8 38880.6 38917.8 40789.4 41142.8 41249.0 41314.6 41486.6 6112.7 8558.2 8711.0 8723.9 8733.0 6275.2 8522.5 8729.7 8750.9 8790.5 8790.5 8815.8 8823.5 8838.7 8848.8 8721.2 8789.0 8795.8 8812.3 8817.2 8502.4 8559.6 8566.0 8567.7 8569.1 38446.2 38623.8 38706.4 38750.4 38754.1 3007.9 3018.8 3032.9 3045.7 3083.9 27692.4 27698.0 27797.7 27946.9 28163.6 2771.4 2786.6 2811.0 2870.5 2882.6 302741.0 303028.0 304095.0 304401.0 304859.5 39475.8 40761.6 48592.0 48696.8 48825.5 50382.5 51090.2 51123.1 51134.5 51241.0 14215.1 14648.9 14661.7 14705.9 14706.7 10181.9 10304.9 11653.8 11818.6 11924.6 11654.4 12251.4 13462.5 13940.7 13960.0 5320.0 5535.0 5805.6 5864.8 5882.8 966.5 990.3 992.7 997.7 1026.2 21677.8 23362.5 24571.1 24594.9 24615.6 1086.6 1088.3 1100.9 1129.3 1136.6 12496.9 12575.6 12616.0 12623.1 12733.6 468363.0 470541.0 471051.6 471540.0 472222.0 55363.0 55679.0 55713.0 55817.4 55924.7 1467.6 1493.7 1531.3 1541.7 1553.0 24002.3 29689.8 29975.6 29996.7 30215.5 54724.0 55444.0 55477.7 55480.4 55561.0 355454.0 490235.0 490691.0 491919.0 493664.0 28972.5 29309.0 29325.3 34213.3 34840.1 33017.7 42953.8 43465.8 43642.7 43837.0 25650.4 28589.0 29121.0 30341.5 30825.7 29260.6 29642.0 30083.8 30275.8 30293.0 35125.5 35162.6 35582.0 36302.0 36415.0 25056.5 29253.1 29970.7 30627.8 30738.2 22604.4 22620.4 24060.8 24488.0 24541.8 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:41198.78 Median:22551.94 stddev:117803.15 (522.4%) stddev-sans-first:118093.76 05:08:41 INFO - PID 1636 | Values: 387.1 418.5 429.7 429.8 446.5 15998.0 21654.9 22170.1 22877.9 23189.8 19364.0 22641.7 23327.0 24103.6 24104.2 6055.6 6207.2 6235.8 6356.4 6441.0 6236.8 6334.0 6343.4 6349.4 6355.4 6109.2 6176.7 6212.5 6264.4 6300.2 6117.8 6204.8 6212.9 6261.7 6266.5 5534.0 5592.5 5592.7 5605.8 5609.8 22462.2 27099.9 27415.9 27630.3 27647.0 2000.8 2008.1 2013.5 2022.2 2086.0 15540.8 16727.1 16815.4 17177.7 17820.8 1831.1 1896.3 1898.6 1914.9 1918.0 149291.0 155806.0 156734.0 156824.0 158863.8 10003.9 10809.0 11239.0 12555.9 12795.3 13440.5 16652.3 16706.7 16778.4 16793.3 8942.9 8959.6 9090.4 9216.6 9243.0 8644.6 8658.2 8724.5 8820.9 8856.6 7875.4 8059.2 8075.9 8092.9 8102.1 2215.9 2217.9 2218.2 2221.5 2224.5 893.0 935.0 943.9 974.2 996.7 20059.9 21463.2 21500.9 21527.6 21536.0 872.0 989.4 1005.4 1017.7 1028.7 10805.7 11088.9 11127.0 11146.8 11202.9 641630.0 704860.0 715795.0 717062.0 717855.0 155932.0 162723.0 165123.0 165445.0 172432.0 1197.9 1199.5 1259.4 1259.4 1262.3 18653.2 21191.4 22113.3 22240.4 22656.9 46381.0 58514.4 65111.0 66631.7 67116.7 78092.5 90986.5 92579.0 93966.0 94894.0 8600.9 8637.4 8684.3 9042.5 9212.7 11333.4 13812.7 13877.4 14315.2 14567.0 11696.4 11737.5 12008.4 12056.7 12105.0 11342.2 11354.6 11397.7 11539.8 11716.2 11988.5 12071.4 12348.3 12361.6 12483.1 8599.7 8822.2 8887.9 8900.1 9118.5 9955.2 11722.5 11988.3 12003.7 12013.5 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6731.03 Median:3608.02 stddev:4173.03 (115.7%) stddev-sans-first:4165.38 05:08:41 INFO - PID 1636 | Values: 1381.1 1419.0 1447.1 1466.2 1476.9 10570.8 11000.1 11011.5 11019.8 11059.7 10716.4 10799.2 11147.5 11152.4 11174.4 2422.4 2471.7 2472.0 2478.4 2481.8 2418.9 2437.0 2475.6 2478.5 2478.6 2391.9 2425.0 2439.1 2443.0 2446.5 2356.8 2411.5 2422.5 2425.7 2449.1 2076.3 2118.6 2381.0 2392.9 2421.9 10814.7 11062.6 11081.7 11084.8 11087.3 6097.1 6337.8 6340.5 6345.4 6365.7 3985.3 3987.6 4052.3 4072.2 4093.7 1177.3 1194.2 1229.4 1239.5 1239.7 14149.3 14571.4 14579.8 14855.9 15047.4 4474.4 4769.5 4960.9 5458.0 5515.0 9215.4 11559.8 11564.9 11570.2 11585.9 6287.6 6301.1 6305.8 6308.4 6312.9 5566.0 5870.2 5895.4 5965.8 6018.3 4003.0 4130.1 4146.9 4204.0 4214.1 1265.5 1268.7 1269.6 1287.4 1288.3 743.5 766.8 770.4 776.3 786.4 5846.4 5881.8 6051.1 6070.1 6082.8 565.9 581.6 596.9 597.2 601.2 3433.8 3782.2 3788.9 3802.7 3811.1 13541.9 14226.3 14229.6 14237.9 14239.9 11558.8 12053.1 14056.4 14106.3 14141.6 8860.9 9174.1 9184.4 9787.5 10014.5 6594.8 6664.8 6843.6 6855.9 6970.8 11220.4 12176.2 12931.3 12935.4 12938.7 12702.6 12892.9 13065.7 13169.5 13211.9 9006.0 9215.4 9264.5 9281.9 9296.7 10103.5 10160.2 10231.0 10253.4 10259.9 7216.3 8535.6 8537.7 8624.9 8960.3 8317.5 8413.7 8446.0 8503.4 8558.9 7586.5 7743.3 7810.0 7942.7 7962.8 5772.6 5940.3 5946.2 6050.6 6067.2 5833.4 5846.7 6012.1 6047.4 6177.0 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5061.31 Median:5628.21 stddev:1550.99 (27.6%) stddev-sans-first:1515.00 05:08:41 INFO - PID 1636 | Values: 378.8 382.6 398.7 399.9 402.1 5592.4 6019.1 6101.3 6115.3 6158.8 4820.6 4887.6 5635.6 5924.6 5931.3 4389.7 4420.8 4435.9 4441.7 4446.7 4202.2 4432.8 4434.9 4453.2 4462.7 4116.5 4305.4 4322.0 4355.4 4367.4 4305.9 4326.1 4328.3 4335.5 4359.2 3772.2 3777.2 3786.7 3786.7 3817.5 5594.5 5728.2 5802.0 5848.2 5852.0 5133.2 5141.8 5164.4 5164.5 5177.2 5490.3 6041.7 6070.6 6278.0 6299.6 4221.3 4227.4 4241.7 4245.7 4268.6 6399.9 6596.9 6599.9 6632.9 6699.1 4249.4 4357.7 4359.8 4363.5 4384.8 3958.9 4198.0 4411.8 4444.4 4453.7 4582.9 4985.4 5068.9 5151.3 5158.2 6428.2 6430.5 6439.6 6441.8 6470.4 1356.3 1471.0 1493.7 1497.1 1497.5 969.2 979.4 1002.3 1003.2 1005.0 5988.3 5988.5 5989.3 6008.5 6051.8 5543.4 6072.7 6124.5 6571.4 6586.8 5069.4 5237.9 5936.2 5955.6 6001.9 3395.4 3413.9 3433.4 3439.8 3439.9 6415.9 6724.4 6736.6 6739.5 6781.6 5928.7 5951.0 6189.4 6707.6 6737.8 5864.4 5892.5 6297.0 6415.4 6796.9 5116.4 5432.5 6524.2 6528.6 6664.2 5727.5 6163.6 6236.5 6462.1 6482.5 5657.9 5671.5 6378.0 6434.8 6733.3 3828.1 4094.0 4095.0 4290.2 4300.6 5007.9 5579.6 5620.8 5738.9 6214.4 5503.1 6025.3 6298.7 6319.7 6323.5 6139.7 6291.2 6321.0 6324.9 6346.0 6101.2 6145.1 6165.8 6186.3 6202.2 5273.5 5339.8 5544.3 6107.3 6257.0 5206.5 5865.8 6093.6 6177.5 6201.0 05:08:41 INFO - PID 1636 | 05:08:41 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:20540.78 Median:17711.84 stddev:29015.84 (163.8%) stddev-sans-first:29059.11 05:08:41 INFO - PID 1636 | Values: 731.7 733.4 740.5 748.2 773.4 15413.9 16383.1 16497.5 16808.1 19096.3 17612.4 17811.3 18086.6 18276.3 18375.9 994.4 1012.1 1019.1 1020.0 1020.5 994.2 1000.2 1026.0 1027.3 1035.6 1062.8 1070.1 1072.1 1073.8 1075.3 986.9 1008.2 1059.6 1067.5 1069.3 782.8 788.5 789.0 789.4 790.2 16606.3 16932.3 16973.6 17121.5 17259.8 12397.0 14858.6 14876.8 14990.0 15052.5 11623.7 11645.9 11872.5 12059.8 12062.0 2960.5 3023.4 3046.0 3123.4 3174.5 107004.0 112905.0 113257.4 113983.4 116477.0 10252.7 11605.7 11650.9 11692.3 11975.8 24584.0 25543.1 25572.0 25577.5 25631.5 8120.6 8203.1 8284.9 8294.3 8294.5 4002.8 4060.1 4105.2 4122.0 4125.2 4217.3 4272.6 4286.5 4309.4 4328.8 843.3 853.3 860.9 862.6 886.4 535.7 553.2 554.6 577.2 589.3 8889.3 9719.8 10392.0 10590.7 10604.8 535.4 544.0 584.9 588.6 593.8 4598.8 4616.5 4706.3 4782.6 4787.3 96570.5 97246.0 98270.0 99832.0 102998.0 53744.0 72853.0 88343.6 88394.6 96005.0 78419.2 79720.0 80068.6 82007.0 82116.0 10246.7 10497.4 10921.6 10945.4 11136.7 37245.7 39330.0 39740.0 40210.6 41023.2 56567.4 66200.3 69837.7 70063.0 72496.6 13846.5 13991.6 14007.5 14020.4 14472.8 15152.3 15187.8 15232.5 15251.0 15664.9 13846.5 14145.7 14151.9 14258.9 14479.0 13646.4 13919.2 14065.3 14385.8 14684.7 13381.6 13607.6 13626.6 13769.0 14235.4 10079.4 10180.0 10423.3 10439.2 10475.6 11112.5 11175.8 11341.4 11624.5 11632.7 05:08:41 INFO - PID 1636 | -------- Summary: end -------- 05:08:41 INFO - PID 1636 | 05:08:42 INFO - TEST-INFO | 1636: exit 0 05:08:42 INFO - TEST-OK | dromaeo_css | took 1121497ms 05:08:42 INFO - TEST-START | kraken 05:08:42 INFO - operating with platform_type : mac_ 05:08:42 INFO - Initialising browser for kraken test... 05:08:42 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQWWtT5/profile http://localhost:49241/getInfo.html) 05:08:44 INFO - PID 1645 | __metrics Screen width/height:1600/1200 05:08:44 INFO - PID 1645 | colorDepth:24 05:08:44 INFO - PID 1645 | Browser inner width/height: 1024/689 05:08:44 INFO - PID 1645 | __metrics 05:08:44 INFO - PID 1645 | 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:08:44 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:08:45 INFO - TEST-INFO | 1645: exit 0 05:08:45 INFO - Browser initialized. 05:08:45 INFO - Running cycle 1/1 for kraken test... 05:08:45 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpQWWtT5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:57 INFO - PID 1647 | RSS: Main: 234422272 05:08:57 INFO - PID 1647 | 05:09:00 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:09:01 INFO - PID 1647 | RSS: Main: 286416896 05:09:01 INFO - PID 1647 | 05:09:04 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:09:05 INFO - PID 1647 | RSS: Main: 286515200 05:09:05 INFO - PID 1647 | 05:09:08 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:09:09 INFO - PID 1647 | RSS: Main: 381431808 05:09:09 INFO - PID 1647 | 05:09:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:09:12 INFO - PID 1647 | RSS: Main: 283201536 05:09:12 INFO - PID 1647 | 05:09:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:15 INFO - PID 1647 | RSS: Main: 287760384 05:09:15 INFO - PID 1647 | 05:09:18 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:18 INFO - PID 1647 | RSS: Main: 327892992 05:09:18 INFO - PID 1647 | 05:09:21 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:22 INFO - PID 1647 | RSS: Main: 336633856 05:09:22 INFO - PID 1647 | 05:09:25 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:09:26 INFO - PID 1647 | RSS: Main: 336015360 05:09:26 INFO - PID 1647 | 05:09:28 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:28 INFO - PID 1647 | RSS: Main: 305123328 05:09:28 INFO - PID 1647 | 05:09:30 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:31 INFO - PID 1647 | RSS: Main: 309112832 05:09:31 INFO - PID 1647 | 05:09:33 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:34 INFO - PID 1647 | RSS: Main: 310861824 05:09:34 INFO - PID 1647 | 05:09:37 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:09:38 INFO - PID 1647 | RSS: Main: 327188480 05:09:38 INFO - PID 1647 | 05:09:41 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:09:42 INFO - PID 1647 | RSS: Main: 310341632 05:09:42 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:09:44 INFO - PID 1647 | RSS: Main: 375779328 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | __start_tp_report 05:09:44 INFO - PID 1647 | _x_x_mozilla_page_load 05:09:44 INFO - PID 1647 | _x_x_mozilla_page_load_details 05:09:44 INFO - PID 1647 | |i|pagename|runs| 05:09:44 INFO - PID 1647 | |0;ai-astar;112;122;124;124;123;122;125;124;124;125 05:09:44 INFO - PID 1647 | |1;audio-beat-detection;161;139;106;198;113;143;156;149;146;117 05:09:44 INFO - PID 1647 | |2;audio-dft;213;218;220;208;212;215;218;219;210;217 05:09:44 INFO - PID 1647 | |3;audio-fft;86;65;61;96;68;62;66;61;82;64 05:09:44 INFO - PID 1647 | |4;audio-oscillator;96;74;92;75;76;73;91;73;74;93 05:09:44 INFO - PID 1647 | |5;imaging-gaussian-blur;92;93;91;92;93;92;94;94;92;93 05:09:44 INFO - PID 1647 | |6;imaging-darkroom;151;101;101;101;99;100;102;101;101;99 05:09:44 INFO - PID 1647 | |7;imaging-desaturate;100;100;101;116;98;147;157;101;102;101 05:09:44 INFO - PID 1647 | |8;json-parse-financial;54;51;52;51;52;53;52;53;53;52 05:09:44 INFO - PID 1647 | |9;json-stringify-tinderbox;59;68;63;74;68;65;71;84;91;90 05:09:44 INFO - PID 1647 | |10;stanford-crypto-aes;93;107;115;116;91;91;90;90;91;89 05:09:44 INFO - PID 1647 | |11;stanford-crypto-ccm;155;156;156;179;157;170;177;158;207;164 05:09:44 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;202;198;206;202;205;215;223;209;204;193 05:09:44 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;73;73;76;73;74;75;74;70;73;72 05:09:44 INFO - PID 1647 | __end_tp_report 05:09:44 INFO - PID 1647 | __start_cc_report 05:09:44 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2192 05:09:44 INFO - PID 1647 | __end_cc_report 05:09:44 INFO - PID 1647 | __startTimestamp1491221384555__endTimestamp 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | ------- Summary: start ------- 05:09:44 INFO - PID 1647 | Number of tests: 14 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:122.50 Median:124.00 stddev:3.84 (3.1%) stddev-sans-first:1.12 05:09:44 INFO - PID 1647 | Values: 112.0 122.0 124.0 124.0 123.0 122.0 125.0 124.0 124.0 125.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:147.50 stddev:26.92 (18.3%) stddev-sans-first:27.74 05:09:44 INFO - PID 1647 | Values: 161.0 139.0 106.0 198.0 113.0 143.0 156.0 149.0 146.0 117.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:215.00 Median:217.50 stddev:4.08 (1.9%) stddev-sans-first:4.27 05:09:44 INFO - PID 1647 | Values: 213.0 218.0 220.0 208.0 212.0 215.0 218.0 219.0 210.0 217.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:71.10 Median:67.00 stddev:12.34 (18.4%) stddev-sans-first:11.85 05:09:44 INFO - PID 1647 | Values: 86.0 65.0 61.0 96.0 68.0 62.0 66.0 61.0 82.0 64.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:83.50 stddev:9.84 (11.8%) stddev-sans-first:8.98 05:09:44 INFO - PID 1647 | Values: 96.0 74.0 92.0 75.0 76.0 73.0 91.0 73.0 74.0 93.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:93.00 stddev:0.97 (1.0%) stddev-sans-first:1.00 05:09:44 INFO - PID 1647 | Values: 92.0 93.0 91.0 92.0 93.0 92.0 94.0 94.0 92.0 93.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:101.50 stddev:15.98 (15.7%) stddev-sans-first:1.01 05:09:44 INFO - PID 1647 | Values: 151.0 101.0 101.0 101.0 99.0 100.0 102.0 101.0 101.0 99.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:112.30 Median:109.00 stddev:21.63 (19.8%) stddev-sans-first:22.48 05:09:44 INFO - PID 1647 | Values: 100.0 100.0 101.0 116.0 98.0 147.0 157.0 101.0 102.0 101.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:0.95 (1.8%) stddev-sans-first:0.78 05:09:44 INFO - PID 1647 | Values: 54.0 51.0 52.0 51.0 52.0 53.0 52.0 53.0 53.0 52.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:72.50 stddev:11.29 (15.6%) stddev-sans-first:10.73 05:09:44 INFO - PID 1647 | Values: 59.0 68.0 63.0 74.0 68.0 65.0 71.0 84.0 91.0 90.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:97.30 Median:90.50 stddev:10.90 (12.0%) stddev-sans-first:11.45 05:09:44 INFO - PID 1647 | Values: 93.0 107.0 115.0 116.0 91.0 91.0 90.0 90.0 91.0 89.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:167.90 Median:167.00 stddev:16.40 (9.8%) stddev-sans-first:16.72 05:09:44 INFO - PID 1647 | Values: 155.0 156.0 156.0 179.0 157.0 170.0 177.0 158.0 207.0 164.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.70 Median:205.50 stddev:8.49 (4.1%) stddev-sans-first:8.89 05:09:44 INFO - PID 1647 | Values: 202.0 198.0 206.0 202.0 205.0 215.0 223.0 209.0 204.0 193.0 05:09:44 INFO - PID 1647 | 05:09:44 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:73.50 stddev:1.64 (2.2%) stddev-sans-first:1.73 05:09:44 INFO - PID 1647 | Values: 73.0 73.0 76.0 73.0 74.0 75.0 74.0 70.0 73.0 72.0 05:09:44 INFO - PID 1647 | -------- Summary: end -------- 05:09:44 INFO - PID 1647 | 05:09:45 INFO - TEST-INFO | 1647: exit 0 05:09:45 INFO - TEST-OK | kraken | took 63133ms 05:09:45 INFO - SUITE-END | took 1184s 05:09:45 INFO - Completed test suite (00:19:44) 05:09:45 INFO - javascript benchmark 05:09:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7711.2922871852925, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3505.193805424752, 3525.506429099928, 3544.6809787119155, 3555.9466608000903, 3597.5503062117236, 6206.286277078138, 6227.190465239518, 6262.5616206865425, 6277.937220627792, 6319.05214217867, 5155.4586768389345, 6241.625502469855, 6251.71867265973, 6258.62448253104, 6259.530535209855, 4917.188663870457, 5241.999999999991, 5423.593230507708, 5448.972755136218, 5547.55619550435, 5298.36419629645, 5355.98236335097, 5469.835904922858, 5493.725313734298, 5545.77816887324, 5316.415194328621, 5321.2284218788445, 5325.174597937326, 5335.573154147659, 5338.893222135574, 5298.523132918017, 5312.256284120226, 5321.361436627591, 5324.334458192726, 5333.093374126408, 5219.373675159005, 5296.470352964711, 5307.548858347045, 5317.654352467116, 5325.041492531382, 5583.022970980103, 5627.943720562824, 6054.727537260834, 6078.361772013964, 6090.786822461193, 6370.5540612157165, 6391.904121438182, 6397.9360206397805, 6399.552031357807, 6554.80335589933, 6647.202335719696, 6693.364130407604, 6735.427488663469, 6750.9873518972545, 6754.425873800733, 6334.556581039329, 6341.143945567335, 6351.936480635134, 6352.205974253218, 6352.428281454645, 6513.869722605521, 6581.3747693969, 6872.518923675295, 6884.311568843075, 6892.862142757117, 5559.471850174227, 6384.106225128486, 6405.935940640627, 6448.161738973903, 6460.676966151674, 6659.4006539411685, 6684.799456016327, 6703.765368212089, 6704.631245281459, 6711.395974362234, 6478.222613286436, 6511.0558968949235, 6520.902186467207, 6565.048068030109, 6574.76988305407, 5520.66875987442, 6291.622502649856, 6359.1733074700915, 6364.809055728336, 6471.870562588816, 5315.867103322448, 5332.813351532678, 5342.946570534323, 5358.035553600312, 5376.193570964387, 4689.132510485571, 4709.105269998689, 4733.313669517969, 4736.052789442126, 4771.427428708508, 6152.0, 6248.625082495065, 6286.402791734778, 6308.085327627468, 6368.10846481493, 6675.098861653614, 6684.364985326386, 6705.094812200388, 6706.128203333536, 6706.5640733352175, 6345.555811093272, 6350.110984462179, 6361.14124593174, 6368.840778980562, 6373.745050198033, 5594.496495315441, 5712.37163911961, 5821.854453638693, 5842.094475356326, 5849.766009359663, 6714.52998290115, 6813.557118787461, 6834.350736680008, 6880.552764070319, 6907.585544867324, 6382.170317858649, 6454.064160696579, 6460.709268082854, 6693.196816382066, 6718.361755633207, 6862.245153033063, 6881.0, 6957.652117394211, 6959.40845028158, 6962.965185174042, 6507.934920650733, 6525.2822189558165, 6530.869382612416, 6546.770863019771, 6552.508561857975, 6377.808665740035, 6460.612363258219, 6506.902396463962, 6538.574992625464, 6617.0, 6357.650329231937, 6423.839404014937, 6467.967660161669, 6473.741050357933, 6481.4814814815645, 6454.354564543508, 6456.096146539394, 6459.741610335534, 6459.9031014533875, 6479.708413121327, 6189.628622282497, 6268.059791031382, 6281.057841323655, 6311.684415779285, 6368.076628888878, 6373.808785736435, 6393.8721225576155, 6414.230292364946, 6435.4529864962005, 6456.12842266288, 6339.778107766206, 6342.714577843916, 6345.809625711236, 6358.841028974312, 6359.109724638462], "value": 6036.068883653484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.4028955966546, 797.6889013284818, 1139.9544018239271, 1155.058627218817, 1162.238733629473, 37993.0, 38811.02972425687, 38853.80573097138, 38880.61119388805, 38917.832465026026, 40789.38815917764, 41142.794286028606, 41248.968775780624, 41314.58685413145, 41486.58513414865, 6112.724927378256, 8558.229759321675, 8711.0, 8723.869141962876, 8732.956335218316, 6275.2469703635625, 8522.488650680947, 8729.738107856774, 8750.86873696895, 8790.51652159131, 8790.47257164569, 8815.823683526325, 8823.514706691129, 8838.734837954871, 8848.778780530474, 8721.171488708562, 8788.956055219747, 8795.824083518357, 8812.250958668521, 8817.162369574913, 8502.44734092282, 8559.614817333173, 8565.957170214171, 8567.657293708244, 8569.100244474303, 38446.231075378615, 38623.8068809657, 38706.419403708685, 38750.41874371887, 38754.093442991885, 3007.9248018799485, 3018.7886847920654, 3032.9241768955953, 3045.6954304569585, 3083.861226244802, 27692.446151076867, 27698.0, 27797.722022779715, 27946.860265698746, 28163.577546336615, 2771.3764403009163, 2786.6098746175553, 2810.971890281112, 2870.468963241807, 2882.610847535577, 302741.0, 303028.0, 304095.0, 304401.0, 304859.47570262005, 39475.80262098671, 40761.59238407637, 48592.0, 48696.7565162179, 48825.5117448821, 50382.49617503778, 51090.23364649533, 51123.098381717995, 51134.48865511372, 51241.0, 14215.076020058663, 14648.926755366156, 14661.706765864623, 14705.852941470448, 14706.705865882837, 10181.898181018243, 10304.896951030543, 11653.766924661459, 11818.645440636794, 11924.582639607572, 11654.417279136009, 12251.448684809206, 13462.528811491551, 13940.721185576233, 13960.0, 5320.0, 5534.972325138348, 5805.564582656318, 5864.765409383577, 5882.764689412461, 966.5167416291854, 990.346371394876, 992.712113487095, 997.665781963036, 1026.2098184397971, 21677.78322216789, 23362.532749345257, 24571.14001009956, 24594.877025614758, 24615.63076553853, 1086.6359769477315, 1088.314361951965, 1100.9229353945307, 1129.3111202166672, 1136.6362763915467, 12496.87503124957, 12575.559855405018, 12615.990720742502, 12623.116381853366, 12733.61799146027, 468363.0, 470541.0, 471051.6447417741, 471540.0, 472222.0, 55363.0, 55679.0, 55713.0, 55817.441825582035, 55924.72037639867, 1467.5597320803715, 1493.6639256167275, 1531.2956040221623, 1541.7301972154828, 1553.0138362140121, 24002.3198376112, 29689.812407503458, 29975.55036674408, 29996.654165564014, 30215.51099540828, 54724.0, 55444.0, 55477.722611386685, 55480.44519554753, 55561.0, 355454.0, 490235.0, 490691.0, 491919.0, 493664.0, 28972.49522590701, 29308.97418590339, 29325.26686832846, 34213.31573368568, 34840.12899677528, 33017.66982330146, 42953.78523107364, 43465.78267108644, 43642.69071927847, 43837.0, 25650.358741031625, 28589.0, 29120.980765673947, 30341.544876826425, 30825.691743082283, 29260.56109158407, 29642.0, 30083.849580751958, 30275.848620756755, 30293.0, 35125.47311790376, 35162.648373515935, 35582.0, 36302.0, 36415.0, 25056.498870022133, 29253.122406327846, 29970.70029299679, 30627.846860765552, 30738.231544211572, 22604.434889127904, 22620.434489137904, 24060.759392405853, 24488.0, 24541.75458245395], "value": 17980.51456457456, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.08840680198114, 418.4664552695313, 429.66271476890626, 429.7786639880461, 446.45532450410485, 15998.0, 21654.89172554137, 22170.1131954722, 22877.911481949952, 23189.768102318973, 19364.0, 22641.660375094387, 23327.0, 24103.638445423334, 24104.156354527597, 6055.636661800284, 6207.224096987865, 6235.781747638834, 6356.36436356363, 6441.0, 6236.812895613139, 6334.0, 6343.397377249154, 6349.42855143037, 6355.364463553631, 6109.236345456818, 6176.72204750785, 6212.534059945522, 6264.373562643722, 6300.21247344082, 6117.847053823645, 6204.78283260086, 6212.87574248517, 6261.7182226799905, 6266.5300102492265, 5534.0315444797, 5592.5246354059545, 5592.713675854577, 5605.80379686709, 5609.831705048855, 22462.213822516296, 27099.91600336004, 27415.903363865422, 27630.309242268904, 27647.0, 2000.7999200080021, 2008.126464987735, 2013.5368865161095, 2022.1911235505768, 2086.0, 15540.844591554165, 16727.080010599413, 16815.41146059894, 17177.65644687112, 17820.841645293014, 1831.111910723298, 1896.2582494699425, 1898.6107847891149, 1914.9085021537728, 1917.9712304315447, 149291.0, 155806.0, 156734.0, 156824.0, 158863.82272354604, 10003.899961000297, 10809.0, 11239.044681202025, 12555.93722031384, 12795.296258705861, 13440.462381504825, 16652.333906643842, 16706.749398759028, 16778.41275555374, 16793.328266869434, 8942.86585701215, 8959.641614335354, 9090.40912340709, 9216.63133474667, 9242.953785231031, 8644.567771611395, 8658.220760131619, 8724.476531408136, 8820.91179088201, 8856.557172141367, 7875.448718589644, 8059.194080592011, 8075.878861817077, 8092.878606820902, 8102.149274326229, 2215.8670479771267, 2217.9021384414496, 2218.234709025384, 2221.500162463433, 2224.5439684864887, 892.9866052009226, 934.9859752103724, 943.9244860411151, 974.2254907348674, 996.6860438961849, 20059.899700501403, 21463.248786292406, 21500.89249553742, 21527.56944861094, 21536.0308786105, 871.9564021799011, 989.366805244644, 1005.4218824175982, 1017.6794309792392, 1028.7428142964259, 10805.675829724963, 11088.944555277334, 11126.998570128728, 11146.832797508045, 11202.94398528002, 641630.0, 704860.0, 715795.0, 717062.0, 717855.0, 155932.0, 162723.0, 165123.0, 165445.0, 172432.0, 1197.874223206568, 1199.4602428907026, 1259.420666493405, 1259.4269607328642, 1262.3246563088703, 18653.227943345362, 21191.364259071637, 22113.336599902028, 22240.44398889976, 22656.886715566317, 46381.0, 58514.4148558526, 65111.0, 66631.66736665336, 67116.66441667761, 78092.50519916786, 90986.54506727424, 92579.0, 93966.0, 94894.0, 8600.913990860012, 8637.352198585006, 8684.348673849612, 9042.547872606476, 9212.723618291462, 11333.376664283214, 13812.723745525234, 13877.375518101508, 14315.234644066033, 14566.980311378102, 11696.35670038122, 11737.471813768572, 12008.39958002114, 12056.698582535157, 12105.031597472005, 11342.20604557673, 11354.602588909678, 11397.658070257905, 11539.769204616028, 11716.23844450108, 11988.520459181535, 12071.39643017863, 12348.259104453762, 12361.567345142877, 12483.12618116723, 8599.656013759379, 8822.161894620001, 8887.911120888966, 8900.109989001048, 9118.544072796201, 9955.153811926068, 11722.531098755955, 11988.340641264815, 12003.699907502383, 12013.459394327101], "value": 10630.980493422401, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.0884816021426, 1419.0147982971819, 1447.1461837515862, 1466.2009204983283, 1476.9113853168815, 10570.78858422831, 11000.119990400779, 11011.504482298296, 11019.779604407917, 11059.72350691233, 10716.41059741714, 10799.190060745455, 11147.498362573684, 11152.442377881114, 11174.441277936112, 2422.39440139965, 2471.7281099079178, 2471.9617760540527, 2478.3680161558777, 2481.751824817513, 2418.9274321770376, 2436.9634455483183, 2475.5915273979863, 2478.5166892455977, 2478.566250906093, 2391.85648861068, 2424.9757502425014, 2439.060961529815, 2442.9511409771794, 2446.5106978604263, 2356.7996720278798, 2411.4815314707334, 2422.5033868057008, 2425.672534207877, 2449.106076282162, 2076.345951025422, 2118.597466481379, 2381.0475809676095, 2392.9401764956015, 2421.9273421797375, 10814.72963175919, 11062.557497700082, 11081.722956926142, 11084.778304433947, 11087.279326843729, 6097.115918191882, 6337.778177763802, 6340.461060809836, 6345.42891139795, 6365.745370185187, 3985.342418500944, 3987.6211759882763, 4052.2705912935658, 4072.1855628874346, 4093.692973027005, 1177.2995067934564, 1194.1939191046008, 1229.4098832560333, 1239.4732238798474, 1239.73965467252, 14149.29253537319, 14571.41714331436, 14579.781303280459, 14855.925720371437, 15047.398104075824, 4474.373587697725, 4769.546893045155, 4960.90078198434, 5457.999999999921, 5515.007298686195, 9215.354925155309, 11559.826602600968, 11564.884351156548, 11570.247933884271, 11585.942070289595, 6287.591306565058, 6301.086342480315, 6305.842353941188, 6308.369163083747, 6312.936870631235, 5566.0, 5870.236869206976, 5895.380984996599, 5965.821025369246, 6018.337982821886, 4003.039783662166, 4130.112025914443, 4146.911187316722, 4203.957960420356, 4214.0518383364115, 1265.4811527273773, 1268.6764874956827, 1269.6254604891578, 1287.3756228229302, 1288.2528133682442, 743.4832791210165, 766.7929658992041, 770.3952397368039, 776.2858170483169, 786.3591571026112, 5846.386129456346, 5881.823545293648, 6051.122587224828, 6070.119832624333, 6082.817515474543, 565.9320881494165, 581.5928849805068, 596.8537708261504, 597.1639704413856, 601.1823919469518, 3433.7939723616164, 3782.243551289753, 3788.9431658525145, 3802.657760801541, 3811.0853395184954, 13541.86458135406, 14226.288685565887, 14229.644258893404, 14237.85762142386, 14239.928800355932, 11558.826617600742, 12053.0960177987, 14056.367463464168, 14106.294685265695, 14141.646458838406, 8860.8670869937, 9174.082591740773, 9184.357095003419, 9787.510624468749, 10014.54934527948, 6594.802155935329, 6664.766733164316, 6843.589384636938, 6855.931440685529, 6970.825729356807, 11220.38287894141, 12176.208546444454, 12931.28877911724, 12935.353232338157, 12938.741225175632, 12702.618921432371, 12892.871071289166, 13065.673358166121, 13169.47322107105, 13211.867881321064, 9005.95497022537, 9215.354925155174, 9264.490453025148, 9281.860772088288, 9296.72109836706, 10103.49482525856, 10160.237982151517, 10231.0, 10253.436061016715, 10259.948700256451, 7216.278372162742, 8535.61589728476, 8537.658493660185, 8624.921884764404, 8960.283177345927, 8317.459365141247, 8413.663453462037, 8446.0, 8503.447275927045, 8558.914410855812, 7586.468947173866, 7743.341815945708, 7810.0, 7942.722004729807, 7962.761117166494, 5772.5959182856795, 5940.287165540163, 5946.197263369563, 6050.5764596479585, 6067.211262535842, 5833.358330583547, 5846.707664616837, 6012.098185272069, 6047.395260473917, 6176.969115154396], "value": 5088.599621280213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.8389934277933, 382.5887171290862, 398.69898226838734, 399.86404622428375, 402.14142805111095, 5592.356878958921, 6019.067044608088, 6101.2678478582575, 6115.266168059828, 6158.784442544517, 4820.638452116088, 4887.633427492934, 5635.604867931133, 5924.614900031495, 5931.288245410546, 4389.692721509504, 4420.756858372788, 4435.866923992269, 4441.711288766236, 4446.6877223843585, 4202.222588821062, 4432.756198409086, 4434.911301773978, 4453.198424283628, 4462.68350836504, 4116.526599441057, 4305.3541968704785, 4321.956780432187, 4355.368471571636, 4367.388565600819, 4305.870823875289, 4326.091520780608, 4328.329108988096, 4335.458067741517, 4359.237133501656, 3772.2266935278203, 3777.2067865748227, 3786.715996300261, 3786.734928555002, 3817.48463957365, 5594.488833340762, 5728.198052272685, 5801.969310741207, 5848.181254624356, 5851.970740146272, 5133.230015497668, 5141.845744627667, 5164.406093299281, 5164.509371609691, 5177.249298851645, 5490.258815059956, 6041.667708276043, 6070.605410648293, 6277.959663109507, 6299.622022678654, 4221.303484925014, 4227.393590435646, 4241.745495270294, 4245.702800803945, 4268.59448352406, 6399.9360006400275, 6596.86806263872, 6599.901001484982, 6632.900506492407, 6699.095622091004, 4249.426327445755, 4357.694961352675, 4359.760213188241, 4363.5418281080665, 4384.758838263926, 3958.940615890764, 4197.950512371907, 4411.808811620845, 4444.355568442557, 4453.688241823042, 4582.908341833145, 4985.401751789808, 5068.873278168076, 5151.2530683050745, 5158.228638509364, 6428.164338636041, 6430.517711171681, 6439.645819479973, 6441.838954026187, 6470.352964703586, 1356.3015047250674, 1470.9705805884037, 1493.6706331365094, 1497.10922001409, 1497.5058230783816, 969.1810420194925, 979.4221409368507, 1002.2983911262146, 1003.247564326755, 1005.0452070533023, 5988.341282458926, 5988.490978266809, 5989.2812862456785, 6008.519318454513, 6051.757929682852, 5543.390227075019, 6072.656062664827, 6124.540659450559, 6571.3757193066585, 6586.769463068769, 5069.366329208849, 5237.9476205238225, 5936.1986131871645, 5955.553333500005, 6001.939980600138, 3395.388830010622, 3413.8634454622033, 3433.3819912415506, 3439.7592168547963, 3439.9312013759586, 6415.903761443488, 6724.394804467622, 6736.608503981114, 6739.460843132538, 6781.593104413751, 5928.7332070056955, 5950.96500784603, 6189.442950134509, 6707.564008339442, 6737.764178253738, 5864.354920958691, 5892.499137573354, 6296.968515157395, 6415.358464153547, 6796.864062718718, 5116.437191908813, 5432.511074003359, 6524.249711283073, 6528.575642583217, 6664.234707390309, 5727.541796656341, 6163.630182189083, 6236.545175743266, 6462.063000864942, 6482.546221764431, 5657.943420565742, 5671.546276297969, 6378.0, 6434.774782882577, 6733.293004234681, 3828.0812604975163, 4093.9969707420682, 4094.955786274481, 4290.249206388932, 4300.602524006, 5007.874803129951, 5579.637323573955, 5620.819627878028, 5738.885222295447, 6214.40963108504, 5503.147012213113, 6025.337212906665, 6298.748050077946, 6319.715612797343, 6323.4625056868845, 6139.662318572343, 6291.245050593958, 6320.988641817287, 6324.905126423016, 6345.968270158804, 6101.237345331833, 6145.1285690015, 6165.784197553064, 6186.319504854552, 6202.162708034538, 5273.499017593323, 5339.813106541253, 5544.251526044094, 6107.267127944675, 6257.030160325156, 5206.5314121727715, 5865.765369385177, 6093.573449858467, 6177.505799536116, 6200.968995154995], "value": 4580.516563678902, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.7073170731703, 733.3876213361842, 740.5371642723298, 748.2069006852738, 773.4276635289883, 15413.922930385346, 16383.09892955887, 16497.50507484776, 16808.075555844425, 19096.33162839301, 17612.383566575172, 17811.287548498043, 18086.638267234648, 18276.268949242014, 18375.90812045941, 994.3834822410116, 1012.1093437774748, 1019.0980981831059, 1020.036065917708, 1020.5101551255365, 994.2394068537575, 1000.2048371544639, 1025.9846102308472, 1027.3117011602208, 1035.6219979707391, 1062.750253690386, 1070.063694267516, 1072.072657151566, 1073.849661047454, 1075.2634445404883, 986.9013098690145, 1008.188408331293, 1059.5761695321855, 1067.524951396629, 1069.2942657845863, 782.792559971608, 788.5308241596241, 788.9782731362842, 789.4316092413453, 790.1940021178423, 16606.335746570123, 16932.322707091702, 16973.575660608585, 17121.486355409357, 17259.827401725946, 12397.0, 14858.554243372717, 14876.776848347283, 14990.0, 15052.54842354731, 11623.6512904613, 11645.883541164649, 11872.52509899603, 12059.758804823941, 12062.0, 2960.5411161270035, 3023.4104349651825, 3046.01004673482, 3123.437781199383, 3174.4763312722857, 107004.0, 112905.0, 113257.43371283173, 113983.43008284905, 116477.0, 10252.692419227435, 11605.6518304451, 11650.88349116498, 11692.298462092302, 11975.820362694567, 24584.0, 25543.105991290588, 25572.0, 25577.488450230892, 25631.48737025249, 8120.553369564613, 8203.138670439637, 8284.917150828414, 8294.294984926228, 8294.502329860228, 4002.7598344099447, 4060.127072679388, 4105.220008198433, 4121.979390103031, 4125.195586860534, 4217.304144816132, 4272.636825869774, 4286.54991225923, 4309.396684464177, 4328.783560821947, 843.3211264931789, 853.3002937591116, 860.9440386374865, 862.607513581319, 886.3618194899661, 535.6598559914482, 553.2254843219445, 554.581291125206, 577.151587166864, 589.2693060604819, 8889.333300002525, 9719.757006074904, 10392.0, 10590.682279531626, 10604.787904241872, 535.3843080457427, 543.9510444060054, 584.8654809393863, 588.6438704583751, 593.7743657410243, 4598.770061496912, 4616.499637617788, 4706.270528068155, 4782.569568738831, 4787.329773831666, 96570.51714741382, 97246.0, 98270.0, 99832.0, 102998.0, 53744.0, 72853.0, 88343.55828220946, 88394.55802721073, 96005.0, 78419.21580784234, 79719.99999999884, 80068.59965700134, 82007.0, 82116.0, 10246.743831404274, 10497.42264175447, 10921.617743378943, 10945.398003109905, 11136.721581960515, 37245.68857785554, 39330.0, 39740.0, 40210.597894020684, 41023.179536409705, 56567.43432565621, 66200.33799662134, 69837.65081174561, 70063.0, 72496.55006899727, 13846.515371962336, 13991.58025259244, 14007.509737159151, 14020.439182432588, 14472.782908256595, 15152.343691743212, 15187.848121519086, 15232.543023709306, 15251.0, 15664.865079680194, 13846.515371961934, 14145.717085658023, 14151.929240354144, 14258.928705356408, 14479.0, 13646.385912633757, 13919.2465127371, 14065.296735162996, 14385.784213237019, 14684.706305874037, 13381.598552043455, 13607.591772246848, 13626.591202263948, 13769.0, 14235.430582776988, 10079.395236285847, 10180.0, 10423.322484038514, 10439.217058720475, 10475.633352832614, 11112.499937502993, 11175.832362514406, 11341.432928353714, 11624.476898539755, 11632.651020469399], "value": 7817.823883915547, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 124.0, 124.0, 123.0, 122.0, 125.0, 124.0, 124.0, 125.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 139.0, 106.0, 198.0, 113.0, 143.0, 156.0, 149.0, 146.0, 117.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 218.0, 220.0, 208.0, 212.0, 215.0, 218.0, 219.0, 210.0, 217.0], "value": 215.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 65.0, 61.0, 96.0, 68.0, 62.0, 66.0, 61.0, 82.0, 64.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 74.0, 92.0, 75.0, 76.0, 73.0, 91.0, 73.0, 74.0, 93.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 92.0, 93.0, 92.0, 94.0, 94.0, 92.0, 93.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 101.0, 101.0, 101.0, 99.0, 100.0, 102.0, 101.0, 101.0, 99.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 101.0, 116.0, 98.0, 147.0, 157.0, 101.0, 102.0, 101.0], "value": 112.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 51.0, 52.0, 53.0, 52.0, 53.0, 53.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 63.0, 74.0, 68.0, 65.0, 71.0, 84.0, 91.0, 90.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 107.0, 115.0, 116.0, 91.0, 91.0, 90.0, 90.0, 91.0, 89.0], "value": 97.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 156.0, 179.0, 157.0, 170.0, 177.0, 158.0, 207.0, 164.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 198.0, 206.0, 202.0, 205.0, 215.0, 223.0, 209.0, 204.0, 193.0], "value": 205.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 76.0, 73.0, 74.0, 75.0, 74.0, 70.0, 73.0, 72.0], "value": 73.3, "unit": "score"}]}]} 05:09:45 INFO - Return code: 0 05:09:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:09:45 ERROR - # TBPL SUCCESS # 05:09:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:45 INFO - 2017-04-03 05:09:45.874 screenresolution[1651:9731] starting screenresolution argv=screenresolution get 05:09:45 INFO - 2017-04-03 05:09:45.877 screenresolution[1651:9731] Display 0: 1600x1200x32@60 05:09:45 INFO - 2017-04-03 05:09:45.883 screenresolution[1652:9734] starting screenresolution argv=screenresolution list 05:09:45 INFO - Available Modes on Display 0 05:09:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:46 INFO - Intel Iris: 05:09:46 INFO - Chipset Model: Intel Iris 05:09:46 INFO - Type: GPU 05:09:46 INFO - Bus: Built-In 05:09:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:46 INFO - Vendor: Intel (0x8086) 05:09:46 INFO - Device ID: 0x0a2e 05:09:46 INFO - Revision ID: 0x0009 05:09:46 INFO - Displays: 05:09:46 INFO - Display: 05:09:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:46 INFO - Display Serial Number: 1600x1200 60 05:09:46 INFO - Main Display: Yes 05:09:46 INFO - Mirror: Off 05:09:46 INFO - Online: Yes 05:09:46 INFO - Rotation: Supported 05:09:46 INFO - Return code: 0 05:09:46 INFO - Running post-action listener: _resource_record_post_action 05:09:46 INFO - [mozharness: 2017-04-03 12:09:46.119809Z] Finished run-tests step (success) 05:09:46 INFO - Running post-run listener: _resource_record_post_run 05:09:46 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 38986752; Write bytes: 516253184; Read time: 409; Write time: 1896 05:09:46 INFO - TinderboxPrint: CPU usage
53.5% 05:09:46 INFO - TinderboxPrint: I/O read bytes / time
38,986,752 / 409 05:09:46 INFO - TinderboxPrint: I/O write bytes / time
516,253,184 / 1,896 05:09:46 INFO - TinderboxPrint: CPU idle
2,241.8 (46.5%) 05:09:46 INFO - TinderboxPrint: CPU system
963.3 (20.0%) 05:09:46 INFO - TinderboxPrint: CPU user
1,620.5 (33.6%) 05:09:46 INFO - TinderboxPrint: Swap in / out
250,134,528 / 0 05:09:46 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 211472896; Write bytes: 207550464; Read time: 17189; Write time: 439 05:09:46 INFO - run-tests - Wall time: 1186s; CPU: 54.0%; Read bytes: 36856832; Write bytes: 298659328; Read time: 354; Write time: 1423 05:09:46 INFO - Running post-run listener: _upload_blobber_files 05:09:46 INFO - Blob upload gear active. 05:09:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:09:46 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:09:46 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:09:46 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:09:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:09:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:09:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:09:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:48 INFO - (blobuploader) - INFO - Done attempting. 05:09:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:09:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:49 INFO - (blobuploader) - INFO - Done attempting. 05:09:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:09:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:09:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:50 INFO - (blobuploader) - INFO - Done attempting. 05:09:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:09:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:09:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:52 INFO - (blobuploader) - INFO - Done attempting. 05:09:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:09:52 INFO - Return code: 0 05:09:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:09:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:09:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f091fbcc9c10966634aca6e8a228074d99f6c99477d03efc8408616b866d398e768f8b5be4f5a99ea01560db0897ec640f3fb4800962248eb8b68920532e2236", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a71d250cf309b539683e2b277ed0eee6e7eb6239a0a0cf6f4eb91c3b27313c07affca7a0acbfe6b23e0feefb4d94d9013e2b8c28be1f1a1e0c842df28e26ba0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6c7ae89d10cc29e9515ecad754a47c38c85559f065f902e61d8e0e6d335f4f7e55a1d5b63c923092b87ebcbfbf8cc894e1da71f3e51c8d8117e41a291e5082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e42f8f4302d52084094acdcf4363cdb130140fed490dd4e96341da4997485e681cbefd0db71048d0233009aed64793f2f445928de50b1d07ab62e90bc48e7d6"} 05:09:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:09:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:09:52 INFO - Contents: 05:09:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f091fbcc9c10966634aca6e8a228074d99f6c99477d03efc8408616b866d398e768f8b5be4f5a99ea01560db0897ec640f3fb4800962248eb8b68920532e2236", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a71d250cf309b539683e2b277ed0eee6e7eb6239a0a0cf6f4eb91c3b27313c07affca7a0acbfe6b23e0feefb4d94d9013e2b8c28be1f1a1e0c842df28e26ba0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6c7ae89d10cc29e9515ecad754a47c38c85559f065f902e61d8e0e6d335f4f7e55a1d5b63c923092b87ebcbfbf8cc894e1da71f3e51c8d8117e41a291e5082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e42f8f4302d52084094acdcf4363cdb130140fed490dd4e96341da4997485e681cbefd0db71048d0233009aed64793f2f445928de50b1d07ab62e90bc48e7d6"} 05:09:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:09:52 INFO - Copying logs to upload dir... 05:09:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:09:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.668366 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-03 05:09:52.308706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 05:09:52.312216) ========= 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.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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/f091fbcc9c10966634aca6e8a228074d99f6c99477d03efc8408616b866d398e768f8b5be4f5a99ea01560db0897ec640f3fb4800962248eb8b68920532e2236", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a71d250cf309b539683e2b277ed0eee6e7eb6239a0a0cf6f4eb91c3b27313c07affca7a0acbfe6b23e0feefb4d94d9013e2b8c28be1f1a1e0c842df28e26ba0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6c7ae89d10cc29e9515ecad754a47c38c85559f065f902e61d8e0e6d335f4f7e55a1d5b63c923092b87ebcbfbf8cc894e1da71f3e51c8d8117e41a291e5082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e42f8f4302d52084094acdcf4363cdb130140fed490dd4e96341da4997485e681cbefd0db71048d0233009aed64793f2f445928de50b1d07ab62e90bc48e7d6"} build_url:https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010522 build_url: 'https://queue.taskcluster.net/v1/task/AusvPxdPQGmVu7JUVpd7kg/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/f091fbcc9c10966634aca6e8a228074d99f6c99477d03efc8408616b866d398e768f8b5be4f5a99ea01560db0897ec640f3fb4800962248eb8b68920532e2236", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a71d250cf309b539683e2b277ed0eee6e7eb6239a0a0cf6f4eb91c3b27313c07affca7a0acbfe6b23e0feefb4d94d9013e2b8c28be1f1a1e0c842df28e26ba0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce6c7ae89d10cc29e9515ecad754a47c38c85559f065f902e61d8e0e6d335f4f7e55a1d5b63c923092b87ebcbfbf8cc894e1da71f3e51c8d8117e41a291e5082", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e42f8f4302d52084094acdcf4363cdb130140fed490dd4e96341da4997485e681cbefd0db71048d0233009aed64793f2f445928de50b1d07ab62e90bc48e7d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 05:09:52.346403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 05:09:52.346731) ========= 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.rnrvbORler/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.IOgH7UWBSu/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 05:09:52.394849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 05:09:52.395410) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 05:09:52.395974) ========= ========= Total master_lag: 0.38 =========