builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0198 starttime: 1479146594.82 results: success (0) buildid: 20161114072544 builduid: 9ee472d3e4524b47a154d9031ac16837 revision: feddafb5cb546b15b160260da8632beb6b89bd71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.820748) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.821168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.821446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.845903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.846213) ========= 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.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 --2016-11-14 10:03:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.37M=0.001s 2016-11-14 10:03:14 (9.37 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.963507) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:14.963821) ========= 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.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.028783 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:15.012363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:03:15.012710) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev feddafb5cb546b15b160260da8632beb6b89bd71 --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 feddafb5cb546b15b160260da8632beb6b89bd71 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 2016-11-14 10:03:15,091 truncating revision to first 12 chars 2016-11-14 10:03:15,092 Setting DEBUG logging. 2016-11-14 10:03:15,092 attempt 1/10 2016-11-14 10:03:15,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/feddafb5cb54?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 10:03:16,754 unpacking tar archive at: mozilla-central-feddafb5cb54/testing/mozharness/ program finished with exit code 0 elapsedTime=1.900414 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 10:03:16.934913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:16.938369) ========= script_repo_revision: feddafb5cb546b15b160260da8632beb6b89bd71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:16.938774) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:16.939061) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 10:03:16.957406) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-14 10:03:16.957734) ========= /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.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:17 INFO - MultiFileLogger online at 20161114 10:03:17 in /builds/slave/test 10:03:17 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 10:03:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:03:17 INFO - {'append_to_log': False, 10:03:17 INFO - 'base_work_dir': '/builds/slave/test', 10:03:17 INFO - 'blob_upload_branch': 'Firefox', 10:03:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:03:17 INFO - 'branch': 'Firefox', 10:03:17 INFO - 'buildbot_json_path': 'buildprops.json', 10:03:17 INFO - 'config_files': ('talos/mac_config.py',), 10:03:17 INFO - 'default_actions': ('clobber', 10:03:17 INFO - 'read-buildbot-config', 10:03:17 INFO - 'download-and-extract', 10:03:17 INFO - 'populate-webroot', 10:03:17 INFO - 'create-virtualenv', 10:03:17 INFO - 'install', 10:03:17 INFO - 'run-tests'), 10:03:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:03:17 INFO - 'download_minidump_stackwalk': True, 10:03:17 INFO - 'download_symbols': 'ondemand', 10:03:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:03:17 INFO - 'tooltool.py': '/tools/tooltool.py', 10:03:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:03:17 INFO - '/tools/misc-python/virtualenv.py')}, 10:03:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:03:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:03:17 INFO - 'installer_path': 'installer.exe', 10:03:17 INFO - 'log_level': 'info', 10:03:17 INFO - 'log_name': 'talos', 10:03:17 INFO - 'log_to_console': True, 10:03:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:03:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:03:17 INFO - 'opt_config_files': (), 10:03:17 INFO - 'pip_index': False, 10:03:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:17 INFO - 'cmd': ('bash', 10:03:17 INFO - '-c', 10:03:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:17 INFO - 'enabled': True, 10:03:17 INFO - 'halt_on_failure': False, 10:03:17 INFO - 'name': 'check_screen_resolution'},), 10:03:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:17 INFO - 'cmd': ('bash', 10:03:17 INFO - '-c', 10:03:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:17 INFO - 'enabled': True, 10:03:17 INFO - 'halt_on_failure': False, 10:03:17 INFO - 'name': 'check_screen_resolution'},), 10:03:17 INFO - 'run_cmd_checks_enabled': True, 10:03:17 INFO - 'sps_profile': False, 10:03:17 INFO - 'sps_profile_interval': 0, 10:03:17 INFO - 'suite': 'dromaeojs', 10:03:17 INFO - 'system_bits': '32', 10:03:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:03:17 INFO - 'title': 't-yosemite-r7-0198', 10:03:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:03:17 INFO - 'use_talos_json': True, 10:03:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:03:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:03:17 INFO - 'work_dir': 'build'} 10:03:17 INFO - [mozharness: 2016-11-14 18:03:17.162637Z] Running clobber step. 10:03:17 INFO - Running pre-action listener: _resource_record_pre_action 10:03:17 INFO - Running main action method: clobber 10:03:17 INFO - rmtree: /builds/slave/test/build 10:03:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:03:19 INFO - Running post-action listener: _resource_record_post_action 10:03:19 INFO - [mozharness: 2016-11-14 18:03:19.398932Z] Finished clobber step (success) 10:03:19 INFO - [mozharness: 2016-11-14 18:03:19.399048Z] Running read-buildbot-config step. 10:03:19 INFO - Running pre-action listener: _resource_record_pre_action 10:03:19 INFO - Running main action method: read_buildbot_config 10:03:19 INFO - Using buildbot properties: 10:03:19 INFO - { 10:03:19 INFO - "project": "", 10:03:19 INFO - "product": "firefox", 10:03:19 INFO - "script_repo_revision": "production", 10:03:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:03:19 INFO - "repository": "", 10:03:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:03:19 INFO - "buildid": "20161114072544", 10:03:19 INFO - "pgo_build": "False", 10:03:19 INFO - "basedir": "/builds/slave/test", 10:03:19 INFO - "buildnumber": 12, 10:03:19 INFO - "slavename": "t-yosemite-r7-0198", 10:03:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:03:19 INFO - "platform": "yosemite_r7", 10:03:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:03:19 INFO - "branch": "mozilla-central", 10:03:19 INFO - "repo_path": "mozilla-central", 10:03:19 INFO - "revision": "feddafb5cb546b15b160260da8632beb6b89bd71", 10:03:19 INFO - "stage_platform": "macosx64", 10:03:19 INFO - "builduid": "9ee472d3e4524b47a154d9031ac16837", 10:03:19 INFO - "slavebuilddir": "test" 10:03:19 INFO - } 10:03:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 10:03:19 INFO - Running post-action listener: _resource_record_post_action 10:03:19 INFO - [mozharness: 2016-11-14 18:03:19.402297Z] Finished read-buildbot-config step (success) 10:03:19 INFO - [mozharness: 2016-11-14 18:03:19.402434Z] Running download-and-extract step. 10:03:19 INFO - Running pre-action listener: _resource_record_pre_action 10:03:19 INFO - Running main action method: download_and_extract 10:03:19 INFO - mkdir: /builds/slave/test/build/tests 10:03: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')]} 10:03:19 INFO - trying https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 10:03:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 10:03:20 INFO - Downloaded 1199 bytes. 10:03:20 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 10:03:20 INFO - Using the following test package requirements: 10:03:20 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:03:20 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 10:03:20 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 10:03:20 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'jsshell-mac.zip'], 10:03:20 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 10:03:20 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 10:03:20 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 10:03:20 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 10:03:20 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 10:03:20 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 10:03:20 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 10:03:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:03:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:03:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:20 INFO - Fetch https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:03:21 INFO - Http code: 200 10:03:21 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 10:03:21 INFO - x-amz-cf-id: XmrLNwjiXV8_I7d-AvFF6mc6vwRChu7rtpd7_6PQhEAj0vNhJ-6X1w== 10:03:21 INFO - x-amz-version-id: KDbbXG8zDrpTkKIic178qhCQl1ua.z21 10:03:22 INFO - Expected file size: 16328793 10:03:22 INFO - Obtained file size: 16328793 10:03:28 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:03:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 10:03:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:28 INFO - Fetch https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 10:03:29 INFO - Http code: 200 10:03:29 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 10:03:29 INFO - x-amz-cf-id: g870-kdnNwfKUcMStMGUYgrdrvZ3XkRO3vJSK5_ytPGbl3tDQ0Q-gg== 10:03:29 INFO - x-amz-version-id: KDbbXG8zDrpTkKIic178qhCQl1ua.z21 10:03:29 INFO - Expected file size: 16328793 10:03:29 INFO - Obtained file size: 16328793 10:03:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 10:03:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:03:35 INFO - Fetch https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 10:03:36 INFO - Http code: 200 10:03:36 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 10:03:36 INFO - x-amz-cf-id: fjmGKr2DVbalXjedOob7TD5zas2298-3R40mmQdSqNpYa5_Kn7VgWg== 10:03:36 INFO - x-amz-version-id: jkn14MN1JviPw7IZ4892agOWSCNHJoKl 10:03:37 INFO - Expected file size: 14409038 10:03:37 INFO - Obtained file size: 14409038 10:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:37 INFO - trying https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:37 INFO - Downloading https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 10:03:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 10:03:40 INFO - Downloaded 110964868 bytes. 10:03:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:40 INFO - mkdir: /builds/slave/test/properties 10:03:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:03:40 INFO - Writing to file /builds/slave/test/properties/build_url 10:03:40 INFO - Contents: 10:03:40 INFO - build_url:https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 10:03:41 INFO - Running post-action listener: _resource_record_post_action 10:03:41 INFO - Running post-action listener: set_extra_try_arguments 10:03:41 INFO - [mozharness: 2016-11-14 18:03:41.104674Z] Finished download-and-extract step (success) 10:03:41 INFO - [mozharness: 2016-11-14 18:03:41.104807Z] Running populate-webroot step. 10:03:41 INFO - Running pre-action listener: _resource_record_pre_action 10:03:41 INFO - Running main action method: populate_webroot 10:03:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:03:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:03:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:03:41 INFO - '--tppagecycles', 10:03:41 INFO - '7'], 10:03:41 INFO - 'tests': ['tsvgm']}}, 10:03:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:03:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:03:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:03:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:03:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:03:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['damp', 'tps']}, 10:03:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'tests': ['damp', 'tps']}, 10:03:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['dromaeo_dom']}, 10:03:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:03:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['basic_compositor_video']}, 10:03:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:03:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'other-e10s': {'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['a11yr', 10:03:41 INFO - 'ts_paint', 10:03:41 INFO - 'tpaint', 10:03:41 INFO - 'sessionrestore', 10:03:41 INFO - 'sessionrestore_no_auto_restore', 10:03:41 INFO - 'tabpaint']}, 10:03:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['tsvgx', 10:03:41 INFO - 'tsvgr_opacity', 10:03:41 INFO - 'tart', 10:03:41 INFO - 'tscrollx', 10:03:41 INFO - 'cart']}, 10:03:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:03:41 INFO - 'tsvgr_opacity', 10:03:41 INFO - 'tart', 10:03:41 INFO - 'tscrollx', 10:03:41 INFO - 'cart']}, 10:03:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'talos_options': ['--disable-e10s'], 10:03:41 INFO - 'tests': ['tp5o']}, 10:03:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'tests': ['tp5o']}, 10:03:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'talos_options': ['--disable-e10s', 10:03:41 INFO - '--xperf_path', 10:03:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:41 INFO - 'tests': ['tp5n']}, 10:03:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:03:41 INFO - 'talos_options': ['--xperf_path', 10:03:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:03:41 INFO - 'tests': ['tp5n']}}, 10:03:41 INFO - 'talos.zip': {'path': '', 10:03:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:03:41 INFO - Running post-action listener: _resource_record_post_action 10:03:41 INFO - [mozharness: 2016-11-14 18:03:41.123875Z] Finished populate-webroot step (success) 10:03:41 INFO - [mozharness: 2016-11-14 18:03:41.124007Z] Running create-virtualenv step. 10:03:41 INFO - Running pre-action listener: _resource_record_pre_action 10:03:41 INFO - Running main action method: create_virtualenv 10:03:41 INFO - Creating virtualenv /builds/slave/test/build/venv 10:03:41 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 10:03:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:03:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:03:41 INFO - Using real prefix '/tools/python27' 10:03:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:03:41 INFO - Installing distribute.............................................................................................................................................................................................done. 10:03:44 INFO - Installing pip.................done. 10:03:44 INFO - Return code: 0 10:03:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:03:45 INFO - Reading from file tmpfile_stdout 10:03:45 INFO - Output received: 10:03:45 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) 10:03:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:45 INFO - 'HOME': '/Users/cltbld', 10:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:45 INFO - 'LOGNAME': 'cltbld', 10:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:45 INFO - 'MOZ_NO_REMOTE': '1', 10:03:45 INFO - 'NO_EM_RESTART': '1', 10:03:45 INFO - 'PAGER': '/bin/cat', 10:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:45 INFO - 'PWD': '/builds/slave/test', 10:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:45 INFO - 'SHELL': '/bin/bash', 10:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:45 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:45 INFO - 'USER': 'cltbld', 10:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:45 INFO - 'XPC_FLAGS': '0x0', 10:03:45 INFO - 'XPC_SERVICE_NAME': '0', 10:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:03:45 INFO - Cleaning up... 10:03:45 INFO - Return code: 0 10:03:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:45 INFO - 'HOME': '/Users/cltbld', 10:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:45 INFO - 'LOGNAME': 'cltbld', 10:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:45 INFO - 'MOZ_NO_REMOTE': '1', 10:03:45 INFO - 'NO_EM_RESTART': '1', 10:03:45 INFO - 'PAGER': '/bin/cat', 10:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:45 INFO - 'PWD': '/builds/slave/test', 10:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:45 INFO - 'SHELL': '/bin/bash', 10:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:45 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:45 INFO - 'USER': 'cltbld', 10:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:45 INFO - 'XPC_FLAGS': '0x0', 10:03:45 INFO - 'XPC_SERVICE_NAME': '0', 10:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:45 INFO - Downloading/unpacking psutil>=3.1.1 10:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:03:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:03:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:03:49 INFO - Installing collected packages: psutil 10:03:49 INFO - Running setup.py install for psutil 10:03:49 INFO - building 'psutil._psutil_osx' extension 10:03:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:03:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:03:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:03:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:03:49 INFO - building 'psutil._psutil_posix' extension 10:03:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:03:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:03:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:03:50 INFO - ^ 10:03:50 INFO - 1 warning generated. 10:03:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:03:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:03:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:03:50 INFO - Successfully installed psutil 10:03:50 INFO - Cleaning up... 10:03:50 INFO - Return code: 0 10:03:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:50 INFO - 'HOME': '/Users/cltbld', 10:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:50 INFO - 'LOGNAME': 'cltbld', 10:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:50 INFO - 'MOZ_NO_REMOTE': '1', 10:03:50 INFO - 'NO_EM_RESTART': '1', 10:03:50 INFO - 'PAGER': '/bin/cat', 10:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:50 INFO - 'PWD': '/builds/slave/test', 10:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:50 INFO - 'SHELL': '/bin/bash', 10:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:50 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:50 INFO - 'USER': 'cltbld', 10:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:50 INFO - 'XPC_FLAGS': '0x0', 10:03:50 INFO - 'XPC_SERVICE_NAME': '0', 10:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:03:54 INFO - Installing collected packages: mozsystemmonitor 10:03:54 INFO - Running setup.py install for mozsystemmonitor 10:03:54 INFO - Successfully installed mozsystemmonitor 10:03:54 INFO - Cleaning up... 10:03:54 INFO - Return code: 0 10:03:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:03:54 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')]} 10:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:54 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')]} 10:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:54 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:54 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 10:03:54 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 10:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:54 INFO - 'HOME': '/Users/cltbld', 10:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:54 INFO - 'LOGNAME': 'cltbld', 10:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:54 INFO - 'MOZ_NO_REMOTE': '1', 10:03:54 INFO - 'NO_EM_RESTART': '1', 10:03:54 INFO - 'PAGER': '/bin/cat', 10:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:54 INFO - 'PWD': '/builds/slave/test', 10:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:54 INFO - 'SHELL': '/bin/bash', 10:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:54 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:54 INFO - 'USER': 'cltbld', 10:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:54 INFO - 'XPC_FLAGS': '0x0', 10:03:54 INFO - 'XPC_SERVICE_NAME': '0', 10:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:54 INFO - Downloading/unpacking jsonschema==2.5.1 10:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:03:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:03:57 INFO - Downloading functools32-3.2.3-2.tar.gz 10:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:03:57 INFO - warning: no files found matching '*.txt' 10:03:57 INFO - no previously-included directories found matching 'build' 10:03:57 INFO - no previously-included directories found matching 'dist' 10:03:57 INFO - no previously-included directories found matching '.git*' 10:03:57 INFO - Installing collected packages: jsonschema, functools32 10:03:57 INFO - Running setup.py install for functools32 10:03:57 INFO - warning: no files found matching '*.txt' 10:03:57 INFO - no previously-included directories found matching 'build' 10:03:57 INFO - no previously-included directories found matching 'dist' 10:03:57 INFO - no previously-included directories found matching '.git*' 10:03:57 INFO - Successfully installed jsonschema functools32 10:03:57 INFO - Cleaning up... 10:03:58 INFO - Return code: 0 10:03:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:58 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:58 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 10:03:58 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 10:03:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:58 INFO - 'HOME': '/Users/cltbld', 10:03:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:58 INFO - 'LOGNAME': 'cltbld', 10:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:58 INFO - 'MOZ_NO_REMOTE': '1', 10:03:58 INFO - 'NO_EM_RESTART': '1', 10:03:58 INFO - 'PAGER': '/bin/cat', 10:03:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:58 INFO - 'PWD': '/builds/slave/test', 10:03:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:58 INFO - 'SHELL': '/bin/bash', 10:03:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:58 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:58 INFO - 'USER': 'cltbld', 10:03:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:58 INFO - 'XPC_FLAGS': '0x0', 10:03:58 INFO - 'XPC_SERVICE_NAME': '0', 10:03:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:03:58 INFO - Cleaning up... 10:03:58 INFO - Return code: 0 10:03:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:03:58 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:03:58 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 10:03:58 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 10:03:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:03:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:03:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:03:58 INFO - 'HOME': '/Users/cltbld', 10:03:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:03:58 INFO - 'LOGNAME': 'cltbld', 10:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:58 INFO - 'MOZ_NO_REMOTE': '1', 10:03:58 INFO - 'NO_EM_RESTART': '1', 10:03:58 INFO - 'PAGER': '/bin/cat', 10:03:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:03:58 INFO - 'PWD': '/builds/slave/test', 10:03:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:03:58 INFO - 'SHELL': '/bin/bash', 10:03:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:03:58 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:03:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:03:58 INFO - 'USER': 'cltbld', 10:03:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:03:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:03:58 INFO - 'XPC_FLAGS': '0x0', 10:03:58 INFO - 'XPC_SERVICE_NAME': '0', 10:03:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:58 INFO - Downloading/unpacking blobuploader==1.2.4 10:03:58 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 10:03:58 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 10:03:58 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 10:03:58 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 10:03:58 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 10:03:58 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 10:04:01 INFO - Downloading blobuploader-1.2.4.tar.gz 10:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:04:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:04:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:02 INFO - Downloading docopt-0.6.1.tar.gz 10:04:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:04:02 INFO - Installing collected packages: blobuploader, requests, docopt 10:04:02 INFO - Running setup.py install for blobuploader 10:04:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:04:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:04:02 INFO - Running setup.py install for requests 10:04:03 INFO - Running setup.py install for docopt 10:04:03 INFO - Successfully installed blobuploader requests docopt 10:04:03 INFO - Cleaning up... 10:04:03 INFO - Return code: 0 10:04:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04: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')]} 10:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:03 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:04:03 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 10:04:03 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 10:04:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:04:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:03 INFO - 'HOME': '/Users/cltbld', 10:04:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:03 INFO - 'LOGNAME': 'cltbld', 10:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:03 INFO - 'MOZ_NO_REMOTE': '1', 10:04:03 INFO - 'NO_EM_RESTART': '1', 10:04:03 INFO - 'PAGER': '/bin/cat', 10:04:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:03 INFO - 'PWD': '/builds/slave/test', 10:04:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:03 INFO - 'SHELL': '/bin/bash', 10:04:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:04:03 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:04:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:03 INFO - 'USER': 'cltbld', 10:04:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:03 INFO - 'XPC_FLAGS': '0x0', 10:04:03 INFO - 'XPC_SERVICE_NAME': '0', 10:04:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Tl2nNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-koxmBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-R2QmZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:03 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-1Htrf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-CZcVs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-x2QKFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-TuiTEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FVUQ6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-UemMzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-34ejos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6bDZEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-UyELUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ZfEoqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:04 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Kf1hlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:05 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8HZztE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:05 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-F4RER2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:05 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-lFKXYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:04:05 INFO - Running setup.py install for manifestparser 10:04:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:04:05 INFO - Running setup.py install for mozcrash 10:04:05 INFO - Running setup.py install for mozdebug 10:04:05 INFO - Running setup.py install for mozdevice 10:04:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:04:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:04:05 INFO - Running setup.py install for mozfile 10:04:06 INFO - Running setup.py install for mozhttpd 10:04:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Running setup.py install for mozinfo 10:04:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Running setup.py install for mozInstall 10:04:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Running setup.py install for mozleak 10:04:06 INFO - Running setup.py install for mozlog 10:04:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Running setup.py install for moznetwork 10:04:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:04:06 INFO - Running setup.py install for mozprocess 10:04:07 INFO - Running setup.py install for mozprofile 10:04:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:04:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:04:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:04:07 INFO - Running setup.py install for mozrunner 10:04:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:04:07 INFO - Running setup.py install for mozscreenshot 10:04:07 INFO - Running setup.py install for moztest 10:04:07 INFO - Running setup.py install for mozversion 10:04:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:04:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:04:07 INFO - Cleaning up... 10:04:07 INFO - Return code: 0 10:04:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:08 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:04:08 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 10:04:08 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 10:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:08 INFO - 'HOME': '/Users/cltbld', 10:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:08 INFO - 'LOGNAME': 'cltbld', 10:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:08 INFO - 'MOZ_NO_REMOTE': '1', 10:04:08 INFO - 'NO_EM_RESTART': '1', 10:04:08 INFO - 'PAGER': '/bin/cat', 10:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:08 INFO - 'PWD': '/builds/slave/test', 10:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:08 INFO - 'SHELL': '/bin/bash', 10:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:04:08 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:08 INFO - 'USER': 'cltbld', 10:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:08 INFO - 'XPC_FLAGS': '0x0', 10:04:08 INFO - 'XPC_SERVICE_NAME': '0', 10:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ru0vfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:08 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)) 10:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Cu3Oxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:08 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)) 10:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-tWD_Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:08 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)) 10:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-L20BhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 10:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:08 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-nggwNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-UdIKMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-3tJ8Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-dyANkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-V3gNgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-mFIuD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-l48A6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-wNTiJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-h854nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:09 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)) 10:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:09 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-jJ2KP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:10 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)) 10:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-mPEPNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:10 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)) 10:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-MHvsU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 10:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-LnxSZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:10 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)) 10:04:10 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)) 10:04:10 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)) 10:04:10 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)) 10:04:10 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:04:10 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:04:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:04: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 10:04: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 10:04: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 10:04: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 10:04: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 10:04: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 10:04:13 INFO - Downloading blessings-1.6.tar.gz 10:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:04:13 INFO - Installing collected packages: blessings 10:04:13 INFO - Running setup.py install for blessings 10:04:13 INFO - Successfully installed blessings 10:04:13 INFO - Cleaning up... 10:04:13 INFO - Return code: 0 10:04:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:04:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:13 INFO - Reading from file tmpfile_stdout 10:04:13 INFO - Current package versions: 10:04:13 INFO - blessings == 1.6 10:04:13 INFO - blobuploader == 1.2.4 10:04:13 INFO - docopt == 0.6.1 10:04:13 INFO - functools32 == 3.2.3-2 10:04:13 INFO - jsonschema == 2.5.1 10:04:13 INFO - manifestparser == 1.1 10:04:13 INFO - mozInstall == 1.12 10:04:13 INFO - mozcrash == 1.0 10:04:13 INFO - mozdebug == 0.1 10:04:13 INFO - mozdevice == 0.48 10:04:13 INFO - mozfile == 1.2 10:04:13 INFO - mozhttpd == 0.7 10:04:13 INFO - mozinfo == 0.9 10:04:13 INFO - mozleak == 0.1 10:04:13 INFO - mozlog == 3.4 10:04:13 INFO - moznetwork == 0.27 10:04:13 INFO - mozprocess == 0.23 10:04:13 INFO - mozprofile == 0.28 10:04:13 INFO - mozrunner == 6.13 10:04:13 INFO - mozscreenshot == 0.1 10:04:13 INFO - mozsystemmonitor == 0.3 10:04:13 INFO - moztest == 0.7 10:04:13 INFO - mozversion == 1.4 10:04:13 INFO - psutil == 3.1.1 10:04:13 INFO - requests == 1.2.3 10:04:13 INFO - wsgiref == 0.1.2 10:04:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:13 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')]} 10:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 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')]} 10:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:13 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:04:13 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 10:04:13 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 10:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:13 INFO - 'HOME': '/Users/cltbld', 10:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:13 INFO - 'LOGNAME': 'cltbld', 10:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:13 INFO - 'MOZ_NO_REMOTE': '1', 10:04:13 INFO - 'NO_EM_RESTART': '1', 10:04:13 INFO - 'PAGER': '/bin/cat', 10:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:13 INFO - 'PWD': '/builds/slave/test', 10:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:13 INFO - 'SHELL': '/bin/bash', 10:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:04:13 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:13 INFO - 'USER': 'cltbld', 10:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:13 INFO - 'XPC_FLAGS': '0x0', 10:04:13 INFO - 'XPC_SERVICE_NAME': '0', 10:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 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)) 10:04:14 INFO - Cleaning up... 10:04:14 INFO - Return code: 0 10:04:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:04:14 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')]} 10:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:14 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')]} 10:04:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:14 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 0x108863490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907b58e570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, '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.rp1xjxSMbC/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.kLfqM81qKy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 10:04:14 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 10:04:14 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 10:04:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:04:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:14 INFO - 'HOME': '/Users/cltbld', 10:04:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:14 INFO - 'LOGNAME': 'cltbld', 10:04:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:14 INFO - 'MOZ_NO_REMOTE': '1', 10:04:14 INFO - 'NO_EM_RESTART': '1', 10:04:14 INFO - 'PAGER': '/bin/cat', 10:04:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:14 INFO - 'PWD': '/builds/slave/test', 10:04:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:14 INFO - 'SHELL': '/bin/bash', 10:04:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:04:14 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:04:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:14 INFO - 'USER': 'cltbld', 10:04:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:14 INFO - 'XPC_FLAGS': '0x0', 10:04:14 INFO - 'XPC_SERVICE_NAME': '0', 10:04:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:04:14 INFO - Cleaning up... 10:04:14 INFO - Return code: 0 10:04:14 INFO - Running post-action listener: _resource_record_post_action 10:04:14 INFO - Running post-action listener: _start_resource_monitoring 10:04:14 INFO - Starting resource monitoring. 10:04:14 INFO - [mozharness: 2016-11-14 18:04:14.437260Z] Finished create-virtualenv step (success) 10:04:14 INFO - [mozharness: 2016-11-14 18:04:14.438172Z] Running install step. 10:04:14 INFO - Running pre-action listener: _resource_record_pre_action 10:04:14 INFO - Running main action method: install 10:04:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:14 INFO - Reading from file tmpfile_stdout 10:04:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:04:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:04:14 INFO - Reading from file tmpfile_stdout 10:04:14 INFO - Output received: 10:04:14 INFO - Usage: mozinstall [options] installer 10:04:14 INFO - Options: 10:04:14 INFO - -h, --help show this help message and exit 10:04:14 INFO - -d DEST, --destination=DEST 10:04:14 INFO - Directory to install application into. [default: 10:04:14 INFO - "/builds/slave/test"] 10:04:14 INFO - --app=APP Application being installed. [default: firefox] 10:04:14 INFO - mkdir: /builds/slave/test/build/application 10:04:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:04:49 INFO - Reading from file tmpfile_stdout 10:04:49 INFO - Output received: 10:04:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:04:49 INFO - Running post-action listener: _resource_record_post_action 10:04:49 INFO - [mozharness: 2016-11-14 18:04:49.216625Z] Finished install step (success) 10:04:49 INFO - [mozharness: 2016-11-14 18:04:49.216765Z] Running run-tests step. 10:04:49 INFO - Running pre-action listener: _resource_record_pre_action 10:04:49 INFO - Running main action method: run_tests 10:04:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:49 INFO - 2016-11-14 10:04:49.231 screenresolution[1639:7802] starting screenresolution argv=screenresolution get 10:04:49 INFO - 2016-11-14 10:04:49.234 screenresolution[1639:7802] Display 0: 1600x1200x32@60 10:04:49 INFO - 2016-11-14 10:04:49.239 screenresolution[1640:7804] starting screenresolution argv=screenresolution list 10:04:49 INFO - Available Modes on Display 0 10:04:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:49 INFO - Intel Iris: 10:04:49 INFO - Chipset Model: Intel Iris 10:04:49 INFO - Type: GPU 10:04:49 INFO - Bus: Built-In 10:04:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:49 INFO - Vendor: Intel (0x8086) 10:04:49 INFO - Device ID: 0x0a2e 10:04:49 INFO - Revision ID: 0x0009 10:04:49 INFO - Displays: 10:04:49 INFO - Display: 10:04:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:49 INFO - Display Serial Number: 1600x1200 60 10:04:49 INFO - Main Display: Yes 10:04:49 INFO - Mirror: Off 10:04:49 INFO - Online: Yes 10:04:49 INFO - Rotation: Supported 10:04:49 INFO - Return code: 0 10:04:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:04:49 INFO - Python 2.7.3 10:04:49 INFO - Return code: 0 10:04:49 INFO - grabbing minidump binary from tooltool 10:04:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:49 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 0x108677d30>, '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 0x10849ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:04:49 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 10:04:49 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 10:04:49 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 10:04:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:04:49 INFO - Return code: 0 10:04:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:04:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:04:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:04:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:04:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:04:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 10:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0198 --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 10:04:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rp1xjxSMbC/Render', 10:04:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:49 INFO - 'HOME': '/Users/cltbld', 10:04:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:49 INFO - 'LOGNAME': 'cltbld', 10:04:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:04:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:49 INFO - 'MOZ_NO_REMOTE': '1', 10:04:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:04:49 INFO - 'NO_EM_RESTART': '1', 10:04:49 INFO - 'PAGER': '/bin/cat', 10:04:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:49 INFO - 'PWD': '/builds/slave/test', 10:04:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:04:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:49 INFO - 'SHELL': '/bin/bash', 10:04:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kLfqM81qKy/Listeners', 10:04:49 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 10:04:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:49 INFO - 'USER': 'cltbld', 10:04:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:49 INFO - 'XPC_FLAGS': '0x0', 10:04:49 INFO - 'XPC_SERVICE_NAME': '0', 10:04:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 10:04:49 INFO - mozversion application_buildid: 20161114072544 10:04:49 INFO - mozversion application_changeset: feddafb5cb546b15b160260da8632beb6b89bd71 10:04:49 INFO - mozversion application_display_name: Nightly 10:04:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:04:49 INFO - mozversion application_name: Firefox 10:04:49 INFO - mozversion application_remotingname: firefox 10:04:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:04:49 INFO - mozversion application_vendor: Mozilla 10:04:49 INFO - mozversion application_version: 53.0a1 10:04:49 INFO - mozversion platform_buildid: 20161114072544 10:04:49 INFO - mozversion platform_changeset: feddafb5cb546b15b160260da8632beb6b89bd71 10:04:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:04:49 INFO - mozversion platform_version: 53.0a1 10:04:49 INFO - using testdate: 1479146689 10:04:49 INFO - actual date: 1479146689 10:04:49 INFO - starting webserver on 'localhost:49273' 10:04:49 INFO - SUITE-START | Running 2 tests 10:04:49 INFO - TEST-START | dromaeo_css 10:04:49 INFO - operating with platform_type : mac_ 10:04:49 INFO - Initialising browser for dromaeo_css test... 10:04:49 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpIYQ3ZJ/profile http://localhost:49273/getInfo.html) 10:04:52 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 10:04:52 INFO - PROCESS | 1650 | colorDepth:24 10:04:52 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 10:04:52 INFO - PROCESS | 1650 | __metrics 10:04:52 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:04:52 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:04:53 INFO - TEST-INFO | 1650: exit 0 10:04:53 INFO - Browser initialized. 10:04:53 INFO - Running cycle 1/1 for dromaeo_css test... 10:04:53 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpIYQ3ZJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:05 INFO - PROCESS | 1652 | RSS: Main: 238063616 10:05:05 INFO - PROCESS | 1652 | 10:05:56 INFO - PROCESS | 1652 | 1479146756016 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:08:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 10:08:11 INFO - PROCESS | 1652 | RSS: Main: 398426112 10:08:11 INFO - PROCESS | 1652 | 10:11:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 10:11:14 INFO - PROCESS | 1652 | RSS: Main: 372285440 10:11:14 INFO - PROCESS | 1652 | 10:14:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 10:14:17 INFO - PROCESS | 1652 | RSS: Main: 339189760 10:14:17 INFO - PROCESS | 1652 | 10:17:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 10:17:20 INFO - PROCESS | 1652 | RSS: Main: 347807744 10:17:20 INFO - PROCESS | 1652 | 10:20:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 10:20:24 INFO - PROCESS | 1652 | RSS: Main: 332042240 10:20:24 INFO - PROCESS | 1652 | 10:23:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 10:23:28 INFO - PROCESS | 1652 | RSS: Main: 453103616 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | __start_tp_report 10:23:28 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 10:23:28 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 10:23:28 INFO - PROCESS | 1652 | |i|pagename|runs| 10:23:28 INFO - PROCESS | 1652 | |0;dojo.html;3041.4221297953372;3283.9163076184846;3289.7203737682294;3302.669733026697;3363.192833719908;5575.8606034849045;5635.154726790984;5661.66030038198;5687.345955215152;5728.971355143223;5618.353889302732;5650.26546548948;5681.403452637475;5706.686132262724;5821.068629019347;5168.069747445458;5187.844364669066;5247.921281180786;5248.947510524884;5257.8948421031555;4599.264117741162;5154.458781827911;5269.841904742864;5278.683279003272;5308.9469105308835;5255.369355677305;5270.288511050997;5276.709780962045;5311.044012077825;5313.1764576490705;5110.029094472038;5158.329417175743;5172.767225474862;5229.5293423591675;5230.293910322096;5071.771770270347;5149.433562308143;5151.304573882516;5162.380514338266;5234.136367499358;5617.691026993513;5648.096304591246;5648.774049038034;5657.773689052433;5674.801381951612;6070.6661133637635;6161.7535298588;6169.136320915076;6171.691415429255;6181.474574661159;6119.694015299217;6158.168647232611;6219.067139929001;6243.96878015607;6246.87506249877;5808.099744539603;5809.070548712186;5812.041013232769;5848.88302233953;5858.677772722499;6147.477464415485;6184.938150618436;6241.75032998684;6246.937530624727;6256.343083976208;5981.760729570856;6054.666993315322;6057.939420605826;6130.908036379458;6132.386761323921;6152.569320147547;6212.347703491158;6232.844178895564;6271.122042913996;6312.810615681537;6199.256089269316;6206.975848984867;6226.034964580497;6229.0967809667345;6239.157713708682;6094.939050609437;6177.135201071854;6181.629102253879;6243.938530449835;6252.406021427957;4866.902661946741;4909.386326709161;4913.238448040559;4916.164252077156;4918.803247870117;4381.78091095444;4427.579379958899;4440.6447484201835;4479.283314669638;4482.551744825491;5808.39011903744;5811.4479124483105;5811.476967072984;5818.156367326714;5831.94168058314;6234.532410069263;6295.087212354183;6303.149074874925;6314.273858506239;6323;5625.634333768292;5680.659160450304;5695.886082278331;5744.712764361765;5751.539876809845;5340.572754179656;5381.865453363619;5388.91916621251;5419.810306639249;5453.100238460689;5290.788368465295;6196.194494715658;6263.530235232284;6272.6236425814595;6303.716332765129;6103.125543060039;6278.937210627835;6306.148669929501;6316.147320111726;6316.305206427382;6171.321154673072;6183.783567575113;6201.162843016135;6229.906551401823;6234.875302494015;6118.680093383066;6119.807225076679;6120.081455037822;6123.343628574205;6136.151476879544;6116.847078823065;6173.969130154321;6206.906896396467;6216.067589861557;6230.06549017651;5894.440028197314;5948.940510594839;5980.521558275241;6009.308929473166;6009.669468179292;4674.929876051794;5990.281166260077;6052.66710330936;6099.915141108964;6123.203983482119;5917.556183286357;5922.851928701817;5929.080992446177;5938.970305148447;5940.257467816523;5814.941850581609;5995.220621319375;6040.546958978182;6112.816615501542;6133.294671112701;5741.339745929272;5834.066549352253;5836.270466191726;5837.182794408701;5838.795642152504 10:23:28 INFO - PROCESS | 1652 | |1;ext.html;453.3087042260552;508.76370419879703;512.0987062769526;513.1558586125825;536.9785208591654;27925.581116283272;30248.941287054957;31102.377952440966;31144.84427577859;37652.623473765256;34190;38164.809175954084;39910.201795964065;40009.799951000205;40124.59875401245;9037.231835293991;9294.163525282724;9294.814103717921;9298.395604285733;9313.767155821124;9222.86165707515;9338.486383248919;9339.15947564719;9342.346035777498;9357.298202634796;9268.563372677247;9341.43951362917;9346.205572526344;9360.970293267736;9365.719028429125;9260.490673012982;9300.395474294182;9331.953340233324;9337.159655630958;9343.2525397968;9242.260619150451;9330.486823224719;9332.95333523328;9333.813323733488;9351.812963740755;38379.424308635396;38693.419598706045;38753;38790;38813.02967425809;3114.595102636643;3169.4611915974338;3198.712115909579;3502.7548071634997;3566.5720113586276;28974.710252897414;29116.12651620455;29754;30308.84845575758;30835.84582077075;2741.752160296207;3129.4679904416303;3130.812151270931;3132.1073494054085;3183.570218020561;480643.5967820173;480737;487215;487264;488279;51495.97008059818;57257.42742572604;58374.41625583775;58496.70751646214;58620;59331.70334148388;62205.68897155485;62205.68897155485;62275.68862155751;62348;15186.924065379602;15362.615934601501;15376.846231537766;15377.154256516;15660.68678626421;11957.820632690517;12033.518659253532;12039.819402708967;12055.939720301343;12062.276263424223;18015.009174495528;18030.459086227434;18065.657853297183;18067.819321806877;18185.181666825025;7458.552486850806;7491.400687944952;7556.2443755624;7627.733029343946;7661.348785353196;863.9870401943975;908.0737647599411;1032.850236715672;1065.1904552540125;1070.5343175718588;26605.733942660714;28816.279593009924;29137.85431072831;29253;29605.25986850346;983.5475681186591;1027.0807627173638;1031.7523794289316;1071.6356438810842;1134.6709454258337;12021.519139234508;14878.884083693767;14880.62798430048;15205.31576079079;15251.771223431657;558915;640850;645750.9412076467;645791;647871;54333.72833135888;55072.44927550673;55089;55102.89794204094;55273.44726552763;1299.311364976564;1342.9664258393423;1364.2564802182835;1365.8019587159806;1367.193355920012;28034.579481308203;28380.85809570939;28592.97180387715;28633.42733145367;28972.565411519266;48844.26733598928;49222.261666075756;49235.75382123067;49336.50663493462;49343;573554;575396;575986.1200693969;576103;577335;25240.87379563164;25503.489930201664;25836.483270334866;25855.870720646275;25914.22257332283;37332.880013599635;43044.56955430531;43939.78030109829;45114;45226.54773452223;36572.63427365799;36952.07619809526;37194;37440.438393424665;37867.431988520744;29501.704982949897;36162.638373615926;36741.816290918374;37587.248255035294;37975.05062373352;28557;31015;31154.844225778725;35743.64256357403;36704.63295367012;26837.597436038086;30507.54238686377;31909.36181276408;32844.67155328416;32959.835200824804;24143;24442.388940275923;24702.629460557746;24928.75071249264;25030.749692502843 10:23:28 INFO - PROCESS | 1652 | |2;jquery.html;342.3324517191474;358.43188546154346;363.0759716521451;369.40341348721813;392.111866622101;14257.797308858982;14791.630209244775;15357.078575285477;15508.208522465951;18997.620047599074;18704.812951870477;18906.716399254023;18952.715709264372;19194.712079318822;19403.223871045142;5314.99999999999;5788.334341550712;5837.532997360201;5848.941510584882;5852.67810270435;5451.890962180754;5626.578006649497;5710.971445142789;5754.136879468071;5776.306843178824;5741.511971482429;5748.568857335696;5750.683712395817;5751.10857817037;5751.511121554673;5571.1086226203815;5609.495145436889;5677.120046392815;5714.685692286923;5714.971425142869;4998.7250701211415;5026.371703537058;5031.119554078058;5031.345925029723;5033.446320904697;24351.756482435125;24653.63019554708;24695.25914222576;24754.50490990188;25038.749612503823;2340.8595484271;2547.522436986543;2619.3320703220747;3029.2124047747525;3125.7343125834104;12641.747165056648;12751.979841612649;14048.086874353237;15001.924990374979;15226.390944362212;1752.666993271287;1776.9733453998201;1788.7316902464604;1841.5212044868297;1875.559243577758;106016;117106.24340634898;118094;118225;118621.40689296498;9241.584128714225;11126.88731126897;11193.94403027996;11463.598774042868;11466.713332166595;14002.879769618406;14037.5086871959;14043.438262469592;14048.297585120907;14052.508162214477;7938.5236885787035;7993.520388776692;7994.480358776661;8375.916240837636;8397.832043359098;7967.203279671987;8039.196080391914;8065;8209.343252539782;8240.13478584752;4374.671899607542;4384.495782984997;4413.977930110329;4414.359917811899;4503.527129651405;1692.1792930428733;1818.1636447234157;1880.9058649974259;1916.6933290673426;1924.6054558815408;831.2684837343099;858.2190206911805;905.2441211588398;964.981944049021;998.8501724741202;19447.513812154808;20293.898530507548;20367.87976661268;20446.38660840177;20746.170153194005;751.4889874885131;858.587877818638;866.1330994408528;899.7570655922864;943.7168849345306;10093.747656308651;10122.797544049077;10735;10746;10752;456218;549486;552273.2386338043;561334;566475;128635;130119;130277;131141.0930254618;133040.3391932156;910.4901255296922;913.3925939250324;948.2556193388157;951.0537015669453;951.0869565217469;17966.371177009263;18118.094095295448;18170.545736356697;18185.363512277007;18209.271629134688;51419.74290128525;51663;52455;52504;52563.73718131538;75235;75970;77254;77792.2220777785;77872;5396.325459317585;7634.389248859967;7937.206279372017;7942.285194332307;7966.960165199369;10074.496275186359;11450;11908.34504102248;12159.635210943687;12881.098323117292;10580.735481613021;10625.203109766644;11016.669499915015;11105.666829995112;11171.553137874394;9391.544310631863;10389.532471038972;10710.946445267724;10994.175436842108;11116.777664446827;10454.738631533968;10827.67516974492;10874.728131796768;11209.607663731731;11237.831432528355;7006.789796306119;7736.226377362444;7873.685052597832;7907.9604601976625;8099.878501822595;10003.899961000297;10527.94736026315;11032.724181895517;11049;11161.274517156358 10:23:28 INFO - PROCESS | 1652 | |3;mootools.html;1341.496938648007;1488.724585951599;1499.3777582303344;1667.8999260044397;1847.0395394394916;7432.405407567395;7860.096088949773;8109.148539403359;8360.163983601637;8403.243708066273;8238.95880520598;8325.125861784516;8329;8335.249827515523;8339.708110216143;2269.1377276634857;2294.4149241943283;2301.2750983440164;2301.493671392291;2304.193532263711;2242.6075436798496;2243.6634504824324;2314.351981445198;2315.270689732729;2316.837821352506;2258.322503249027;2270.71616047994;2279.544091181762;2289.175896677195;2299.0803678528637;2238.932832015042;2249.988750056247;2280.703508543895;2280.9543809123725;2283.862968221912;2229.063793206857;2289.6565515172692;2292.5071109711494;2295.7130358705163;2302.26327575176;8310.168983101701;8364.205400487004;8364.247217750371;8379.62291696875;8403.159684031549;4916.459189489154;5120.231965205212;5122.078025955327;5156.793728250866;5171.482851714836;3256.47896336585;3454.3091381723757;3464.566929133858;3530.046887340404;3541.3448512025357;976.1214906584061;1022.8874823769379;1046.5604446132565;1054.1092776603757;1069;10689.30519516229;10749.301295415851;10755.623553175601;10827.458627068616;10838.458077096113;3782.470454136423;4064.9634343242874;4261.765602891809;4352.891177720519;4360.716553424018;8549;8570.74287771368;8573.356998225157;8578.957105214558;8579.056303806576;4898.730569818623;5006.549410553068;5007.324011258506;5014.2979982802435;5018.89962200754;4063.2482990646827;4087.1212689271947;4212.957870421318;4550.271956486946;4702.553257440538;3320.2197483591335;3349.4975753636663;3445.4659527773233;3456.9654303456646;3614.150674591469;964.7829238421299;1087.983680244797;1104.265663333874;1118.949647265875;1127.8026345389687;806.7176488229073;829.6515463505342;857.5283594023263;902.4224496322358;906.9637214511478;5402.08164612009;5560.972195139079;5798.652080875161;5879.353271140171;5923.437273459014;960.5485421851719;975.6048800235916;990.2721499697716;997.9700608981742;1046.1317106801337;3656.670899619047;3689.7232707546505;3710.793991952626;3785.4511095890944;3834.079820842978;9770.169535589412;10036.39781613091;10136.746581335377;10180.592776289013;10183.643572474928;10119.645812396531;10123;10137.746556336151;10149.949250253701;10159.644412445678;7530.585817780185;7699.961500192463;7887.723929662549;7909.564973926489;7933.801654958672;4589.885252868705;5502.972485137549;5599;5702.144678298123;5835.620684655653;9065;9357.719268421959;9370;9483.952580237055;9523.952365239957;8123;9372.34393592442;9462.668806591737;9541.713748587554;9607;7027.8565037664;7073.009778631099;7128.322809333105;7182.317679820409;7220;7763.883541746765;7839.09850367215;7842.686292548234;7849.882251766114;7851.293383595504;7010.684519196547;7108.964455177691;7116.217216106328;7210.242924493063;7287.4534409920525;6753.459723222229;6907.171139463297;6946.5137440378685;6988.231294557493;6991.196012458632;6343.9682801585695;6512.576682515811;6609;6610.801675949537;6677.799666010027;4703.482616912069;4714.858554243378;4785.95214047869;4814.229723244264;4941.209406494943;3843;4746.311784791255;4869.975650121868;4923.433805112458;5027.145385284437 10:23:28 INFO - PROCESS | 1652 | |4;prototype.html;276.0751482533513;292.22560215429115;306.2557984098642;306.4223937877102;310.870988539756;4403.889902752433;5111.258867464216;5251.949610077985;5302.072137375953;5334.359876814778;5121.284369736143;5123.641345105835;5182.10853038641;5187.48125187481;5202.788814753724;4001.659858911996;4035.132446523997;4040.151693056403;4061.73598716084;4065.0447144920918;3727.5154229950067;4017.1764788218347;4056.7565946043333;4098.95476653453;4101.79491025449;3657.664952292421;3882.0294926268434;3983.880483585497;4002.8398864045403;4006.5793091725386;3328.3842489139342;3873.9346679663036;3961.227560625679;3961.7622942623534;3974.3839704845795;3310.5034244863223;3419.059758566389;3423.503591979149;3441.346144232588;3484.390231709466;4188.853390131361;4432.20220360335;4525.479569849444;4570.451545814491;4748.670372295728;4452.577005184502;4468.642508599304;4496.820127194908;4497.775111244458;4509.751963641998;4501;5286.418493965661;5314.388845282803;5375.408705042442;5465.934142842148;3207.85564649589;3712.2575484903127;3713.2387860488807;3714.2171757556252;3734;5682.943170568283;5694.373618901917;5702.629329093637;5709;5766.308043034863;3227.8870239541975;3658.5183000884685;3797.1646237827636;3801.06145865605;3815.0271680721794;3251.4960181171955;3333.383324085052;3786.753860999026;3812.6377994090517;3843.0776613612534;4445.755483448376;4471.776411179428;4506.152477484281;4548.793834990027;4598.609118224921;4402.8679139625865;4866.853994380174;5450.538907793501;5489.258950041693;5583.3858275589655;1089.226649079147;1126.9661910142709;1150.5857891159176;1188.2989036468525;1249.8000319948765;738.737748099417;836.2766207230702;846.4498076250486;856.3363393370064;867.3971589745066;4026.7181297309494;4095.365218391154;4580.519045500241;4775.469001429248;4888.560029597354;4275.819332140631;4572.039871626996;5195.922061169085;5308.115618955292;5373.892522149613;4275;4282.678799090081;4434.201843668106;4756.262779269219;4866.90722989111;2302.4013756423115;2527.36815796051;2735.617013618095;2778.9583156252675;2779.374640705825;5473.7263136843;5487.75305111271;5598.636088654224;5602.406027057774;5603.439656034446;4909.3126962225315;5081.263216833612;5414.891702166014;5451.48210919962;5470.56235501159;4891.3559398248535;5402;5423.267858839006;5439.265699130646;5519.448055194448;4432;5262.236975638588;5271.917592550844;5292.708901010481;5378.515933565998;4606.976965115153;5140.30605868203;5166.689998600096;5196.844094677166;5803.7968671096305;4531.750753708446;5315.946840531546;5598.580106492111;5895.526118470382;5974;3246.4967929970894;3316.4527852904966;3700.0009997301117;3741.7567858089137;3817.9809100954317;4965.255211718271;5516;5633.943610582026;5862;5873.148393482836;5516.862078448071;5587.916181257366;5596.132599447093;5610.7755689773585;5620.606557540933;5496.890062198654;5562.554995600423;5591.888162236651;5612.831615051555;5663.66018038919;4751;4953.826616068421;5189.948100518946;5316.6544174628525;5418.834950485664;4694.460137084279;4846.975765121293;5525.364583072998;5538.113901775697;5582.7766889323975;4259.467566554181;4880.585150262267;5408.918866217088;5476.808311709071;5573.804916827891 10:23:28 INFO - PROCESS | 1652 | |5;yui.html;578.6730497269043;692.3180667042965;706.9399101076411;761.9238076192381;779.9142094369619;13523.729525409486;13786.517471888486;13842.792358114637;14080.225587592678;14089.57731268061;12430.937845310784;13604.727905441885;14089.084209526374;14305.427782888672;14375.92812035941;651.4365074210821;804.67008526504;827.4993628854546;828.5567221536479;828.747232094214;614.0390289197392;639.59705385607;665.8668266346726;673.377126158304;795.3041089047084;861.9224269815716;865.5628907401784;871.3290766110091;871.3290766110091;872.5724395046414;863.5078005536847;863.9654413823503;864.5072308783996;864.6627815152095;866.3588944181323;501.02096717963826;517.5574883474612;637.2448648351718;638.6934271549682;638.8626445314279;12890.87109128906;13122.737545249041;13292.20246785196;13811.723765524734;13811.792823107662;12514.81227781584;12625.810612840814;12831.294278814661;12937.676558086123;13165.946724262036;10133.746656333651;10508.579656813794;10527.94736026315;10549.73625659357;10656.307340022873;3541.8406171722077;3556.8043757593323;3575.606683264839;3613.6567026132475;3672.797996110213;95408;95902.99375489858;95994.52002740011;96472.51763741206;97231;8533.829323413496;9733.734614500212;9770.75573110678;9838.262130340254;9994.10053095225;19829.405117846487;20043;20173.596528069356;20185.697214541793;20376.28682996088;7094.326039026284;7133.82165445868;7207.783766487014;7314.634268286564;7409.814754631069;3952.8616498422416;3993.600639935983;4000.9199816003515;4006.958190870395;4140.544540100586;3180.840957952093;3297.1427428868665;3300.405926933127;3302.1579497228536;3302.339532093591;594.191899017336;596.0790578556141;600.3786081405724;608.6530677513774;609.9817005489842;512.6667666017045;522.6733291692692;565.5108331293402;619.3558698953128;645.1902861908369;8753.124687531195;8802.427842190236;8898.777530561787;8900.332475064397;9413.670521531714;600.5885968111858;603.5774957529746;618.1386608644016;657.1227411405731;672.7342699633617;3292.983535082309;4243;4257.340112282601;4359.280718681445;4375.624771123407;87292;90655;92437.07562924284;97268.05463890824;118143;64984;91564.54217728869;92353.5382323084;94433;96223;72649.91025134521;81978.59010704908;83391.74912376319;84126;84710.30579388377;9149.313801464783;9322.300827437834;9350.205232555038;9360.11078947499;9363.438193708127;38460.230795384494;48889.02221955612;49016.754916226615;52024;53397;72271.63864180645;74586.62706686431;76419;76577;79851;9862.457564833716;11793.469293881624;11969.461374238354;11974.521019159136;12073.346953011967;11031.503582338975;13332.733345333232;13417.731645367234;13631;14004.43982240699;12003.819942700691;12139.817902731289;12170.695732606755;12522.812157817822;12576.622701318975;12425.502979880703;12481.625551233114;12730.809037864625;12763.439256054355;12766.234025958101;12084.214526055779;12138.757224855277;12144.696382590506;12471.438785254866;12740.171888827197;9494.525273736424;9824.283522249227;10081.395116293046;10093.848592270975;10332.845007324746;9899.406035637596;10644.44533311984;10703.678889633324;10707.19696022817;10711.517981690999 10:23:28 INFO - PROCESS | 1652 | __end_tp_report 10:23:28 INFO - PROCESS | 1652 | __start_cc_report 10:23:28 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1836 10:23:28 INFO - PROCESS | 1652 | __end_cc_report 10:23:28 INFO - PROCESS | 1652 | __startTimestamp1479147808157__endTimestamp 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | ------- Summary: start ------- 10:23:28 INFO - PROCESS | 1652 | Number of tests: 6 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5704.71 Median:5858.68 stddev:643.95 (11.0%) stddev-sans-first:611.09 10:23:28 INFO - PROCESS | 1652 | Values: 3041.4 3283.9 3289.7 3302.7 3363.2 5575.9 5635.2 5661.7 5687.3 5729.0 5618.4 5650.3 5681.4 5706.7 5821.1 5168.1 5187.8 5247.9 5248.9 5257.9 4599.3 5154.5 5269.8 5278.7 5308.9 5255.4 5270.3 5276.7 5311.0 5313.2 5110.0 5158.3 5172.8 5229.5 5230.3 5071.8 5149.4 5151.3 5162.4 5234.1 5617.7 5648.1 5648.8 5657.8 5674.8 6070.7 6161.8 6169.1 6171.7 6181.5 6119.7 6158.2 6219.1 6244.0 6246.9 5808.1 5809.1 5812.0 5848.9 5858.7 6147.5 6184.9 6241.8 6246.9 6256.3 5981.8 6054.7 6057.9 6130.9 6132.4 6152.6 6212.3 6232.8 6271.1 6312.8 6199.3 6207.0 6226.0 6229.1 6239.2 6094.9 6177.1 6181.6 6243.9 6252.4 4866.9 4909.4 4913.2 4916.2 4918.8 4381.8 4427.6 4440.6 4479.3 4482.6 5808.4 5811.4 5811.5 5818.2 5831.9 6234.5 6295.1 6303.1 6314.3 6323.0 5625.6 5680.7 5695.9 5744.7 5751.5 5340.6 5381.9 5388.9 5419.8 5453.1 5290.8 6196.2 6263.5 6272.6 6303.7 6103.1 6278.9 6306.1 6316.1 6316.3 6171.3 6183.8 6201.2 6229.9 6234.9 6118.7 6119.8 6120.1 6123.3 6136.2 6116.8 6174.0 6206.9 6216.1 6230.1 5894.4 5948.9 5980.5 6009.3 6009.7 4674.9 5990.3 6052.7 6099.9 6123.2 5917.6 5922.9 5929.1 5939.0 5940.3 5814.9 5995.2 6040.5 6112.8 6133.3 5741.3 5834.1 5836.3 5837.2 5838.8 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68356.03 Median:37619.94 stddev:151518.43 (402.8%) stddev-sans-first:151857.71 10:23:28 INFO - PROCESS | 1652 | Values: 453.3 508.8 512.1 513.2 537.0 27925.6 30248.9 31102.4 31144.8 37652.6 34190.0 38164.8 39910.2 40009.8 40124.6 9037.2 9294.2 9294.8 9298.4 9313.8 9222.9 9338.5 9339.2 9342.3 9357.3 9268.6 9341.4 9346.2 9361.0 9365.7 9260.5 9300.4 9332.0 9337.2 9343.3 9242.3 9330.5 9333.0 9333.8 9351.8 38379.4 38693.4 38753.0 38790.0 38813.0 3114.6 3169.5 3198.7 3502.8 3566.6 28974.7 29116.1 29754.0 30308.8 30835.8 2741.8 3129.5 3130.8 3132.1 3183.6 480643.6 480737.0 487215.0 487264.0 488279.0 51496.0 57257.4 58374.4 58496.7 58620.0 59331.7 62205.7 62205.7 62275.7 62348.0 15186.9 15362.6 15376.8 15377.2 15660.7 11957.8 12033.5 12039.8 12055.9 12062.3 18015.0 18030.5 18065.7 18067.8 18185.2 7458.6 7491.4 7556.2 7627.7 7661.3 864.0 908.1 1032.9 1065.2 1070.5 26605.7 28816.3 29137.9 29253.0 29605.3 983.5 1027.1 1031.8 1071.6 1134.7 12021.5 14878.9 14880.6 15205.3 15251.8 558915.0 640850.0 645750.9 645791.0 647871.0 54333.7 55072.4 55089.0 55102.9 55273.4 1299.3 1343.0 1364.3 1365.8 1367.2 28034.6 28380.9 28593.0 28633.4 28972.6 48844.3 49222.3 49235.8 49336.5 49343.0 573554.0 575396.0 575986.1 576103.0 577335.0 25240.9 25503.5 25836.5 25855.9 25914.2 37332.9 43044.6 43939.8 45114.0 45226.5 36572.6 36952.1 37194.0 37440.4 37867.4 29501.7 36162.6 36741.8 37587.2 37975.1 28557.0 31015.0 31154.8 35743.6 36704.6 26837.6 30507.5 31909.4 32844.7 32959.8 24143.0 24442.4 24702.6 24928.8 25030.7 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:32985.00 Median:2824.27 stddev:90583.29 (3207.3%) stddev-sans-first:90804.24 10:23:28 INFO - PROCESS | 1652 | Values: 342.3 358.4 363.1 369.4 392.1 14257.8 14791.6 15357.1 15508.2 18997.6 18704.8 18906.7 18952.7 19194.7 19403.2 5315.0 5788.3 5837.5 5848.9 5852.7 5451.9 5626.6 5711.0 5754.1 5776.3 5741.5 5748.6 5750.7 5751.1 5751.5 5571.1 5609.5 5677.1 5714.7 5715.0 4998.7 5026.4 5031.1 5031.3 5033.4 24351.8 24653.6 24695.3 24754.5 25038.7 2340.9 2547.5 2619.3 3029.2 3125.7 12641.7 12752.0 14048.1 15001.9 15226.4 1752.7 1777.0 1788.7 1841.5 1875.6 106016.0 117106.2 118094.0 118225.0 118621.4 9241.6 11126.9 11193.9 11463.6 11466.7 14002.9 14037.5 14043.4 14048.3 14052.5 7938.5 7993.5 7994.5 8375.9 8397.8 7967.2 8039.2 8065.0 8209.3 8240.1 4374.7 4384.5 4414.0 4414.4 4503.5 1692.2 1818.2 1880.9 1916.7 1924.6 831.3 858.2 905.2 965.0 998.9 19447.5 20293.9 20367.9 20446.4 20746.2 751.5 858.6 866.1 899.8 943.7 10093.7 10122.8 10735.0 10746.0 10752.0 456218.0 549486.0 552273.2 561334.0 566475.0 128635.0 130119.0 130277.0 131141.1 133040.3 910.5 913.4 948.3 951.1 951.1 17966.4 18118.1 18170.5 18185.4 18209.3 51419.7 51663.0 52455.0 52504.0 52563.7 75235.0 75970.0 77254.0 77792.2 77872.0 5396.3 7634.4 7937.2 7942.3 7967.0 10074.5 11450.0 11908.3 12159.6 12881.1 10580.7 10625.2 11016.7 11105.7 11171.6 9391.5 10389.5 10710.9 10994.2 11116.8 10454.7 10827.7 10874.7 11209.6 11237.8 7006.8 7736.2 7873.7 7908.0 8099.9 10003.9 10527.9 11032.7 11049.0 11161.3 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5360.36 Median:5006.94 stddev:2968.02 (59.3%) stddev-sans-first:2960.98 10:23:28 INFO - PROCESS | 1652 | Values: 1341.5 1488.7 1499.4 1667.9 1847.0 7432.4 7860.1 8109.1 8360.2 8403.2 8239.0 8325.1 8329.0 8335.2 8339.7 2269.1 2294.4 2301.3 2301.5 2304.2 2242.6 2243.7 2314.4 2315.3 2316.8 2258.3 2270.7 2279.5 2289.2 2299.1 2238.9 2250.0 2280.7 2281.0 2283.9 2229.1 2289.7 2292.5 2295.7 2302.3 8310.2 8364.2 8364.2 8379.6 8403.2 4916.5 5120.2 5122.1 5156.8 5171.5 3256.5 3454.3 3464.6 3530.0 3541.3 976.1 1022.9 1046.6 1054.1 1069.0 10689.3 10749.3 10755.6 10827.5 10838.5 3782.5 4065.0 4261.8 4352.9 4360.7 8549.0 8570.7 8573.4 8579.0 8579.1 4898.7 5006.5 5007.3 5014.3 5018.9 4063.2 4087.1 4213.0 4550.3 4702.6 3320.2 3349.5 3445.5 3457.0 3614.2 964.8 1088.0 1104.3 1118.9 1127.8 806.7 829.7 857.5 902.4 907.0 5402.1 5561.0 5798.7 5879.4 5923.4 960.5 975.6 990.3 998.0 1046.1 3656.7 3689.7 3710.8 3785.5 3834.1 9770.2 10036.4 10136.7 10180.6 10183.6 10119.6 10123.0 10137.7 10149.9 10159.6 7530.6 7700.0 7887.7 7909.6 7933.8 4589.9 5503.0 5599.0 5702.1 5835.6 9065.0 9357.7 9370.0 9484.0 9524.0 8123.0 9372.3 9462.7 9541.7 9607.0 7027.9 7073.0 7128.3 7182.3 7220.0 7763.9 7839.1 7842.7 7849.9 7851.3 7010.7 7109.0 7116.2 7210.2 7287.5 6753.5 6907.2 6946.5 6988.2 6991.2 6344.0 6512.6 6609.0 6610.8 6677.8 4703.5 4714.9 4786.0 4814.2 4941.2 3843.0 4746.3 4870.0 4923.4 5027.1 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4345.34 Median:4749.84 stddev:1350.89 (28.4%) stddev-sans-first:1319.70 10:23:28 INFO - PROCESS | 1652 | Values: 276.1 292.2 306.3 306.4 310.9 4403.9 5111.3 5251.9 5302.1 5334.4 5121.3 5123.6 5182.1 5187.5 5202.8 4001.7 4035.1 4040.2 4061.7 4065.0 3727.5 4017.2 4056.8 4099.0 4101.8 3657.7 3882.0 3983.9 4002.8 4006.6 3328.4 3873.9 3961.2 3961.8 3974.4 3310.5 3419.1 3423.5 3441.3 3484.4 4188.9 4432.2 4525.5 4570.5 4748.7 4452.6 4468.6 4496.8 4497.8 4509.8 4501.0 5286.4 5314.4 5375.4 5465.9 3207.9 3712.3 3713.2 3714.2 3734.0 5682.9 5694.4 5702.6 5709.0 5766.3 3227.9 3658.5 3797.2 3801.1 3815.0 3251.5 3333.4 3786.8 3812.6 3843.1 4445.8 4471.8 4506.2 4548.8 4598.6 4402.9 4866.9 5450.5 5489.3 5583.4 1089.2 1127.0 1150.6 1188.3 1249.8 738.7 836.3 846.4 856.3 867.4 4026.7 4095.4 4580.5 4775.5 4888.6 4275.8 4572.0 5195.9 5308.1 5373.9 4275.0 4282.7 4434.2 4756.3 4866.9 2302.4 2527.4 2735.6 2779.0 2779.4 5473.7 5487.8 5598.6 5602.4 5603.4 4909.3 5081.3 5414.9 5451.5 5470.6 4891.4 5402.0 5423.3 5439.3 5519.4 4432.0 5262.2 5271.9 5292.7 5378.5 4607.0 5140.3 5166.7 5196.8 5803.8 4531.8 5315.9 5598.6 5895.5 5974.0 3246.5 3316.5 3700.0 3741.8 3818.0 4965.3 5516.0 5633.9 5862.0 5873.1 5516.9 5587.9 5596.1 5610.8 5620.6 5496.9 5562.6 5591.9 5612.8 5663.7 4751.0 4953.8 5189.9 5316.7 5418.8 4694.5 4847.0 5525.4 5538.1 5582.8 4259.5 4880.6 5408.9 5476.8 5573.8 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:19724.22 Median:26981.26 stddev:28969.49 (107.4%) stddev-sans-first:29015.09 10:23:28 INFO - PROCESS | 1652 | Values: 578.7 692.3 706.9 761.9 779.9 13523.7 13786.5 13842.8 14080.2 14089.6 12430.9 13604.7 14089.1 14305.4 14375.9 651.4 804.7 827.5 828.6 828.7 614.0 639.6 665.9 673.4 795.3 861.9 865.6 871.3 871.3 872.6 863.5 864.0 864.5 864.7 866.4 501.0 517.6 637.2 638.7 638.9 12890.9 13122.7 13292.2 13811.7 13811.8 12514.8 12625.8 12831.3 12937.7 13165.9 10133.7 10508.6 10527.9 10549.7 10656.3 3541.8 3556.8 3575.6 3613.7 3672.8 95408.0 95903.0 95994.5 96472.5 97231.0 8533.8 9733.7 9770.8 9838.3 9994.1 19829.4 20043.0 20173.6 20185.7 20376.3 7094.3 7133.8 7207.8 7314.6 7409.8 3952.9 3993.6 4000.9 4007.0 4140.5 3180.8 3297.1 3300.4 3302.2 3302.3 594.2 596.1 600.4 608.7 610.0 512.7 522.7 565.5 619.4 645.2 8753.1 8802.4 8898.8 8900.3 9413.7 600.6 603.6 618.1 657.1 672.7 3293.0 4243.0 4257.3 4359.3 4375.6 87292.0 90655.0 92437.1 97268.1 118143.0 64984.0 91564.5 92353.5 94433.0 96223.0 72649.9 81978.6 83391.7 84126.0 84710.3 9149.3 9322.3 9350.2 9360.1 9363.4 38460.2 48889.0 49016.8 52024.0 53397.0 72271.6 74586.6 76419.0 76577.0 79851.0 9862.5 11793.5 11969.5 11974.5 12073.3 11031.5 13332.7 13417.7 13631.0 14004.4 12003.8 12139.8 12170.7 12522.8 12576.6 12425.5 12481.6 12730.8 12763.4 12766.2 12084.2 12138.8 12144.7 12471.4 12740.2 9494.5 9824.3 10081.4 10093.8 10332.8 9899.4 10644.4 10703.7 10707.2 10711.5 10:23:28 INFO - PROCESS | 1652 | -------- Summary: end -------- 10:23:28 INFO - PROCESS | 1652 | 10:23:28 INFO - TEST-INFO | 1652: exit 0 10:23:28 INFO - TEST-OK | dromaeo_css | took 1119017ms 10:23:28 INFO - TEST-START | kraken 10:23:28 INFO - operating with platform_type : mac_ 10:23:28 INFO - Initialising browser for kraken test... 10:23:28 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpXuIQ3V/profile http://localhost:49273/getInfo.html) 10:23:31 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 10:23:31 INFO - PROCESS | 1659 | colorDepth:24 10:23:31 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 10:23:31 INFO - PROCESS | 1659 | __metrics 10:23:31 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:23:31 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:23:32 INFO - TEST-INFO | 1659: exit 0 10:23:32 INFO - Browser initialized. 10:23:32 INFO - Running cycle 1/1 for kraken test... 10:23:32 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpXuIQ3V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:23:44 INFO - PROCESS | 1660 | RSS: Main: 229711872 10:23:44 INFO - PROCESS | 1660 | 10:23:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 10:23:47 INFO - PROCESS | 1660 | RSS: Main: 289193984 10:23:47 INFO - PROCESS | 1660 | 10:23:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 10:23:51 INFO - PROCESS | 1660 | RSS: Main: 291291136 10:23:51 INFO - PROCESS | 1660 | 10:23:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 10:23:55 INFO - PROCESS | 1660 | RSS: Main: 380493824 10:23:55 INFO - PROCESS | 1660 | 10:23:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 10:23:57 INFO - PROCESS | 1660 | RSS: Main: 281718784 10:23:57 INFO - PROCESS | 1660 | 10:23:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:24:00 INFO - PROCESS | 1660 | RSS: Main: 286650368 10:24:00 INFO - PROCESS | 1660 | 10:24:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 10:24:03 INFO - PROCESS | 1660 | RSS: Main: 329580544 10:24:03 INFO - PROCESS | 1660 | 10:24:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 10:24:07 INFO - PROCESS | 1660 | RSS: Main: 329572352 10:24:07 INFO - PROCESS | 1660 | 10:24:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 10:24:10 INFO - PROCESS | 1660 | RSS: Main: 326443008 10:24:10 INFO - PROCESS | 1660 | 10:24:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:24:13 INFO - PROCESS | 1660 | RSS: Main: 302465024 10:24:13 INFO - PROCESS | 1660 | 10:24:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:24:15 INFO - PROCESS | 1660 | RSS: Main: 306229248 10:24:15 INFO - PROCESS | 1660 | 10:24:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:24:18 INFO - PROCESS | 1660 | RSS: Main: 312250368 10:24:18 INFO - PROCESS | 1660 | 10:24:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:24:22 INFO - PROCESS | 1660 | RSS: Main: 320602112 10:24:22 INFO - PROCESS | 1660 | 10:24:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:24:26 INFO - PROCESS | 1660 | RSS: Main: 319512576 10:24:26 INFO - PROCESS | 1660 | 10:24:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 10:24:28 INFO - PROCESS | 1660 | RSS: Main: 363741184 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | __start_tp_report 10:24:28 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 10:24:28 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 10:24:28 INFO - PROCESS | 1660 | |i|pagename|runs| 10:24:28 INFO - PROCESS | 1660 | |0;ai-astar;111;113;114;114;114;113;112;113;113;115 10:24:28 INFO - PROCESS | 1660 | |1;audio-beat-detection;148;112;118;176;137;129;110;116;141;125 10:24:28 INFO - PROCESS | 1660 | |2;audio-dft;186;185;183;184;185;183;179;183;181;184 10:24:28 INFO - PROCESS | 1660 | |3;audio-fft;79;59;59;88;62;59;57;58;84;57 10:24:28 INFO - PROCESS | 1660 | |4;audio-oscillator;89;70;91;69;67;70;90;68;68;92 10:24:28 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;87;91;87;89;86;91;87;90;86 10:24:28 INFO - PROCESS | 1660 | |6;imaging-darkroom;99;99;100;99;108;98;99;99;97;98 10:24:28 INFO - PROCESS | 1660 | |7;imaging-desaturate;127;130;79;80;84;81;105;130;131;82 10:24:28 INFO - PROCESS | 1660 | |8;json-parse-financial;57;56;52;53;80;77;79;78;54;54 10:24:28 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;76;76;73;72;68;70;70;71;71 10:24:28 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;88;90;89;90;87;86;86;94;107;103 10:24:28 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;147;150;155;160;156;156;155;153;237;160 10:24:28 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;175;181;174;175;222;206;180;173;176;216 10:24:28 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;68;68;66;67;68;68;67;66;66 10:24:28 INFO - PROCESS | 1660 | __end_tp_report 10:24:28 INFO - PROCESS | 1660 | __start_cc_report 10:24:28 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2065 10:24:28 INFO - PROCESS | 1660 | __end_cc_report 10:24:28 INFO - PROCESS | 1660 | __startTimestamp1479147868242__endTimestamp 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | ------- Summary: start ------- 10:24:28 INFO - PROCESS | 1660 | Number of tests: 14 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:1.14 (1.0%) stddev-sans-first:0.88 10:24:28 INFO - PROCESS | 1660 | Values: 111.0 113.0 114.0 114.0 114.0 113.0 112.0 113.0 113.0 115.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:133.00 stddev:20.24 (15.2%) stddev-sans-first:20.53 10:24:28 INFO - PROCESS | 1660 | Values: 148.0 112.0 118.0 176.0 137.0 129.0 110.0 116.0 141.0 125.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.06 (1.1%) stddev-sans-first:1.94 10:24:28 INFO - PROCESS | 1660 | Values: 186.0 185.0 183.0 184.0 185.0 183.0 179.0 183.0 181.0 184.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.50 stddev:12.32 (20.4%) stddev-sans-first:12.16 10:24:28 INFO - PROCESS | 1660 | Values: 79.0 59.0 59.0 88.0 62.0 59.0 57.0 58.0 84.0 57.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.50 stddev:11.34 (14.3%) stddev-sans-first:11.22 10:24:28 INFO - PROCESS | 1660 | Values: 89.0 70.0 91.0 69.0 67.0 70.0 90.0 68.0 68.0 92.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:89.50 stddev:2.01 (2.2%) stddev-sans-first:2.05 10:24:28 INFO - PROCESS | 1660 | Values: 90.0 87.0 91.0 87.0 89.0 86.0 91.0 87.0 90.0 86.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:99.00 stddev:3.06 (3.1%) stddev-sans-first:3.24 10:24:28 INFO - PROCESS | 1660 | Values: 99.0 99.0 100.0 99.0 108.0 98.0 99.0 99.0 97.0 98.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:102.90 Median:79.50 stddev:24.07 (30.3%) stddev-sans-first:23.89 10:24:28 INFO - PROCESS | 1660 | Values: 127.0 130.0 79.0 80.0 84.0 81.0 105.0 130.0 131.0 82.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:67.00 stddev:12.58 (18.8%) stddev-sans-first:13.08 10:24:28 INFO - PROCESS | 1660 | Values: 57.0 56.0 52.0 53.0 80.0 77.0 79.0 78.0 54.0 54.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.57 (5.0%) stddev-sans-first:2.71 10:24:28 INFO - PROCESS | 1660 | Values: 64.0 76.0 76.0 73.0 72.0 68.0 70.0 70.0 71.0 71.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:88.50 stddev:7.30 (8.3%) stddev-sans-first:7.60 10:24:28 INFO - PROCESS | 1660 | Values: 88.0 90.0 89.0 90.0 87.0 86.0 86.0 94.0 107.0 103.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:162.90 Median:156.00 stddev:26.34 (16.9%) stddev-sans-first:27.30 10:24:28 INFO - PROCESS | 1660 | Values: 147.0 150.0 155.0 160.0 156.0 156.0 155.0 153.0 237.0 160.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:180.50 stddev:19.09 (10.6%) stddev-sans-first:19.68 10:24:28 INFO - PROCESS | 1660 | Values: 175.0 181.0 174.0 175.0 222.0 206.0 180.0 173.0 176.0 216.0 10:24:28 INFO - PROCESS | 1660 | 10:24:28 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:0.88 (1.3%) stddev-sans-first:0.93 10:24:28 INFO - PROCESS | 1660 | Values: 67.0 68.0 68.0 66.0 67.0 68.0 68.0 67.0 66.0 66.0 10:24:28 INFO - PROCESS | 1660 | -------- Summary: end -------- 10:24:28 INFO - PROCESS | 1660 | 10:24:29 INFO - TEST-INFO | 1660: exit 0 10:24:29 INFO - TEST-OK | kraken | took 60275ms 10:24:29 INFO - SUITE-END | took 1179s 10:24:29 INFO - Completed test suite (00:19:39) 10:24:29 INFO - javascript benchmark 10:24:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3041.4221297953372, 3283.9163076184846, 3289.7203737682294, 3302.669733026697, 3363.192833719908, 5575.8606034849045, 5635.154726790984, 5661.66030038198, 5687.345955215152, 5728.971355143223, 5618.353889302732, 5650.26546548948, 5681.403452637475, 5706.686132262724, 5821.068629019347, 5168.069747445458, 5187.844364669066, 5247.921281180786, 5248.947510524884, 5257.8948421031555, 4599.264117741162, 5154.458781827911, 5269.841904742864, 5278.683279003272, 5308.9469105308835, 5255.369355677305, 5270.288511050997, 5276.709780962045, 5311.044012077825, 5313.1764576490705, 5110.029094472038, 5158.329417175743, 5172.767225474862, 5229.5293423591675, 5230.293910322096, 5071.771770270347, 5149.433562308143, 5151.304573882516, 5162.380514338266, 5234.136367499358, 5617.691026993513, 5648.096304591246, 5648.774049038034, 5657.773689052433, 5674.801381951612, 6070.6661133637635, 6161.7535298588, 6169.136320915076, 6171.691415429255, 6181.474574661159, 6119.694015299217, 6158.168647232611, 6219.067139929001, 6243.96878015607, 6246.87506249877, 5808.099744539603, 5809.070548712186, 5812.041013232769, 5848.88302233953, 5858.677772722499, 6147.477464415485, 6184.938150618436, 6241.75032998684, 6246.937530624727, 6256.343083976208, 5981.760729570856, 6054.666993315322, 6057.939420605826, 6130.908036379458, 6132.386761323921, 6152.569320147547, 6212.347703491158, 6232.844178895564, 6271.122042913996, 6312.810615681537, 6199.256089269316, 6206.975848984867, 6226.034964580497, 6229.0967809667345, 6239.157713708682, 6094.939050609437, 6177.135201071854, 6181.629102253879, 6243.938530449835, 6252.406021427957, 4866.902661946741, 4909.386326709161, 4913.238448040559, 4916.164252077156, 4918.803247870117, 4381.78091095444, 4427.579379958899, 4440.6447484201835, 4479.283314669638, 4482.551744825491, 5808.39011903744, 5811.4479124483105, 5811.476967072984, 5818.156367326714, 5831.94168058314, 6234.532410069263, 6295.087212354183, 6303.149074874925, 6314.273858506239, 6323.0, 5625.634333768292, 5680.659160450304, 5695.886082278331, 5744.712764361765, 5751.539876809845, 5340.572754179656, 5381.865453363619, 5388.91916621251, 5419.810306639249, 5453.100238460689, 5290.788368465295, 6196.194494715658, 6263.530235232284, 6272.6236425814595, 6303.716332765129, 6103.125543060039, 6278.937210627835, 6306.148669929501, 6316.147320111726, 6316.305206427382, 6171.321154673072, 6183.783567575113, 6201.162843016135, 6229.906551401823, 6234.875302494015, 6118.680093383066, 6119.807225076679, 6120.081455037822, 6123.343628574205, 6136.151476879544, 6116.847078823065, 6173.969130154321, 6206.906896396467, 6216.067589861557, 6230.06549017651, 5894.440028197314, 5948.940510594839, 5980.521558275241, 6009.308929473166, 6009.669468179292, 4674.929876051794, 5990.281166260077, 6052.66710330936, 6099.915141108964, 6123.203983482119, 5917.556183286357, 5922.851928701817, 5929.080992446177, 5938.970305148447, 5940.257467816523, 5814.941850581609, 5995.220621319375, 6040.546958978182, 6112.816615501542, 6133.294671112701, 5741.339745929272, 5834.066549352253, 5836.270466191726, 5837.182794408701, 5838.795642152504], "value": 5662.852976959506, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.3087042260552, 508.76370419879703, 512.0987062769526, 513.1558586125825, 536.9785208591654, 27925.581116283272, 30248.941287054957, 31102.377952440966, 31144.84427577859, 37652.623473765256, 34190.0, 38164.809175954084, 39910.201795964065, 40009.799951000205, 40124.59875401245, 9037.231835293991, 9294.163525282724, 9294.814103717921, 9298.395604285733, 9313.767155821124, 9222.86165707515, 9338.486383248919, 9339.15947564719, 9342.346035777498, 9357.298202634796, 9268.563372677247, 9341.43951362917, 9346.205572526344, 9360.970293267736, 9365.719028429125, 9260.490673012982, 9300.395474294182, 9331.953340233324, 9337.159655630958, 9343.2525397968, 9242.260619150451, 9330.486823224719, 9332.95333523328, 9333.813323733488, 9351.812963740755, 38379.424308635396, 38693.419598706045, 38753.0, 38790.0, 38813.02967425809, 3114.595102636643, 3169.4611915974338, 3198.712115909579, 3502.7548071634997, 3566.5720113586276, 28974.710252897414, 29116.12651620455, 29754.0, 30308.84845575758, 30835.84582077075, 2741.752160296207, 3129.4679904416303, 3130.812151270931, 3132.1073494054085, 3183.570218020561, 480643.5967820173, 480737.0, 487215.0, 487264.0, 488279.0, 51495.97008059818, 57257.42742572604, 58374.41625583775, 58496.70751646214, 58620.0, 59331.70334148388, 62205.68897155485, 62205.68897155485, 62275.68862155751, 62348.0, 15186.924065379602, 15362.615934601501, 15376.846231537766, 15377.154256516, 15660.68678626421, 11957.820632690517, 12033.518659253532, 12039.819402708967, 12055.939720301343, 12062.276263424223, 18015.009174495528, 18030.459086227434, 18065.657853297183, 18067.819321806877, 18185.181666825025, 7458.552486850806, 7491.400687944952, 7556.2443755624, 7627.733029343946, 7661.348785353196, 863.9870401943975, 908.0737647599411, 1032.850236715672, 1065.1904552540125, 1070.5343175718588, 26605.733942660714, 28816.279593009924, 29137.85431072831, 29253.0, 29605.25986850346, 983.5475681186591, 1027.0807627173638, 1031.7523794289316, 1071.6356438810842, 1134.6709454258337, 12021.519139234508, 14878.884083693767, 14880.62798430048, 15205.31576079079, 15251.771223431657, 558915.0, 640850.0, 645750.9412076467, 645791.0, 647871.0, 54333.72833135888, 55072.44927550673, 55089.0, 55102.89794204094, 55273.44726552763, 1299.311364976564, 1342.9664258393423, 1364.2564802182835, 1365.8019587159806, 1367.193355920012, 28034.579481308203, 28380.85809570939, 28592.97180387715, 28633.42733145367, 28972.565411519266, 48844.26733598928, 49222.261666075756, 49235.75382123067, 49336.50663493462, 49343.0, 573554.0, 575396.0, 575986.1200693969, 576103.0, 577335.0, 25240.87379563164, 25503.489930201664, 25836.483270334866, 25855.870720646275, 25914.22257332283, 37332.880013599635, 43044.56955430531, 43939.78030109829, 45114.0, 45226.54773452223, 36572.63427365799, 36952.07619809526, 37194.0, 37440.438393424665, 37867.431988520744, 29501.704982949897, 36162.638373615926, 36741.816290918374, 37587.248255035294, 37975.05062373352, 28557.0, 31015.0, 31154.844225778725, 35743.64256357403, 36704.63295367012, 26837.597436038086, 30507.54238686377, 31909.36181276408, 32844.67155328416, 32959.835200824804, 24143.0, 24442.388940275923, 24702.629460557746, 24928.75071249264, 25030.749692502843], "value": 19085.54521740719, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.3324517191474, 358.43188546154346, 363.0759716521451, 369.40341348721813, 392.111866622101, 14257.797308858982, 14791.630209244775, 15357.078575285477, 15508.208522465951, 18997.620047599074, 18704.812951870477, 18906.716399254023, 18952.715709264372, 19194.712079318822, 19403.223871045142, 5314.99999999999, 5788.334341550712, 5837.532997360201, 5848.941510584882, 5852.67810270435, 5451.890962180754, 5626.578006649497, 5710.971445142789, 5754.136879468071, 5776.306843178824, 5741.511971482429, 5748.568857335696, 5750.683712395817, 5751.10857817037, 5751.511121554673, 5571.1086226203815, 5609.495145436889, 5677.120046392815, 5714.685692286923, 5714.971425142869, 4998.7250701211415, 5026.371703537058, 5031.119554078058, 5031.345925029723, 5033.446320904697, 24351.756482435125, 24653.63019554708, 24695.25914222576, 24754.50490990188, 25038.749612503823, 2340.8595484271, 2547.522436986543, 2619.3320703220747, 3029.2124047747525, 3125.7343125834104, 12641.747165056648, 12751.979841612649, 14048.086874353237, 15001.924990374979, 15226.390944362212, 1752.666993271287, 1776.9733453998201, 1788.7316902464604, 1841.5212044868297, 1875.559243577758, 106016.0, 117106.24340634898, 118094.0, 118225.0, 118621.40689296498, 9241.584128714225, 11126.88731126897, 11193.94403027996, 11463.598774042868, 11466.713332166595, 14002.879769618406, 14037.5086871959, 14043.438262469592, 14048.297585120907, 14052.508162214477, 7938.5236885787035, 7993.520388776692, 7994.480358776661, 8375.916240837636, 8397.832043359098, 7967.203279671987, 8039.196080391914, 8065.0, 8209.343252539782, 8240.13478584752, 4374.671899607542, 4384.495782984997, 4413.977930110329, 4414.359917811899, 4503.527129651405, 1692.1792930428733, 1818.1636447234157, 1880.9058649974259, 1916.6933290673426, 1924.6054558815408, 831.2684837343099, 858.2190206911805, 905.2441211588398, 964.981944049021, 998.8501724741202, 19447.513812154808, 20293.898530507548, 20367.87976661268, 20446.38660840177, 20746.170153194005, 751.4889874885131, 858.587877818638, 866.1330994408528, 899.7570655922864, 943.7168849345306, 10093.747656308651, 10122.797544049077, 10735.0, 10746.0, 10752.0, 456218.0, 549486.0, 552273.2386338043, 561334.0, 566475.0, 128635.0, 130119.0, 130277.0, 131141.0930254618, 133040.3391932156, 910.4901255296922, 913.3925939250324, 948.2556193388157, 951.0537015669453, 951.0869565217469, 17966.371177009263, 18118.094095295448, 18170.545736356697, 18185.363512277007, 18209.271629134688, 51419.74290128525, 51663.0, 52455.0, 52504.0, 52563.73718131538, 75235.0, 75970.0, 77254.0, 77792.2220777785, 77872.0, 5396.325459317585, 7634.389248859967, 7937.206279372017, 7942.285194332307, 7966.960165199369, 10074.496275186359, 11450.0, 11908.34504102248, 12159.635210943687, 12881.098323117292, 10580.735481613021, 10625.203109766644, 11016.669499915015, 11105.666829995112, 11171.553137874394, 9391.544310631863, 10389.532471038972, 10710.946445267724, 10994.175436842108, 11116.777664446827, 10454.738631533968, 10827.67516974492, 10874.728131796768, 11209.607663731731, 11237.831432528355, 7006.789796306119, 7736.226377362444, 7873.685052597832, 7907.9604601976625, 8099.878501822595, 10003.899961000297, 10527.94736026315, 11032.724181895517, 11049.0, 11161.274517156358], "value": 9279.399769344043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.496938648007, 1488.724585951599, 1499.3777582303344, 1667.8999260044397, 1847.0395394394916, 7432.405407567395, 7860.096088949773, 8109.148539403359, 8360.163983601637, 8403.243708066273, 8238.95880520598, 8325.125861784516, 8329.0, 8335.249827515523, 8339.708110216143, 2269.1377276634857, 2294.4149241943283, 2301.2750983440164, 2301.493671392291, 2304.193532263711, 2242.6075436798496, 2243.6634504824324, 2314.351981445198, 2315.270689732729, 2316.837821352506, 2258.322503249027, 2270.71616047994, 2279.544091181762, 2289.175896677195, 2299.0803678528637, 2238.932832015042, 2249.988750056247, 2280.703508543895, 2280.9543809123725, 2283.862968221912, 2229.063793206857, 2289.6565515172692, 2292.5071109711494, 2295.7130358705163, 2302.26327575176, 8310.168983101701, 8364.205400487004, 8364.247217750371, 8379.62291696875, 8403.159684031549, 4916.459189489154, 5120.231965205212, 5122.078025955327, 5156.793728250866, 5171.482851714836, 3256.47896336585, 3454.3091381723757, 3464.566929133858, 3530.046887340404, 3541.3448512025357, 976.1214906584061, 1022.8874823769379, 1046.5604446132565, 1054.1092776603757, 1069.0, 10689.30519516229, 10749.301295415851, 10755.623553175601, 10827.458627068616, 10838.458077096113, 3782.470454136423, 4064.9634343242874, 4261.765602891809, 4352.891177720519, 4360.716553424018, 8549.0, 8570.74287771368, 8573.356998225157, 8578.957105214558, 8579.056303806576, 4898.730569818623, 5006.549410553068, 5007.324011258506, 5014.2979982802435, 5018.89962200754, 4063.2482990646827, 4087.1212689271947, 4212.957870421318, 4550.271956486946, 4702.553257440538, 3320.2197483591335, 3349.4975753636663, 3445.4659527773233, 3456.9654303456646, 3614.150674591469, 964.7829238421299, 1087.983680244797, 1104.265663333874, 1118.949647265875, 1127.8026345389687, 806.7176488229073, 829.6515463505342, 857.5283594023263, 902.4224496322358, 906.9637214511478, 5402.08164612009, 5560.972195139079, 5798.652080875161, 5879.353271140171, 5923.437273459014, 960.5485421851719, 975.6048800235916, 990.2721499697716, 997.9700608981742, 1046.1317106801337, 3656.670899619047, 3689.7232707546505, 3710.793991952626, 3785.4511095890944, 3834.079820842978, 9770.169535589412, 10036.39781613091, 10136.746581335377, 10180.592776289013, 10183.643572474928, 10119.645812396531, 10123.0, 10137.746556336151, 10149.949250253701, 10159.644412445678, 7530.585817780185, 7699.961500192463, 7887.723929662549, 7909.564973926489, 7933.801654958672, 4589.885252868705, 5502.972485137549, 5599.0, 5702.144678298123, 5835.620684655653, 9065.0, 9357.719268421959, 9370.0, 9483.952580237055, 9523.952365239957, 8123.0, 9372.34393592442, 9462.668806591737, 9541.713748587554, 9607.0, 7027.8565037664, 7073.009778631099, 7128.322809333105, 7182.317679820409, 7220.0, 7763.883541746765, 7839.09850367215, 7842.686292548234, 7849.882251766114, 7851.293383595504, 7010.684519196547, 7108.964455177691, 7116.217216106328, 7210.242924493063, 7287.4534409920525, 6753.459723222229, 6907.171139463297, 6946.5137440378685, 6988.231294557493, 6991.196012458632, 6343.9682801585695, 6512.576682515811, 6609.0, 6610.801675949537, 6677.799666010027, 4703.482616912069, 4714.858554243378, 4785.95214047869, 4814.229723244264, 4941.209406494943, 3843.0, 4746.311784791255, 4869.975650121868, 4923.433805112458, 5027.145385284437], "value": 4333.21207930635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.0751482533513, 292.22560215429115, 306.2557984098642, 306.4223937877102, 310.870988539756, 4403.889902752433, 5111.258867464216, 5251.949610077985, 5302.072137375953, 5334.359876814778, 5121.284369736143, 5123.641345105835, 5182.10853038641, 5187.48125187481, 5202.788814753724, 4001.659858911996, 4035.132446523997, 4040.151693056403, 4061.73598716084, 4065.0447144920918, 3727.5154229950067, 4017.1764788218347, 4056.7565946043333, 4098.95476653453, 4101.79491025449, 3657.664952292421, 3882.0294926268434, 3983.880483585497, 4002.8398864045403, 4006.5793091725386, 3328.3842489139342, 3873.9346679663036, 3961.227560625679, 3961.7622942623534, 3974.3839704845795, 3310.5034244863223, 3419.059758566389, 3423.503591979149, 3441.346144232588, 3484.390231709466, 4188.853390131361, 4432.20220360335, 4525.479569849444, 4570.451545814491, 4748.670372295728, 4452.577005184502, 4468.642508599304, 4496.820127194908, 4497.775111244458, 4509.751963641998, 4501.0, 5286.418493965661, 5314.388845282803, 5375.408705042442, 5465.934142842148, 3207.85564649589, 3712.2575484903127, 3713.2387860488807, 3714.2171757556252, 3734.0, 5682.943170568283, 5694.373618901917, 5702.629329093637, 5709.0, 5766.308043034863, 3227.8870239541975, 3658.5183000884685, 3797.1646237827636, 3801.06145865605, 3815.0271680721794, 3251.4960181171955, 3333.383324085052, 3786.753860999026, 3812.6377994090517, 3843.0776613612534, 4445.755483448376, 4471.776411179428, 4506.152477484281, 4548.793834990027, 4598.609118224921, 4402.8679139625865, 4866.853994380174, 5450.538907793501, 5489.258950041693, 5583.3858275589655, 1089.226649079147, 1126.9661910142709, 1150.5857891159176, 1188.2989036468525, 1249.8000319948765, 738.737748099417, 836.2766207230702, 846.4498076250486, 856.3363393370064, 867.3971589745066, 4026.7181297309494, 4095.365218391154, 4580.519045500241, 4775.469001429248, 4888.560029597354, 4275.819332140631, 4572.039871626996, 5195.922061169085, 5308.115618955292, 5373.892522149613, 4275.0, 4282.678799090081, 4434.201843668106, 4756.262779269219, 4866.90722989111, 2302.4013756423115, 2527.36815796051, 2735.617013618095, 2778.9583156252675, 2779.374640705825, 5473.7263136843, 5487.75305111271, 5598.636088654224, 5602.406027057774, 5603.439656034446, 4909.3126962225315, 5081.263216833612, 5414.891702166014, 5451.48210919962, 5470.56235501159, 4891.3559398248535, 5402.0, 5423.267858839006, 5439.265699130646, 5519.448055194448, 4432.0, 5262.236975638588, 5271.917592550844, 5292.708901010481, 5378.515933565998, 4606.976965115153, 5140.30605868203, 5166.689998600096, 5196.844094677166, 5803.7968671096305, 4531.750753708446, 5315.946840531546, 5598.580106492111, 5895.526118470382, 5974.0, 3246.4967929970894, 3316.4527852904966, 3700.0009997301117, 3741.7567858089137, 3817.9809100954317, 4965.255211718271, 5516.0, 5633.943610582026, 5862.0, 5873.148393482836, 5516.862078448071, 5587.916181257366, 5596.132599447093, 5610.7755689773585, 5620.606557540933, 5496.890062198654, 5562.554995600423, 5591.888162236651, 5612.831615051555, 5663.66018038919, 4751.0, 4953.826616068421, 5189.948100518946, 5316.6544174628525, 5418.834950485664, 4694.460137084279, 4846.975765121293, 5525.364583072998, 5538.113901775697, 5582.7766889323975, 4259.467566554181, 4880.585150262267, 5408.918866217088, 5476.808311709071, 5573.804916827891], "value": 3910.652800285022, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.6730497269043, 692.3180667042965, 706.9399101076411, 761.9238076192381, 779.9142094369619, 13523.729525409486, 13786.517471888486, 13842.792358114637, 14080.225587592678, 14089.57731268061, 12430.937845310784, 13604.727905441885, 14089.084209526374, 14305.427782888672, 14375.92812035941, 651.4365074210821, 804.67008526504, 827.4993628854546, 828.5567221536479, 828.747232094214, 614.0390289197392, 639.59705385607, 665.8668266346726, 673.377126158304, 795.3041089047084, 861.9224269815716, 865.5628907401784, 871.3290766110091, 871.3290766110091, 872.5724395046414, 863.5078005536847, 863.9654413823503, 864.5072308783996, 864.6627815152095, 866.3588944181323, 501.02096717963826, 517.5574883474612, 637.2448648351718, 638.6934271549682, 638.8626445314279, 12890.87109128906, 13122.737545249041, 13292.20246785196, 13811.723765524734, 13811.792823107662, 12514.81227781584, 12625.810612840814, 12831.294278814661, 12937.676558086123, 13165.946724262036, 10133.746656333651, 10508.579656813794, 10527.94736026315, 10549.73625659357, 10656.307340022873, 3541.8406171722077, 3556.8043757593323, 3575.606683264839, 3613.6567026132475, 3672.797996110213, 95408.0, 95902.99375489858, 95994.52002740011, 96472.51763741206, 97231.0, 8533.829323413496, 9733.734614500212, 9770.75573110678, 9838.262130340254, 9994.10053095225, 19829.405117846487, 20043.0, 20173.596528069356, 20185.697214541793, 20376.28682996088, 7094.326039026284, 7133.82165445868, 7207.783766487014, 7314.634268286564, 7409.814754631069, 3952.8616498422416, 3993.600639935983, 4000.9199816003515, 4006.958190870395, 4140.544540100586, 3180.840957952093, 3297.1427428868665, 3300.405926933127, 3302.1579497228536, 3302.339532093591, 594.191899017336, 596.0790578556141, 600.3786081405724, 608.6530677513774, 609.9817005489842, 512.6667666017045, 522.6733291692692, 565.5108331293402, 619.3558698953128, 645.1902861908369, 8753.124687531195, 8802.427842190236, 8898.777530561787, 8900.332475064397, 9413.670521531714, 600.5885968111858, 603.5774957529746, 618.1386608644016, 657.1227411405731, 672.7342699633617, 3292.983535082309, 4243.0, 4257.340112282601, 4359.280718681445, 4375.624771123407, 87292.0, 90655.0, 92437.07562924284, 97268.05463890824, 118143.0, 64984.0, 91564.54217728869, 92353.5382323084, 94433.0, 96223.0, 72649.91025134521, 81978.59010704908, 83391.74912376319, 84126.0, 84710.30579388377, 9149.313801464783, 9322.300827437834, 9350.205232555038, 9360.11078947499, 9363.438193708127, 38460.230795384494, 48889.02221955612, 49016.754916226615, 52024.0, 53397.0, 72271.63864180645, 74586.62706686431, 76419.0, 76577.0, 79851.0, 9862.457564833716, 11793.469293881624, 11969.461374238354, 11974.521019159136, 12073.346953011967, 11031.503582338975, 13332.733345333232, 13417.731645367234, 13631.0, 14004.43982240699, 12003.819942700691, 12139.817902731289, 12170.695732606755, 12522.812157817822, 12576.622701318975, 12425.502979880703, 12481.625551233114, 12730.809037864625, 12763.439256054355, 12766.234025958101, 12084.214526055779, 12138.757224855277, 12144.696382590506, 12471.438785254866, 12740.171888827197, 9494.525273736424, 9824.283522249227, 10081.395116293046, 10093.848592270975, 10332.845007324746, 9899.406035637596, 10644.44533311984, 10703.678889633324, 10707.19696022817, 10711.517981690999], "value": 6954.082368473405, "unit": "score"}], "name": "dromaeo_css", "value": 7005.424602422901, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 114.0, 114.0, 113.0, 112.0, 113.0, 113.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 118.0, 176.0, 137.0, 129.0, 110.0, 116.0, 141.0, 125.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 183.0, 184.0, 185.0, 183.0, 179.0, 183.0, 181.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 62.0, 59.0, 57.0, 58.0, 84.0, 57.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 91.0, 69.0, 67.0, 70.0, 90.0, 68.0, 68.0, 92.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 89.0, 86.0, 91.0, 87.0, 90.0, 86.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 100.0, 99.0, 108.0, 98.0, 99.0, 99.0, 97.0, 98.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 130.0, 79.0, 80.0, 84.0, 81.0, 105.0, 130.0, 131.0, 82.0], "value": 102.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 52.0, 53.0, 80.0, 77.0, 79.0, 78.0, 54.0, 54.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 76.0, 76.0, 73.0, 72.0, 68.0, 70.0, 70.0, 71.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 89.0, 90.0, 87.0, 86.0, 86.0, 94.0, 107.0, 103.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 155.0, 160.0, 156.0, 156.0, 155.0, 153.0, 237.0, 160.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 181.0, 174.0, 175.0, 222.0, 206.0, 180.0, 173.0, 176.0, 216.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 66.0, 67.0, 68.0, 68.0, 67.0, 66.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1507.1, "alertThreshold": 2.0}]} 10:24:29 INFO - Return code: 0 10:24:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:24:29 INFO - # TBPL SUCCESS # 10:24:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:24:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:24:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:24:29 INFO - 2016-11-14 10:24:29.574 screenresolution[1664:9971] starting screenresolution argv=screenresolution get 10:24:29 INFO - 2016-11-14 10:24:29.577 screenresolution[1664:9971] Display 0: 1600x1200x32@60 10:24:29 INFO - 2016-11-14 10:24:29.583 screenresolution[1665:9974] starting screenresolution argv=screenresolution list 10:24:29 INFO - Available Modes on Display 0 10:24:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:24:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:24:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:24:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:24:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:24:29 INFO - Intel Iris: 10:24:29 INFO - Chipset Model: Intel Iris 10:24:29 INFO - Type: GPU 10:24:29 INFO - Bus: Built-In 10:24:29 INFO - VRAM (Dynamic, Max): 1536 MB 10:24:29 INFO - Vendor: Intel (0x8086) 10:24:29 INFO - Device ID: 0x0a2e 10:24:29 INFO - Revision ID: 0x0009 10:24:29 INFO - Displays: 10:24:29 INFO - Display: 10:24:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:24:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:24:29 INFO - Display Serial Number: 1600x1200 60 10:24:29 INFO - Main Display: Yes 10:24:29 INFO - Mirror: Off 10:24:29 INFO - Online: Yes 10:24:29 INFO - Rotation: Supported 10:24:29 INFO - Return code: 0 10:24:29 INFO - Running post-action listener: _resource_record_post_action 10:24:29 INFO - [mozharness: 2016-11-14 18:24:29.817532Z] Finished run-tests step (success) 10:24:29 INFO - Running post-run listener: _resource_record_post_run 10:24:29 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38343680; Write bytes: 697491456; Read time: 423; Write time: 1970 10:24:29 INFO - TinderboxPrint: CPU usage
53.6% 10:24:29 INFO - TinderboxPrint: I/O read bytes / time
38,343,680 / 423 10:24:29 INFO - TinderboxPrint: I/O write bytes / time
697,491,456 / 1,970 10:24:29 INFO - TinderboxPrint: CPU idle
2,251.9 (46.4%) 10:24:29 INFO - TinderboxPrint: CPU system
975.0 (20.1%) 10:24:29 INFO - TinderboxPrint: CPU user
1,630.8 (33.6%) 10:24:29 INFO - TinderboxPrint: Swap in / out
436,424,704 / 0 10:24:29 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388897280; Write bytes: 379342848; Read time: 29228; Write time: 737 10:24:29 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 32029696; Write bytes: 294486016; Read time: 340; Write time: 1186 10:24:30 INFO - Running post-run listener: _upload_blobber_files 10:24:30 INFO - Blob upload gear active. 10:24:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:24:30 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 10:24:30 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'] 10:24:30 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 10:24:30 INFO - (blobuploader) - INFO - Open directory for files ... 10:24:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:24:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:24:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:31 INFO - (blobuploader) - INFO - Done attempting. 10:24:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:24:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:24:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:32 INFO - (blobuploader) - INFO - Done attempting. 10:24:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:24:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:24:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:24:35 INFO - (blobuploader) - INFO - Done attempting. 10:24:35 INFO - (blobuploader) - INFO - Iteration through files over. 10:24:35 INFO - Return code: 0 10:24:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:24:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:24:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee605c17b39573660a7caaac5c0225695ab9e5b99c59fc3035b0c51e9e707f6840df9ae1e0dcc0bb2057478bf1d0ad03fe57a06dfb70200ffb7fb062a143b798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1904450f2836673c0756c93131a7ce4d79c490ab359f80057197f5bb5bee0d0da1d38d97a40ae693408b0a8298b2c6bf8493a841a97508f1a17a4b861a67d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da346edf04ee219e125c6a0acaea009a8f6af6b727fbc356c0a3a642d5c4ec4f5e169f6b180669178969d026e81a702a0ddfecae23b6533fff697a543f9dbe3"} 10:24:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:24:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:24:35 INFO - Contents: 10:24:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee605c17b39573660a7caaac5c0225695ab9e5b99c59fc3035b0c51e9e707f6840df9ae1e0dcc0bb2057478bf1d0ad03fe57a06dfb70200ffb7fb062a143b798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1904450f2836673c0756c93131a7ce4d79c490ab359f80057197f5bb5bee0d0da1d38d97a40ae693408b0a8298b2c6bf8493a841a97508f1a17a4b861a67d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da346edf04ee219e125c6a0acaea009a8f6af6b727fbc356c0a3a642d5c4ec4f5e169f6b180669178969d026e81a702a0ddfecae23b6533fff697a543f9dbe3"} 10:24:35 INFO - Running post-run listener: copy_logs_to_upload_dir 10:24:35 INFO - Copying logs to upload dir... 10:24:35 INFO - mkdir: /builds/slave/test/build/upload/logs 10:24:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.271595 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-14 10:24:35.267555) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:24:35.271393) ========= 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.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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/ee605c17b39573660a7caaac5c0225695ab9e5b99c59fc3035b0c51e9e707f6840df9ae1e0dcc0bb2057478bf1d0ad03fe57a06dfb70200ffb7fb062a143b798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1904450f2836673c0756c93131a7ce4d79c490ab359f80057197f5bb5bee0d0da1d38d97a40ae693408b0a8298b2c6bf8493a841a97508f1a17a4b861a67d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da346edf04ee219e125c6a0acaea009a8f6af6b727fbc356c0a3a642d5c4ec4f5e169f6b180669178969d026e81a702a0ddfecae23b6533fff697a543f9dbe3"} build_url:https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010937 build_url: 'https://queue.taskcluster.net/v1/task/FVZehNqJSp22HcLd22A9rg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee605c17b39573660a7caaac5c0225695ab9e5b99c59fc3035b0c51e9e707f6840df9ae1e0dcc0bb2057478bf1d0ad03fe57a06dfb70200ffb7fb062a143b798", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e1904450f2836673c0756c93131a7ce4d79c490ab359f80057197f5bb5bee0d0da1d38d97a40ae693408b0a8298b2c6bf8493a841a97508f1a17a4b861a67d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1da346edf04ee219e125c6a0acaea009a8f6af6b727fbc356c0a3a642d5c4ec4f5e169f6b180669178969d026e81a702a0ddfecae23b6533fff697a543f9dbe3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:24:35.303995) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:24:35.304422) ========= 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.rp1xjxSMbC/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.kLfqM81qKy/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:24:35.353113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:24:35.353444) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:24:35.353808) ========= ========= Total master_lag: 0.17 =========