builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1481019843.12 results: success (0) buildid: 20161206004003 builduid: f27165c7e62d4db5aa67d2c80ad34387 revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.116722) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.117165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.117442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.144339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.144725) ========= 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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-06 02:24:03-- 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% 8.49M=0.001s 2016-12-06 02:24:03 (8.49 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.325492) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.325793) ========= 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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.020221 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:03.374406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 02:24:03.374727) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 46049b21980d67b13d6915c052a3025c273a1788 --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-aurora --rev 46049b21980d67b13d6915c052a3025c273a1788 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-06 02:24:03,453 truncating revision to first 12 chars 2016-12-06 02:24:03,454 Setting DEBUG logging. 2016-12-06 02:24:03,454 attempt 1/10 2016-12-06 02:24:03,454 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/46049b21980d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 02:24:03,745 unpacking tar archive at: mozilla-aurora-46049b21980d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.245809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-06 02:24:04.645194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:04.645460) ========= script_repo_revision: 46049b21980d67b13d6915c052a3025c273a1788 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:04.645828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:04.646102) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 02:24:04.667112) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-06 02:24:04.667375) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:04 INFO - MultiFileLogger online at 20161206 02:24:04 in /builds/slave/test 02:24:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:24:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:04 INFO - {'append_to_log': False, 02:24:04 INFO - 'base_work_dir': '/builds/slave/test', 02:24:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:24:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:04 INFO - 'branch': 'Mozilla-Aurora', 02:24:04 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:04 INFO - 'config_files': ('talos/mac_config.py',), 02:24:04 INFO - 'default_actions': ('clobber', 02:24:04 INFO - 'read-buildbot-config', 02:24:04 INFO - 'download-and-extract', 02:24:04 INFO - 'populate-webroot', 02:24:04 INFO - 'create-virtualenv', 02:24:04 INFO - 'install', 02:24:04 INFO - 'run-tests'), 02:24:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:04 INFO - 'download_minidump_stackwalk': True, 02:24:04 INFO - 'download_symbols': 'ondemand', 02:24:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:04 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:04 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:04 INFO - 'installer_path': 'installer.exe', 02:24:04 INFO - 'log_level': 'info', 02:24:04 INFO - 'log_name': 'talos', 02:24:04 INFO - 'log_to_console': True, 02:24:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:24:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:24:04 INFO - 'opt_config_files': (), 02:24:04 INFO - 'pip_index': False, 02:24:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:04 INFO - 'cmd': ('bash', 02:24:04 INFO - '-c', 02:24:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:04 INFO - 'enabled': True, 02:24:04 INFO - 'halt_on_failure': False, 02:24:04 INFO - 'name': 'check_screen_resolution'},), 02:24:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:04 INFO - 'cmd': ('bash', 02:24:04 INFO - '-c', 02:24:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:04 INFO - 'enabled': True, 02:24:04 INFO - 'halt_on_failure': False, 02:24:04 INFO - 'name': 'check_screen_resolution'},), 02:24:04 INFO - 'run_cmd_checks_enabled': True, 02:24:04 INFO - 'sps_profile': False, 02:24:04 INFO - 'sps_profile_interval': 0, 02:24:04 INFO - 'suite': 'dromaeojs', 02:24:04 INFO - 'system_bits': '32', 02:24:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:04 INFO - 'title': 't-yosemite-r7-0034', 02:24:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:04 INFO - 'use_talos_json': True, 02:24:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:04 INFO - 'work_dir': 'build'} 02:24:04 INFO - [mozharness: 2016-12-06 10:24:04.878603Z] Running clobber step. 02:24:04 INFO - Running pre-action listener: _resource_record_pre_action 02:24:04 INFO - Running main action method: clobber 02:24:04 INFO - rmtree: /builds/slave/test/build 02:24:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:24:05 INFO - Running post-action listener: _resource_record_post_action 02:24:05 INFO - [mozharness: 2016-12-06 10:24:05.820201Z] Finished clobber step (success) 02:24:05 INFO - [mozharness: 2016-12-06 10:24:05.820404Z] Running read-buildbot-config step. 02:24:05 INFO - Running pre-action listener: _resource_record_pre_action 02:24:05 INFO - Running main action method: read_buildbot_config 02:24:05 INFO - Using buildbot properties: 02:24:05 INFO - { 02:24:05 INFO - "project": "", 02:24:05 INFO - "product": "firefox", 02:24:05 INFO - "script_repo_revision": "production", 02:24:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:24:05 INFO - "repository": "", 02:24:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:24:05 INFO - "buildid": "20161206004003", 02:24:05 INFO - "stage_platform": "macosx64", 02:24:05 INFO - "basedir": "/builds/slave/test", 02:24:05 INFO - "nightly_build": "True", 02:24:05 INFO - "buildnumber": 56, 02:24:05 INFO - "slavename": "t-yosemite-r7-0034", 02:24:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:24:05 INFO - "platform": "yosemite_r7", 02:24:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:24:05 INFO - "branch": "mozilla-aurora", 02:24:05 INFO - "repo_path": "releases/mozilla-aurora", 02:24:05 INFO - "revision": "46049b21980d67b13d6915c052a3025c273a1788", 02:24:05 INFO - "pgo_build": "False", 02:24:05 INFO - "builduid": "f27165c7e62d4db5aa67d2c80ad34387", 02:24:05 INFO - "slavebuilddir": "test" 02:24:05 INFO - } 02:24:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:24:05 INFO - Running post-action listener: _resource_record_post_action 02:24:05 INFO - [mozharness: 2016-12-06 10:24:05.826171Z] Finished read-buildbot-config step (success) 02:24:05 INFO - [mozharness: 2016-12-06 10:24:05.826371Z] Running download-and-extract step. 02:24:05 INFO - Running pre-action listener: _resource_record_pre_action 02:24:05 INFO - Running main action method: download_and_extract 02:24:05 INFO - mkdir: /builds/slave/test/build/tests 02:24:05 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')]} 02:24:05 INFO - trying https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:24:05 INFO - Downloading https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:24:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:24:07 INFO - Downloaded 1199 bytes. 02:24:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:24:07 INFO - Using the following test package requirements: 02:24:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:24:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:24:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:24:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'jsshell-mac.zip'], 02:24:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:24:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:24:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:24:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:24:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:24:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:24:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:24:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:24:07 INFO - Http code: 200 02:24:07 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 02:24:07 INFO - x-amz-cf-id: wqFIhW2-w67d3oAUbV9OcZV4uOOqozqD2gElr3zoT8udiClMT_Jzzg== 02:24:07 INFO - x-amz-version-id: p1F22bvmTnRMsWAK8jDvZ8phR8gafu03 02:24:07 INFO - Expected file size: 16282014 02:24:07 INFO - Obtained file size: 16282014 02:24:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:24:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:24:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:24:15 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:24:15 INFO - Http code: 200 02:24:15 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 02:24:15 INFO - x-amz-cf-id: L2BpVfkXlqh-dRv0qGGgSKCO4pYGQTpBjtkdKsi7khWHxJbwl6sSIQ== 02:24:15 INFO - x-amz-version-id: p1F22bvmTnRMsWAK8jDvZ8phR8gafu03 02:24:15 INFO - Expected file size: 16282014 02:24:15 INFO - Obtained file size: 16282014 02:24:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:24:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:24:22 INFO - Fetch https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:24:23 INFO - Http code: 200 02:24:23 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 02:24:23 INFO - x-amz-cf-id: yA9DEs-Lwcoo5dJE8pfWVscEn97VuuuwY-Q8b4WdI10cLG27uTS4Zg== 02:24:23 INFO - x-amz-version-id: Eevi5E7gG9YrlgAPXsY6hZrthaoUChRY 02:24:24 INFO - Expected file size: 14409038 02:24:24 INFO - Obtained file size: 14409038 02:24:24 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')]} 02:24:24 INFO - trying https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:24 INFO - Downloading https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:24:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:24:27 INFO - Downloaded 109857732 bytes. 02:24:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:27 INFO - mkdir: /builds/slave/test/properties 02:24:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:24:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:24:27 INFO - Contents: 02:24:27 INFO - build_url:https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:24:28 INFO - Running post-action listener: _resource_record_post_action 02:24:28 INFO - Running post-action listener: set_extra_try_arguments 02:24:28 INFO - [mozharness: 2016-12-06 10:24:28.287129Z] Finished download-and-extract step (success) 02:24:28 INFO - [mozharness: 2016-12-06 10:24:28.287280Z] Running populate-webroot step. 02:24:28 INFO - Running pre-action listener: _resource_record_pre_action 02:24:28 INFO - Running main action method: populate_webroot 02:24:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:24:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:24:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:24:28 INFO - '--tppagecycles', 02:24:28 INFO - '7'], 02:24:28 INFO - 'tests': ['tsvgm']}}, 02:24:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:24:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:24:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:24:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:24:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['damp', 'tps']}, 02:24:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'tests': ['damp', 'tps']}, 02:24:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['dromaeo_dom']}, 02:24:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:24:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['basic_compositor_video']}, 02:24:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:24:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'other-e10s': {'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['a11yr', 02:24:28 INFO - 'ts_paint', 02:24:28 INFO - 'tpaint', 02:24:28 INFO - 'sessionrestore', 02:24:28 INFO - 'sessionrestore_no_auto_restore', 02:24:28 INFO - 'tabpaint']}, 02:24:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['tsvgx', 02:24:28 INFO - 'tsvgr_opacity', 02:24:28 INFO - 'tart', 02:24:28 INFO - 'tscrollx', 02:24:28 INFO - 'cart']}, 02:24:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:24:28 INFO - 'tsvgr_opacity', 02:24:28 INFO - 'tart', 02:24:28 INFO - 'tscrollx', 02:24:28 INFO - 'cart']}, 02:24:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'talos_options': ['--disable-e10s'], 02:24:28 INFO - 'tests': ['tp5o']}, 02:24:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'tests': ['tp5o']}, 02:24:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'talos_options': ['--disable-e10s', 02:24:28 INFO - '--xperf_path', 02:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:28 INFO - 'tests': ['tp5n']}, 02:24:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:28 INFO - 'talos_options': ['--xperf_path', 02:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:28 INFO - 'tests': ['tp5n']}}, 02:24:28 INFO - 'talos.zip': {'path': '', 02:24:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:24:28 INFO - Running post-action listener: _resource_record_post_action 02:24:28 INFO - [mozharness: 2016-12-06 10:24:28.306564Z] Finished populate-webroot step (success) 02:24:28 INFO - [mozharness: 2016-12-06 10:24:28.306682Z] Running create-virtualenv step. 02:24:28 INFO - Running pre-action listener: _resource_record_pre_action 02:24:28 INFO - Running main action method: create_virtualenv 02:24:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:24:28 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 02:24:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:24:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:24:28 INFO - Using real prefix '/tools/python27' 02:24:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:24:29 INFO - Installing distribute.............................................................................................................................................................................................done. 02:24:32 INFO - Installing pip.................done. 02:24:32 INFO - Return code: 0 02:24:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:24:32 INFO - Reading from file tmpfile_stdout 02:24:32 INFO - Output received: 02:24:32 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) 02:24:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:24:32 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')]} 02:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:32 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')]} 02:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:32 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:32 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 02:24:32 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 02:24:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:32 INFO - 'HOME': '/Users/cltbld', 02:24:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:32 INFO - 'LOGNAME': 'cltbld', 02:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:32 INFO - 'MOZ_NO_REMOTE': '1', 02:24:32 INFO - 'NO_EM_RESTART': '1', 02:24:32 INFO - 'PAGER': '/bin/cat', 02:24:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:32 INFO - 'PWD': '/builds/slave/test', 02:24:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:32 INFO - 'SHELL': '/bin/bash', 02:24:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:32 INFO - 'USER': 'cltbld', 02:24:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:32 INFO - 'XPC_FLAGS': '0x0', 02:24:32 INFO - 'XPC_SERVICE_NAME': '0', 02:24:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:32 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 02:24:32 INFO - Cleaning up... 02:24:32 INFO - Return code: 0 02:24:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:24:32 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')]} 02:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:32 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')]} 02:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:32 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:32 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 02:24:32 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 02:24:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:32 INFO - 'HOME': '/Users/cltbld', 02:24:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:32 INFO - 'LOGNAME': 'cltbld', 02:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:32 INFO - 'MOZ_NO_REMOTE': '1', 02:24:32 INFO - 'NO_EM_RESTART': '1', 02:24:32 INFO - 'PAGER': '/bin/cat', 02:24:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:32 INFO - 'PWD': '/builds/slave/test', 02:24:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:32 INFO - 'SHELL': '/bin/bash', 02:24:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:32 INFO - 'USER': 'cltbld', 02:24:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:32 INFO - 'XPC_FLAGS': '0x0', 02:24:32 INFO - 'XPC_SERVICE_NAME': '0', 02:24:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:33 INFO - Downloading/unpacking psutil>=3.1.1 02:24:33 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 02:24:33 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 02:24:33 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 02:24:33 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 02:24:33 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 02:24:33 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 02:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:24:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:36 INFO - Installing collected packages: psutil 02:24:36 INFO - Running setup.py install for psutil 02:24:37 INFO - building 'psutil._psutil_osx' extension 02:24:37 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 02:24:37 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 02:24:37 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 02:24:37 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 02:24:37 INFO - building 'psutil._psutil_posix' extension 02:24:37 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 02:24:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:24:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:24:37 INFO - ^ 02:24:37 INFO - 1 warning generated. 02:24:37 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 02:24:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:37 INFO - Successfully installed psutil 02:24:37 INFO - Cleaning up... 02:24:37 INFO - Return code: 0 02:24:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:37 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:37 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 02:24:37 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 02:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:37 INFO - 'HOME': '/Users/cltbld', 02:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:37 INFO - 'LOGNAME': 'cltbld', 02:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:37 INFO - 'MOZ_NO_REMOTE': '1', 02:24:37 INFO - 'NO_EM_RESTART': '1', 02:24:37 INFO - 'PAGER': '/bin/cat', 02:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:37 INFO - 'PWD': '/builds/slave/test', 02:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:37 INFO - 'SHELL': '/bin/bash', 02:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:37 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:37 INFO - 'USER': 'cltbld', 02:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:37 INFO - 'XPC_FLAGS': '0x0', 02:24:37 INFO - 'XPC_SERVICE_NAME': '0', 02:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:24:40 INFO - Installing collected packages: mozsystemmonitor 02:24:40 INFO - Running setup.py install for mozsystemmonitor 02:24:41 INFO - Successfully installed mozsystemmonitor 02:24:41 INFO - Cleaning up... 02:24:41 INFO - Return code: 0 02:24:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:24: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')]} 02:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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')]} 02:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:41 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:41 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 02:24:41 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 02:24:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:41 INFO - 'HOME': '/Users/cltbld', 02:24:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:41 INFO - 'LOGNAME': 'cltbld', 02:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:41 INFO - 'MOZ_NO_REMOTE': '1', 02:24:41 INFO - 'NO_EM_RESTART': '1', 02:24:41 INFO - 'PAGER': '/bin/cat', 02:24:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:41 INFO - 'PWD': '/builds/slave/test', 02:24:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:41 INFO - 'SHELL': '/bin/bash', 02:24:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:41 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:41 INFO - 'USER': 'cltbld', 02:24:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:41 INFO - 'XPC_FLAGS': '0x0', 02:24:41 INFO - 'XPC_SERVICE_NAME': '0', 02:24:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:41 INFO - Downloading/unpacking jsonschema==2.5.1 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:24:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:44 INFO - Downloading functools32-3.2.3-2.tar.gz 02:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:24:44 INFO - warning: no files found matching '*.txt' 02:24:44 INFO - no previously-included directories found matching 'build' 02:24:44 INFO - no previously-included directories found matching 'dist' 02:24:44 INFO - no previously-included directories found matching '.git*' 02:24:44 INFO - Installing collected packages: jsonschema, functools32 02:24:44 INFO - Running setup.py install for functools32 02:24:44 INFO - warning: no files found matching '*.txt' 02:24:44 INFO - no previously-included directories found matching 'build' 02:24:44 INFO - no previously-included directories found matching 'dist' 02:24:44 INFO - no previously-included directories found matching '.git*' 02:24:44 INFO - Successfully installed jsonschema functools32 02:24:44 INFO - Cleaning up... 02:24:44 INFO - Return code: 0 02:24:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:44 INFO - 'HOME': '/Users/cltbld', 02:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:45 INFO - 'LOGNAME': 'cltbld', 02:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:45 INFO - 'MOZ_NO_REMOTE': '1', 02:24:45 INFO - 'NO_EM_RESTART': '1', 02:24:45 INFO - 'PAGER': '/bin/cat', 02:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:45 INFO - 'PWD': '/builds/slave/test', 02:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:45 INFO - 'SHELL': '/bin/bash', 02:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:45 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:45 INFO - 'USER': 'cltbld', 02:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:45 INFO - 'XPC_FLAGS': '0x0', 02:24:45 INFO - 'XPC_SERVICE_NAME': '0', 02:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:24:45 INFO - Cleaning up... 02:24:45 INFO - Return code: 0 02:24:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:45 INFO - 'HOME': '/Users/cltbld', 02:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:45 INFO - 'LOGNAME': 'cltbld', 02:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:45 INFO - 'MOZ_NO_REMOTE': '1', 02:24:45 INFO - 'NO_EM_RESTART': '1', 02:24:45 INFO - 'PAGER': '/bin/cat', 02:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:45 INFO - 'PWD': '/builds/slave/test', 02:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:45 INFO - 'SHELL': '/bin/bash', 02:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:45 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:45 INFO - 'USER': 'cltbld', 02:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:45 INFO - 'XPC_FLAGS': '0x0', 02:24:45 INFO - 'XPC_SERVICE_NAME': '0', 02:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:45 INFO - Downloading/unpacking blobuploader==1.2.4 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:48 INFO - Downloading blobuploader-1.2.4.tar.gz 02:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:24:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:24:48 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 02:24:48 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 02:24:48 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 02:24:48 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 02:24:48 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 02:24:48 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 02:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:24:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:49 INFO - Downloading docopt-0.6.1.tar.gz 02:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:24:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:24:49 INFO - Running setup.py install for blobuploader 02:24:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:24:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:24:49 INFO - Running setup.py install for requests 02:24:50 INFO - Running setup.py install for docopt 02:24:50 INFO - Successfully installed blobuploader requests docopt 02:24:50 INFO - Cleaning up... 02:24:50 INFO - Return code: 0 02:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24: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')]} 02:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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')]} 02:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:50 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:50 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 02:24:50 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 02:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:50 INFO - 'HOME': '/Users/cltbld', 02:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:50 INFO - 'LOGNAME': 'cltbld', 02:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:50 INFO - 'MOZ_NO_REMOTE': '1', 02:24:50 INFO - 'NO_EM_RESTART': '1', 02:24:50 INFO - 'PAGER': '/bin/cat', 02:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:50 INFO - 'PWD': '/builds/slave/test', 02:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:50 INFO - 'SHELL': '/bin/bash', 02:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:50 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:50 INFO - 'USER': 'cltbld', 02:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:50 INFO - 'XPC_FLAGS': '0x0', 02:24:50 INFO - 'XPC_SERVICE_NAME': '0', 02:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-uvtunj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:50 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xV0KLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-XXmwru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-m8Cpyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JJbNLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JMhC_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-CgghIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-feNdzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ENZVkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8Hkbqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-u4W1jR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OIx3wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9RcV_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-8M5mOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-BRDy7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-s8Xq8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4wI9MT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:24:52 INFO - Running setup.py install for manifestparser 02:24:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:24:52 INFO - Running setup.py install for mozcrash 02:24:52 INFO - Running setup.py install for mozdebug 02:24:53 INFO - Running setup.py install for mozdevice 02:24:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozfile 02:24:53 INFO - Running setup.py install for mozhttpd 02:24:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozinfo 02:24:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozInstall 02:24:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:24:53 INFO - Running setup.py install for mozleak 02:24:54 INFO - Running setup.py install for mozlog 02:24:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for moznetwork 02:24:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for mozprocess 02:24:54 INFO - Running setup.py install for mozprofile 02:24:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for mozrunner 02:24:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:24:54 INFO - Running setup.py install for mozscreenshot 02:24:55 INFO - Running setup.py install for moztest 02:24:55 INFO - Running setup.py install for mozversion 02:24:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:24:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:24:55 INFO - Cleaning up... 02:24:55 INFO - Return code: 0 02:24:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24: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')]} 02:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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')]} 02:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:55 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:24:55 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 02:24:55 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 02:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:55 INFO - 'HOME': '/Users/cltbld', 02:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:55 INFO - 'LOGNAME': 'cltbld', 02:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:55 INFO - 'MOZ_NO_REMOTE': '1', 02:24:55 INFO - 'NO_EM_RESTART': '1', 02:24:55 INFO - 'PAGER': '/bin/cat', 02:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:55 INFO - 'PWD': '/builds/slave/test', 02:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:55 INFO - 'SHELL': '/bin/bash', 02:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:24:55 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:55 INFO - 'USER': 'cltbld', 02:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:55 INFO - 'XPC_FLAGS': '0x0', 02:24:55 INFO - 'XPC_SERVICE_NAME': '0', 02:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-HrUI7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-OWKaKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:55 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)) 02:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:55 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QAY39M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xdY1Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2BCENY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-see7Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-j_xCBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WQryVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-eRMlwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-1zy1Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QU8aPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:56 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)) 02:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:56 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UT83vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:57 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)) 02:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pxMvCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:57 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)) 02:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ll3UQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:57 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)) 02:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_UiHgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:57 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)) 02:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-jYkBAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:57 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Q1cMxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:57 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)) 02:24:57 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)) 02:24:57 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)) 02:24:57 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)) 02:24:57 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)) 02:24:57 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)) 02:24:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:00 INFO - Downloading blessings-1.6.tar.gz 02:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:25:00 INFO - Installing collected packages: blessings 02:25:00 INFO - Running setup.py install for blessings 02:25:00 INFO - Successfully installed blessings 02:25:00 INFO - Cleaning up... 02:25:00 INFO - Return code: 0 02:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:25:00 INFO - Reading from file tmpfile_stdout 02:25:00 INFO - Current package versions: 02:25:00 INFO - blessings == 1.6 02:25:00 INFO - blobuploader == 1.2.4 02:25:00 INFO - docopt == 0.6.1 02:25:00 INFO - functools32 == 3.2.3-2 02:25:00 INFO - jsonschema == 2.5.1 02:25:00 INFO - manifestparser == 1.1 02:25:00 INFO - mozInstall == 1.12 02:25:00 INFO - mozcrash == 1.0 02:25:00 INFO - mozdebug == 0.1 02:25:00 INFO - mozdevice == 0.48 02:25:00 INFO - mozfile == 1.2 02:25:00 INFO - mozhttpd == 0.7 02:25:00 INFO - mozinfo == 0.9 02:25:00 INFO - mozleak == 0.1 02:25:00 INFO - mozlog == 3.4 02:25:00 INFO - moznetwork == 0.27 02:25:00 INFO - mozprocess == 0.23 02:25:00 INFO - mozprofile == 0.28 02:25:00 INFO - mozrunner == 6.13 02:25:00 INFO - mozscreenshot == 0.1 02:25:00 INFO - mozsystemmonitor == 0.3 02:25:00 INFO - moztest == 0.7 02:25:00 INFO - mozversion == 1.4 02:25:00 INFO - psutil == 3.1.1 02:25:00 INFO - requests == 1.2.3 02:25:00 INFO - wsgiref == 0.1.2 02:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:01 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:25:01 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 02:25:01 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 02:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:01 INFO - 'HOME': '/Users/cltbld', 02:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:01 INFO - 'LOGNAME': 'cltbld', 02:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:01 INFO - 'MOZ_NO_REMOTE': '1', 02:25:01 INFO - 'NO_EM_RESTART': '1', 02:25:01 INFO - 'PAGER': '/bin/cat', 02:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:01 INFO - 'PWD': '/builds/slave/test', 02:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:01 INFO - 'SHELL': '/bin/bash', 02:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:25:01 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:01 INFO - 'USER': 'cltbld', 02:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:01 INFO - 'XPC_FLAGS': '0x0', 02:25:01 INFO - 'XPC_SERVICE_NAME': '0', 02:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 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)) 02:25:01 INFO - Cleaning up... 02:25:01 INFO - Return code: 0 02:25:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:01 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 0x101e30490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101da6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922ad5d940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, '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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 02:25:01 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 02:25:01 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 02:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:01 INFO - 'HOME': '/Users/cltbld', 02:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:01 INFO - 'LOGNAME': 'cltbld', 02:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:01 INFO - 'MOZ_NO_REMOTE': '1', 02:25:01 INFO - 'NO_EM_RESTART': '1', 02:25:01 INFO - 'PAGER': '/bin/cat', 02:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:01 INFO - 'PWD': '/builds/slave/test', 02:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:01 INFO - 'SHELL': '/bin/bash', 02:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:25:01 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:01 INFO - 'USER': 'cltbld', 02:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:01 INFO - 'XPC_FLAGS': '0x0', 02:25:01 INFO - 'XPC_SERVICE_NAME': '0', 02:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:25:01 INFO - Cleaning up... 02:25:01 INFO - Return code: 0 02:25:01 INFO - Running post-action listener: _resource_record_post_action 02:25:01 INFO - Running post-action listener: _start_resource_monitoring 02:25:01 INFO - Starting resource monitoring. 02:25:01 INFO - [mozharness: 2016-12-06 10:25:01.575008Z] Finished create-virtualenv step (success) 02:25:01 INFO - [mozharness: 2016-12-06 10:25:01.576055Z] Running install step. 02:25:01 INFO - Running pre-action listener: _resource_record_pre_action 02:25:01 INFO - Running main action method: install 02:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:25:01 INFO - Reading from file tmpfile_stdout 02:25:01 INFO - Detecting whether we're running mozinstall >=1.0... 02:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:25:01 INFO - Reading from file tmpfile_stdout 02:25:01 INFO - Output received: 02:25:01 INFO - Usage: mozinstall [options] installer 02:25:01 INFO - Options: 02:25:01 INFO - -h, --help show this help message and exit 02:25:01 INFO - -d DEST, --destination=DEST 02:25:01 INFO - Directory to install application into. [default: 02:25:01 INFO - "/builds/slave/test"] 02:25:01 INFO - --app=APP Application being installed. [default: firefox] 02:25:01 INFO - mkdir: /builds/slave/test/build/application 02:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:25:34 INFO - Reading from file tmpfile_stdout 02:25:34 INFO - Output received: 02:25:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:25:34 INFO - Running post-action listener: _resource_record_post_action 02:25:34 INFO - [mozharness: 2016-12-06 10:25:34.606820Z] Finished install step (success) 02:25:34 INFO - [mozharness: 2016-12-06 10:25:34.606972Z] Running run-tests step. 02:25:34 INFO - Running pre-action listener: _resource_record_pre_action 02:25:34 INFO - Running main action method: run_tests 02:25:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:25:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:25:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:25:34 INFO - 2016-12-06 02:25:34.622 screenresolution[1921:23876] starting screenresolution argv=screenresolution get 02:25:34 INFO - 2016-12-06 02:25:34.625 screenresolution[1921:23876] Display 0: 1600x1200x32@60 02:25:34 INFO - 2016-12-06 02:25:34.631 screenresolution[1922:23878] starting screenresolution argv=screenresolution list 02:25:34 INFO - Available Modes on Display 0 02:25:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:25:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:25:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:25:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:25:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:25:34 INFO - Intel Iris: 02:25:34 INFO - Chipset Model: Intel Iris 02:25:34 INFO - Type: GPU 02:25:34 INFO - Bus: Built-In 02:25:34 INFO - VRAM (Dynamic, Max): 1536 MB 02:25:34 INFO - Vendor: Intel (0x8086) 02:25:34 INFO - Device ID: 0x0a2e 02:25:34 INFO - Revision ID: 0x0009 02:25:34 INFO - Displays: 02:25:34 INFO - Display: 02:25:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:25:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:25:34 INFO - Display Serial Number: 1600x1200 60 02:25:34 INFO - Main Display: Yes 02:25:34 INFO - Mirror: Off 02:25:34 INFO - Online: Yes 02:25:34 INFO - Rotation: Supported 02:25:34 INFO - Return code: 0 02:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:25:34 INFO - Python 2.7.3 02:25:34 INFO - Return code: 0 02:25:34 INFO - grabbing minidump binary from tooltool 02:25:34 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')]} 02:25:34 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 0x101c44d30>, '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 0x101aaadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101c3cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:25:34 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 02:25:34 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 02:25:34 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 02:25:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:25:34 INFO - Return code: 0 02:25:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:25:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:25:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:25:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:25:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:25:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 02:25:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 02:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J9Z8zZw24w/Render', 02:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:34 INFO - 'HOME': '/Users/cltbld', 02:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:34 INFO - 'LOGNAME': 'cltbld', 02:25:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:25:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:34 INFO - 'MOZ_NO_REMOTE': '1', 02:25:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:25:34 INFO - 'NO_EM_RESTART': '1', 02:25:35 INFO - 'PAGER': '/bin/cat', 02:25:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:35 INFO - 'PWD': '/builds/slave/test', 02:25:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:35 INFO - 'SHELL': '/bin/bash', 02:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F1tLCc5ofJ/Listeners', 02:25:35 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 02:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:35 INFO - 'USER': 'cltbld', 02:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:35 INFO - 'XPC_FLAGS': '0x0', 02:25:35 INFO - 'XPC_SERVICE_NAME': '0', 02:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 02:25:35 INFO - mozversion application_buildid: 20161206004003 02:25:35 INFO - mozversion application_changeset: 46049b21980d67b13d6915c052a3025c273a1788 02:25:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:25:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:25:35 INFO - mozversion application_name: Firefox 02:25:35 INFO - mozversion application_remotingname: firefox-dev 02:25:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:25:35 INFO - mozversion application_vendor: Mozilla 02:25:35 INFO - mozversion application_version: 52.0a2 02:25:35 INFO - mozversion platform_buildid: 20161206004003 02:25:35 INFO - mozversion platform_changeset: 46049b21980d67b13d6915c052a3025c273a1788 02:25:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:25:35 INFO - mozversion platform_version: 52.0a2 02:25:35 INFO - using testdate: 1481019935 02:25:35 INFO - actual date: 1481019935 02:25:35 INFO - starting webserver on 'localhost:49729' 02:25:35 INFO - SUITE-START | Running 2 tests 02:25:35 INFO - TEST-START | dromaeo_css 02:25:35 INFO - operating with platform_type : mac_ 02:25:35 INFO - Initialising browser for dromaeo_css test... 02:25:35 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpueq_6k/profile http://localhost:49729/getInfo.html) 02:25:38 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 02:25:38 INFO - PROCESS | 1932 | colorDepth:24 02:25:38 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 02:25:38 INFO - PROCESS | 1932 | __metrics 02:25:38 INFO - PROCESS | 1932 | 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" 02:25:38 INFO - PROCESS | 1932 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:25:39 INFO - TEST-INFO | 1932: exit 0 02:25:39 INFO - Browser initialized. 02:25:39 INFO - Running cycle 1/1 for dromaeo_css test... 02:25:39 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpueq_6k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:51 INFO - PROCESS | 1935 | RSS: Main: 222760960 02:25:51 INFO - PROCESS | 1935 | 02:26:42 INFO - PROCESS | 1935 | 1481020002339 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:28:54 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49729/tests/dromaeo/cssquery-ext.html) 02:28:56 INFO - PROCESS | 1935 | RSS: Main: 363671552 02:28:56 INFO - PROCESS | 1935 | 02:31:59 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-ext.html (next: http://localhost:49729/tests/dromaeo/cssquery-jquery.html) 02:32:00 INFO - PROCESS | 1935 | RSS: Main: 344084480 02:32:00 INFO - PROCESS | 1935 | 02:35:02 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49729/tests/dromaeo/cssquery-mootools.html) 02:35:02 INFO - PROCESS | 1935 | RSS: Main: 338845696 02:35:02 INFO - PROCESS | 1935 | 02:38:05 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49729/tests/dromaeo/cssquery-prototype.html) 02:38:05 INFO - PROCESS | 1935 | RSS: Main: 315817984 02:38:05 INFO - PROCESS | 1935 | 02:41:09 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49729/tests/dromaeo/cssquery-yui.html) 02:41:10 INFO - PROCESS | 1935 | RSS: Main: 303386624 02:41:10 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49729/tests/dromaeo/cssquery-yui.html (next: http://localhost:49729/tests/dromaeo/cssquery-dojo.html) 02:44:13 INFO - PROCESS | 1935 | RSS: Main: 537251840 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | __start_tp_report 02:44:13 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 02:44:13 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 02:44:13 INFO - PROCESS | 1935 | |i|pagename|runs| 02:44:13 INFO - PROCESS | 1935 | |0;dojo.html;2739.5342791725407;2769.404578015726;2769.7784177265817;2796.2170592234156;2823.33651591876;4733.810647574097;4802.23164293713;4839.080574690811;4840.709557426557;4847.4425441074245;4772.522747725226;4799.4480634727015;4837.443693975194;4841.709497430156;4883.853484395465;4414.205443020247;4448.28827387618;4466;4469.709468884512;4565.680402371835;4340.631046361063;4384.408104905845;4413.757243351614;4506.436695413074;4545.250033744428;4359.193549193409;4437.979264769105;4465.4641443026885;4483.3723278741;4512.977435112836;4403.537628549005;4443.289073748185;4462.3752674625575;4495.34817451471;4508.887277818048;4441.644668426518;4447.866564003053;4450.821967121311;4469.575390337913;4483.058557702853;4611.631069514431;4615.169269531483;4717.811287548494;4726;4761.904761904777;4413.58070983256;5151.459096794858;5196.0647083524955;5204.739763011872;5206.114960456732;5226.346706661668;5300.204969254604;5304.946950530522;5335.973320133375;5371.355437347544;4794.760261986957;4843.491433399513;4865.172920603506;4932.827351042696;4940.6788558743565;5239.73801309933;5365.463453654604;5372.7313634318125;5417.6478528895495;5424.1321388577635;5123;5129.948700513021;5135.460776618476;5151.922721159186;5171.405288391883;5074.137396642506;5222.817201397933;5256.39551451588;5256.605754568346;5293.153095504701;4777.856664300077;4981.850544483599;5084.974575127175;5092.363454568179;5136.255242989746;5159.638825282194;5178.016176926372;5181.948180518222;5193.143131383355;5218.034663587248;4242.300020496584;4257.765822879709;4274.871753847389;4292.978535107367;4309.267424537836;3740.8129593520216;3796.2217745362686;3810.1046254130256;3868.1103346230525;3880.3209438348176;4792.448868380111;4796.5203479652455;4801.063792560487;4803.231482962709;4805.183118869801;5145;5261.4738526147075;5281.287026251483;5290.418054014055;5298.152295632757;3992.5208974923194;4757.666963312604;4786.7845946932475;4852.684575502581;4878.512148785093;3964.940525892114;4622.19111655459;4633.027064316531;4678.69588476748;4718.693284936468;5443.047466693313;5476.808311709071;5487.039768040657;5500.339959204921;5505.697186654773;5056.393232811939;5145.228215767665;5151.562117220078;5184.429712731672;5302.920456193237;5492.5605951524585;5493.368262649847;5495.477929596682;5517.724113794375;5525.364583072837;5049.116404629249;5203.089459344524;5209.6874187548865;5225.529702326657;5244.737763111905;5124.871878203075;5226.216067589892;5280.313559237275;5287.788488460573;5292.629515933848;5061.215511595708;5103.770330335218;5172.353455818023;5207.843764687065;5242.082635538842;5147.613928955268;5171.482851714798;5175.301334319819;5193.844184674465;5221.738913054408;4891.584215341736;5073.34046573943;5076.695398276115;5083.38999320084;5086.694798312114;5011.198208286657;5076.82231121909;5093.592512598909;5105.923411148761;5146.9963357145;5000.324956131021;5030.245463180552;5054.494550544916;5073.441921388718;5123.462036486116 02:44:13 INFO - PROCESS | 1935 | |1;ext.html;417.97492150470976;421.8125975379307;445.25642177563475;471.16133282756704;505.67131364612976;29024.854875725618;30666.54000189999;30671.84664076679;30677.233069173282;38687.41968870469;38206;40484.797576012155;40492.190156196855;40621.39067913983;40637.39043914343;9060.04869488704;9255;9257.490838003907;9262.351635385492;9290.349675522717;9176.036516165806;9276.907230927705;9277.536123193848;9284.814303713922;9302.627894884197;9210.585523651453;9270.304727145458;9280.907190928072;9295.44227346358;9342.813143737121;9197.448153110834;9267.72196834096;9301.627934882597;9309.929358123834;9335.906640933572;8146.674133034671;8836.337274704423;8958.955205223932;9037.322200834964;9049.140331668481;37927.81036094829;38383.23233535314;38430.23139537222;38433.039174020596;38464.03839903997;3152.274976755359;3192.4892017277125;3230.4346739320526;3344.3645707315777;3548.1129717570607;27829;27937.301567460978;28629.57055644167;28770.712292877222;29687.851560742274;3000.8799648014055;3096.349766549027;3147.134538002045;3164.841757912095;3168.239622490609;457543;463420;463959.9999999966;466565;470075;32067.03798886037;32994;59038.11442828361;59524.70237648784;59621.701891490266;33660.66339336575;34061.318773624385;34100.658993410245;34206.48690269648;34350;13463.461461541428;13474.730505389838;13770.380332885044;13964.092333998258;13966.231857247745;11464.4840982156;11742.412879356169;11854.525818967144;11865.644030679094;11876.881231187577;6335.841603959846;7419.4806363554035;7501.887471687929;7525.849483010309;7541.321281084619;2717.782577393804;3038.4378889904997;3086.891958781432;3123.453395655751;3144.7798654093995;770.9614519274014;817.2807929022422;864.312871267344;877.7454538183855;892.081156408887;26779;27606.72393276082;28477.572836407056;28606.713932860406;28652.570211446844;913.5751875377848;932.990670093304;949.3449519831295;965.4834663455018;970.3207754571828;14276.643083922778;14547.127172369692;14560.635984100481;14989.400423983137;15079.869009824088;627925;633537;634731;635094;637732;55798.884022319326;56321.71839140778;56456.58858528487;56467.71766141225;56544.43455565392;1250.2935841249664;1268.923864568129;1295.41706232194;1296.3906963727034;1303.328785675378;27460.764265607697;27890.72109278881;28040.71959280381;28515;28525.572116418687;48953;49224;49305;49322.75338623284;49396.75301623469;538337;541356;542838;543184;543271;25609;25899;40908.1818363637;42628.36057459203;42923;44771.32843007422;45204.095918082116;45747.54252457433;47059.29411058906;47243;36029;37419.43870841885;38208;38551.42172867465;40098.79950600228;37609.81195094007;37771;38278.61721382862;38534;38691.613083868804;31810.204744881565;32519.51220731738;32520.67479325271;35158.64841351656;37094;28879.5668064975;28994.7100528992;33606.15984600405;34232.973010809714;35092.94721158369;24767.62848557237;24982.37544061413;25034.74965250324;25070.874645626656;25226.117085901904 02:44:13 INFO - PROCESS | 1935 | |2;jquery.html;326.50697446855247;334.58845619887535;338.71209471948833;352.2778304475824;362.80408579367145;18499.260029598816;18896.71654925177;20188.99999999998;20511.846256143115;20520.486987825312;15446.536603901874;18864;19033.428997130075;19849.20603175875;20150.798492015074;5595.468430499106;5915.615484993483;5919.230500035011;5924.7333869975955;5927.814437112574;5550.8057217997375;5661.60368774186;5763.538916886639;5784.421557844224;5803.477687008169;5735.426457354252;5744.31068271808;5745.913811292834;5747.482726554609;5762;5431.701256430894;5706.429357064281;5756.107803290476;5765.385692006245;5781.392953739881;4945.406551213841;5024.296598476216;5034.924476132825;5039.596832253411;5039.924401133986;23227.07091716329;23396.415089622897;23503.41241468977;24244.63633045488;24630.753692463026;2315.6063469210108;2318.5362927414585;2365.929022129339;2381.9523609527887;2398.424378149249;12666.84663607189;15172.620684482867;15189.392424303014;15566.455174068966;15588.532344029698;1759.6128851652616;1795.9551011224694;1800.6308706715222;1809.3576780243088;1829.350580543901;108149;113470;113818;114376;114556.42721786421;7857.4499785014505;8575.228229459379;9191.77020574491;9606.327557070937;9962.900370996342;12040.939795300967;12088.879111208951;12120.81818772719;12133.939330303292;12134.757304853854;6278.529110316653;7379.077615298088;7428.739994100289;7502.887456688154;7540.359069479156;7501.474896757175;7660.73187438437;7703.229677032364;7753.767386978399;7834.255745704149;3173.809571425722;3725.2549490101546;3731.5149030625844;3732.720045996561;3799.601041890617;1823.2069049963309;1836.6510363030982;1840.6502764474708;1846.510674671213;1852.9166187521594;722.9783106506814;767.5893397032561;771.4060173666246;778.9883151752729;793.3573805217677;19533.706994395096;20463.28378506745;20550.69173962392;20559.280425185047;20755.8962205188;805.20284917932;859.4542465534431;873.3886279604302;880.9515476648846;886.3883920095113;9935.602575896883;10854.611985363208;10890.074343680875;10899.83650245247;10905.34567925927;540916;546690;553140;558764;563365;111869.88130118758;116248.41875790567;122657;126034;130376;859.8022454835423;902.593832775267;928.7120992492349;928.7353104365224;937.6249500199974;15429.459968901034;18310.359137430125;18690.906545467187;19004.42986710401;19140.138693758538;27688.861555692092;47609;48042.5195748038;48646.27030594473;50767.74616126896;56132;70038.64980675065;71456.28543714705;75000;75092;7446.851062978819;7528.058992625922;7651.502652327581;7885.1720569339395;7940.28537431634;8531.93350831146;11478.253913495597;11976.820347694966;12284.877151228731;12506.624801255977;9347.766305842408;10015.499225038573;10492.842607361048;10588;10678.519466624177;9917.950410247902;10281.177505799667;10374.68875933693;10515.001074897873;10807.243492955418;10453.26827122094;10665.520051597541;10687.572497100029;10858.022777950022;11202.327860328407;7530;7791.727289545065;7824;7833.5691536966015;7916.525008499509;9667.70996870095;10236.590536378462;10331.586736530455;10331.690049298533;10497.212709047004 02:44:13 INFO - PROCESS | 1935 | |3;mootools.html;1181.722295260613;1334.3128288931196;1359.5377571625645;1360.1974834847438;1394.616480467872;7032.64836758162;7434.293742094499;7629.008228930238;7880.172581878906;7906.4860784049;7717.151113377524;7728.381729461644;7765.029371328584;7781.377489800817;7835.843283134334;2143.3784202581273;2182.6289530779804;2191.550732099916;2193.451637090727;2195.089038049208;2168.5879682860204;2209.0611490116717;2213.457702862801;2214.169686367612;2215.3353993801875;2139;2159;2178.9564208715815;2184.1918490158664;2186.267600353883;2085.0721428964116;2150.3011521255653;2156.9029393677324;2160.83793715472;2167.674848772681;1874.381454120137;2156.978430215709;2189.8467107302495;2196.165457126298;2199.4611320226486;7785.221477852226;7828.686852525892;7881.4877032993245;7887.723929662492;7905.249001344863;4056.3104272273786;4703.2004559225015;4894.192458244385;4914.459409464956;4917.286993385939;3175.523671449278;3264.689854463822;3266.934661306784;3282.4748040313675;3283.1627934876456;906.3293163059354;934.7382732834817;950.5294879034852;957.1146689312358;958.7411398922252;10127.442990635513;10246.026627470379;10273.897261027369;10275.02387273208;10279.691609251735;3485.2506711056735;3982.8008599570485;4171.5828417158045;4189.895252618709;4206.348016057516;7639.815828546584;8116.147804480563;8117.959410202911;8118.594070296462;8126.553039582762;4390.912181756347;4756.904861902743;4760.904781904412;4763.73799441034;4765.809367625326;4009.5389030261895;4140.254754144283;4286.957130428718;4390.495093064274;4476.104779044204;3207.3905957868396;3208.8235147067135;3377.273886114497;3494.633063528293;3504.789712617251;1030.9841528399165;1048.4286064095086;1060.734816295926;1067.0503252105632;1068.935863848156;655.4265018109154;757.2049348184385;808.7614153824636;811.2495941254434;824.8287431846672;5037;5242.423333433319;5267.578593712494;5390.784368625289;5398.4871437213405;383.6336298834606;384.1184481614701;387.3975967370765;389.47809934687655;397.6917888636272;3486.930261394758;3571.74997750155;3581.4985901973746;3655.2324011957785;3736.252749450121;9129;9417.576209070608;9428.95285523568;9500.144986951207;9514.143727064597;9064.546772661472;9300;9381.437113773196;9461.006594307637;9472.90527094734;7246.311600397954;7270.745523906638;7289.890651640229;7352.228016058344;7357.558546487228;3915.921681566296;5048.495150485069;5218.634695571425;5262.184361423985;5410.971915336074;8481.618327175169;8789.91210087891;8813.206811387006;8817.38278320537;8857.291416686521;8869.68956086534;8951.686690965786;9008.774780630536;9052.456852588866;9157.130072643089;5804.767809287581;6066.969665151646;6699.799006029827;6862.245153033063;6866.828329291808;7424.888626670712;7560.206178351414;7564.8487030260185;7593.97481340012;7612;6029.854327677727;6512.706928188148;6635.303293154342;6765.2896445872475;6895.793126206021;5396.703181325065;6597.670116494252;6635.933640663531;6645.202575690948;6986.54587451814;6310.873782524415;6324;6369.713362898773;6394.104825324552;6398.776042838478;4552.339910712994;4554.840580579664;4607.654425918136;4614.907701845877;4645.465771436328;4360.956390436055;4486.214912390254;4598.126355992351;4613.1004454131125;4830.830920917751 02:44:13 INFO - PROCESS | 1935 | |4;prototype.html;263.13035417943695;267.2130575455284;272.7899517371623;275.2952441749121;279.43414585464444;3826.65560099591;3842.7117966152546;3846.326892793757;3866.7873266970305;3898.25933072716;3543.3090547343213;3603.0812142903524;3647.908802279938;3670.2659468106417;3741.9232326059027;3039.313181184442;3043.939121217574;3047.283888286251;3059.2504836315134;3070.401271752009;2775.875085621152;2848.5584734366207;3016.8793248270044;3024.9395012099744;3053.3359319170995;2774.3643538638275;2803.9018634347804;2820.746132848043;2950.9262268443354;2973.50937095379;2893.2766808297924;3006.827337338451;3018.5321275202377;3020.7885448018646;3048.71037251463;2678.7589116979566;2702.297402675299;2703.2566044337964;2711.295063283541;2723.9182824515096;2920.532714765627;3067.6472205696186;3255.8046517208804;3525.11872031992;3569.5538057742783;3392.9830350848333;3396.5414669019615;3407.505911642798;3420.7776494527775;3426.4688973208945;3674.8713795017047;3742.251549690073;3959.920801583981;3989.150469796631;3997.140614767839;2678.370582913014;2841.9715802841915;2849.7603542458887;2936.9573801300658;2952.46855565998;3579.6778289954027;4155.252054630135;4174.039970806701;4180.602842729936;4192.67306644812;2591.714911359749;2948.380840023576;2955.2907302247304;2972.539256415259;3017.275853795117;2672.786177105827;2739.4521095780924;2940.1767505098605;2971.6136902202575;2989.177976056602;3372.46552818467;3382.3573521030926;3382.9323413531592;3399.473081672345;3515.6308587598446;3392.2876195998624;3758.079270578671;3954.3277642800795;4035.354343305116;4049.987503124219;879.5162660536807;934.6214333857641;947.8815148106487;976.2873102634974;979.8236317462926;574.8850229954026;653.2911790707041;674.9021391898148;684.4114061906756;697.7871749116466;3568.8215589220436;3705.184859330943;3708.351038568294;3714.5728241252064;3732.925341493209;4072.898177545585;4085.9989302621047;4091.7954102294766;4094.2016306819883;4113.794310284474;3605.8016809075752;3651.5128052709597;3694.3350196964802;3694.371956767356;3701.481792549045;1676.7743559123978;1744.930202791874;1751.30823324786;1946.9778366357775;1956.2566224834472;3962.9894376934276;3967.444557761916;3980.3233450312937;4048.5951404859866;4055.2497787909333;3364.411228035084;3372.6458721833856;3883.3204189266767;3915.378275095525;3921.9019524512114;3956.22853543562;3970.4441378207075;4026.1947610478023;4026.275270451347;4044.5550989391145;3236.4174448598537;3653.390657033075;3816.7900765457057;3881.8835434936996;3898.5321761388814;3191.936161276715;3802.904927376838;3817.9809100954317;3863.9806800965816;3872.786996715208;3933.131762412831;3946.9013274668364;3957.485526881487;4020;4051.0075031617444;2643.9471210576066;2665.280374298967;2737.61673365725;2774.3618967637153;2781.443711257797;3487.633798451126;3514.2939936249454;3586.444101164324;3783.4135708965155;3849.2301539692735;2950.690177531328;3705.775974901745;3706.777593344408;3744.943825842669;3763.7553559018575;3411.5053317269353;3504.001359612498;3779.1307999160895;3791.734578579473;3797;3455.2053027803245;3655.83548740302;3686.7787932724123;3690.8708195214112;3785.545734511876;2965.6589492208673;3586.5696116466193;3671.7246206534082;3728.925421491501;3850.8652197174083;3138.6390565085308;3619.2942376236383;3653.9634603654686;3787.337215987136;3940.349842275992 02:44:13 INFO - PROCESS | 1935 | |5;yui.html;637.6779726238251;642.036944583125;680.3026897430134;709.8686742952551;727.0221552012545;12318.199317044397;13274.601761947146;13364.532241371555;14192.36134373953;14391.992560520768;12246.326452045134;12647.683807904785;12694.04794640401;12807.807882881763;12814.16707913985;763.5685837501808;792.2157064506155;793.6864938349348;796.1839114907209;797.1789057270992;625.3964923848486;644.9938096569343;646.1567654211252;655.403582739707;791.3748138970207;688.6625553478784;820.3806126374578;840.3193413335218;840.861257892447;855.4354126276659;761.596353932414;805.5126648377735;843.8607629741147;844.6874656377119;847.174005344784;489.1879480063114;596.3618927747312;596.8179705189915;623.7910300049489;626.4174317884366;11517.769644607144;12835.614931552069;13337.73324533504;13371.799423008662;13726.93136534321;12983.87016129836;13255.86744132556;13429.194248345131;13452.730945381136;13481.662958426117;10584.417857017861;10737.087347575465;10787.5684972601;10825.83761243582;10867.945660271727;3439.07145070832;3459.135216195951;3500.177458297298;3539.203679172191;3629.3467175908327;74531;75211.24788752098;81061.78407323894;83075;84404;10044.748881278027;10113.292069555207;10203.591856325664;10293.125084367912;10515.579376824995;19997.900010499856;20336.593268134555;20339.79660203379;20658.896705516676;20698.896505517376;6665.766698165541;6949.89575156373;7350.7427240046345;7485.176630570633;7672.309492145734;4053.8986525337104;4084;4097.7746223957965;4163.770992595376;4310.051788606502;3185.5062465317915;3275.901722948315;3285.0965984354493;3287.112479630486;3290.341931613687;598.5211830535554;615.2248167309133;627.6736097229507;629.568745409396;632.8702615963716;505.7092172001114;522.6106550619812;542.9267048948342;589.4635881347953;595.2559300873908;8029.437939344307;8457.492550446992;8710.47737135774;8769.605367758466;8881.37830351882;528.801699362739;564.4130104691076;576.6770608459192;582.0890306670047;600.3306313460547;3776.3580191367532;3912.667423268997;3924.7056470764805;4015.3374693175883;4059.167870586523;66936;78479.21520784833;86197;105226.47386763018;108600;59932.70033649804;60082.09876851851;62128.06807897885;62377;85130;79618.60190699126;80938.78591821127;81227.5938620303;82727;89728.5513572428;7526.247375262649;7566.167721550515;7627.237276272328;7741.535507869546;7766.029246344207;42312;46476.30285545787;51224.48775512197;51737.22394164015;51828.70428239324;61264;65757.67121164364;67743.3225667737;67832.6608366955;68688.96966545605;11734;11784.292942423483;11889.643310700692;12023.699407514532;12323.260604363766;13102.934485327512;13180.604581862559;13739.862601373858;13767.586972390844;13949.790753138508;9798.363106398061;11797.775248137816;12198.390080496118;12826.807597885852;13087.803682944954;11921.46353414081;12463;13168.47326106945;13363.665908352368;13391.129576577492;12067.456964436797;13096.214227146402;13215.933920330337;13219.140755850838;13226.206427614374;10199.235057370579;10684.105524230028;10864.837027444437;11103.777924441305;11125;8094.797630059295;8450.239478446969;9332.533373331442;10506.789864202521;10947.945260273647 02:44:13 INFO - PROCESS | 1935 | __end_tp_report 02:44:13 INFO - PROCESS | 1935 | __start_cc_report 02:44:13 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,1815 02:44:13 INFO - PROCESS | 1935 | __end_cc_report 02:44:13 INFO - PROCESS | 1935 | __startTimestamp1481021053360__endTimestamp 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | ------- Summary: start ------- 02:44:13 INFO - PROCESS | 1935 | Number of tests: 6 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:4847.61 Median:5049.12 stddev:540.43 (10.7%) stddev-sans-first:516.17 02:44:13 INFO - PROCESS | 1935 | Values: 2739.5 2769.4 2769.8 2796.2 2823.3 4733.8 4802.2 4839.1 4840.7 4847.4 4772.5 4799.4 4837.4 4841.7 4883.9 4414.2 4448.3 4466.0 4469.7 4565.7 4340.6 4384.4 4413.8 4506.4 4545.3 4359.2 4438.0 4465.5 4483.4 4513.0 4403.5 4443.3 4462.4 4495.3 4508.9 4441.6 4447.9 4450.8 4469.6 4483.1 4611.6 4615.2 4717.8 4726.0 4761.9 4413.6 5151.5 5196.1 5204.7 5206.1 5226.3 5300.2 5304.9 5336.0 5371.4 4794.8 4843.5 4865.2 4932.8 4940.7 5239.7 5365.5 5372.7 5417.6 5424.1 5123.0 5129.9 5135.5 5151.9 5171.4 5074.1 5222.8 5256.4 5256.6 5293.2 4777.9 4981.9 5085.0 5092.4 5136.3 5159.6 5178.0 5181.9 5193.1 5218.0 4242.3 4257.8 4274.9 4293.0 4309.3 3740.8 3796.2 3810.1 3868.1 3880.3 4792.4 4796.5 4801.1 4803.2 4805.2 5145.0 5261.5 5281.3 5290.4 5298.2 3992.5 4757.7 4786.8 4852.7 4878.5 3964.9 4622.2 4633.0 4678.7 4718.7 5443.0 5476.8 5487.0 5500.3 5505.7 5056.4 5145.2 5151.6 5184.4 5302.9 5492.6 5493.4 5495.5 5517.7 5525.4 5049.1 5203.1 5209.7 5225.5 5244.7 5124.9 5226.2 5280.3 5287.8 5292.6 5061.2 5103.8 5172.4 5207.8 5242.1 5147.6 5171.5 5175.3 5193.8 5221.7 4891.6 5073.3 5076.7 5083.4 5086.7 5011.2 5076.8 5093.6 5105.9 5147.0 5000.3 5030.2 5054.5 5073.4 5123.5 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:66064.83 Median:38542.71 stddev:147584.42 (382.9%) stddev-sans-first:147916.13 02:44:13 INFO - PROCESS | 1935 | Values: 418.0 421.8 445.3 471.2 505.7 29024.9 30666.5 30671.8 30677.2 38687.4 38206.0 40484.8 40492.2 40621.4 40637.4 9060.0 9255.0 9257.5 9262.4 9290.3 9176.0 9276.9 9277.5 9284.8 9302.6 9210.6 9270.3 9280.9 9295.4 9342.8 9197.4 9267.7 9301.6 9309.9 9335.9 8146.7 8836.3 8959.0 9037.3 9049.1 37927.8 38383.2 38430.2 38433.0 38464.0 3152.3 3192.5 3230.4 3344.4 3548.1 27829.0 27937.3 28629.6 28770.7 29687.9 3000.9 3096.3 3147.1 3164.8 3168.2 457543.0 463420.0 463960.0 466565.0 470075.0 32067.0 32994.0 59038.1 59524.7 59621.7 33660.7 34061.3 34100.7 34206.5 34350.0 13463.5 13474.7 13770.4 13964.1 13966.2 11464.5 11742.4 11854.5 11865.6 11876.9 6335.8 7419.5 7501.9 7525.8 7541.3 2717.8 3038.4 3086.9 3123.5 3144.8 771.0 817.3 864.3 877.7 892.1 26779.0 27606.7 28477.6 28606.7 28652.6 913.6 933.0 949.3 965.5 970.3 14276.6 14547.1 14560.6 14989.4 15079.9 627925.0 633537.0 634731.0 635094.0 637732.0 55798.9 56321.7 56456.6 56467.7 56544.4 1250.3 1268.9 1295.4 1296.4 1303.3 27460.8 27890.7 28040.7 28515.0 28525.6 48953.0 49224.0 49305.0 49322.8 49396.8 538337.0 541356.0 542838.0 543184.0 543271.0 25609.0 25899.0 40908.2 42628.4 42923.0 44771.3 45204.1 45747.5 47059.3 47243.0 36029.0 37419.4 38208.0 38551.4 40098.8 37609.8 37771.0 38278.6 38534.0 38691.6 31810.2 32519.5 32520.7 35158.6 37094.0 28879.6 28994.7 33606.2 34233.0 35092.9 24767.6 24982.4 25034.7 25070.9 25226.1 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:32561.13 Median:357.54 stddev:92348.96 (25828.9%) stddev-sans-first:92576.31 02:44:13 INFO - PROCESS | 1935 | Values: 326.5 334.6 338.7 352.3 362.8 18499.3 18896.7 20189.0 20511.8 20520.5 15446.5 18864.0 19033.4 19849.2 20150.8 5595.5 5915.6 5919.2 5924.7 5927.8 5550.8 5661.6 5763.5 5784.4 5803.5 5735.4 5744.3 5745.9 5747.5 5762.0 5431.7 5706.4 5756.1 5765.4 5781.4 4945.4 5024.3 5034.9 5039.6 5039.9 23227.1 23396.4 23503.4 24244.6 24630.8 2315.6 2318.5 2365.9 2382.0 2398.4 12666.8 15172.6 15189.4 15566.5 15588.5 1759.6 1796.0 1800.6 1809.4 1829.4 108149.0 113470.0 113818.0 114376.0 114556.4 7857.4 8575.2 9191.8 9606.3 9962.9 12040.9 12088.9 12120.8 12133.9 12134.8 6278.5 7379.1 7428.7 7502.9 7540.4 7501.5 7660.7 7703.2 7753.8 7834.3 3173.8 3725.3 3731.5 3732.7 3799.6 1823.2 1836.7 1840.7 1846.5 1852.9 723.0 767.6 771.4 779.0 793.4 19533.7 20463.3 20550.7 20559.3 20755.9 805.2 859.5 873.4 881.0 886.4 9935.6 10854.6 10890.1 10899.8 10905.3 540916.0 546690.0 553140.0 558764.0 563365.0 111869.9 116248.4 122657.0 126034.0 130376.0 859.8 902.6 928.7 928.7 937.6 15429.5 18310.4 18690.9 19004.4 19140.1 27688.9 47609.0 48042.5 48646.3 50767.7 56132.0 70038.6 71456.3 75000.0 75092.0 7446.9 7528.1 7651.5 7885.2 7940.3 8531.9 11478.3 11976.8 12284.9 12506.6 9347.8 10015.5 10492.8 10588.0 10678.5 9918.0 10281.2 10374.7 10515.0 10807.2 10453.3 10665.5 10687.6 10858.0 11202.3 7530.0 7791.7 7824.0 7833.6 7916.5 9667.7 10236.6 10331.6 10331.7 10497.2 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:5034.46 Median:4977.14 stddev:2815.51 (56.6%) stddev-sans-first:2808.52 02:44:13 INFO - PROCESS | 1935 | Values: 1181.7 1334.3 1359.5 1360.2 1394.6 7032.6 7434.3 7629.0 7880.2 7906.5 7717.2 7728.4 7765.0 7781.4 7835.8 2143.4 2182.6 2191.6 2193.5 2195.1 2168.6 2209.1 2213.5 2214.2 2215.3 2139.0 2159.0 2179.0 2184.2 2186.3 2085.1 2150.3 2156.9 2160.8 2167.7 1874.4 2157.0 2189.8 2196.2 2199.5 7785.2 7828.7 7881.5 7887.7 7905.2 4056.3 4703.2 4894.2 4914.5 4917.3 3175.5 3264.7 3266.9 3282.5 3283.2 906.3 934.7 950.5 957.1 958.7 10127.4 10246.0 10273.9 10275.0 10279.7 3485.3 3982.8 4171.6 4189.9 4206.3 7639.8 8116.1 8118.0 8118.6 8126.6 4390.9 4756.9 4760.9 4763.7 4765.8 4009.5 4140.3 4287.0 4390.5 4476.1 3207.4 3208.8 3377.3 3494.6 3504.8 1031.0 1048.4 1060.7 1067.1 1068.9 655.4 757.2 808.8 811.2 824.8 5037.0 5242.4 5267.6 5390.8 5398.5 383.6 384.1 387.4 389.5 397.7 3486.9 3571.7 3581.5 3655.2 3736.3 9129.0 9417.6 9429.0 9500.1 9514.1 9064.5 9300.0 9381.4 9461.0 9472.9 7246.3 7270.7 7289.9 7352.2 7357.6 3915.9 5048.5 5218.6 5262.2 5411.0 8481.6 8789.9 8813.2 8817.4 8857.3 8869.7 8951.7 9008.8 9052.5 9157.1 5804.8 6067.0 6699.8 6862.2 6866.8 7424.9 7560.2 7564.8 7594.0 7612.0 6029.9 6512.7 6635.3 6765.3 6895.8 5396.7 6597.7 6635.9 6645.2 6986.5 6310.9 6324.0 6369.7 6394.1 6398.8 4552.3 4554.8 4607.7 4614.9 4645.5 4361.0 4486.2 4598.1 4613.1 4830.8 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:3199.66 Median:3633.60 stddev:933.70 (25.7%) stddev-sans-first:909.93 02:44:13 INFO - PROCESS | 1935 | Values: 263.1 267.2 272.8 275.3 279.4 3826.7 3842.7 3846.3 3866.8 3898.3 3543.3 3603.1 3647.9 3670.3 3741.9 3039.3 3043.9 3047.3 3059.3 3070.4 2775.9 2848.6 3016.9 3024.9 3053.3 2774.4 2803.9 2820.7 2950.9 2973.5 2893.3 3006.8 3018.5 3020.8 3048.7 2678.8 2702.3 2703.3 2711.3 2723.9 2920.5 3067.6 3255.8 3525.1 3569.6 3393.0 3396.5 3407.5 3420.8 3426.5 3674.9 3742.3 3959.9 3989.2 3997.1 2678.4 2842.0 2849.8 2937.0 2952.5 3579.7 4155.3 4174.0 4180.6 4192.7 2591.7 2948.4 2955.3 2972.5 3017.3 2672.8 2739.5 2940.2 2971.6 2989.2 3372.5 3382.4 3382.9 3399.5 3515.6 3392.3 3758.1 3954.3 4035.4 4050.0 879.5 934.6 947.9 976.3 979.8 574.9 653.3 674.9 684.4 697.8 3568.8 3705.2 3708.4 3714.6 3732.9 4072.9 4086.0 4091.8 4094.2 4113.8 3605.8 3651.5 3694.3 3694.4 3701.5 1676.8 1744.9 1751.3 1947.0 1956.3 3963.0 3967.4 3980.3 4048.6 4055.2 3364.4 3372.6 3883.3 3915.4 3921.9 3956.2 3970.4 4026.2 4026.3 4044.6 3236.4 3653.4 3816.8 3881.9 3898.5 3191.9 3802.9 3818.0 3864.0 3872.8 3933.1 3946.9 3957.5 4020.0 4051.0 2643.9 2665.3 2737.6 2774.4 2781.4 3487.6 3514.3 3586.4 3783.4 3849.2 2950.7 3705.8 3706.8 3744.9 3763.8 3411.5 3504.0 3779.1 3791.7 3797.0 3455.2 3655.8 3686.8 3690.9 3785.5 2965.7 3586.6 3671.7 3728.9 3850.9 3138.6 3619.3 3654.0 3787.3 3940.3 02:44:13 INFO - PROCESS | 1935 | 02:44:13 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:18173.66 Median:497.45 stddev:25534.55 (5133.1%) stddev-sans-first:25572.24 02:44:13 INFO - PROCESS | 1935 | Values: 637.7 642.0 680.3 709.9 727.0 12318.2 13274.6 13364.5 14192.4 14392.0 12246.3 12647.7 12694.0 12807.8 12814.2 763.6 792.2 793.7 796.2 797.2 625.4 645.0 646.2 655.4 791.4 688.7 820.4 840.3 840.9 855.4 761.6 805.5 843.9 844.7 847.2 489.2 596.4 596.8 623.8 626.4 11517.8 12835.6 13337.7 13371.8 13726.9 12983.9 13255.9 13429.2 13452.7 13481.7 10584.4 10737.1 10787.6 10825.8 10867.9 3439.1 3459.1 3500.2 3539.2 3629.3 74531.0 75211.2 81061.8 83075.0 84404.0 10044.7 10113.3 10203.6 10293.1 10515.6 19997.9 20336.6 20339.8 20658.9 20698.9 6665.8 6949.9 7350.7 7485.2 7672.3 4053.9 4084.0 4097.8 4163.8 4310.1 3185.5 3275.9 3285.1 3287.1 3290.3 598.5 615.2 627.7 629.6 632.9 505.7 522.6 542.9 589.5 595.3 8029.4 8457.5 8710.5 8769.6 8881.4 528.8 564.4 576.7 582.1 600.3 3776.4 3912.7 3924.7 4015.3 4059.2 66936.0 78479.2 86197.0 105226.5 108600.0 59932.7 60082.1 62128.1 62377.0 85130.0 79618.6 80938.8 81227.6 82727.0 89728.6 7526.2 7566.2 7627.2 7741.5 7766.0 42312.0 46476.3 51224.5 51737.2 51828.7 61264.0 65757.7 67743.3 67832.7 68689.0 11734.0 11784.3 11889.6 12023.7 12323.3 13102.9 13180.6 13739.9 13767.6 13949.8 9798.4 11797.8 12198.4 12826.8 13087.8 11921.5 12463.0 13168.5 13363.7 13391.1 12067.5 13096.2 13215.9 13219.1 13226.2 10199.2 10684.1 10864.8 11103.8 11125.0 8094.8 8450.2 9332.5 10506.8 10947.9 02:44:13 INFO - PROCESS | 1935 | -------- Summary: end -------- 02:44:13 INFO - PROCESS | 1935 | 02:44:14 INFO - TEST-INFO | 1935: exit 0 02:44:14 INFO - TEST-OK | dromaeo_css | took 1118806ms 02:44:14 INFO - TEST-START | kraken 02:44:14 INFO - operating with platform_type : mac_ 02:44:14 INFO - Initialising browser for kraken test... 02:44:14 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpj7r9fq/profile http://localhost:49729/getInfo.html) 02:44:16 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 02:44:16 INFO - PROCESS | 1938 | colorDepth:24 02:44:16 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 02:44:16 INFO - PROCESS | 1938 | __metrics 02:44:16 INFO - PROCESS | 1938 | 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" 02:44:16 INFO - PROCESS | 1938 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:44:17 INFO - TEST-INFO | 1938: exit 0 02:44:17 INFO - Browser initialized. 02:44:17 INFO - Running cycle 1/1 for kraken test... 02:44:17 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpj7r9fq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:44:30 INFO - PROCESS | 1940 | RSS: Main: 222728192 02:44:30 INFO - PROCESS | 1940 | 02:44:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49729/tests/kraken/driver.html?testName=audio-beat-detection) 02:44:34 INFO - PROCESS | 1940 | RSS: Main: 270823424 02:44:34 INFO - PROCESS | 1940 | 02:44:36 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49729/tests/kraken/driver.html?testName=audio-dft) 02:44:37 INFO - PROCESS | 1940 | RSS: Main: 286461952 02:44:37 INFO - PROCESS | 1940 | 02:44:40 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49729/tests/kraken/driver.html?testName=audio-fft) 02:44:41 INFO - PROCESS | 1940 | RSS: Main: 377167872 02:44:41 INFO - PROCESS | 1940 | 02:44:43 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49729/tests/kraken/driver.html?testName=audio-oscillator) 02:44:44 INFO - PROCESS | 1940 | RSS: Main: 282906624 02:44:44 INFO - PROCESS | 1940 | 02:44:46 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49729/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:44:47 INFO - PROCESS | 1940 | RSS: Main: 284819456 02:44:47 INFO - PROCESS | 1940 | 02:44:49 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49729/tests/kraken/driver.html?testName=imaging-darkroom) 02:44:50 INFO - PROCESS | 1940 | RSS: Main: 324751360 02:44:50 INFO - PROCESS | 1940 | 02:44:53 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49729/tests/kraken/driver.html?testName=imaging-desaturate) 02:44:54 INFO - PROCESS | 1940 | RSS: Main: 327929856 02:44:54 INFO - PROCESS | 1940 | 02:44:57 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49729/tests/kraken/driver.html?testName=json-parse-financial) 02:44:57 INFO - PROCESS | 1940 | RSS: Main: 323411968 02:44:57 INFO - PROCESS | 1940 | 02:44:59 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49729/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:45:00 INFO - PROCESS | 1940 | RSS: Main: 299147264 02:45:00 INFO - PROCESS | 1940 | 02:45:02 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:45:02 INFO - PROCESS | 1940 | RSS: Main: 302530560 02:45:02 INFO - PROCESS | 1940 | 02:45:05 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:45:05 INFO - PROCESS | 1940 | RSS: Main: 304959488 02:45:05 INFO - PROCESS | 1940 | 02:45:08 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:45:09 INFO - PROCESS | 1940 | RSS: Main: 320532480 02:45:09 INFO - PROCESS | 1940 | 02:45:12 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:45:13 INFO - PROCESS | 1940 | RSS: Main: 314589184 02:45:13 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49729/tests/kraken/driver.html?testName=ai-astar) 02:45:15 INFO - PROCESS | 1940 | RSS: Main: 359383040 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | __start_tp_report 02:45:15 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 02:45:15 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 02:45:15 INFO - PROCESS | 1940 | |i|pagename|runs| 02:45:15 INFO - PROCESS | 1940 | |0;ai-astar;114;118;119;118;118;117;117;116;117;117 02:45:15 INFO - PROCESS | 1940 | |1;audio-beat-detection;182;144;123;158;122;123;122;119;146;141 02:45:15 INFO - PROCESS | 1940 | |2;audio-dft;214;212;215;213;213;213;214;215;212;212 02:45:15 INFO - PROCESS | 1940 | |3;audio-fft;87;60;60;92;63;62;62;61;87;61 02:45:15 INFO - PROCESS | 1940 | |4;audio-oscillator;93;73;98;101;70;72;94;70;72;97 02:45:15 INFO - PROCESS | 1940 | |5;imaging-gaussian-blur;91;87;91;87;95;87;91;87;90;87 02:45:15 INFO - PROCESS | 1940 | |6;imaging-darkroom;99;101;112;101;99;102;99;101;99;101 02:45:15 INFO - PROCESS | 1940 | |7;imaging-desaturate;99;105;99;97;100;112;148;100;105;100 02:45:15 INFO - PROCESS | 1940 | |8;json-parse-financial;57;55;53;53;57;59;59;58;57;58 02:45:15 INFO - PROCESS | 1940 | |9;json-stringify-tinderbox;68;75;82;107;109;108;79;80;80;79 02:45:15 INFO - PROCESS | 1940 | |10;stanford-crypto-aes;91;89;90;89;90;89;91;89;90;93 02:45:15 INFO - PROCESS | 1940 | |11;stanford-crypto-ccm;153;158;151;155;172;162;166;154;195;170 02:45:15 INFO - PROCESS | 1940 | |12;stanford-crypto-pbkdf2;176;176;176;173;177;177;181;175;176;178 02:45:15 INFO - PROCESS | 1940 | |13;stanford-crypto-sha256-iterative;73;72;73;81;88;88;73;72;72;70 02:45:15 INFO - PROCESS | 1940 | __end_tp_report 02:45:15 INFO - PROCESS | 1940 | __start_cc_report 02:45:15 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,2111 02:45:15 INFO - PROCESS | 1940 | __end_cc_report 02:45:15 INFO - PROCESS | 1940 | __startTimestamp1481021115392__endTimestamp 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | ------- Summary: start ------- 02:45:15 INFO - PROCESS | 1940 | Number of tests: 14 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#0] ai-astar Cycles:10 Average:117.10 Median:117.50 stddev:1.37 (1.2%) stddev-sans-first:0.88 02:45:15 INFO - PROCESS | 1940 | Values: 114.0 118.0 119.0 118.0 118.0 117.0 117.0 116.0 117.0 117.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:142.50 stddev:20.46 (14.4%) stddev-sans-first:14.22 02:45:15 INFO - PROCESS | 1940 | Values: 182.0 144.0 123.0 158.0 122.0 123.0 122.0 119.0 146.0 141.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#2] audio-dft Cycles:10 Average:213.30 Median:213.50 stddev:1.16 (0.5%) stddev-sans-first:1.20 02:45:15 INFO - PROCESS | 1940 | Values: 214.0 212.0 215.0 213.0 213.0 213.0 214.0 215.0 212.0 212.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#3] audio-fft Cycles:10 Average:69.50 Median:62.50 stddev:13.33 (21.3%) stddev-sans-first:12.54 02:45:15 INFO - PROCESS | 1940 | Values: 87.0 60.0 60.0 92.0 63.0 62.0 62.0 61.0 87.0 61.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:83.00 stddev:13.48 (16.2%) stddev-sans-first:13.90 02:45:15 INFO - PROCESS | 1940 | Values: 93.0 73.0 98.0 101.0 70.0 72.0 94.0 70.0 72.0 97.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.50 stddev:2.75 (3.0%) stddev-sans-first:2.85 02:45:15 INFO - PROCESS | 1940 | Values: 91.0 87.0 91.0 87.0 95.0 87.0 91.0 87.0 90.0 87.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:105.50 stddev:3.89 (3.7%) stddev-sans-first:4.03 02:45:15 INFO - PROCESS | 1940 | Values: 99.0 101.0 112.0 101.0 99.0 102.0 99.0 101.0 99.0 101.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:130.00 stddev:15.23 (11.7%) stddev-sans-first:15.91 02:45:15 INFO - PROCESS | 1940 | Values: 99.0 105.0 99.0 97.0 100.0 112.0 148.0 100.0 105.0 100.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.50 stddev:2.22 (3.9%) stddev-sans-first:2.35 02:45:15 INFO - PROCESS | 1940 | Values: 57.0 55.0 53.0 53.0 57.0 59.0 59.0 58.0 57.0 58.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:86.70 Median:79.00 stddev:15.20 (19.2%) stddev-sans-first:14.54 02:45:15 INFO - PROCESS | 1940 | Values: 68.0 75.0 82.0 107.0 109.0 108.0 79.0 80.0 80.0 79.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:90.00 stddev:1.29 (1.4%) stddev-sans-first:1.32 02:45:15 INFO - PROCESS | 1940 | Values: 91.0 89.0 90.0 89.0 90.0 89.0 91.0 89.0 90.0 93.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:163.60 Median:164.00 stddev:13.23 (8.1%) stddev-sans-first:13.46 02:45:15 INFO - PROCESS | 1940 | Values: 153.0 158.0 151.0 155.0 172.0 162.0 166.0 154.0 195.0 170.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:176.50 stddev:2.07 (1.2%) stddev-sans-first:2.19 02:45:15 INFO - PROCESS | 1940 | Values: 176.0 176.0 176.0 173.0 177.0 177.0 181.0 175.0 176.0 178.0 02:45:15 INFO - PROCESS | 1940 | 02:45:15 INFO - PROCESS | 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:73.00 stddev:6.86 (9.4%) stddev-sans-first:7.18 02:45:15 INFO - PROCESS | 1940 | Values: 73.0 72.0 73.0 81.0 88.0 88.0 73.0 72.0 72.0 70.0 02:45:15 INFO - PROCESS | 1940 | -------- Summary: end -------- 02:45:15 INFO - PROCESS | 1940 | 02:45:16 INFO - TEST-INFO | 1940: exit 0 02:45:16 INFO - TEST-OK | kraken | took 62512ms 02:45:16 INFO - SUITE-END | took 1181s 02:45:16 INFO - Completed test suite (00:19:41) 02:45:16 INFO - javascript benchmark 02:45:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2739.5342791725407, 2769.404578015726, 2769.7784177265817, 2796.2170592234156, 2823.33651591876, 4733.810647574097, 4802.23164293713, 4839.080574690811, 4840.709557426557, 4847.4425441074245, 4772.522747725226, 4799.4480634727015, 4837.443693975194, 4841.709497430156, 4883.853484395465, 4414.205443020247, 4448.28827387618, 4466.0, 4469.709468884512, 4565.680402371835, 4340.631046361063, 4384.408104905845, 4413.757243351614, 4506.436695413074, 4545.250033744428, 4359.193549193409, 4437.979264769105, 4465.4641443026885, 4483.3723278741, 4512.977435112836, 4403.537628549005, 4443.289073748185, 4462.3752674625575, 4495.34817451471, 4508.887277818048, 4441.644668426518, 4447.866564003053, 4450.821967121311, 4469.575390337913, 4483.058557702853, 4611.631069514431, 4615.169269531483, 4717.811287548494, 4726.0, 4761.904761904777, 4413.58070983256, 5151.459096794858, 5196.0647083524955, 5204.739763011872, 5206.114960456732, 5226.346706661668, 5300.204969254604, 5304.946950530522, 5335.973320133375, 5371.355437347544, 4794.760261986957, 4843.491433399513, 4865.172920603506, 4932.827351042696, 4940.6788558743565, 5239.73801309933, 5365.463453654604, 5372.7313634318125, 5417.6478528895495, 5424.1321388577635, 5123.0, 5129.948700513021, 5135.460776618476, 5151.922721159186, 5171.405288391883, 5074.137396642506, 5222.817201397933, 5256.39551451588, 5256.605754568346, 5293.153095504701, 4777.856664300077, 4981.850544483599, 5084.974575127175, 5092.363454568179, 5136.255242989746, 5159.638825282194, 5178.016176926372, 5181.948180518222, 5193.143131383355, 5218.034663587248, 4242.300020496584, 4257.765822879709, 4274.871753847389, 4292.978535107367, 4309.267424537836, 3740.8129593520216, 3796.2217745362686, 3810.1046254130256, 3868.1103346230525, 3880.3209438348176, 4792.448868380111, 4796.5203479652455, 4801.063792560487, 4803.231482962709, 4805.183118869801, 5145.0, 5261.4738526147075, 5281.287026251483, 5290.418054014055, 5298.152295632757, 3992.5208974923194, 4757.666963312604, 4786.7845946932475, 4852.684575502581, 4878.512148785093, 3964.940525892114, 4622.19111655459, 4633.027064316531, 4678.69588476748, 4718.693284936468, 5443.047466693313, 5476.808311709071, 5487.039768040657, 5500.339959204921, 5505.697186654773, 5056.393232811939, 5145.228215767665, 5151.562117220078, 5184.429712731672, 5302.920456193237, 5492.5605951524585, 5493.368262649847, 5495.477929596682, 5517.724113794375, 5525.364583072837, 5049.116404629249, 5203.089459344524, 5209.6874187548865, 5225.529702326657, 5244.737763111905, 5124.871878203075, 5226.216067589892, 5280.313559237275, 5287.788488460573, 5292.629515933848, 5061.215511595708, 5103.770330335218, 5172.353455818023, 5207.843764687065, 5242.082635538842, 5147.613928955268, 5171.482851714798, 5175.301334319819, 5193.844184674465, 5221.738913054408, 4891.584215341736, 5073.34046573943, 5076.695398276115, 5083.38999320084, 5086.694798312114, 5011.198208286657, 5076.82231121909, 5093.592512598909, 5105.923411148761, 5146.9963357145, 5000.324956131021, 5030.245463180552, 5054.494550544916, 5073.441921388718, 5123.462036486116], "value": 4812.990257203115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.97492150470976, 421.8125975379307, 445.25642177563475, 471.16133282756704, 505.67131364612976, 29024.854875725618, 30666.54000189999, 30671.84664076679, 30677.233069173282, 38687.41968870469, 38206.0, 40484.797576012155, 40492.190156196855, 40621.39067913983, 40637.39043914343, 9060.04869488704, 9255.0, 9257.490838003907, 9262.351635385492, 9290.349675522717, 9176.036516165806, 9276.907230927705, 9277.536123193848, 9284.814303713922, 9302.627894884197, 9210.585523651453, 9270.304727145458, 9280.907190928072, 9295.44227346358, 9342.813143737121, 9197.448153110834, 9267.72196834096, 9301.627934882597, 9309.929358123834, 9335.906640933572, 8146.674133034671, 8836.337274704423, 8958.955205223932, 9037.322200834964, 9049.140331668481, 37927.81036094829, 38383.23233535314, 38430.23139537222, 38433.039174020596, 38464.03839903997, 3152.274976755359, 3192.4892017277125, 3230.4346739320526, 3344.3645707315777, 3548.1129717570607, 27829.0, 27937.301567460978, 28629.57055644167, 28770.712292877222, 29687.851560742274, 3000.8799648014055, 3096.349766549027, 3147.134538002045, 3164.841757912095, 3168.239622490609, 457543.0, 463420.0, 463959.9999999966, 466565.0, 470075.0, 32067.03798886037, 32994.0, 59038.11442828361, 59524.70237648784, 59621.701891490266, 33660.66339336575, 34061.318773624385, 34100.658993410245, 34206.48690269648, 34350.0, 13463.461461541428, 13474.730505389838, 13770.380332885044, 13964.092333998258, 13966.231857247745, 11464.4840982156, 11742.412879356169, 11854.525818967144, 11865.644030679094, 11876.881231187577, 6335.841603959846, 7419.4806363554035, 7501.887471687929, 7525.849483010309, 7541.321281084619, 2717.782577393804, 3038.4378889904997, 3086.891958781432, 3123.453395655751, 3144.7798654093995, 770.9614519274014, 817.2807929022422, 864.312871267344, 877.7454538183855, 892.081156408887, 26779.0, 27606.72393276082, 28477.572836407056, 28606.713932860406, 28652.570211446844, 913.5751875377848, 932.990670093304, 949.3449519831295, 965.4834663455018, 970.3207754571828, 14276.643083922778, 14547.127172369692, 14560.635984100481, 14989.400423983137, 15079.869009824088, 627925.0, 633537.0, 634731.0, 635094.0, 637732.0, 55798.884022319326, 56321.71839140778, 56456.58858528487, 56467.71766141225, 56544.43455565392, 1250.2935841249664, 1268.923864568129, 1295.41706232194, 1296.3906963727034, 1303.328785675378, 27460.764265607697, 27890.72109278881, 28040.71959280381, 28515.0, 28525.572116418687, 48953.0, 49224.0, 49305.0, 49322.75338623284, 49396.75301623469, 538337.0, 541356.0, 542838.0, 543184.0, 543271.0, 25609.0, 25899.0, 40908.1818363637, 42628.36057459203, 42923.0, 44771.32843007422, 45204.095918082116, 45747.54252457433, 47059.29411058906, 47243.0, 36029.0, 37419.43870841885, 38208.0, 38551.42172867465, 40098.79950600228, 37609.81195094007, 37771.0, 38278.61721382862, 38534.0, 38691.613083868804, 31810.204744881565, 32519.51220731738, 32520.67479325271, 35158.64841351656, 37094.0, 28879.5668064975, 28994.7100528992, 33606.15984600405, 34232.973010809714, 35092.94721158369, 24767.62848557237, 24982.37544061413, 25034.74965250324, 25070.874645626656, 25226.117085901904], "value": 17700.49351702947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.50697446855247, 334.58845619887535, 338.71209471948833, 352.2778304475824, 362.80408579367145, 18499.260029598816, 18896.71654925177, 20188.99999999998, 20511.846256143115, 20520.486987825312, 15446.536603901874, 18864.0, 19033.428997130075, 19849.20603175875, 20150.798492015074, 5595.468430499106, 5915.615484993483, 5919.230500035011, 5924.7333869975955, 5927.814437112574, 5550.8057217997375, 5661.60368774186, 5763.538916886639, 5784.421557844224, 5803.477687008169, 5735.426457354252, 5744.31068271808, 5745.913811292834, 5747.482726554609, 5762.0, 5431.701256430894, 5706.429357064281, 5756.107803290476, 5765.385692006245, 5781.392953739881, 4945.406551213841, 5024.296598476216, 5034.924476132825, 5039.596832253411, 5039.924401133986, 23227.07091716329, 23396.415089622897, 23503.41241468977, 24244.63633045488, 24630.753692463026, 2315.6063469210108, 2318.5362927414585, 2365.929022129339, 2381.9523609527887, 2398.424378149249, 12666.84663607189, 15172.620684482867, 15189.392424303014, 15566.455174068966, 15588.532344029698, 1759.6128851652616, 1795.9551011224694, 1800.6308706715222, 1809.3576780243088, 1829.350580543901, 108149.0, 113470.0, 113818.0, 114376.0, 114556.42721786421, 7857.4499785014505, 8575.228229459379, 9191.77020574491, 9606.327557070937, 9962.900370996342, 12040.939795300967, 12088.879111208951, 12120.81818772719, 12133.939330303292, 12134.757304853854, 6278.529110316653, 7379.077615298088, 7428.739994100289, 7502.887456688154, 7540.359069479156, 7501.474896757175, 7660.73187438437, 7703.229677032364, 7753.767386978399, 7834.255745704149, 3173.809571425722, 3725.2549490101546, 3731.5149030625844, 3732.720045996561, 3799.601041890617, 1823.2069049963309, 1836.6510363030982, 1840.6502764474708, 1846.510674671213, 1852.9166187521594, 722.9783106506814, 767.5893397032561, 771.4060173666246, 778.9883151752729, 793.3573805217677, 19533.706994395096, 20463.28378506745, 20550.69173962392, 20559.280425185047, 20755.8962205188, 805.20284917932, 859.4542465534431, 873.3886279604302, 880.9515476648846, 886.3883920095113, 9935.602575896883, 10854.611985363208, 10890.074343680875, 10899.83650245247, 10905.34567925927, 540916.0, 546690.0, 553140.0, 558764.0, 563365.0, 111869.88130118758, 116248.41875790567, 122657.0, 126034.0, 130376.0, 859.8022454835423, 902.593832775267, 928.7120992492349, 928.7353104365224, 937.6249500199974, 15429.459968901034, 18310.359137430125, 18690.906545467187, 19004.42986710401, 19140.138693758538, 27688.861555692092, 47609.0, 48042.5195748038, 48646.27030594473, 50767.74616126896, 56132.0, 70038.64980675065, 71456.28543714705, 75000.0, 75092.0, 7446.851062978819, 7528.058992625922, 7651.502652327581, 7885.1720569339395, 7940.28537431634, 8531.93350831146, 11478.253913495597, 11976.820347694966, 12284.877151228731, 12506.624801255977, 9347.766305842408, 10015.499225038573, 10492.842607361048, 10588.0, 10678.519466624177, 9917.950410247902, 10281.177505799667, 10374.68875933693, 10515.001074897873, 10807.243492955418, 10453.26827122094, 10665.520051597541, 10687.572497100029, 10858.022777950022, 11202.327860328407, 7530.0, 7791.727289545065, 7824.0, 7833.5691536966015, 7916.525008499509, 9667.70996870095, 10236.590536378462, 10331.586736530455, 10331.690049298533, 10497.212709047004], "value": 8954.47371234643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1181.722295260613, 1334.3128288931196, 1359.5377571625645, 1360.1974834847438, 1394.616480467872, 7032.64836758162, 7434.293742094499, 7629.008228930238, 7880.172581878906, 7906.4860784049, 7717.151113377524, 7728.381729461644, 7765.029371328584, 7781.377489800817, 7835.843283134334, 2143.3784202581273, 2182.6289530779804, 2191.550732099916, 2193.451637090727, 2195.089038049208, 2168.5879682860204, 2209.0611490116717, 2213.457702862801, 2214.169686367612, 2215.3353993801875, 2139.0, 2159.0, 2178.9564208715815, 2184.1918490158664, 2186.267600353883, 2085.0721428964116, 2150.3011521255653, 2156.9029393677324, 2160.83793715472, 2167.674848772681, 1874.381454120137, 2156.978430215709, 2189.8467107302495, 2196.165457126298, 2199.4611320226486, 7785.221477852226, 7828.686852525892, 7881.4877032993245, 7887.723929662492, 7905.249001344863, 4056.3104272273786, 4703.2004559225015, 4894.192458244385, 4914.459409464956, 4917.286993385939, 3175.523671449278, 3264.689854463822, 3266.934661306784, 3282.4748040313675, 3283.1627934876456, 906.3293163059354, 934.7382732834817, 950.5294879034852, 957.1146689312358, 958.7411398922252, 10127.442990635513, 10246.026627470379, 10273.897261027369, 10275.02387273208, 10279.691609251735, 3485.2506711056735, 3982.8008599570485, 4171.5828417158045, 4189.895252618709, 4206.348016057516, 7639.815828546584, 8116.147804480563, 8117.959410202911, 8118.594070296462, 8126.553039582762, 4390.912181756347, 4756.904861902743, 4760.904781904412, 4763.73799441034, 4765.809367625326, 4009.5389030261895, 4140.254754144283, 4286.957130428718, 4390.495093064274, 4476.104779044204, 3207.3905957868396, 3208.8235147067135, 3377.273886114497, 3494.633063528293, 3504.789712617251, 1030.9841528399165, 1048.4286064095086, 1060.734816295926, 1067.0503252105632, 1068.935863848156, 655.4265018109154, 757.2049348184385, 808.7614153824636, 811.2495941254434, 824.8287431846672, 5037.0, 5242.423333433319, 5267.578593712494, 5390.784368625289, 5398.4871437213405, 383.6336298834606, 384.1184481614701, 387.3975967370765, 389.47809934687655, 397.6917888636272, 3486.930261394758, 3571.74997750155, 3581.4985901973746, 3655.2324011957785, 3736.252749450121, 9129.0, 9417.576209070608, 9428.95285523568, 9500.144986951207, 9514.143727064597, 9064.546772661472, 9300.0, 9381.437113773196, 9461.006594307637, 9472.90527094734, 7246.311600397954, 7270.745523906638, 7289.890651640229, 7352.228016058344, 7357.558546487228, 3915.921681566296, 5048.495150485069, 5218.634695571425, 5262.184361423985, 5410.971915336074, 8481.618327175169, 8789.91210087891, 8813.206811387006, 8817.38278320537, 8857.291416686521, 8869.68956086534, 8951.686690965786, 9008.774780630536, 9052.456852588866, 9157.130072643089, 5804.767809287581, 6066.969665151646, 6699.799006029827, 6862.245153033063, 6866.828329291808, 7424.888626670712, 7560.206178351414, 7564.8487030260185, 7593.97481340012, 7612.0, 6029.854327677727, 6512.706928188148, 6635.303293154342, 6765.2896445872475, 6895.793126206021, 5396.703181325065, 6597.670116494252, 6635.933640663531, 6645.202575690948, 6986.54587451814, 6310.873782524415, 6324.0, 6369.713362898773, 6394.104825324552, 6398.776042838478, 4552.339910712994, 4554.840580579664, 4607.654425918136, 4614.907701845877, 4645.465771436328, 4360.956390436055, 4486.214912390254, 4598.126355992351, 4613.1004454131125, 4830.830920917751], "value": 3978.1776618569097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.13035417943695, 267.2130575455284, 272.7899517371623, 275.2952441749121, 279.43414585464444, 3826.65560099591, 3842.7117966152546, 3846.326892793757, 3866.7873266970305, 3898.25933072716, 3543.3090547343213, 3603.0812142903524, 3647.908802279938, 3670.2659468106417, 3741.9232326059027, 3039.313181184442, 3043.939121217574, 3047.283888286251, 3059.2504836315134, 3070.401271752009, 2775.875085621152, 2848.5584734366207, 3016.8793248270044, 3024.9395012099744, 3053.3359319170995, 2774.3643538638275, 2803.9018634347804, 2820.746132848043, 2950.9262268443354, 2973.50937095379, 2893.2766808297924, 3006.827337338451, 3018.5321275202377, 3020.7885448018646, 3048.71037251463, 2678.7589116979566, 2702.297402675299, 2703.2566044337964, 2711.295063283541, 2723.9182824515096, 2920.532714765627, 3067.6472205696186, 3255.8046517208804, 3525.11872031992, 3569.5538057742783, 3392.9830350848333, 3396.5414669019615, 3407.505911642798, 3420.7776494527775, 3426.4688973208945, 3674.8713795017047, 3742.251549690073, 3959.920801583981, 3989.150469796631, 3997.140614767839, 2678.370582913014, 2841.9715802841915, 2849.7603542458887, 2936.9573801300658, 2952.46855565998, 3579.6778289954027, 4155.252054630135, 4174.039970806701, 4180.602842729936, 4192.67306644812, 2591.714911359749, 2948.380840023576, 2955.2907302247304, 2972.539256415259, 3017.275853795117, 2672.786177105827, 2739.4521095780924, 2940.1767505098605, 2971.6136902202575, 2989.177976056602, 3372.46552818467, 3382.3573521030926, 3382.9323413531592, 3399.473081672345, 3515.6308587598446, 3392.2876195998624, 3758.079270578671, 3954.3277642800795, 4035.354343305116, 4049.987503124219, 879.5162660536807, 934.6214333857641, 947.8815148106487, 976.2873102634974, 979.8236317462926, 574.8850229954026, 653.2911790707041, 674.9021391898148, 684.4114061906756, 697.7871749116466, 3568.8215589220436, 3705.184859330943, 3708.351038568294, 3714.5728241252064, 3732.925341493209, 4072.898177545585, 4085.9989302621047, 4091.7954102294766, 4094.2016306819883, 4113.794310284474, 3605.8016809075752, 3651.5128052709597, 3694.3350196964802, 3694.371956767356, 3701.481792549045, 1676.7743559123978, 1744.930202791874, 1751.30823324786, 1946.9778366357775, 1956.2566224834472, 3962.9894376934276, 3967.444557761916, 3980.3233450312937, 4048.5951404859866, 4055.2497787909333, 3364.411228035084, 3372.6458721833856, 3883.3204189266767, 3915.378275095525, 3921.9019524512114, 3956.22853543562, 3970.4441378207075, 4026.1947610478023, 4026.275270451347, 4044.5550989391145, 3236.4174448598537, 3653.390657033075, 3816.7900765457057, 3881.8835434936996, 3898.5321761388814, 3191.936161276715, 3802.904927376838, 3817.9809100954317, 3863.9806800965816, 3872.786996715208, 3933.131762412831, 3946.9013274668364, 3957.485526881487, 4020.0, 4051.0075031617444, 2643.9471210576066, 2665.280374298967, 2737.61673365725, 2774.3618967637153, 2781.443711257797, 3487.633798451126, 3514.2939936249454, 3586.444101164324, 3783.4135708965155, 3849.2301539692735, 2950.690177531328, 3705.775974901745, 3706.777593344408, 3744.943825842669, 3763.7553559018575, 3411.5053317269353, 3504.001359612498, 3779.1307999160895, 3791.734578579473, 3797.0, 3455.2053027803245, 3655.83548740302, 3686.7787932724123, 3690.8708195214112, 3785.545734511876, 2965.6589492208673, 3586.5696116466193, 3671.7246206534082, 3728.925421491501, 3850.8652197174083, 3138.6390565085308, 3619.2942376236383, 3653.9634603654686, 3787.337215987136, 3940.349842275992], "value": 2914.9293103906093, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.6779726238251, 642.036944583125, 680.3026897430134, 709.8686742952551, 727.0221552012545, 12318.199317044397, 13274.601761947146, 13364.532241371555, 14192.36134373953, 14391.992560520768, 12246.326452045134, 12647.683807904785, 12694.04794640401, 12807.807882881763, 12814.16707913985, 763.5685837501808, 792.2157064506155, 793.6864938349348, 796.1839114907209, 797.1789057270992, 625.3964923848486, 644.9938096569343, 646.1567654211252, 655.403582739707, 791.3748138970207, 688.6625553478784, 820.3806126374578, 840.3193413335218, 840.861257892447, 855.4354126276659, 761.596353932414, 805.5126648377735, 843.8607629741147, 844.6874656377119, 847.174005344784, 489.1879480063114, 596.3618927747312, 596.8179705189915, 623.7910300049489, 626.4174317884366, 11517.769644607144, 12835.614931552069, 13337.73324533504, 13371.799423008662, 13726.93136534321, 12983.87016129836, 13255.86744132556, 13429.194248345131, 13452.730945381136, 13481.662958426117, 10584.417857017861, 10737.087347575465, 10787.5684972601, 10825.83761243582, 10867.945660271727, 3439.07145070832, 3459.135216195951, 3500.177458297298, 3539.203679172191, 3629.3467175908327, 74531.0, 75211.24788752098, 81061.78407323894, 83075.0, 84404.0, 10044.748881278027, 10113.292069555207, 10203.591856325664, 10293.125084367912, 10515.579376824995, 19997.900010499856, 20336.593268134555, 20339.79660203379, 20658.896705516676, 20698.896505517376, 6665.766698165541, 6949.89575156373, 7350.7427240046345, 7485.176630570633, 7672.309492145734, 4053.8986525337104, 4084.0, 4097.7746223957965, 4163.770992595376, 4310.051788606502, 3185.5062465317915, 3275.901722948315, 3285.0965984354493, 3287.112479630486, 3290.341931613687, 598.5211830535554, 615.2248167309133, 627.6736097229507, 629.568745409396, 632.8702615963716, 505.7092172001114, 522.6106550619812, 542.9267048948342, 589.4635881347953, 595.2559300873908, 8029.437939344307, 8457.492550446992, 8710.47737135774, 8769.605367758466, 8881.37830351882, 528.801699362739, 564.4130104691076, 576.6770608459192, 582.0890306670047, 600.3306313460547, 3776.3580191367532, 3912.667423268997, 3924.7056470764805, 4015.3374693175883, 4059.167870586523, 66936.0, 78479.21520784833, 86197.0, 105226.47386763018, 108600.0, 59932.70033649804, 60082.09876851851, 62128.06807897885, 62377.0, 85130.0, 79618.60190699126, 80938.78591821127, 81227.5938620303, 82727.0, 89728.5513572428, 7526.247375262649, 7566.167721550515, 7627.237276272328, 7741.535507869546, 7766.029246344207, 42312.0, 46476.30285545787, 51224.48775512197, 51737.22394164015, 51828.70428239324, 61264.0, 65757.67121164364, 67743.3225667737, 67832.6608366955, 68688.96966545605, 11734.0, 11784.292942423483, 11889.643310700692, 12023.699407514532, 12323.260604363766, 13102.934485327512, 13180.604581862559, 13739.862601373858, 13767.586972390844, 13949.790753138508, 9798.363106398061, 11797.775248137816, 12198.390080496118, 12826.807597885852, 13087.803682944954, 11921.46353414081, 12463.0, 13168.47326106945, 13363.665908352368, 13391.129576577492, 12067.456964436797, 13096.214227146402, 13215.933920330337, 13219.140755850838, 13226.206427614374, 10199.235057370579, 10684.105524230028, 10864.837027444437, 11103.777924441305, 11125.0, 8094.797630059295, 8450.239478446969, 9332.533373331442, 10506.789864202521, 10947.945260273647], "value": 6737.458170671374, "unit": "score"}], "name": "dromaeo_css", "value": 6250.09006873963, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 119.0, 118.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 144.0, 123.0, 158.0, 122.0, 123.0, 122.0, 119.0, 146.0, 141.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 212.0, 215.0, 213.0, 213.0, 213.0, 214.0, 215.0, 212.0, 212.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 60.0, 92.0, 63.0, 62.0, 62.0, 61.0, 87.0, 61.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 73.0, 98.0, 101.0, 70.0, 72.0, 94.0, 70.0, 72.0, 97.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 91.0, 87.0, 95.0, 87.0, 91.0, 87.0, 90.0, 87.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 101.0, 112.0, 101.0, 99.0, 102.0, 99.0, 101.0, 99.0, 101.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 105.0, 99.0, 97.0, 100.0, 112.0, 148.0, 100.0, 105.0, 100.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 53.0, 53.0, 57.0, 59.0, 59.0, 58.0, 57.0, 58.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 82.0, 107.0, 109.0, 108.0, 79.0, 80.0, 80.0, 79.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 89.0, 90.0, 89.0, 91.0, 89.0, 90.0, 93.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 158.0, 151.0, 155.0, 172.0, 162.0, 166.0, 154.0, 195.0, 170.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 176.0, 173.0, 177.0, 177.0, 181.0, 175.0, 176.0, 178.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 81.0, 88.0, 88.0, 73.0, 72.0, 72.0, 70.0], "value": 76.2, "unit": "score"}], "name": "kraken", "value": 1568.7999999999997, "alertThreshold": 2.0}]} 02:45:16 INFO - Return code: 0 02:45:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:45:16 INFO - # TBPL SUCCESS # 02:45:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:16 INFO - 2016-12-06 02:45:16.725 screenresolution[1944:25997] starting screenresolution argv=screenresolution get 02:45:16 INFO - 2016-12-06 02:45:16.728 screenresolution[1944:25997] Display 0: 1600x1200x32@60 02:45:16 INFO - 2016-12-06 02:45:16.735 screenresolution[1945:26000] starting screenresolution argv=screenresolution list 02:45:16 INFO - Available Modes on Display 0 02:45:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:16 INFO - Intel Iris: 02:45:16 INFO - Chipset Model: Intel Iris 02:45:16 INFO - Type: GPU 02:45:16 INFO - Bus: Built-In 02:45:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:16 INFO - Vendor: Intel (0x8086) 02:45:16 INFO - Device ID: 0x0a2e 02:45:16 INFO - Revision ID: 0x0009 02:45:16 INFO - Displays: 02:45:16 INFO - Display: 02:45:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:16 INFO - Display Serial Number: 1600x1200 60 02:45:16 INFO - Main Display: Yes 02:45:16 INFO - Mirror: Off 02:45:16 INFO - Online: Yes 02:45:16 INFO - Rotation: Supported 02:45:16 INFO - Return code: 0 02:45:16 INFO - Running post-action listener: _resource_record_post_action 02:45:16 INFO - [mozharness: 2016-12-06 10:45:16.994630Z] Finished run-tests step (success) 02:45:16 INFO - Running post-run listener: _resource_record_post_run 02:45:17 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 159744; Write bytes: 653433344; Read time: 4; Write time: 1843 02:45:17 INFO - TinderboxPrint: CPU usage
53.8% 02:45:17 INFO - TinderboxPrint: I/O read bytes / time
159,744 / 4 02:45:17 INFO - TinderboxPrint: I/O write bytes / time
653,433,344 / 1,843 02:45:17 INFO - TinderboxPrint: CPU idle
2,244.1 (46.2%) 02:45:17 INFO - TinderboxPrint: CPU system
981.1 (20.2%) 02:45:17 INFO - TinderboxPrint: CPU user
1,632.4 (33.6%) 02:45:17 INFO - TinderboxPrint: Swap in / out
348,172,288 / 0 02:45:17 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 0; Write bytes: 352491008; Read time: 0; Write time: 723 02:45:17 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 49152; Write bytes: 299557888; Read time: 2; Write time: 1113 02:45:17 INFO - Running post-run listener: _upload_blobber_files 02:45:17 INFO - Blob upload gear active. 02:45:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:45:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:45:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:45:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:45:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:45:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:45:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:19 INFO - (blobuploader) - INFO - Done attempting. 02:45:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:45:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:45:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:20 INFO - (blobuploader) - INFO - Done attempting. 02:45:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:45:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:45:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:22 INFO - (blobuploader) - INFO - Done attempting. 02:45:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:45:22 INFO - Return code: 0 02:45:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:45:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:45:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7296e3727bda7a36bbda68e92080999ecd42d7480823624b875c8ab6c00c3a18389861286a805e690f387fa8d7001aedc290ca57085e1fcdb40fc602980928a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6c4cc3734a541f78378b6cf220f99c25ef66ae50b47db6b4bcffcb0ebd2ddd5adb9429ce35711e501616c06684714a6f2179383f5a06434362baf2e8d91dde0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356053e3dc3049d5797c8c656b184b2b1afe75547d1e2e7482065e7034fbad8874e7fb4976c4f5e47d085e846e227d6ce3ffc252ec6ee8ff8d501817ceee45a9"} 02:45:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:45:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:45:22 INFO - Contents: 02:45:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7296e3727bda7a36bbda68e92080999ecd42d7480823624b875c8ab6c00c3a18389861286a805e690f387fa8d7001aedc290ca57085e1fcdb40fc602980928a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6c4cc3734a541f78378b6cf220f99c25ef66ae50b47db6b4bcffcb0ebd2ddd5adb9429ce35711e501616c06684714a6f2179383f5a06434362baf2e8d91dde0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356053e3dc3049d5797c8c656b184b2b1afe75547d1e2e7482065e7034fbad8874e7fb4976c4f5e47d085e846e227d6ce3ffc252ec6ee8ff8d501817ceee45a9"} 02:45:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:45:22 INFO - Copying logs to upload dir... 02:45:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:45:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.001758 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-06 02:45:22.706160) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:22.709355) ========= 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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-Aurora/sha512/d7296e3727bda7a36bbda68e92080999ecd42d7480823624b875c8ab6c00c3a18389861286a805e690f387fa8d7001aedc290ca57085e1fcdb40fc602980928a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6c4cc3734a541f78378b6cf220f99c25ef66ae50b47db6b4bcffcb0ebd2ddd5adb9429ce35711e501616c06684714a6f2179383f5a06434362baf2e8d91dde0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356053e3dc3049d5797c8c656b184b2b1afe75547d1e2e7482065e7034fbad8874e7fb4976c4f5e47d085e846e227d6ce3ffc252ec6ee8ff8d501817ceee45a9"} build_url:https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010987 build_url: 'https://queue.taskcluster.net/v1/task/NJgNhZEMS_m8Z3pITkV_uA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d7296e3727bda7a36bbda68e92080999ecd42d7480823624b875c8ab6c00c3a18389861286a805e690f387fa8d7001aedc290ca57085e1fcdb40fc602980928a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6c4cc3734a541f78378b6cf220f99c25ef66ae50b47db6b4bcffcb0ebd2ddd5adb9429ce35711e501616c06684714a6f2179383f5a06434362baf2e8d91dde0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/356053e3dc3049d5797c8c656b184b2b1afe75547d1e2e7482065e7034fbad8874e7fb4976c4f5e47d085e846e227d6ce3ffc252ec6ee8ff8d501817ceee45a9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:22.741812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:22.742156) ========= 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.J9Z8zZw24w/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.F1tLCc5ofJ/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.004536 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 02:45:22.790874) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 02:45:22.791200) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 02:45:22.791540) ========= ========= Total master_lag: 0.18 =========