builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0326 starttime: 1486145121.63 results: success (0) buildid: 20170203074109 builduid: f1936783772a420f8c67178e14e80373 revision: cb874e871110d67e78f2c8bc37b618ebb9ba0e4c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.625406) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.625922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.626252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.651483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.651791) ========= 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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-03 10:05:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-02-03 10:05:21 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152563 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.831261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.831879) ========= 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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.024318 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:21.883594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 10:05:21.884406) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cb874e871110d67e78f2c8bc37b618ebb9ba0e4c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cb874e871110d67e78f2c8bc37b618ebb9ba0e4c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-03 10:05:21,994 truncating revision to first 12 chars 2017-02-03 10:05:21,994 Setting DEBUG logging. 2017-02-03 10:05:21,994 attempt 1/10 2017-02-03 10:05:21,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cb874e871110?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 10:05:22,700 unpacking tar archive at: mozilla-beta-cb874e871110/testing/mozharness/ program finished with exit code 0 elapsedTime=0.985415 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 10:05:22.896155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:22.896540) ========= script_repo_revision: cb874e871110d67e78f2c8bc37b618ebb9ba0e4c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:22.896984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:22.897326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 10:05:22.920008) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-03 10:05:22.920474) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:05:23 INFO - MultiFileLogger online at 20170203 10:05:23 in /builds/slave/test 10:05:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:05:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:05:23 INFO - {'append_to_log': False, 10:05:23 INFO - 'base_work_dir': '/builds/slave/test', 10:05:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:05:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:05:23 INFO - 'branch': 'Mozilla-Beta', 10:05:23 INFO - 'buildbot_json_path': 'buildprops.json', 10:05:23 INFO - 'config_files': ('talos/mac_config.py',), 10:05:23 INFO - 'default_actions': ('clobber', 10:05:23 INFO - 'read-buildbot-config', 10:05:23 INFO - 'download-and-extract', 10:05:23 INFO - 'populate-webroot', 10:05:23 INFO - 'create-virtualenv', 10:05:23 INFO - 'install', 10:05:23 INFO - 'run-tests'), 10:05:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:05:23 INFO - 'download_minidump_stackwalk': True, 10:05:23 INFO - 'download_symbols': 'ondemand', 10:05:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:05:23 INFO - 'tooltool.py': '/tools/tooltool.py', 10:05:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:05:23 INFO - '/tools/misc-python/virtualenv.py')}, 10:05:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:05:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:05:23 INFO - 'installer_path': 'installer.exe', 10:05:23 INFO - 'log_level': 'info', 10:05:23 INFO - 'log_name': 'talos', 10:05:23 INFO - 'log_to_console': True, 10:05:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:05:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:05:23 INFO - 'opt_config_files': (), 10:05:23 INFO - 'pip_index': False, 10:05:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:23 INFO - 'cmd': ('bash', 10:05:23 INFO - '-c', 10:05:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:23 INFO - 'enabled': True, 10:05:23 INFO - 'halt_on_failure': False, 10:05:23 INFO - 'name': 'check_screen_resolution'},), 10:05:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:05:23 INFO - 'cmd': ('bash', 10:05:23 INFO - '-c', 10:05:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:05:23 INFO - 'enabled': True, 10:05:23 INFO - 'halt_on_failure': False, 10:05:23 INFO - 'name': 'check_screen_resolution'},), 10:05:23 INFO - 'run_cmd_checks_enabled': True, 10:05:23 INFO - 'sps_profile': False, 10:05:23 INFO - 'sps_profile_interval': 0, 10:05:23 INFO - 'suite': 'dromaeojs', 10:05:23 INFO - 'system_bits': '32', 10:05:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:05:23 INFO - 'title': 't-yosemite-r7-0326', 10:05:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:05:23 INFO - 'use_talos_json': True, 10:05:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:05:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:05:23 INFO - 'work_dir': 'build'} 10:05:23 INFO - [mozharness: 2017-02-03 18:05:23.136529Z] Running clobber step. 10:05:23 INFO - Running pre-action listener: _resource_record_pre_action 10:05:23 INFO - Running main action method: clobber 10:05:23 INFO - rmtree: /builds/slave/test/build 10:05:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:05:25 INFO - Running post-action listener: _resource_record_post_action 10:05:25 INFO - [mozharness: 2017-02-03 18:05:25.397079Z] Finished clobber step (success) 10:05:25 INFO - [mozharness: 2017-02-03 18:05:25.397222Z] Running read-buildbot-config step. 10:05:25 INFO - Running pre-action listener: _resource_record_pre_action 10:05:25 INFO - Running main action method: read_buildbot_config 10:05:25 INFO - Using buildbot properties: 10:05:25 INFO - { 10:05:25 INFO - "project": "", 10:05:25 INFO - "product": "firefox", 10:05:25 INFO - "script_repo_revision": "production", 10:05:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 10:05:25 INFO - "repository": "", 10:05:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 10:05:25 INFO - "buildid": "20170203074109", 10:05:25 INFO - "pgo_build": "False", 10:05:25 INFO - "basedir": "/builds/slave/test", 10:05:25 INFO - "buildnumber": 0, 10:05:25 INFO - "slavename": "t-yosemite-r7-0326", 10:05:25 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:05:25 INFO - "platform": "yosemite_r7", 10:05:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:05:25 INFO - "branch": "mozilla-beta", 10:05:25 INFO - "repo_path": "releases/mozilla-beta", 10:05:25 INFO - "revision": "cb874e871110d67e78f2c8bc37b618ebb9ba0e4c", 10:05:25 INFO - "stage_platform": "macosx64", 10:05:25 INFO - "builduid": "f1936783772a420f8c67178e14e80373", 10:05:25 INFO - "slavebuilddir": "test" 10:05:25 INFO - } 10:05:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 10:05:25 INFO - Running post-action listener: _resource_record_post_action 10:05:25 INFO - [mozharness: 2017-02-03 18:05:25.400381Z] Finished read-buildbot-config step (success) 10:05:25 INFO - [mozharness: 2017-02-03 18:05:25.400518Z] Running download-and-extract step. 10:05:25 INFO - Running pre-action listener: _resource_record_pre_action 10:05:25 INFO - Running main action method: download_and_extract 10:05:25 INFO - mkdir: /builds/slave/test/build/tests 10:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:25 INFO - trying https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 10:05:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 10:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 10:05:26 INFO - Downloaded 1165 bytes. 10:05:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 10:05:26 INFO - Using the following test package requirements: 10:05:26 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 10:05:26 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 10:05:26 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 10:05:26 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:05:26 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 10:05:26 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 10:05:26 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 10:05:26 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 10:05:26 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 10:05:26 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 10:05:26 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 10:05:26 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 10:05:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 10:05:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 10:05:26 INFO - Fetch https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 10:05:27 INFO - Http code: 200 10:05:27 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 10:05:27 INFO - x-amz-cf-id: dpxPdnGj5EMb0Nm-Kr9y08qh9iUh7v7KOSCO-U-mWSRBkfgjlV1seA== 10:05:27 INFO - x-amz-version-id: m1dnlO.024PRP3MTrohQR6kdFKI7sQjH 10:05:27 INFO - Expected file size: 16092080 10:05:27 INFO - Obtained file size: 16092080 10:05:34 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:05:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 10:05:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 10:05:34 INFO - Fetch https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 10:05:34 INFO - Http code: 200 10:05:34 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 10:05:34 INFO - x-amz-cf-id: v3D7eew0A0_nWsEt6bRBne5db9-9G2hjE65pGGj-fF6LvYDKJePMgQ== 10:05:34 INFO - x-amz-version-id: m1dnlO.024PRP3MTrohQR6kdFKI7sQjH 10:05:35 INFO - Expected file size: 16092080 10:05:35 INFO - Obtained file size: 16092080 10:05:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 10:05:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 10:05:41 INFO - Fetch https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 10:05:42 INFO - Http code: 200 10:05:42 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 10:05:42 INFO - x-amz-cf-id: PRa_Zh2IUDXdcWKxYcSaQ2E5J5e7ZKN1Zcv9yXsnwTaBWekAaj5v3g== 10:05:42 INFO - x-amz-version-id: JIScwb5vS15tewzLnFVnLXXpDAtmCxDk 10:05:42 INFO - Expected file size: 14403005 10:05:42 INFO - Obtained file size: 14403005 10:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:42 INFO - trying https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:05:42 INFO - Downloading https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 10:05:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 10:05:45 INFO - Downloaded 82132975 bytes. 10:05:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:05:45 INFO - mkdir: /builds/slave/test/properties 10:05:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:45 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:45 INFO - Contents: 10:05:45 INFO - build_url:https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg 10:05:45 INFO - Running post-action listener: _resource_record_post_action 10:05:45 INFO - Running post-action listener: set_extra_try_arguments 10:05:45 INFO - [mozharness: 2017-02-03 18:05:45.898186Z] Finished download-and-extract step (success) 10:05:45 INFO - [mozharness: 2017-02-03 18:05:45.898309Z] Running populate-webroot step. 10:05:45 INFO - Running pre-action listener: _resource_record_pre_action 10:05:45 INFO - Running main action method: populate_webroot 10:05:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:45 INFO - '--tppagecycles', 10:05:45 INFO - '7'], 10:05:45 INFO - 'tests': ['tsvgm']}}, 10:05:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['damp', 'tps']}, 10:05:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'tests': ['damp', 'tps']}, 10:05:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['dromaeo_dom']}, 10:05:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['basic_compositor_video']}, 10:05:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:05:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['a11yr', 10:05:45 INFO - 'ts_paint', 10:05:45 INFO - 'tpaint', 10:05:45 INFO - 'sessionrestore', 10:05:45 INFO - 'sessionrestore_no_auto_restore', 10:05:45 INFO - 'tabpaint']}, 10:05:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['tsvgx', 10:05:45 INFO - 'tsvgr_opacity', 10:05:45 INFO - 'tart', 10:05:45 INFO - 'tscrollx', 10:05:45 INFO - 'cart']}, 10:05:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:45 INFO - 'tsvgr_opacity', 10:05:45 INFO - 'tart', 10:05:45 INFO - 'tscrollx', 10:05:45 INFO - 'cart']}, 10:05:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'talos_options': ['--disable-e10s'], 10:05:45 INFO - 'tests': ['tp5o']}, 10:05:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'tests': ['tp5o']}, 10:05:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'talos_options': ['--disable-e10s', 10:05:45 INFO - '--xperf_path', 10:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:45 INFO - 'tests': ['tp5n']}, 10:05:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:05:45 INFO - 'talos_options': ['--xperf_path', 10:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:45 INFO - 'tests': ['tp5n']}}, 10:05:45 INFO - 'talos.zip': {'path': '', 10:05:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:45 INFO - Running post-action listener: _resource_record_post_action 10:05:45 INFO - [mozharness: 2017-02-03 18:05:45.917177Z] Finished populate-webroot step (success) 10:05:45 INFO - [mozharness: 2017-02-03 18:05:45.917323Z] Running create-virtualenv step. 10:05:45 INFO - Running pre-action listener: _resource_record_pre_action 10:05:45 INFO - Running main action method: create_virtualenv 10:05:45 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:45 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:05:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:46 INFO - Using real prefix '/tools/python27' 10:05:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:46 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:49 INFO - Installing pip.................done. 10:05:49 INFO - Return code: 0 10:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:05:49 INFO - Reading from file tmpfile_stdout 10:05:49 INFO - Output received: 10:05:49 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:05:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05: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:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:49 INFO - 'HOME': '/Users/cltbld', 10:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:49 INFO - 'LOGNAME': 'cltbld', 10:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:49 INFO - 'MOZ_NO_REMOTE': '1', 10:05:49 INFO - 'NO_EM_RESTART': '1', 10:05:49 INFO - 'PAGER': '/bin/cat', 10:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:49 INFO - 'PWD': '/builds/slave/test', 10:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:49 INFO - 'SHELL': '/bin/bash', 10:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:05:49 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:49 INFO - 'USER': 'cltbld', 10:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:49 INFO - 'XPC_FLAGS': '0x0', 10:05:49 INFO - 'XPC_SERVICE_NAME': '0', 10:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:50 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:05:50 INFO - Cleaning up... 10:05:50 INFO - Return code: 0 10:05:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05: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:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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', '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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:05: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 psutil>=3.1.1 10:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:50 INFO - 'HOME': '/Users/cltbld', 10:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:50 INFO - 'LOGNAME': 'cltbld', 10:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:50 INFO - 'MOZ_NO_REMOTE': '1', 10:05:50 INFO - 'NO_EM_RESTART': '1', 10:05:50 INFO - 'PAGER': '/bin/cat', 10:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:50 INFO - 'PWD': '/builds/slave/test', 10:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:50 INFO - 'SHELL': '/bin/bash', 10:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:05:50 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:50 INFO - 'USER': 'cltbld', 10:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:50 INFO - 'XPC_FLAGS': '0x0', 10:05:50 INFO - 'XPC_SERVICE_NAME': '0', 10:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:50 INFO - Downloading/unpacking psutil>=3.1.1 10:05: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:05: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:05: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:05: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:05: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:05: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:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:54 INFO - Installing collected packages: psutil 10:05:54 INFO - Running setup.py install for psutil 10:05:54 INFO - building 'psutil._psutil_osx' extension 10:05:54 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:05:54 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:05:54 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:05:54 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:05:54 INFO - building 'psutil._psutil_posix' extension 10:05:54 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:05:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:05:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:05:55 INFO - ^ 10:05:55 INFO - 1 warning generated. 10:05:55 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:05:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:55 INFO - Successfully installed psutil 10:05:55 INFO - Cleaning up... 10:05:55 INFO - Return code: 0 10:05:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:55 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:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 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:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:55 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:05:55 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:05:55 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:05:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:05:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:55 INFO - 'HOME': '/Users/cltbld', 10:05:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:55 INFO - 'LOGNAME': 'cltbld', 10:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:55 INFO - 'MOZ_NO_REMOTE': '1', 10:05:55 INFO - 'NO_EM_RESTART': '1', 10:05:55 INFO - 'PAGER': '/bin/cat', 10:05:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:55 INFO - 'PWD': '/builds/slave/test', 10:05:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:55 INFO - 'SHELL': '/bin/bash', 10:05:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:05:55 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:05:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:55 INFO - 'USER': 'cltbld', 10:05:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:55 INFO - 'XPC_FLAGS': '0x0', 10:05:55 INFO - 'XPC_SERVICE_NAME': '0', 10:05:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:58 INFO - Installing collected packages: mozsystemmonitor 10:05:58 INFO - Running setup.py install for mozsystemmonitor 10:05:58 INFO - Successfully installed mozsystemmonitor 10:05:58 INFO - Cleaning up... 10:05:58 INFO - Return code: 0 10:05:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05: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:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05: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', '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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:05: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:05: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 jsonschema==2.5.1 10:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:58 INFO - 'HOME': '/Users/cltbld', 10:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:58 INFO - 'LOGNAME': 'cltbld', 10:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:58 INFO - 'MOZ_NO_REMOTE': '1', 10:05:58 INFO - 'NO_EM_RESTART': '1', 10:05:58 INFO - 'PAGER': '/bin/cat', 10:05:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:58 INFO - 'PWD': '/builds/slave/test', 10:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:58 INFO - 'SHELL': '/bin/bash', 10:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:05:58 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:58 INFO - 'USER': 'cltbld', 10:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:58 INFO - 'XPC_FLAGS': '0x0', 10:05:58 INFO - 'XPC_SERVICE_NAME': '0', 10:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:58 INFO - Downloading/unpacking jsonschema==2.5.1 10:05: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:05: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:05: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:05: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:05: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:05: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:06:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:06:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:06: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:06: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:06: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:06: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:06: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:06: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:06:01 INFO - Downloading functools32-3.2.3-2.tar.gz 10:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:06:01 INFO - warning: no files found matching '*.txt' 10:06:01 INFO - no previously-included directories found matching 'build' 10:06:01 INFO - no previously-included directories found matching 'dist' 10:06:01 INFO - no previously-included directories found matching '.git*' 10:06:01 INFO - Installing collected packages: jsonschema, functools32 10:06:01 INFO - Running setup.py install for functools32 10:06:01 INFO - warning: no files found matching '*.txt' 10:06:01 INFO - no previously-included directories found matching 'build' 10:06:01 INFO - no previously-included directories found matching 'dist' 10:06:01 INFO - no previously-included directories found matching '.git*' 10:06:02 INFO - Successfully installed jsonschema functools32 10:06:02 INFO - Cleaning up... 10:06:02 INFO - Return code: 0 10:06:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:02 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:02 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:06:02 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:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:02 INFO - 'HOME': '/Users/cltbld', 10:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:02 INFO - 'LOGNAME': 'cltbld', 10:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:02 INFO - 'MOZ_NO_REMOTE': '1', 10:06:02 INFO - 'NO_EM_RESTART': '1', 10:06:02 INFO - 'PAGER': '/bin/cat', 10:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:02 INFO - 'PWD': '/builds/slave/test', 10:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:02 INFO - 'SHELL': '/bin/bash', 10:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:02 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:02 INFO - 'USER': 'cltbld', 10:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:02 INFO - 'XPC_FLAGS': '0x0', 10:06:02 INFO - 'XPC_SERVICE_NAME': '0', 10:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:06:02 INFO - Cleaning up... 10:06:02 INFO - Return code: 0 10:06:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:02 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:02 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:06:02 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:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:02 INFO - 'HOME': '/Users/cltbld', 10:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:02 INFO - 'LOGNAME': 'cltbld', 10:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:02 INFO - 'MOZ_NO_REMOTE': '1', 10:06:02 INFO - 'NO_EM_RESTART': '1', 10:06:02 INFO - 'PAGER': '/bin/cat', 10:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:02 INFO - 'PWD': '/builds/slave/test', 10:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:02 INFO - 'SHELL': '/bin/bash', 10:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:02 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:02 INFO - 'USER': 'cltbld', 10:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:02 INFO - 'XPC_FLAGS': '0x0', 10:06:02 INFO - 'XPC_SERVICE_NAME': '0', 10:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:06:02 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:06:02 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:06:02 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:06:02 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:06:02 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:06:02 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:06:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:06:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:06:05 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:06:05 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:06:05 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:06:05 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:06:05 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:06:05 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:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:06:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:06:05 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:06:05 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:06:05 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:06:05 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:06:05 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:06:05 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:06:06 INFO - Downloading docopt-0.6.1.tar.gz 10:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:06:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:06:06 INFO - Running setup.py install for blobuploader 10:06:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:06:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:06:06 INFO - Running setup.py install for requests 10:06:07 INFO - Running setup.py install for docopt 10:06:07 INFO - Successfully installed blobuploader requests docopt 10:06:07 INFO - Cleaning up... 10:06:07 INFO - Return code: 0 10:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06: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:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06: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:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:07 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:07 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:06:07 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:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:07 INFO - 'HOME': '/Users/cltbld', 10:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:07 INFO - 'LOGNAME': 'cltbld', 10:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:07 INFO - 'MOZ_NO_REMOTE': '1', 10:06:07 INFO - 'NO_EM_RESTART': '1', 10:06:07 INFO - 'PAGER': '/bin/cat', 10:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:07 INFO - 'PWD': '/builds/slave/test', 10:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:07 INFO - 'SHELL': '/bin/bash', 10:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:07 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:07 INFO - 'USER': 'cltbld', 10:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:07 INFO - 'XPC_FLAGS': '0x0', 10:06:07 INFO - 'XPC_SERVICE_NAME': '0', 10:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:07 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-R8pIc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:07 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-V_eJOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:07 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-hezG8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:07 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-SKuxNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-TUcPVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-JSgQtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-xg2AQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-SXCnxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-dPxkRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-i2ky35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-_PQrHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-gnmDbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:08 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-gUIBYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:09 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-oAMIn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:09 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-93VcW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:09 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-7sajeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:09 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-TSseS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:06:09 INFO - Running setup.py install for manifestparser 10:06:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:06:09 INFO - Running setup.py install for mozcrash 10:06:09 INFO - Running setup.py install for mozdebug 10:06:09 INFO - Running setup.py install for mozdevice 10:06:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Running setup.py install for mozfile 10:06:10 INFO - Running setup.py install for mozhttpd 10:06:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Running setup.py install for mozinfo 10:06:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Running setup.py install for mozInstall 10:06:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Running setup.py install for mozleak 10:06:10 INFO - Running setup.py install for mozlog 10:06:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:06:10 INFO - Running setup.py install for moznetwork 10:06:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:06:11 INFO - Running setup.py install for mozprocess 10:06:11 INFO - Running setup.py install for mozprofile 10:06:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:06:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:06:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:06:11 INFO - Running setup.py install for mozrunner 10:06:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:06:11 INFO - Running setup.py install for mozscreenshot 10:06:11 INFO - Running setup.py install for moztest 10:06:11 INFO - Running setup.py install for mozversion 10:06:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:06:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:06:12 INFO - Cleaning up... 10:06:12 INFO - Return code: 0 10:06:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:06:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:12 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:12 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:06:12 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:06:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:12 INFO - 'HOME': '/Users/cltbld', 10:06:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:12 INFO - 'LOGNAME': 'cltbld', 10:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:12 INFO - 'MOZ_NO_REMOTE': '1', 10:06:12 INFO - 'NO_EM_RESTART': '1', 10:06:12 INFO - 'PAGER': '/bin/cat', 10:06:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:12 INFO - 'PWD': '/builds/slave/test', 10:06:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:12 INFO - 'SHELL': '/bin/bash', 10:06:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:12 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:12 INFO - 'USER': 'cltbld', 10:06:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:12 INFO - 'XPC_FLAGS': '0x0', 10:06:12 INFO - 'XPC_SERVICE_NAME': '0', 10:06:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-_jkTRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-c2Z7Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-M9L4_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-RXhjtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-2zpF9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-ywl6TS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:06:12 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:06:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:06:12 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-eZNtcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-fh9sTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-9qe_TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-OeYl_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-HsRclV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-yph4sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-WU1RjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-NC8jgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-26ZmhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:06:13 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:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-QtgSfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:06:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:06:13 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-aA2SAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:14 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:06:16 INFO - Downloading blessings-1.6.tar.gz 10:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:06:16 INFO - Installing collected packages: blessings 10:06:16 INFO - Running setup.py install for blessings 10:06:16 INFO - Successfully installed blessings 10:06:16 INFO - Cleaning up... 10:06:17 INFO - Return code: 0 10:06:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:06:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:17 INFO - Reading from file tmpfile_stdout 10:06:17 INFO - Current package versions: 10:06:17 INFO - blessings == 1.6 10:06:17 INFO - blobuploader == 1.2.4 10:06:17 INFO - docopt == 0.6.1 10:06:17 INFO - functools32 == 3.2.3-2 10:06:17 INFO - jsonschema == 2.5.1 10:06:17 INFO - manifestparser == 1.1 10:06:17 INFO - mozInstall == 1.12 10:06:17 INFO - mozcrash == 1.0 10:06:17 INFO - mozdebug == 0.1 10:06:17 INFO - mozdevice == 0.48 10:06:17 INFO - mozfile == 1.2 10:06:17 INFO - mozhttpd == 0.7 10:06:17 INFO - mozinfo == 0.9 10:06:17 INFO - mozleak == 0.1 10:06:17 INFO - mozlog == 3.4 10:06:17 INFO - moznetwork == 0.27 10:06:17 INFO - mozprocess == 0.23 10:06:17 INFO - mozprofile == 0.28 10:06:17 INFO - mozrunner == 6.13 10:06:17 INFO - mozscreenshot == 0.1 10:06:17 INFO - mozsystemmonitor == 0.3 10:06:17 INFO - moztest == 0.8 10:06:17 INFO - mozversion == 1.4 10:06:17 INFO - psutil == 3.1.1 10:06:17 INFO - requests == 1.2.3 10:06:17 INFO - wsgiref == 0.1.2 10:06:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:06:17 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:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:17 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:06:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:18 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:18 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:06:18 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:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:18 INFO - 'HOME': '/Users/cltbld', 10:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:18 INFO - 'LOGNAME': 'cltbld', 10:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:18 INFO - 'MOZ_NO_REMOTE': '1', 10:06:18 INFO - 'NO_EM_RESTART': '1', 10:06:18 INFO - 'PAGER': '/bin/cat', 10:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:18 INFO - 'PWD': '/builds/slave/test', 10:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:18 INFO - 'SHELL': '/bin/bash', 10:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:18 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:18 INFO - 'USER': 'cltbld', 10:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:18 INFO - 'XPC_FLAGS': '0x0', 10:06:18 INFO - 'XPC_SERVICE_NAME': '0', 10:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 INFO - Cleaning up... 10:06:18 INFO - Return code: 0 10:06:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:06:18 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:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:18 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:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:06:18 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 0x10fa70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc160c7cb20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, '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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:06:18 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:06:18 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:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:18 INFO - 'HOME': '/Users/cltbld', 10:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:18 INFO - 'LOGNAME': 'cltbld', 10:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:18 INFO - 'MOZ_NO_REMOTE': '1', 10:06:18 INFO - 'NO_EM_RESTART': '1', 10:06:18 INFO - 'PAGER': '/bin/cat', 10:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:18 INFO - 'PWD': '/builds/slave/test', 10:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:18 INFO - 'SHELL': '/bin/bash', 10:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:18 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:18 INFO - 'USER': 'cltbld', 10:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:18 INFO - 'XPC_FLAGS': '0x0', 10:06:18 INFO - 'XPC_SERVICE_NAME': '0', 10:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:06:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:06:18 INFO - Cleaning up... 10:06:18 INFO - Return code: 0 10:06:18 INFO - Running post-action listener: _resource_record_post_action 10:06:18 INFO - Running post-action listener: _start_resource_monitoring 10:06:19 INFO - Starting resource monitoring. 10:06:19 INFO - [mozharness: 2017-02-03 18:06:19.012315Z] Finished create-virtualenv step (success) 10:06:19 INFO - [mozharness: 2017-02-03 18:06:19.013377Z] Running install step. 10:06:19 INFO - Running pre-action listener: _resource_record_pre_action 10:06:19 INFO - Running main action method: install 10:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:06:19 INFO - Reading from file tmpfile_stdout 10:06:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:06:19 INFO - Reading from file tmpfile_stdout 10:06:19 INFO - Output received: 10:06:19 INFO - Usage: mozinstall [options] installer 10:06:19 INFO - Options: 10:06:19 INFO - -h, --help show this help message and exit 10:06:19 INFO - -d DEST, --destination=DEST 10:06:19 INFO - Directory to install application into. [default: 10:06:19 INFO - "/builds/slave/test"] 10:06:19 INFO - --app=APP Application being installed. [default: firefox] 10:06:19 INFO - mkdir: /builds/slave/test/build/application 10:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:06:40 INFO - Reading from file tmpfile_stdout 10:06:40 INFO - Output received: 10:06:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:06:40 INFO - Running post-action listener: _resource_record_post_action 10:06:40 INFO - [mozharness: 2017-02-03 18:06:40.854457Z] Finished install step (success) 10:06:40 INFO - [mozharness: 2017-02-03 18:06:40.854624Z] Running run-tests step. 10:06:40 INFO - Running pre-action listener: _resource_record_pre_action 10:06:40 INFO - Running main action method: run_tests 10:06:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:06:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:06:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:06:40 INFO - 2017-02-03 10:06:40.869 screenresolution[1622:7296] starting screenresolution argv=screenresolution get 10:06:40 INFO - 2017-02-03 10:06:40.871 screenresolution[1622:7296] Display 0: 1600x1200x32@60 10:06:40 INFO - 2017-02-03 10:06:40.877 screenresolution[1623:7298] starting screenresolution argv=screenresolution list 10:06:40 INFO - Available Modes on Display 0 10:06:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:06:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:06:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:06:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:06:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:06:41 INFO - Intel Iris: 10:06:41 INFO - Chipset Model: Intel Iris 10:06:41 INFO - Type: GPU 10:06:41 INFO - Bus: Built-In 10:06:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:06:41 INFO - Vendor: Intel (0x8086) 10:06:41 INFO - Device ID: 0x0a2e 10:06:41 INFO - Revision ID: 0x0009 10:06:41 INFO - Displays: 10:06:41 INFO - Display: 10:06:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:06:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:06:41 INFO - Display Serial Number: 1600x1200 60 10:06:41 INFO - Main Display: Yes 10:06:41 INFO - Mirror: Off 10:06:41 INFO - Online: Yes 10:06:41 INFO - Rotation: Supported 10:06:41 INFO - Return code: 0 10:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:06:41 INFO - Python 2.7.3 10:06:41 INFO - Return code: 0 10:06:41 INFO - grabbing minidump binary from tooltool 10:06:41 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:06:41 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 0x10f884d30>, '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 0x10f6eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f87cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:06:41 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:06:41 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:06:41 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:06:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:06:41 INFO - Return code: 0 10:06:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:06:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:06:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:06:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:06:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0326 --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:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cShm4qgwTc/Render', 10:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:06:41 INFO - 'HOME': '/Users/cltbld', 10:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:06:41 INFO - 'LOGNAME': 'cltbld', 10:06:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:06:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:06:41 INFO - 'MOZ_NO_REMOTE': '1', 10:06:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:06:41 INFO - 'NO_EM_RESTART': '1', 10:06:41 INFO - 'PAGER': '/bin/cat', 10:06:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:06:41 INFO - 'PWD': '/builds/slave/test', 10:06:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:06:41 INFO - 'SHELL': '/bin/bash', 10:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5t9dNaoZ0t/Listeners', 10:06:41 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 10:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:06:41 INFO - 'USER': 'cltbld', 10:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:06:41 INFO - 'XPC_FLAGS': '0x0', 10:06:41 INFO - 'XPC_SERVICE_NAME': '0', 10:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:06:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:06:41 INFO - mozversion application_buildid: 20170203074109 10:06:41 INFO - mozversion application_changeset: cb874e871110d67e78f2c8bc37b618ebb9ba0e4c 10:06:41 INFO - mozversion application_display_name: Firefox 10:06:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:06:41 INFO - mozversion application_name: Firefox 10:06:41 INFO - mozversion application_remotingname: firefox 10:06:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:06:41 INFO - mozversion application_vendor: Mozilla 10:06:41 INFO - mozversion application_version: 52.0 10:06:41 INFO - mozversion platform_buildid: 20170203074109 10:06:41 INFO - mozversion platform_changeset: cb874e871110d67e78f2c8bc37b618ebb9ba0e4c 10:06:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:06:41 INFO - mozversion platform_version: 52.0 10:06:41 INFO - using testdate: 1486145201 10:06:41 INFO - actual date: 1486145201 10:06:41 INFO - starting webserver on 'localhost:49235' 10:06:41 INFO - SUITE-START | Running 2 tests 10:06:41 INFO - TEST-START | dromaeo_css 10:06:41 INFO - operating with platform_type : mac_ 10:06:41 INFO - Initialising browser for dromaeo_css test... 10:06:41 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpP6aw49/profile http://localhost:49235/getInfo.html) 10:06:44 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 10:06:44 INFO - PROCESS | 1633 | colorDepth:24 10:06:44 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 10:06:44 INFO - PROCESS | 1633 | __metrics 10:06:44 INFO - PROCESS | 1633 | 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:06:44 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:45 INFO - TEST-INFO | 1633: exit 0 10:06:45 INFO - Browser initialized. 10:06:45 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:45 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpP6aw49/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:57 INFO - PROCESS | 1637 | RSS: Main: 168255488 10:06:57 INFO - PROCESS | 1637 | 10:10:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 10:10:02 INFO - PROCESS | 1637 | RSS: Main: 300748800 10:10:02 INFO - PROCESS | 1637 | 10:13:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 10:13:05 INFO - PROCESS | 1637 | RSS: Main: 300302336 10:13:05 INFO - PROCESS | 1637 | 10:16:07 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 10:16:08 INFO - PROCESS | 1637 | RSS: Main: 268320768 10:16:08 INFO - PROCESS | 1637 | 10:19:10 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 10:19:11 INFO - PROCESS | 1637 | RSS: Main: 259366912 10:19:11 INFO - PROCESS | 1637 | 10:22:14 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 10:22:15 INFO - PROCESS | 1637 | RSS: Main: 256303104 10:22:15 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 10:25:18 INFO - PROCESS | 1637 | RSS: Main: 315314176 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | __start_tp_report 10:25:18 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 10:25:18 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 10:25:18 INFO - PROCESS | 1637 | |i|pagename|runs| 10:25:18 INFO - PROCESS | 1637 | |0;dojo.html;3444.586649602047;3475.7566970312087;3484.738644601655;3504.56192975878;3530.329237444887;5687.829365119048;5708.37207907131;5714.142878568212;5718.228039214705;5753.884922301557;4454.33185022246;4735.50277220892;5680.034394152994;5743.798967036155;5750.252467179272;4691.765411729409;4750.833720819772;5191.273221748958;5245.2132180173085;5352.170413585901;5178.663386879841;5190.4809519047985;5198.974005129969;5200.245964335169;5248.186531087687;5299.470052994689;5304.230886521452;5310.787568497255;5312.57499400049;5349.652272602288;5284.471552844704;5288.65623734456;5295.523402893719;5297.12597421425;5307.52232299095;5066.138756411419;5154.433012368637;5208.713520756357;5227.111391063529;5245.344331958505;5611.130274807411;5636.577256705723;5690.2887139107615;5721.656700597978;5724.084146536534;6172.907406388863;6224.564280500367;6253.530985176085;6262.436380725757;6275.560710750249;6282.371762823726;6287.96856015717;6291.905621415683;6291.968540157316;6302.432781049728;5734.197212390227;5777.653340799566;5791.131330300489;5836.824895253149;5853.853653658693;6235.532335074888;6269.905951410732;6275.090111933744;6277;6292.402221788922;6177.752889884444;6178.62928224308;6182.103594978703;6183.876322473615;6198.845028874314;6112.969435152796;6142.235993802807;6198.256209254918;6258.812235632939;6280.340564240781;5133.512316329943;6249.125122482856;6262.966610509215;6268.153799237045;6270.028145637433;6164.784232551929;6273.749050038039;6277.78027769035;6278.121063051177;6289.433950944437;4915.680480768739;4976.303317535548;4976.353074100344;4986.152354099811;5010.974945125251;4432.534583868712;4439.733615983051;4445.066536027406;4467.865964021084;4496.887577810581;4987.900241995139;5829.358770535237;5846.006178949504;5858;5871.4715675589405;6222.751089956351;6255.374462553708;6256.7810126645345;6264.123022776815;6273.435390814849;5613.4947854692455;5662.66024038559;5670.744816483269;5771.567132465082;5833.329167145747;5451.3458384994055;5491.478309560585;5506.862328441741;5510.421405752419;5520.834374968757;6350.682465876687;6381.617102973929;6383.776567820104;6390.392912673288;6470.48236141117;6125.173101631222;6163.568550201443;6192.287886892885;6194.163787888758;6268.843278917881;6192.133101365903;6244.687765611792;6248.937510625017;6271.310155882758;6274.058891166179;6185.597936134317;6206.224221972254;6235.376462353728;6287.434130928238;6298.590591611531;6244.1570387997035;6275.937240627535;6280.466160376236;6297.1813664223355;6298.1812364392335;6127.20346354971;6186.072089186658;6194.008958566607;6195.1636529068;6201.56589038781;6014.127951447103;6084.056971169476;6111.95640283616;6120.938790612215;6125.111858780541;4147.605977432139;4590.586847183769;6055.091736239423;6107.389261073857;6126.785562505291;6149.41580549847;6178.073289006506;6190.845228869134;6197.535184861244;6207.6275423474735;4939.6542242042715;5884.293884733859;5979.073243647242;6029.909551356821;6065.090236464565 10:25:18 INFO - PROCESS | 1637 | |1;ext.html;485.3302442629169;487.8462436338062;492.72653677209144;518.0002594991664;519.898619769145;29421.411571768578;40535.986600335;41695.58304416959;41788.164236715325;41831.79084104575;43166;43503.12993740131;43521.564784352144;43535;43565.34651980215;9663.66177183797;9861.654842080528;9863.457509836957;9868.555914983843;9874.802503949917;9642.517874106301;9752.561134748954;9753;9757.902420975806;9767.120959113678;9712.951435242849;9773.706788796348;9774.266929980244;9782.608695652165;9787.902120978806;9703.320767546275;9760.023997600254;9765.316427850054;9767.267454940908;9790.167835733972;9653.855192172123;9739.977302383219;9755.219582433388;9757.804843903154;9766.218702503782;40725.59274407248;41164.970875728344;41240.793796031125;41281.793591032154;41358.759237222934;3384.4077286474767;3385.119868834121;3881.243157584272;3886.5336159661024;4081.0613558881328;30478.238044048856;30613.540796888065;30966.380672386425;31442;31897.52153717696;3112.393083348748;3183.220111072802;3189.617245930503;3244.3997860395666;3285.4250506161566;508168;516410;517080;517388;518783;47001.76499117551;58452;58736.11895821566;58961.82076358535;58986.41013589895;35630.643693562735;35827;35860.74487392981;36763.816180918926;60393.396066039655;14226.573202803933;16967.49097527076;17150.31398744061;17162.828371716372;17194.656106877792;12988.870111298766;13093.345332733325;13094.869051309555;13097.738045239043;13099.41052652633;15653;18874;18931.14809833561;18965.24139034451;19007.714884276746;7749.7675069748;7867.6066196689935;7877;7916.525008499509;7922.841543169219;883.8497455432592;1004.7638804880847;1031.4945676618554;1051.1905832508926;1128.920975531705;29790.55314170289;29799.55300670492;30092.69907300899;30230.09309720712;30636.234094147825;847.8139360034099;1052.6368402900987;1055.488088277185;1102.2614848051824;1135.8296255561731;14601.415943362359;15380.384784608714;15790.131542765259;16070.357185712674;16460.012399256084;684339;688268;688966;689870;690370;57837.71081144567;58442.83114337774;58480.70759646175;58575.414245857;58607.706961465774;1128.8927551882557;1319.6305034590523;1358.7554240236655;1376.105531404595;1409.2953523238382;29045.12864614065;29840.403191935606;30014.54978175373;30125.849370753007;30877.536836947016;48650.51349486601;48996.34534719196;49078.5092149074;49085.75457122691;49179.754101229264;532092.3395382998;534706;536998.3150084225;537253;537696;23823.166189183296;24398.87800560986;25966.09118680837;26378.812953417528;26413.471730565667;44535.109297813215;45296.54703453055;45948.770256148506;46023.9624383318;46055.53944460512;37515.06212344713;37945.62054379531;38198.2360352797;38367.61632383752;39563;37927.86216413512;38274.61725382822;38278.808605956794;38308.616913830505;39047.41428878626;37223.81388093042;38085.80957095197;38677.2264554713;38752.41871371988;38760;28965.710342897146;32161.678383216804;32402.189945251557;34430;34730;21520.923953801936;24600.138995135083;25367.61948570736;26005.959761609323;26122.608160877222 10:25:18 INFO - PROCESS | 1637 | |2;jquery.html;380.66311314486677;383.11882669859335;391.8804764546813;399.6003996003996;416.4107787480715;19379.903100484495;20806.89596552019;20825.65370495243;21119.47201319966;21333.466663333424;20191.697124543145;20230.797692023076;20791.376258712226;20865.58268834626;21245.93770311486;5803.129530570406;5859.501942334896;5975.880482390349;6018.759249630009;6037;4301.956980430203;4526.864194074166;4527.09458108378;5929.584929054968;6040.426159514839;6276.37236276371;6556.573822701533;6573.934260657404;6652.900206496906;6671.199456065255;6475.417212450879;6537.346265373449;6586.012098185287;6629.33706629338;6652.201735791736;5633.80281690143;5743.195952566644;5799.043157878945;5808.664007278349;5814;25986.610200847;26116.8694156528;26121.82451789674;26159.869200654066;26354.73645263561;2271.4094335472896;2421.3946513371657;2493.887775050127;2568.6275490053836;2629.9605505917425;15404.998675086084;15472.690546189013;15575.688486230325;16004.439844605384;16480.670386592203;1904.4286713985748;1907.4659095453296;1914.8946807925558;1972.763268407786;1981.2768339556103;114171;114963;120373.39813301053;122930.38534807268;126137;10710.73223169427;10724.249302548747;11221.158413119048;11242.301847556528;11384.601538946097;14139.151650900978;14179.291035448186;14182.929085354508;14199.574012779427;14216.289185540682;8552.657893684307;8753.868691969625;8772.912270877338;8894.42186257891;8989.775255618531;8869.423487473294;8889.066648001866;8911.955440222757;9078.319126065571;9104.81790364189;6657.567258128303;6731.562448440835;6752.797416077526;6753.696083676246;6758.83102922421;1611.7018351604756;1620.5462470508276;1827.45176447068;1867.794542600313;1874.4564076417687;811.6712731343815;965.6764983730388;998.1316254858319;998.7103739915343;1040.78143589847;20445.693314600292;22216.444588885082;22367.888160559418;22436.55126897453;22523.662145067836;941.9293552983554;981.5435822342645;996.1681995533811;1024.3239461955222;1029.505837198134;9624.951875240718;11051.281666691639;11088.168387370979;11804.397872509218;12104.87895121055;616586;620323;621686;635748;637843.8107809431;122825.38587307185;134911;135739;148019;149140;894.2398960883197;959.6737109382843;970.8737864077625;1004.9949750251202;1039.4231201682987;17448.21483033292;18368.265269389074;18969.14638841228;19211;19242.518937026685;50607.74696126496;52017.7399113002;53512.73243633757;55982;56513.434865650815;59484;72759;74947.62526187334;75403.24596754182;79234;5769.9711501442225;6004.519638428827;8099;8144.267015968591;8244.628991695268;11722.58970936013;12716;12797.81688416193;13205.471781128646;13288.402021908843;11047;11223.775524489301;11485.885141148481;11637.76724465523;11643.47604357789;11001.669949901516;11137.663480382405;11246.156538259827;11549.538018479167;11754.88245117538;11354.318740875575;11573.537058517903;11613.941930290295;11687.532498700297;12127.393630318626;8242.340612750846;8356.415050946376;8402.41183117176;8428.620712068094;8456.365772567206;11243.887561124284;11271.718207044889;11293.830592540955;11650.88349116498;11747.823782643096 10:25:18 INFO - PROCESS | 1637 | |3;mootools.html;1071.8392241163824;1079.1852151625521;1297.4161627267729;1384.6746014686553;1777.5200695812123;7643.312101910827;8079.232472915079;8314.501129932192;8534.658613655462;8543.231109200186;8454.704085357014;8543.231109200171;8544.017437994633;8552.572371381437;8593.785155371119;2369.360272726367;2410;2412.445137618349;2412.8069754419603;2416.9879150604224;2128.8716980000545;2177.42298290953;2273.795358417742;2345.6247000479925;2433.8783060846886;2000.9799902001012;2372.383180373098;2374.2283757778705;2393.3418309964727;2406.217979156781;2346.23747282134;2357.1514254868234;2367.419982104378;2370.573296806574;2375.2636682628354;2386.844855084414;2392.916247931314;2416.5287768885073;2417.2506522977933;2420.9031638734427;8329;8419.368547358972;8613.052564217931;8613.741587752378;8616.48301101936;5180.4042535108565;5410.053240682865;5412.5940554458175;5419.59353048519;5419.593530485229;3063.391719347338;3394.4229480988292;3541.5041894134843;3616.1140520572367;3659.835307411173;1069.2354966199127;1075.8063548561256;1078.9676309710721;1093.6992327110029;1117.3016864459712;11104.389258590774;11156.16328775345;11216.158788090845;11224.326540407601;11241.494132763963;4350.847720329773;4483.103379324083;4595.931061034087;4601.861944141681;4609.262517997171;8871.733847984571;8887;8889;8895.4662720237;8899.599518021576;5238.214267859776;5239.449857764956;5239.816606418757;5245.291885595396;5256;4213.810378532973;4457.4205353304515;4550.862284428893;4587.1513769952;4961.131801934719;3745.5318085239346;3792;3792.943105853414;3899.2786334528205;3901.668358189529;1146.8337091121741;1149.6263714292888;1150.440270398357;1158.9942050289694;1165.114512970132;814.4583851738646;893.5442924108621;907.9228265597366;912.2519533982071;928.1924725488733;5505.201745746845;5602.551795856321;5712.7714891403875;5798.188253644493;5809.593328467051;923.8152369526122;930.9813803724023;961.6105477281573;963.7494251494662;1004.4525733475274;3774.226283611853;3817.599152089046;3869.903252418656;3928.4500169976227;3929.5677475477673;9989.051040151175;10473.528691208914;10496.947515262375;10497.947510262553;10508.159347252202;10433.739156521147;10443.68668939933;10458;10462.581496740197;10469.31949423285;7649.732259371007;7983.12185659577;7990.12108668046;7991.480553763987;8144.30773384269;5769.307683078057;5782.971085144547;5784.4504772046585;5829.76680932758;5877.089051197071;9235.907640923773;9438.85841712361;9654.275929305471;9671.9032809671;9725.027497250217;9033.954830225806;9317.021712720067;9766.316357854881;9828.852567211641;9963.00369963027;7160.534565253241;7288.562686238843;7307.67115479794;7313.926860731537;7403.851922961403;8155.9184408157535;8162.347012239125;8239.670413183645;8254.752357429286;8272.875906861282;7219.133703955559;7310.6710198040155;7389.963050184715;7393.963030184815;7446.292602202782;7148.857022859618;7202.423806095605;7229.421646268391;7275.745348912763;7281.308275713799;6508.446782023581;6624.238212605612;6686.7993960181275;6769.153855768029;6777.152855893013;4293.055527783882;4878.902421951612;4909.803607855646;4939.950600494093;5017.498250175099;4899.902001959869;4962.206047032601;4999.65002449825;5057.46896575869;5092.541671249605 10:25:18 INFO - PROCESS | 1637 | |4;prototype.html;306.6963705931128;311.9454095533281;314.76549970272146;317.9570757947678;320.91335339458334;5326.348831862126;5328.333958255218;5353.411124776281;5370;5408.215808707735;4945.6043516518685;5307.469253074691;5330.840074797762;5339.22581225722;5349.946500534994;3435.2823588205897;3889.144388234591;3906.4140378943102;4084.7140700151003;4126.690498212631;3521.735869809762;4036.8587099451524;4065.3901914712733;4088.6115818997296;4110.609492098246;3286.9317471821632;4006.33736999145;4024.9597504025023;4030.8387664493384;4068.572799856002;3004.0987703688916;3120.1435146088043;3415.129142068781;3665.4685070664605;4038.5057528714415;3330.5004249362546;3360.235876165033;3537.8231088445473;3542.8937131886087;3545.007397928584;4268.374949406124;4717.174494463455;5211.452797456251;5222.926117628256;5242.501962313574;4225.577442255781;4271.466066741657;4647.419072615923;4673.065386922629;4679.368285281512;4659;5452.854900470906;5496.560275177976;5518.862028449321;5618.179637846263;3262.4290749119045;3835.7314987950854;3851.807409629507;3855.4024126260474;3878.864239751595;4436.600705936481;5786.652800831964;5803.361630220672;5809.477147056743;5825;3793.336166170909;3866.3620502616754;3880;3884.8446062157195;3888.3389823730035;3039.2705750620134;3706.755764649515;3949.9012524687114;3956.0109972506875;3958.5051868516434;4525.705829121097;4581.931271030937;4584.220682483986;4608.308753686973;4608.608268297232;5165.767540460688;5509.531689806331;5569.331680198321;5642.887142257132;5658.519025882846;1088.1675518228562;1144.48927415812;1191.8569771627288;1276.0493432392927;1283.2108253424076;831.7255305749088;855.6021450025768;873.7636244713786;881.184903963831;881.8765372847806;4410.889727756832;4516.006478574708;4518.593326600621;4594.047529799822;4900.975495122501;4924.704517728947;5289.9471005290225;5531.2809334787025;5695.345035320991;5711.742971566289;5144.768485418165;5298.046351656739;5311.893762124736;5315.438656100422;5318.73406329682;2394.4253379188867;2644.299260695917;2763.402401742356;2867.7562407195624;2874.238326843388;5019.824306149268;5221.55616772571;5662.687327913217;5677.489025987681;5716.742746576414;5187.377514698261;5589.469000445032;5629.943700563023;5633.4084921082695;5636.633618814764;4777.587318404184;5428.88707814897;5484.818805092957;5641.323041234996;5690.886182276331;5500.972495137498;5506.862328441821;5512.4211957743855;5524.558035357242;5528.778848846162;5194.844154675366;5421.810236641699;5424.728763561885;5428.8512662654775;5482.5888058394985;4588.518205588499;5682.715864206691;5707.229524014205;5717.7141142941855;5720.399358067343;3152.8423578820507;3321.555123521276;3699.7595156314756;3702.981874878144;3733.272011957642;4769.165396055746;4959.801607935642;5074.771635276347;5271.551918087006;5544.667319960815;4803.471618121934;5330.520253177077;5330.707795647909;5332.213708407727;5350.6254562180275;5016.398032236155;5285.418603953485;5311.575073994149;5328.547073498796;5335.813246536352;4413.0953154602585;5017.344276388856;5129.1791414047475;5177.275181474522;5244.370675518962;5292.073887069824;5294.947050529599;5309.761060752198;5348.4116747157;5550.500454959073;4463.598276155161;4814.253790662453;5411.78352865881;5507.9449205507435;5575.498205161555 10:25:18 INFO - PROCESS | 1637 | |5;yui.html;602.4908951935614;681.8636272745449;713.1940906775343;735.2611074808077;738.5421038955848;14511.419543218271;15124.621884452894;15554.45559405421;15636.687266254668;15785.447509337175;11159.721006974829;14109.223992680398;14202.573922782307;14287.642808929782;14322.925780566447;840.4705035827451;841.4824882697159;851.2381418630338;882.6204731965253;883.8409086364452;700.1563116444697;721.7654262364726;860.9784755381135;884.663827745459;888.2449917570078;921.870938068671;926.6108234541475;929.5491686532026;930.2465003347277;931.920786733125;922.8431166701677;923.8475651517491;924.1497822003739;924.5562130177485;924.7965447601517;686.4028295382999;687.0381465947663;687.3538873458932;688.3082502085422;688.9621070841101;15368.154751488664;15687.294071766797;15688.921555392264;15837.287322070535;15841.287142078634;14171.503997360145;14287.285635718172;14397.424103035866;14475.203863787592;14525.636859078502;9671.806563868753;11391.088712902947;11750.529978800838;11806.822897656542;11856.644300670994;3838.8848334550007;3889.4360317753767;3944.960550394488;4017;4021.4369988201674;96260;97925;100421;100423;101036.97926041437;11240.662780116609;11314.981651651391;11338;11463.942680286546;11567.479463423997;20110.39668809938;21661.566768664852;22658.093676253095;23125.76874231236;23179.768202317762;8065.435419520694;8081.757547273592;8204.179582041868;8255.339572834278;8353.916460835435;4359.498657654347;4400.273954797422;4424.616270241039;4469.910601788011;4548.7725613719185;3505.4741788731894;3541.769784963969;3550.538430004083;3594.370985077653;3608.332458495187;583.291301069203;632.6393955445352;658.0951192110848;673.9082686048534;674.2347435660561;560.6327855254815;631.8041407163702;650.3853858104046;685.4002747595854;689.1041645860362;9176.265898728087;9534.951155372903;9651.613935442503;9764.755881102887;9814.46020468881;653.8888388973791;660.2605082307846;671.8051764988196;700.5551474813428;712.6971037309123;4624.722516648944;4671.5094915034115;4689.132510485571;4696.412948381452;4707.646926480527;58443.70778146082;86880;126385.36807315904;128243;132809;69642.60714785778;70396.64801675959;111490.44254778675;112019.43990279996;114832.85167148389;74840;77130.22869771342;81833.59083204546;81904.59047704605;91060.54469727608;9117.452952822587;9986.201103911815;10157.085862272432;10218.642347518098;10244.692659220234;44165.116697665224;44759;57348.71325643345;59259;60203.698981504815;66712.66643666945;68476.31523684699;74541.13647158864;84388;87253.56373218093;12137.393130343624;12695.619131426072;12849.100562960502;13044.478220871059;13118.934405327913;14093.365798538885;14169.787453188417;14832.332545035715;15043.172625505702;15194.924025379802;13678.589642310746;13702.745722244568;13897.791533126809;14077.014608977273;14565.431469290092;13048;13556.322183890963;14262.71474570482;14417.207053612152;14658.560243192722;12737.363131843556;13740;13859.307034648025;13896.791548126585;14206.508316626605;10719.946400268262;11107.500162492546;11577.015953643699;11652.94173529127;12013.099017573892;11434.142439316918;11461.197716159788;11471.655850324503;11586.420678966186;11696.122790790554 10:25:18 INFO - PROCESS | 1637 | __end_tp_report 10:25:18 INFO - PROCESS | 1637 | __start_cc_report 10:25:18 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1361 10:25:18 INFO - PROCESS | 1637 | __end_cc_report 10:25:18 INFO - PROCESS | 1637 | __startTimestamp1486146318472__endTimestamp 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | ------- Summary: start ------- 10:25:18 INFO - PROCESS | 1637 | Number of tests: 6 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5728.22 Median:6029.91 stddev:668.07 (11.1%) stddev-sans-first:645.65 10:25:18 INFO - PROCESS | 1637 | Values: 3444.6 3475.8 3484.7 3504.6 3530.3 5687.8 5708.4 5714.1 5718.2 5753.9 4454.3 4735.5 5680.0 5743.8 5750.3 4691.8 4750.8 5191.3 5245.2 5352.2 5178.7 5190.5 5199.0 5200.2 5248.2 5299.5 5304.2 5310.8 5312.6 5349.7 5284.5 5288.7 5295.5 5297.1 5307.5 5066.1 5154.4 5208.7 5227.1 5245.3 5611.1 5636.6 5690.3 5721.7 5724.1 6172.9 6224.6 6253.5 6262.4 6275.6 6282.4 6288.0 6291.9 6292.0 6302.4 5734.2 5777.7 5791.1 5836.8 5853.9 6235.5 6269.9 6275.1 6277.0 6292.4 6177.8 6178.6 6182.1 6183.9 6198.8 6113.0 6142.2 6198.3 6258.8 6280.3 5133.5 6249.1 6263.0 6268.2 6270.0 6164.8 6273.7 6277.8 6278.1 6289.4 4915.7 4976.3 4976.4 4986.2 5011.0 4432.5 4439.7 4445.1 4467.9 4496.9 4987.9 5829.4 5846.0 5858.0 5871.5 6222.8 6255.4 6256.8 6264.1 6273.4 5613.5 5662.7 5670.7 5771.6 5833.3 5451.3 5491.5 5506.9 5510.4 5520.8 6350.7 6381.6 6383.8 6390.4 6470.5 6125.2 6163.6 6192.3 6194.2 6268.8 6192.1 6244.7 6248.9 6271.3 6274.1 6185.6 6206.2 6235.4 6287.4 6298.6 6244.2 6275.9 6280.5 6297.2 6298.2 6127.2 6186.1 6194.0 6195.2 6201.6 6014.1 6084.1 6112.0 6120.9 6125.1 4147.6 4590.6 6055.1 6107.4 6126.8 6149.4 6178.1 6190.8 6197.5 6207.6 4939.7 5884.3 5979.1 6029.9 6065.1 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:70374.77 Median:39305.21 stddev:156560.27 (398.3%) stddev-sans-first:156911.52 10:25:18 INFO - PROCESS | 1637 | Values: 485.3 487.8 492.7 518.0 519.9 29421.4 40536.0 41695.6 41788.2 41831.8 43166.0 43503.1 43521.6 43535.0 43565.3 9663.7 9861.7 9863.5 9868.6 9874.8 9642.5 9752.6 9753.0 9757.9 9767.1 9713.0 9773.7 9774.3 9782.6 9787.9 9703.3 9760.0 9765.3 9767.3 9790.2 9653.9 9740.0 9755.2 9757.8 9766.2 40725.6 41165.0 41240.8 41281.8 41358.8 3384.4 3385.1 3881.2 3886.5 4081.1 30478.2 30613.5 30966.4 31442.0 31897.5 3112.4 3183.2 3189.6 3244.4 3285.4 508168.0 516410.0 517080.0 517388.0 518783.0 47001.8 58452.0 58736.1 58961.8 58986.4 35630.6 35827.0 35860.7 36763.8 60393.4 14226.6 16967.5 17150.3 17162.8 17194.7 12988.9 13093.3 13094.9 13097.7 13099.4 15653.0 18874.0 18931.1 18965.2 19007.7 7749.8 7867.6 7877.0 7916.5 7922.8 883.8 1004.8 1031.5 1051.2 1128.9 29790.6 29799.6 30092.7 30230.1 30636.2 847.8 1052.6 1055.5 1102.3 1135.8 14601.4 15380.4 15790.1 16070.4 16460.0 684339.0 688268.0 688966.0 689870.0 690370.0 57837.7 58442.8 58480.7 58575.4 58607.7 1128.9 1319.6 1358.8 1376.1 1409.3 29045.1 29840.4 30014.5 30125.8 30877.5 48650.5 48996.3 49078.5 49085.8 49179.8 532092.3 534706.0 536998.3 537253.0 537696.0 23823.2 24398.9 25966.1 26378.8 26413.5 44535.1 45296.5 45948.8 46024.0 46055.5 37515.1 37945.6 38198.2 38367.6 39563.0 37927.9 38274.6 38278.8 38308.6 39047.4 37223.8 38085.8 38677.2 38752.4 38760.0 28965.7 32161.7 32402.2 34430.0 34730.0 21520.9 24600.1 25367.6 26006.0 26122.6 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:36355.56 Median:2457.64 stddev:104493.87 (4251.8%) stddev-sans-first:104752.09 10:25:18 INFO - PROCESS | 1637 | Values: 380.7 383.1 391.9 399.6 416.4 19379.9 20806.9 20825.7 21119.5 21333.5 20191.7 20230.8 20791.4 20865.6 21245.9 5803.1 5859.5 5975.9 6018.8 6037.0 4302.0 4526.9 4527.1 5929.6 6040.4 6276.4 6556.6 6573.9 6652.9 6671.2 6475.4 6537.3 6586.0 6629.3 6652.2 5633.8 5743.2 5799.0 5808.7 5814.0 25986.6 26116.9 26121.8 26159.9 26354.7 2271.4 2421.4 2493.9 2568.6 2630.0 15405.0 15472.7 15575.7 16004.4 16480.7 1904.4 1907.5 1914.9 1972.8 1981.3 114171.0 114963.0 120373.4 122930.4 126137.0 10710.7 10724.2 11221.2 11242.3 11384.6 14139.2 14179.3 14182.9 14199.6 14216.3 8552.7 8753.9 8772.9 8894.4 8989.8 8869.4 8889.1 8912.0 9078.3 9104.8 6657.6 6731.6 6752.8 6753.7 6758.8 1611.7 1620.5 1827.5 1867.8 1874.5 811.7 965.7 998.1 998.7 1040.8 20445.7 22216.4 22367.9 22436.6 22523.7 941.9 981.5 996.2 1024.3 1029.5 9625.0 11051.3 11088.2 11804.4 12104.9 616586.0 620323.0 621686.0 635748.0 637843.8 122825.4 134911.0 135739.0 148019.0 149140.0 894.2 959.7 970.9 1005.0 1039.4 17448.2 18368.3 18969.1 19211.0 19242.5 50607.7 52017.7 53512.7 55982.0 56513.4 59484.0 72759.0 74947.6 75403.2 79234.0 5770.0 6004.5 8099.0 8144.3 8244.6 11722.6 12716.0 12797.8 13205.5 13288.4 11047.0 11223.8 11485.9 11637.8 11643.5 11001.7 11137.7 11246.2 11549.5 11754.9 11354.3 11573.5 11613.9 11687.5 12127.4 8242.3 8356.4 8402.4 8428.6 8456.4 11243.9 11271.7 11293.8 11650.9 11747.8 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5537.72 Median:5209.31 stddev:3063.05 (58.8%) stddev-sans-first:3053.24 10:25:18 INFO - PROCESS | 1637 | Values: 1071.8 1079.2 1297.4 1384.7 1777.5 7643.3 8079.2 8314.5 8534.7 8543.2 8454.7 8543.2 8544.0 8552.6 8593.8 2369.4 2410.0 2412.4 2412.8 2417.0 2128.9 2177.4 2273.8 2345.6 2433.9 2001.0 2372.4 2374.2 2393.3 2406.2 2346.2 2357.2 2367.4 2370.6 2375.3 2386.8 2392.9 2416.5 2417.3 2420.9 8329.0 8419.4 8613.1 8613.7 8616.5 5180.4 5410.1 5412.6 5419.6 5419.6 3063.4 3394.4 3541.5 3616.1 3659.8 1069.2 1075.8 1079.0 1093.7 1117.3 11104.4 11156.2 11216.2 11224.3 11241.5 4350.8 4483.1 4595.9 4601.9 4609.3 8871.7 8887.0 8889.0 8895.5 8899.6 5238.2 5239.4 5239.8 5245.3 5256.0 4213.8 4457.4 4550.9 4587.2 4961.1 3745.5 3792.0 3792.9 3899.3 3901.7 1146.8 1149.6 1150.4 1159.0 1165.1 814.5 893.5 907.9 912.3 928.2 5505.2 5602.6 5712.8 5798.2 5809.6 923.8 931.0 961.6 963.7 1004.5 3774.2 3817.6 3869.9 3928.5 3929.6 9989.1 10473.5 10496.9 10497.9 10508.2 10433.7 10443.7 10458.0 10462.6 10469.3 7649.7 7983.1 7990.1 7991.5 8144.3 5769.3 5783.0 5784.5 5829.8 5877.1 9235.9 9438.9 9654.3 9671.9 9725.0 9034.0 9317.0 9766.3 9828.9 9963.0 7160.5 7288.6 7307.7 7313.9 7403.9 8155.9 8162.3 8239.7 8254.8 8272.9 7219.1 7310.7 7390.0 7394.0 7446.3 7148.9 7202.4 7229.4 7275.7 7281.3 6508.4 6624.2 6686.8 6769.2 6777.2 4293.1 4878.9 4909.8 4940.0 5017.5 4899.9 4962.2 4999.7 5057.5 5092.5 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4411.03 Median:5018.58 stddev:1366.99 (27.2%) stddev-sans-first:1335.67 10:25:18 INFO - PROCESS | 1637 | Values: 306.7 311.9 314.8 318.0 320.9 5326.3 5328.3 5353.4 5370.0 5408.2 4945.6 5307.5 5330.8 5339.2 5349.9 3435.3 3889.1 3906.4 4084.7 4126.7 3521.7 4036.9 4065.4 4088.6 4110.6 3286.9 4006.3 4025.0 4030.8 4068.6 3004.1 3120.1 3415.1 3665.5 4038.5 3330.5 3360.2 3537.8 3542.9 3545.0 4268.4 4717.2 5211.5 5222.9 5242.5 4225.6 4271.5 4647.4 4673.1 4679.4 4659.0 5452.9 5496.6 5518.9 5618.2 3262.4 3835.7 3851.8 3855.4 3878.9 4436.6 5786.7 5803.4 5809.5 5825.0 3793.3 3866.4 3880.0 3884.8 3888.3 3039.3 3706.8 3949.9 3956.0 3958.5 4525.7 4581.9 4584.2 4608.3 4608.6 5165.8 5509.5 5569.3 5642.9 5658.5 1088.2 1144.5 1191.9 1276.0 1283.2 831.7 855.6 873.8 881.2 881.9 4410.9 4516.0 4518.6 4594.0 4901.0 4924.7 5289.9 5531.3 5695.3 5711.7 5144.8 5298.0 5311.9 5315.4 5318.7 2394.4 2644.3 2763.4 2867.8 2874.2 5019.8 5221.6 5662.7 5677.5 5716.7 5187.4 5589.5 5629.9 5633.4 5636.6 4777.6 5428.9 5484.8 5641.3 5690.9 5501.0 5506.9 5512.4 5524.6 5528.8 5194.8 5421.8 5424.7 5428.9 5482.6 4588.5 5682.7 5707.2 5717.7 5720.4 3152.8 3321.6 3699.8 3703.0 3733.3 4769.2 4959.8 5074.8 5271.6 5544.7 4803.5 5330.5 5330.7 5332.2 5350.6 5016.4 5285.4 5311.6 5328.5 5335.8 4413.1 5017.3 5129.2 5177.3 5244.4 5292.1 5294.9 5309.8 5348.4 5550.5 4463.6 4814.3 5411.8 5507.9 5575.5 10:25:18 INFO - PROCESS | 1637 | 10:25:18 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:21091.65 Median:4190.47 stddev:30917.25 (737.8%) stddev-sans-first:30965.71 10:25:18 INFO - PROCESS | 1637 | Values: 602.5 681.9 713.2 735.3 738.5 14511.4 15124.6 15554.5 15636.7 15785.4 11159.7 14109.2 14202.6 14287.6 14322.9 840.5 841.5 851.2 882.6 883.8 700.2 721.8 861.0 884.7 888.2 921.9 926.6 929.5 930.2 931.9 922.8 923.8 924.1 924.6 924.8 686.4 687.0 687.4 688.3 689.0 15368.2 15687.3 15688.9 15837.3 15841.3 14171.5 14287.3 14397.4 14475.2 14525.6 9671.8 11391.1 11750.5 11806.8 11856.6 3838.9 3889.4 3945.0 4017.0 4021.4 96260.0 97925.0 100421.0 100423.0 101037.0 11240.7 11315.0 11338.0 11463.9 11567.5 20110.4 21661.6 22658.1 23125.8 23179.8 8065.4 8081.8 8204.2 8255.3 8353.9 4359.5 4400.3 4424.6 4469.9 4548.8 3505.5 3541.8 3550.5 3594.4 3608.3 583.3 632.6 658.1 673.9 674.2 560.6 631.8 650.4 685.4 689.1 9176.3 9535.0 9651.6 9764.8 9814.5 653.9 660.3 671.8 700.6 712.7 4624.7 4671.5 4689.1 4696.4 4707.6 58443.7 86880.0 126385.4 128243.0 132809.0 69642.6 70396.6 111490.4 112019.4 114832.9 74840.0 77130.2 81833.6 81904.6 91060.5 9117.5 9986.2 10157.1 10218.6 10244.7 44165.1 44759.0 57348.7 59259.0 60203.7 66712.7 68476.3 74541.1 84388.0 87253.6 12137.4 12695.6 12849.1 13044.5 13118.9 14093.4 14169.8 14832.3 15043.2 15194.9 13678.6 13702.7 13897.8 14077.0 14565.4 13048.0 13556.3 14262.7 14417.2 14658.6 12737.4 13740.0 13859.3 13896.8 14206.5 10719.9 11107.5 11577.0 11652.9 12013.1 11434.1 11461.2 11471.7 11586.4 11696.1 10:25:18 INFO - PROCESS | 1637 | -------- Summary: end -------- 10:25:18 INFO - PROCESS | 1637 | 10:25:19 INFO - TEST-INFO | 1637: exit 0 10:25:19 INFO - TEST-OK | dromaeo_css | took 1117624ms 10:25:19 INFO - TEST-START | kraken 10:25:19 INFO - operating with platform_type : mac_ 10:25:19 INFO - Initialising browser for kraken test... 10:25:19 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp5xckLK/profile http://localhost:49235/getInfo.html) 10:25:21 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:25:21 INFO - PROCESS | 1647 | colorDepth:24 10:25:21 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:25:21 INFO - PROCESS | 1647 | __metrics 10:25:21 INFO - PROCESS | 1647 | 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:25:21 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:25:22 INFO - TEST-INFO | 1647: exit 0 10:25:22 INFO - Browser initialized. 10:25:22 INFO - Running cycle 1/1 for kraken test... 10:25:22 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp5xckLK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:25:34 INFO - PROCESS | 1649 | RSS: Main: 167993344 10:25:34 INFO - PROCESS | 1649 | 10:25:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 10:25:37 INFO - PROCESS | 1649 | RSS: Main: 218398720 10:25:37 INFO - PROCESS | 1649 | 10:25:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 10:25:41 INFO - PROCESS | 1649 | RSS: Main: 232136704 10:25:41 INFO - PROCESS | 1649 | 10:25:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 10:25:44 INFO - PROCESS | 1649 | RSS: Main: 329842688 10:25:44 INFO - PROCESS | 1649 | 10:25:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 10:25:47 INFO - PROCESS | 1649 | RSS: Main: 232423424 10:25:47 INFO - PROCESS | 1649 | 10:25:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:50 INFO - PROCESS | 1649 | RSS: Main: 234041344 10:25:50 INFO - PROCESS | 1649 | 10:25:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:53 INFO - PROCESS | 1649 | RSS: Main: 274497536 10:25:53 INFO - PROCESS | 1649 | 10:25:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:57 INFO - PROCESS | 1649 | RSS: Main: 273002496 10:25:57 INFO - PROCESS | 1649 | 10:25:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 10:26:00 INFO - PROCESS | 1649 | RSS: Main: 272146432 10:26:00 INFO - PROCESS | 1649 | 10:26:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:26:02 INFO - PROCESS | 1649 | RSS: Main: 248705024 10:26:02 INFO - PROCESS | 1649 | 10:26:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:26:05 INFO - PROCESS | 1649 | RSS: Main: 251351040 10:26:05 INFO - PROCESS | 1649 | 10:26:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:26:07 INFO - PROCESS | 1649 | RSS: Main: 255041536 10:26:07 INFO - PROCESS | 1649 | 10:26:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:26:11 INFO - PROCESS | 1649 | RSS: Main: 270675968 10:26:11 INFO - PROCESS | 1649 | 10:26:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:26:14 INFO - PROCESS | 1649 | RSS: Main: 263819264 10:26:14 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 10:26:16 INFO - PROCESS | 1649 | RSS: Main: 307662848 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | __start_tp_report 10:26:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:26:16 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:26:16 INFO - PROCESS | 1649 | |i|pagename|runs| 10:26:16 INFO - PROCESS | 1649 | |0;ai-astar;101;113;111;110;110;113;112;109;112;112 10:26:16 INFO - PROCESS | 1649 | |1;audio-beat-detection;179;136;136;141;106;102;105;96;133;106 10:26:16 INFO - PROCESS | 1649 | |2;audio-dft;181;184;182;183;181;183;181;181;182;191 10:26:16 INFO - PROCESS | 1649 | |3;audio-fft;78;81;81;114;72;59;59;60;105;81 10:26:16 INFO - PROCESS | 1649 | |4;audio-oscillator;87;70;92;69;66;68;88;67;69;90 10:26:16 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;85;88;100;134;84;88;85;88;85 10:26:16 INFO - PROCESS | 1649 | |6;imaging-darkroom;96;98;97;98;97;97;115;159;106;97 10:26:16 INFO - PROCESS | 1649 | |7;imaging-desaturate;82;83;81;82;79;83;94;136;133;83 10:26:16 INFO - PROCESS | 1649 | |8;json-parse-financial;50;49;48;51;56;57;57;57;57;55 10:26:16 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;74;78;70;72;68;68;72;69;71 10:26:16 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;83;82;85;81;81;80;80;86;86;83 10:26:16 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;138;132;138;138;142;145;152;139;183;141 10:26:16 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;163;165;137;193;226;164;163;159;165;160 10:26:16 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;65;68;75;82;82;74;65;65;65 10:26:16 INFO - PROCESS | 1649 | __end_tp_report 10:26:16 INFO - PROCESS | 1649 | __start_cc_report 10:26:16 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1902 10:26:16 INFO - PROCESS | 1649 | __end_cc_report 10:26:16 INFO - PROCESS | 1649 | __startTimestamp1486146376915__endTimestamp 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:26:16 INFO - PROCESS | 1649 | Number of tests: 14 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:110.30 Median:112.00 stddev:3.53 (3.2%) stddev-sans-first:1.41 10:26:16 INFO - PROCESS | 1649 | Values: 101.0 113.0 111.0 110.0 110.0 113.0 112.0 109.0 112.0 112.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:136.00 stddev:25.73 (18.9%) stddev-sans-first:18.02 10:26:16 INFO - PROCESS | 1649 | Values: 179.0 136.0 136.0 141.0 106.0 102.0 105.0 96.0 133.0 106.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:182.90 Median:182.50 stddev:3.03 (1.7%) stddev-sans-first:3.14 10:26:16 INFO - PROCESS | 1649 | Values: 181.0 184.0 182.0 183.0 181.0 183.0 181.0 181.0 182.0 191.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:79.00 Median:75.00 stddev:18.63 (24.8%) stddev-sans-first:19.76 10:26:16 INFO - PROCESS | 1649 | Values: 78.0 81.0 81.0 114.0 72.0 59.0 59.0 60.0 105.0 81.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:78.50 stddev:11.02 (14.0%) stddev-sans-first:11.02 10:26:16 INFO - PROCESS | 1649 | Values: 87.0 70.0 92.0 69.0 66.0 68.0 88.0 67.0 69.0 90.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:86.50 stddev:15.25 (17.6%) stddev-sans-first:16.12 10:26:16 INFO - PROCESS | 1649 | Values: 89.0 85.0 88.0 100.0 134.0 84.0 88.0 85.0 88.0 85.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:97.00 stddev:19.56 (20.2%) stddev-sans-first:20.40 10:26:16 INFO - PROCESS | 1649 | Values: 96.0 98.0 97.0 98.0 97.0 97.0 115.0 159.0 106.0 97.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:82.50 stddev:21.93 (26.6%) stddev-sans-first:22.86 10:26:16 INFO - PROCESS | 1649 | Values: 82.0 83.0 81.0 82.0 79.0 83.0 94.0 136.0 133.0 83.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:56.50 stddev:3.74 (6.6%) stddev-sans-first:3.72 10:26:16 INFO - PROCESS | 1649 | Values: 50.0 49.0 48.0 51.0 56.0 57.0 57.0 57.0 57.0 55.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:3.62 (5.1%) stddev-sans-first:3.20 10:26:16 INFO - PROCESS | 1649 | Values: 65.0 74.0 78.0 70.0 72.0 68.0 68.0 72.0 69.0 71.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:2.31 (2.8%) stddev-sans-first:2.45 10:26:16 INFO - PROCESS | 1649 | Values: 83.0 82.0 85.0 81.0 81.0 80.0 80.0 86.0 86.0 83.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:141.50 stddev:14.41 (10.2%) stddev-sans-first:15.08 10:26:16 INFO - PROCESS | 1649 | Values: 138.0 132.0 138.0 138.0 142.0 145.0 152.0 139.0 183.0 141.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:164.50 stddev:23.94 (14.6%) stddev-sans-first:25.27 10:26:16 INFO - PROCESS | 1649 | Values: 163.0 165.0 137.0 193.0 226.0 164.0 163.0 159.0 165.0 160.0 10:26:16 INFO - PROCESS | 1649 | 10:26:16 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:71.00 stddev:6.90 (9.7%) stddev-sans-first:7.24 10:26:16 INFO - PROCESS | 1649 | Values: 68.0 65.0 68.0 75.0 82.0 82.0 74.0 65.0 65.0 65.0 10:26:16 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:26:16 INFO - PROCESS | 1649 | 10:26:17 INFO - TEST-INFO | 1649: exit 0 10:26:17 INFO - TEST-OK | kraken | took 58892ms 10:26:17 INFO - SUITE-END | took 1176s 10:26:18 INFO - Completed test suite (00:19:36) 10:26:18 INFO - javascript benchmark 10:26:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3444.586649602047, 3475.7566970312087, 3484.738644601655, 3504.56192975878, 3530.329237444887, 5687.829365119048, 5708.37207907131, 5714.142878568212, 5718.228039214705, 5753.884922301557, 4454.33185022246, 4735.50277220892, 5680.034394152994, 5743.798967036155, 5750.252467179272, 4691.765411729409, 4750.833720819772, 5191.273221748958, 5245.2132180173085, 5352.170413585901, 5178.663386879841, 5190.4809519047985, 5198.974005129969, 5200.245964335169, 5248.186531087687, 5299.470052994689, 5304.230886521452, 5310.787568497255, 5312.57499400049, 5349.652272602288, 5284.471552844704, 5288.65623734456, 5295.523402893719, 5297.12597421425, 5307.52232299095, 5066.138756411419, 5154.433012368637, 5208.713520756357, 5227.111391063529, 5245.344331958505, 5611.130274807411, 5636.577256705723, 5690.2887139107615, 5721.656700597978, 5724.084146536534, 6172.907406388863, 6224.564280500367, 6253.530985176085, 6262.436380725757, 6275.560710750249, 6282.371762823726, 6287.96856015717, 6291.905621415683, 6291.968540157316, 6302.432781049728, 5734.197212390227, 5777.653340799566, 5791.131330300489, 5836.824895253149, 5853.853653658693, 6235.532335074888, 6269.905951410732, 6275.090111933744, 6277.0, 6292.402221788922, 6177.752889884444, 6178.62928224308, 6182.103594978703, 6183.876322473615, 6198.845028874314, 6112.969435152796, 6142.235993802807, 6198.256209254918, 6258.812235632939, 6280.340564240781, 5133.512316329943, 6249.125122482856, 6262.966610509215, 6268.153799237045, 6270.028145637433, 6164.784232551929, 6273.749050038039, 6277.78027769035, 6278.121063051177, 6289.433950944437, 4915.680480768739, 4976.303317535548, 4976.353074100344, 4986.152354099811, 5010.974945125251, 4432.534583868712, 4439.733615983051, 4445.066536027406, 4467.865964021084, 4496.887577810581, 4987.900241995139, 5829.358770535237, 5846.006178949504, 5858.0, 5871.4715675589405, 6222.751089956351, 6255.374462553708, 6256.7810126645345, 6264.123022776815, 6273.435390814849, 5613.4947854692455, 5662.66024038559, 5670.744816483269, 5771.567132465082, 5833.329167145747, 5451.3458384994055, 5491.478309560585, 5506.862328441741, 5510.421405752419, 5520.834374968757, 6350.682465876687, 6381.617102973929, 6383.776567820104, 6390.392912673288, 6470.48236141117, 6125.173101631222, 6163.568550201443, 6192.287886892885, 6194.163787888758, 6268.843278917881, 6192.133101365903, 6244.687765611792, 6248.937510625017, 6271.310155882758, 6274.058891166179, 6185.597936134317, 6206.224221972254, 6235.376462353728, 6287.434130928238, 6298.590591611531, 6244.1570387997035, 6275.937240627535, 6280.466160376236, 6297.1813664223355, 6298.1812364392335, 6127.20346354971, 6186.072089186658, 6194.008958566607, 6195.1636529068, 6201.56589038781, 6014.127951447103, 6084.056971169476, 6111.95640283616, 6120.938790612215, 6125.111858780541, 4147.605977432139, 4590.586847183769, 6055.091736239423, 6107.389261073857, 6126.785562505291, 6149.41580549847, 6178.073289006506, 6190.845228869134, 6197.535184861244, 6207.6275423474735, 4939.6542242042715, 5884.293884733859, 5979.073243647242, 6029.909551356821, 6065.090236464565], "value": 5688.480798758789, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.3302442629169, 487.8462436338062, 492.72653677209144, 518.0002594991664, 519.898619769145, 29421.411571768578, 40535.986600335, 41695.58304416959, 41788.164236715325, 41831.79084104575, 43166.0, 43503.12993740131, 43521.564784352144, 43535.0, 43565.34651980215, 9663.66177183797, 9861.654842080528, 9863.457509836957, 9868.555914983843, 9874.802503949917, 9642.517874106301, 9752.561134748954, 9753.0, 9757.902420975806, 9767.120959113678, 9712.951435242849, 9773.706788796348, 9774.266929980244, 9782.608695652165, 9787.902120978806, 9703.320767546275, 9760.023997600254, 9765.316427850054, 9767.267454940908, 9790.167835733972, 9653.855192172123, 9739.977302383219, 9755.219582433388, 9757.804843903154, 9766.218702503782, 40725.59274407248, 41164.970875728344, 41240.793796031125, 41281.793591032154, 41358.759237222934, 3384.4077286474767, 3385.119868834121, 3881.243157584272, 3886.5336159661024, 4081.0613558881328, 30478.238044048856, 30613.540796888065, 30966.380672386425, 31442.0, 31897.52153717696, 3112.393083348748, 3183.220111072802, 3189.617245930503, 3244.3997860395666, 3285.4250506161566, 508168.0, 516410.0, 517080.0, 517388.0, 518783.0, 47001.76499117551, 58452.0, 58736.11895821566, 58961.82076358535, 58986.41013589895, 35630.643693562735, 35827.0, 35860.74487392981, 36763.816180918926, 60393.396066039655, 14226.573202803933, 16967.49097527076, 17150.31398744061, 17162.828371716372, 17194.656106877792, 12988.870111298766, 13093.345332733325, 13094.869051309555, 13097.738045239043, 13099.41052652633, 15653.0, 18874.0, 18931.14809833561, 18965.24139034451, 19007.714884276746, 7749.7675069748, 7867.6066196689935, 7877.0, 7916.525008499509, 7922.841543169219, 883.8497455432592, 1004.7638804880847, 1031.4945676618554, 1051.1905832508926, 1128.920975531705, 29790.55314170289, 29799.55300670492, 30092.69907300899, 30230.09309720712, 30636.234094147825, 847.8139360034099, 1052.6368402900987, 1055.488088277185, 1102.2614848051824, 1135.8296255561731, 14601.415943362359, 15380.384784608714, 15790.131542765259, 16070.357185712674, 16460.012399256084, 684339.0, 688268.0, 688966.0, 689870.0, 690370.0, 57837.71081144567, 58442.83114337774, 58480.70759646175, 58575.414245857, 58607.706961465774, 1128.8927551882557, 1319.6305034590523, 1358.7554240236655, 1376.105531404595, 1409.2953523238382, 29045.12864614065, 29840.403191935606, 30014.54978175373, 30125.849370753007, 30877.536836947016, 48650.51349486601, 48996.34534719196, 49078.5092149074, 49085.75457122691, 49179.754101229264, 532092.3395382998, 534706.0, 536998.3150084225, 537253.0, 537696.0, 23823.166189183296, 24398.87800560986, 25966.09118680837, 26378.812953417528, 26413.471730565667, 44535.109297813215, 45296.54703453055, 45948.770256148506, 46023.9624383318, 46055.53944460512, 37515.06212344713, 37945.62054379531, 38198.2360352797, 38367.61632383752, 39563.0, 37927.86216413512, 38274.61725382822, 38278.808605956794, 38308.616913830505, 39047.41428878626, 37223.81388093042, 38085.80957095197, 38677.2264554713, 38752.41871371988, 38760.0, 28965.710342897146, 32161.678383216804, 32402.189945251557, 34430.0, 34730.0, 21520.923953801936, 24600.138995135083, 25367.61948570736, 26005.959761609323, 26122.608160877222], "value": 19787.07617031798, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.66311314486677, 383.11882669859335, 391.8804764546813, 399.6003996003996, 416.4107787480715, 19379.903100484495, 20806.89596552019, 20825.65370495243, 21119.47201319966, 21333.466663333424, 20191.697124543145, 20230.797692023076, 20791.376258712226, 20865.58268834626, 21245.93770311486, 5803.129530570406, 5859.501942334896, 5975.880482390349, 6018.759249630009, 6037.0, 4301.956980430203, 4526.864194074166, 4527.09458108378, 5929.584929054968, 6040.426159514839, 6276.37236276371, 6556.573822701533, 6573.934260657404, 6652.900206496906, 6671.199456065255, 6475.417212450879, 6537.346265373449, 6586.012098185287, 6629.33706629338, 6652.201735791736, 5633.80281690143, 5743.195952566644, 5799.043157878945, 5808.664007278349, 5814.0, 25986.610200847, 26116.8694156528, 26121.82451789674, 26159.869200654066, 26354.73645263561, 2271.4094335472896, 2421.3946513371657, 2493.887775050127, 2568.6275490053836, 2629.9605505917425, 15404.998675086084, 15472.690546189013, 15575.688486230325, 16004.439844605384, 16480.670386592203, 1904.4286713985748, 1907.4659095453296, 1914.8946807925558, 1972.763268407786, 1981.2768339556103, 114171.0, 114963.0, 120373.39813301053, 122930.38534807268, 126137.0, 10710.73223169427, 10724.249302548747, 11221.158413119048, 11242.301847556528, 11384.601538946097, 14139.151650900978, 14179.291035448186, 14182.929085354508, 14199.574012779427, 14216.289185540682, 8552.657893684307, 8753.868691969625, 8772.912270877338, 8894.42186257891, 8989.775255618531, 8869.423487473294, 8889.066648001866, 8911.955440222757, 9078.319126065571, 9104.81790364189, 6657.567258128303, 6731.562448440835, 6752.797416077526, 6753.696083676246, 6758.83102922421, 1611.7018351604756, 1620.5462470508276, 1827.45176447068, 1867.794542600313, 1874.4564076417687, 811.6712731343815, 965.6764983730388, 998.1316254858319, 998.7103739915343, 1040.78143589847, 20445.693314600292, 22216.444588885082, 22367.888160559418, 22436.55126897453, 22523.662145067836, 941.9293552983554, 981.5435822342645, 996.1681995533811, 1024.3239461955222, 1029.505837198134, 9624.951875240718, 11051.281666691639, 11088.168387370979, 11804.397872509218, 12104.87895121055, 616586.0, 620323.0, 621686.0, 635748.0, 637843.8107809431, 122825.38587307185, 134911.0, 135739.0, 148019.0, 149140.0, 894.2398960883197, 959.6737109382843, 970.8737864077625, 1004.9949750251202, 1039.4231201682987, 17448.21483033292, 18368.265269389074, 18969.14638841228, 19211.0, 19242.518937026685, 50607.74696126496, 52017.7399113002, 53512.73243633757, 55982.0, 56513.434865650815, 59484.0, 72759.0, 74947.62526187334, 75403.24596754182, 79234.0, 5769.9711501442225, 6004.519638428827, 8099.0, 8144.267015968591, 8244.628991695268, 11722.58970936013, 12716.0, 12797.81688416193, 13205.471781128646, 13288.402021908843, 11047.0, 11223.775524489301, 11485.885141148481, 11637.76724465523, 11643.47604357789, 11001.669949901516, 11137.663480382405, 11246.156538259827, 11549.538018479167, 11754.88245117538, 11354.318740875575, 11573.537058517903, 11613.941930290295, 11687.532498700297, 12127.393630318626, 8242.340612750846, 8356.415050946376, 8402.41183117176, 8428.620712068094, 8456.365772567206, 11243.887561124284, 11271.718207044889, 11293.830592540955, 11650.88349116498, 11747.823782643096], "value": 9952.570579761448, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1071.8392241163824, 1079.1852151625521, 1297.4161627267729, 1384.6746014686553, 1777.5200695812123, 7643.312101910827, 8079.232472915079, 8314.501129932192, 8534.658613655462, 8543.231109200186, 8454.704085357014, 8543.231109200171, 8544.017437994633, 8552.572371381437, 8593.785155371119, 2369.360272726367, 2410.0, 2412.445137618349, 2412.8069754419603, 2416.9879150604224, 2128.8716980000545, 2177.42298290953, 2273.795358417742, 2345.6247000479925, 2433.8783060846886, 2000.9799902001012, 2372.383180373098, 2374.2283757778705, 2393.3418309964727, 2406.217979156781, 2346.23747282134, 2357.1514254868234, 2367.419982104378, 2370.573296806574, 2375.2636682628354, 2386.844855084414, 2392.916247931314, 2416.5287768885073, 2417.2506522977933, 2420.9031638734427, 8329.0, 8419.368547358972, 8613.052564217931, 8613.741587752378, 8616.48301101936, 5180.4042535108565, 5410.053240682865, 5412.5940554458175, 5419.59353048519, 5419.593530485229, 3063.391719347338, 3394.4229480988292, 3541.5041894134843, 3616.1140520572367, 3659.835307411173, 1069.2354966199127, 1075.8063548561256, 1078.9676309710721, 1093.6992327110029, 1117.3016864459712, 11104.389258590774, 11156.16328775345, 11216.158788090845, 11224.326540407601, 11241.494132763963, 4350.847720329773, 4483.103379324083, 4595.931061034087, 4601.861944141681, 4609.262517997171, 8871.733847984571, 8887.0, 8889.0, 8895.4662720237, 8899.599518021576, 5238.214267859776, 5239.449857764956, 5239.816606418757, 5245.291885595396, 5256.0, 4213.810378532973, 4457.4205353304515, 4550.862284428893, 4587.1513769952, 4961.131801934719, 3745.5318085239346, 3792.0, 3792.943105853414, 3899.2786334528205, 3901.668358189529, 1146.8337091121741, 1149.6263714292888, 1150.440270398357, 1158.9942050289694, 1165.114512970132, 814.4583851738646, 893.5442924108621, 907.9228265597366, 912.2519533982071, 928.1924725488733, 5505.201745746845, 5602.551795856321, 5712.7714891403875, 5798.188253644493, 5809.593328467051, 923.8152369526122, 930.9813803724023, 961.6105477281573, 963.7494251494662, 1004.4525733475274, 3774.226283611853, 3817.599152089046, 3869.903252418656, 3928.4500169976227, 3929.5677475477673, 9989.051040151175, 10473.528691208914, 10496.947515262375, 10497.947510262553, 10508.159347252202, 10433.739156521147, 10443.68668939933, 10458.0, 10462.581496740197, 10469.31949423285, 7649.732259371007, 7983.12185659577, 7990.12108668046, 7991.480553763987, 8144.30773384269, 5769.307683078057, 5782.971085144547, 5784.4504772046585, 5829.76680932758, 5877.089051197071, 9235.907640923773, 9438.85841712361, 9654.275929305471, 9671.9032809671, 9725.027497250217, 9033.954830225806, 9317.021712720067, 9766.316357854881, 9828.852567211641, 9963.00369963027, 7160.534565253241, 7288.562686238843, 7307.67115479794, 7313.926860731537, 7403.851922961403, 8155.9184408157535, 8162.347012239125, 8239.670413183645, 8254.752357429286, 8272.875906861282, 7219.133703955559, 7310.6710198040155, 7389.963050184715, 7393.963030184815, 7446.292602202782, 7148.857022859618, 7202.423806095605, 7229.421646268391, 7275.745348912763, 7281.308275713799, 6508.446782023581, 6624.238212605612, 6686.7993960181275, 6769.153855768029, 6777.152855893013, 4293.055527783882, 4878.902421951612, 4909.803607855646, 4939.950600494093, 5017.498250175099, 4899.902001959869, 4962.206047032601, 4999.65002449825, 5057.46896575869, 5092.541671249605], "value": 4460.946470615329, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.6963705931128, 311.9454095533281, 314.76549970272146, 317.9570757947678, 320.91335339458334, 5326.348831862126, 5328.333958255218, 5353.411124776281, 5370.0, 5408.215808707735, 4945.6043516518685, 5307.469253074691, 5330.840074797762, 5339.22581225722, 5349.946500534994, 3435.2823588205897, 3889.144388234591, 3906.4140378943102, 4084.7140700151003, 4126.690498212631, 3521.735869809762, 4036.8587099451524, 4065.3901914712733, 4088.6115818997296, 4110.609492098246, 3286.9317471821632, 4006.33736999145, 4024.9597504025023, 4030.8387664493384, 4068.572799856002, 3004.0987703688916, 3120.1435146088043, 3415.129142068781, 3665.4685070664605, 4038.5057528714415, 3330.5004249362546, 3360.235876165033, 3537.8231088445473, 3542.8937131886087, 3545.007397928584, 4268.374949406124, 4717.174494463455, 5211.452797456251, 5222.926117628256, 5242.501962313574, 4225.577442255781, 4271.466066741657, 4647.419072615923, 4673.065386922629, 4679.368285281512, 4659.0, 5452.854900470906, 5496.560275177976, 5518.862028449321, 5618.179637846263, 3262.4290749119045, 3835.7314987950854, 3851.807409629507, 3855.4024126260474, 3878.864239751595, 4436.600705936481, 5786.652800831964, 5803.361630220672, 5809.477147056743, 5825.0, 3793.336166170909, 3866.3620502616754, 3880.0, 3884.8446062157195, 3888.3389823730035, 3039.2705750620134, 3706.755764649515, 3949.9012524687114, 3956.0109972506875, 3958.5051868516434, 4525.705829121097, 4581.931271030937, 4584.220682483986, 4608.308753686973, 4608.608268297232, 5165.767540460688, 5509.531689806331, 5569.331680198321, 5642.887142257132, 5658.519025882846, 1088.1675518228562, 1144.48927415812, 1191.8569771627288, 1276.0493432392927, 1283.2108253424076, 831.7255305749088, 855.6021450025768, 873.7636244713786, 881.184903963831, 881.8765372847806, 4410.889727756832, 4516.006478574708, 4518.593326600621, 4594.047529799822, 4900.975495122501, 4924.704517728947, 5289.9471005290225, 5531.2809334787025, 5695.345035320991, 5711.742971566289, 5144.768485418165, 5298.046351656739, 5311.893762124736, 5315.438656100422, 5318.73406329682, 2394.4253379188867, 2644.299260695917, 2763.402401742356, 2867.7562407195624, 2874.238326843388, 5019.824306149268, 5221.55616772571, 5662.687327913217, 5677.489025987681, 5716.742746576414, 5187.377514698261, 5589.469000445032, 5629.943700563023, 5633.4084921082695, 5636.633618814764, 4777.587318404184, 5428.88707814897, 5484.818805092957, 5641.323041234996, 5690.886182276331, 5500.972495137498, 5506.862328441821, 5512.4211957743855, 5524.558035357242, 5528.778848846162, 5194.844154675366, 5421.810236641699, 5424.728763561885, 5428.8512662654775, 5482.5888058394985, 4588.518205588499, 5682.715864206691, 5707.229524014205, 5717.7141142941855, 5720.399358067343, 3152.8423578820507, 3321.555123521276, 3699.7595156314756, 3702.981874878144, 3733.272011957642, 4769.165396055746, 4959.801607935642, 5074.771635276347, 5271.551918087006, 5544.667319960815, 4803.471618121934, 5330.520253177077, 5330.707795647909, 5332.213708407727, 5350.6254562180275, 5016.398032236155, 5285.418603953485, 5311.575073994149, 5328.547073498796, 5335.813246536352, 4413.0953154602585, 5017.344276388856, 5129.1791414047475, 5177.275181474522, 5244.370675518962, 5292.073887069824, 5294.947050529599, 5309.761060752198, 5348.4116747157, 5550.500454959073, 4463.598276155161, 4814.253790662453, 5411.78352865881, 5507.9449205507435, 5575.498205161555], "value": 3975.951204457292, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.4908951935614, 681.8636272745449, 713.1940906775343, 735.2611074808077, 738.5421038955848, 14511.419543218271, 15124.621884452894, 15554.45559405421, 15636.687266254668, 15785.447509337175, 11159.721006974829, 14109.223992680398, 14202.573922782307, 14287.642808929782, 14322.925780566447, 840.4705035827451, 841.4824882697159, 851.2381418630338, 882.6204731965253, 883.8409086364452, 700.1563116444697, 721.7654262364726, 860.9784755381135, 884.663827745459, 888.2449917570078, 921.870938068671, 926.6108234541475, 929.5491686532026, 930.2465003347277, 931.920786733125, 922.8431166701677, 923.8475651517491, 924.1497822003739, 924.5562130177485, 924.7965447601517, 686.4028295382999, 687.0381465947663, 687.3538873458932, 688.3082502085422, 688.9621070841101, 15368.154751488664, 15687.294071766797, 15688.921555392264, 15837.287322070535, 15841.287142078634, 14171.503997360145, 14287.285635718172, 14397.424103035866, 14475.203863787592, 14525.636859078502, 9671.806563868753, 11391.088712902947, 11750.529978800838, 11806.822897656542, 11856.644300670994, 3838.8848334550007, 3889.4360317753767, 3944.960550394488, 4017.0, 4021.4369988201674, 96260.0, 97925.0, 100421.0, 100423.0, 101036.97926041437, 11240.662780116609, 11314.981651651391, 11338.0, 11463.942680286546, 11567.479463423997, 20110.39668809938, 21661.566768664852, 22658.093676253095, 23125.76874231236, 23179.768202317762, 8065.435419520694, 8081.757547273592, 8204.179582041868, 8255.339572834278, 8353.916460835435, 4359.498657654347, 4400.273954797422, 4424.616270241039, 4469.910601788011, 4548.7725613719185, 3505.4741788731894, 3541.769784963969, 3550.538430004083, 3594.370985077653, 3608.332458495187, 583.291301069203, 632.6393955445352, 658.0951192110848, 673.9082686048534, 674.2347435660561, 560.6327855254815, 631.8041407163702, 650.3853858104046, 685.4002747595854, 689.1041645860362, 9176.265898728087, 9534.951155372903, 9651.613935442503, 9764.755881102887, 9814.46020468881, 653.8888388973791, 660.2605082307846, 671.8051764988196, 700.5551474813428, 712.6971037309123, 4624.722516648944, 4671.5094915034115, 4689.132510485571, 4696.412948381452, 4707.646926480527, 58443.70778146082, 86880.0, 126385.36807315904, 128243.0, 132809.0, 69642.60714785778, 70396.64801675959, 111490.44254778675, 112019.43990279996, 114832.85167148389, 74840.0, 77130.22869771342, 81833.59083204546, 81904.59047704605, 91060.54469727608, 9117.452952822587, 9986.201103911815, 10157.085862272432, 10218.642347518098, 10244.692659220234, 44165.116697665224, 44759.0, 57348.71325643345, 59259.0, 60203.698981504815, 66712.66643666945, 68476.31523684699, 74541.13647158864, 84388.0, 87253.56373218093, 12137.393130343624, 12695.619131426072, 12849.100562960502, 13044.478220871059, 13118.934405327913, 14093.365798538885, 14169.787453188417, 14832.332545035715, 15043.172625505702, 15194.924025379802, 13678.589642310746, 13702.745722244568, 13897.791533126809, 14077.014608977273, 14565.431469290092, 13048.0, 13556.322183890963, 14262.71474570482, 14417.207053612152, 14658.560243192722, 12737.363131843556, 13740.0, 13859.307034648025, 13896.791548126585, 14206.508316626605, 10719.946400268262, 11107.500162492546, 11577.015953643699, 11652.94173529127, 12013.099017573892, 11434.142439316918, 11461.197716159788, 11471.655850324503, 11586.420678966186, 11696.122790790554], "value": 7613.5189621595055, "unit": "score"}], "name": "dromaeo_css", "value": 7299.658488779478, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 111.0, 110.0, 110.0, 113.0, 112.0, 109.0, 112.0, 112.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 136.0, 136.0, 141.0, 106.0, 102.0, 105.0, 96.0, 133.0, 106.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 182.0, 183.0, 181.0, 183.0, 181.0, 181.0, 182.0, 191.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 81.0, 114.0, 72.0, 59.0, 59.0, 60.0, 105.0, 81.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 92.0, 69.0, 66.0, 68.0, 88.0, 67.0, 69.0, 90.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 100.0, 134.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 98.0, 97.0, 97.0, 115.0, 159.0, 106.0, 97.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 81.0, 82.0, 79.0, 83.0, 94.0, 136.0, 133.0, 83.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 48.0, 51.0, 56.0, 57.0, 57.0, 57.0, 57.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 78.0, 70.0, 72.0, 68.0, 68.0, 72.0, 69.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 85.0, 81.0, 81.0, 80.0, 80.0, 86.0, 86.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 138.0, 138.0, 142.0, 145.0, 152.0, 139.0, 183.0, 141.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 137.0, 193.0, 226.0, 164.0, 163.0, 159.0, 165.0, 160.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 68.0, 75.0, 82.0, 82.0, 74.0, 65.0, 65.0, 65.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1457.3000000000002, "alertThreshold": 2.0}]} 10:26:18 INFO - Return code: 0 10:26:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:26:18 INFO - # TBPL SUCCESS # 10:26:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:26:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:26:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:26:18 INFO - 2017-02-03 10:26:18.262 screenresolution[1653:9822] starting screenresolution argv=screenresolution get 10:26:18 INFO - 2017-02-03 10:26:18.264 screenresolution[1653:9822] Display 0: 1600x1200x32@60 10:26:18 INFO - 2017-02-03 10:26:18.271 screenresolution[1654:9825] starting screenresolution argv=screenresolution list 10:26:18 INFO - Available Modes on Display 0 10:26:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:26:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:26:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:26:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:26:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:26:18 INFO - Intel Iris: 10:26:18 INFO - Chipset Model: Intel Iris 10:26:18 INFO - Type: GPU 10:26:18 INFO - Bus: Built-In 10:26:18 INFO - VRAM (Dynamic, Max): 1536 MB 10:26:18 INFO - Vendor: Intel (0x8086) 10:26:18 INFO - Device ID: 0x0a2e 10:26:18 INFO - Revision ID: 0x0009 10:26:18 INFO - Displays: 10:26:18 INFO - Display: 10:26:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:26:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:26:18 INFO - Display Serial Number: 1600x1200 60 10:26:18 INFO - Main Display: Yes 10:26:18 INFO - Mirror: Off 10:26:18 INFO - Online: Yes 10:26:18 INFO - Rotation: Supported 10:26:18 INFO - Return code: 0 10:26:18 INFO - Running post-action listener: _resource_record_post_action 10:26:18 INFO - [mozharness: 2017-02-03 18:26:18.512735Z] Finished run-tests step (success) 10:26:18 INFO - Running post-run listener: _resource_record_post_run 10:26:18 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 38175744; Write bytes: 500244480; Read time: 241; Write time: 1572 10:26:18 INFO - TinderboxPrint: CPU usage
53.4% 10:26:18 INFO - TinderboxPrint: I/O read bytes / time
38,175,744 / 241 10:26:18 INFO - TinderboxPrint: I/O write bytes / time
500,244,480 / 1,572 10:26:18 INFO - TinderboxPrint: CPU idle
2,236.9 (46.6%) 10:26:18 INFO - TinderboxPrint: CPU system
958.1 (20.0%) 10:26:18 INFO - TinderboxPrint: CPU user
1,602.7 (33.4%) 10:26:18 INFO - TinderboxPrint: Swap in / out
229,576,704 / 0 10:26:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 179024384; Write bytes: 174505984; Read time: 16647; Write time: 378 10:26:18 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37245952; Write bytes: 308002816; Read time: 229; Write time: 1139 10:26:19 INFO - Running post-run listener: _upload_blobber_files 10:26:19 INFO - Blob upload gear active. 10:26:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:26:19 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:26:19 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:26:19 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:26:19 INFO - (blobuploader) - INFO - Open directory for files ... 10:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:20 INFO - (blobuploader) - INFO - Done attempting. 10:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:21 INFO - (blobuploader) - INFO - Done attempting. 10:26:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:26:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:26:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:26:23 INFO - (blobuploader) - INFO - Done attempting. 10:26:23 INFO - (blobuploader) - INFO - Iteration through files over. 10:26:24 INFO - Return code: 0 10:26:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:26:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:26:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ade79a9eb4acfe05c6cf8e1a61eb56ac8eb9cf82284dd98a9dfa3665d363cb25f1f7fde34cedacbd9d69010f851fab09e716b0a6d73b2b1496d4c223aa9d51e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5993626672f65e3e261ee302d4dc8baab68ddd07bf6fea014d61898ba7c410e375346f38e9a08a94432673b3660868a6eb702f25cc186ee332226abec6db62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65c1feb6f45a309c1a5a1db04b8cb01eb4802f70d8f37bed22992fc2f1fbbd1f31321d819dfd2f196f88260df409b4e482d04942d204659875d3388030aab629"} 10:26:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:26:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:26:24 INFO - Contents: 10:26:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ade79a9eb4acfe05c6cf8e1a61eb56ac8eb9cf82284dd98a9dfa3665d363cb25f1f7fde34cedacbd9d69010f851fab09e716b0a6d73b2b1496d4c223aa9d51e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5993626672f65e3e261ee302d4dc8baab68ddd07bf6fea014d61898ba7c410e375346f38e9a08a94432673b3660868a6eb702f25cc186ee332226abec6db62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65c1feb6f45a309c1a5a1db04b8cb01eb4802f70d8f37bed22992fc2f1fbbd1f31321d819dfd2f196f88260df409b4e482d04942d204659875d3388030aab629"} 10:26:24 INFO - Running post-run listener: copy_logs_to_upload_dir 10:26:24 INFO - Copying logs to upload dir... 10:26:24 INFO - mkdir: /builds/slave/test/build/upload/logs 10:26:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.099964 ========= master_lag: 1.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-03 10:26:25.142016) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 10:26:25.145580) ========= 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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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/Mozilla-Beta/sha512/7ade79a9eb4acfe05c6cf8e1a61eb56ac8eb9cf82284dd98a9dfa3665d363cb25f1f7fde34cedacbd9d69010f851fab09e716b0a6d73b2b1496d4c223aa9d51e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5993626672f65e3e261ee302d4dc8baab68ddd07bf6fea014d61898ba7c410e375346f38e9a08a94432673b3660868a6eb702f25cc186ee332226abec6db62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65c1feb6f45a309c1a5a1db04b8cb01eb4802f70d8f37bed22992fc2f1fbbd1f31321d819dfd2f196f88260df409b4e482d04942d204659875d3388030aab629"} build_url:https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/dqyKb6dVTjmf1FK_9gQXYQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ade79a9eb4acfe05c6cf8e1a61eb56ac8eb9cf82284dd98a9dfa3665d363cb25f1f7fde34cedacbd9d69010f851fab09e716b0a6d73b2b1496d4c223aa9d51e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5993626672f65e3e261ee302d4dc8baab68ddd07bf6fea014d61898ba7c410e375346f38e9a08a94432673b3660868a6eb702f25cc186ee332226abec6db62c8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65c1feb6f45a309c1a5a1db04b8cb01eb4802f70d8f37bed22992fc2f1fbbd1f31321d819dfd2f196f88260df409b4e482d04942d204659875d3388030aab629"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 10:26:25.190036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:26:25.190336) ========= 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.cShm4qgwTc/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.5t9dNaoZ0t/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.005106 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 10:26:25.244061) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 10:26:25.244804) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 10:26:25.245578) ========= ========= Total master_lag: 1.28 =========