builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0129 starttime: 1480632451.62 results: success (0) buildid: 20161201123547 builduid: 0ee35926c2644a0d81e7c9fca739d59b revision: ce9addb78dc104b226dd9198f5c91e0f76df693c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.625304) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.625739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.626016) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.650465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.650781) ========= 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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-01 14:47:31-- 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.20M=0.001s 2016-12-01 14:47:31 (8.20 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138328 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.812214) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.812528) ========= 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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.020929 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:31.861017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 14:47:31.861395) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ce9addb78dc104b226dd9198f5c91e0f76df693c --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 ce9addb78dc104b226dd9198f5c91e0f76df693c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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-01 14:47:32,058 truncating revision to first 12 chars 2016-12-01 14:47:32,058 Setting DEBUG logging. 2016-12-01 14:47:32,058 attempt 1/10 2016-12-01 14:47:32,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ce9addb78dc1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 14:47:32,829 unpacking tar archive at: mozilla-aurora-ce9addb78dc1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018066 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 14:47:32.900826) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:32.901173) ========= script_repo_revision: ce9addb78dc104b226dd9198f5c91e0f76df693c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:32.901553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:32.901814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 14:47:32.921300) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-01 14:47:32.921653) ========= /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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:47:33 INFO - MultiFileLogger online at 20161201 14:47:33 in /builds/slave/test 14:47:33 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 14:47:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:47:33 INFO - {'append_to_log': False, 14:47:33 INFO - 'base_work_dir': '/builds/slave/test', 14:47:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:47:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:47:33 INFO - 'branch': 'Mozilla-Aurora', 14:47:33 INFO - 'buildbot_json_path': 'buildprops.json', 14:47:33 INFO - 'config_files': ('talos/mac_config.py',), 14:47:33 INFO - 'default_actions': ('clobber', 14:47:33 INFO - 'read-buildbot-config', 14:47:33 INFO - 'download-and-extract', 14:47:33 INFO - 'populate-webroot', 14:47:33 INFO - 'create-virtualenv', 14:47:33 INFO - 'install', 14:47:33 INFO - 'run-tests'), 14:47:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:47:33 INFO - 'download_minidump_stackwalk': True, 14:47:33 INFO - 'download_symbols': 'ondemand', 14:47:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:47:33 INFO - 'tooltool.py': '/tools/tooltool.py', 14:47:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:47:33 INFO - '/tools/misc-python/virtualenv.py')}, 14:47:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:47:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:47:33 INFO - 'installer_path': 'installer.exe', 14:47:33 INFO - 'log_level': 'info', 14:47:33 INFO - 'log_name': 'talos', 14:47:33 INFO - 'log_to_console': True, 14:47:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:47:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:47:33 INFO - 'opt_config_files': (), 14:47:33 INFO - 'pip_index': False, 14:47:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:47:33 INFO - 'cmd': ('bash', 14:47:33 INFO - '-c', 14:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:47:33 INFO - 'enabled': True, 14:47:33 INFO - 'halt_on_failure': False, 14:47:33 INFO - 'name': 'check_screen_resolution'},), 14:47:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:47:33 INFO - 'cmd': ('bash', 14:47:33 INFO - '-c', 14:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:47:33 INFO - 'enabled': True, 14:47:33 INFO - 'halt_on_failure': False, 14:47:33 INFO - 'name': 'check_screen_resolution'},), 14:47:33 INFO - 'run_cmd_checks_enabled': True, 14:47:33 INFO - 'sps_profile': False, 14:47:33 INFO - 'sps_profile_interval': 0, 14:47:33 INFO - 'suite': 'dromaeojs', 14:47:33 INFO - 'system_bits': '32', 14:47:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:47:33 INFO - 'title': 't-yosemite-r7-0129', 14:47:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:47:33 INFO - 'use_talos_json': True, 14:47:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:47:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:47:33 INFO - 'work_dir': 'build'} 14:47:33 INFO - [mozharness: 2016-12-01 22:47:33.241769Z] Running clobber step. 14:47:33 INFO - Running pre-action listener: _resource_record_pre_action 14:47:33 INFO - Running main action method: clobber 14:47:33 INFO - rmtree: /builds/slave/test/build 14:47:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:47:34 INFO - Running post-action listener: _resource_record_post_action 14:47:34 INFO - [mozharness: 2016-12-01 22:47:34.895194Z] Finished clobber step (success) 14:47:34 INFO - [mozharness: 2016-12-01 22:47:34.895398Z] Running read-buildbot-config step. 14:47:34 INFO - Running pre-action listener: _resource_record_pre_action 14:47:34 INFO - Running main action method: read_buildbot_config 14:47:34 INFO - Using buildbot properties: 14:47:34 INFO - { 14:47:34 INFO - "project": "", 14:47:34 INFO - "product": "firefox", 14:47:34 INFO - "script_repo_revision": "production", 14:47:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:47:34 INFO - "repository": "", 14:47:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:47:34 INFO - "buildid": "20161201123547", 14:47:34 INFO - "pgo_build": "False", 14:47:34 INFO - "basedir": "/builds/slave/test", 14:47:34 INFO - "buildnumber": 4, 14:47:34 INFO - "slavename": "t-yosemite-r7-0129", 14:47:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:47:34 INFO - "platform": "yosemite_r7", 14:47:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:47:34 INFO - "branch": "mozilla-aurora", 14:47:34 INFO - "repo_path": "releases/mozilla-aurora", 14:47:34 INFO - "revision": "ce9addb78dc104b226dd9198f5c91e0f76df693c", 14:47:34 INFO - "stage_platform": "macosx64", 14:47:34 INFO - "builduid": "0ee35926c2644a0d81e7c9fca739d59b", 14:47:34 INFO - "slavebuilddir": "test" 14:47:34 INFO - } 14:47:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:47:34 INFO - Running post-action listener: _resource_record_post_action 14:47:34 INFO - [mozharness: 2016-12-01 22:47:34.902393Z] Finished read-buildbot-config step (success) 14:47:34 INFO - [mozharness: 2016-12-01 22:47:34.902834Z] Running download-and-extract step. 14:47:34 INFO - Running pre-action listener: _resource_record_pre_action 14:47:34 INFO - Running main action method: download_and_extract 14:47:34 INFO - mkdir: /builds/slave/test/build/tests 14:47: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')]} 14:47:34 INFO - trying https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:47:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/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 14:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/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 14:47:36 INFO - Downloaded 1199 bytes. 14:47:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:47:36 INFO - Using the following test package requirements: 14:47:36 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:47:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:47:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:47:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'jsshell-mac.zip'], 14:47:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:47:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:47:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:47:36 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:47:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:47:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:47:36 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:47:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:47:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:47:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:47:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:47:36 INFO - Http code: 200 14:47:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 14:47:36 INFO - x-amz-cf-id: BdeTHso9T1sID7kvcA69mbxFkXgWXAXYvTEuhBk5Di2SJv72KzJEUg== 14:47:36 INFO - x-amz-version-id: DxMvpkZ.ZrKX9SkqS._UrvFUUW1tJ_Bs 14:47:37 INFO - Expected file size: 16274095 14:47:37 INFO - Obtained file size: 16274095 14:47:43 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 14:47:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:47:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:47:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:47:46 INFO - Http code: 200 14:47:46 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 14:47:46 INFO - x-amz-cf-id: gBP_DrFj5_RRy9-n4aOUmKX2YBSunzatJC6fqHqndhEt7n4kpM8LTA== 14:47:46 INFO - x-amz-version-id: DxMvpkZ.ZrKX9SkqS._UrvFUUW1tJ_Bs 14:47:46 INFO - Expected file size: 16274095 14:47:46 INFO - Obtained file size: 16274095 14:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:47:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:47:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:47:53 INFO - Http code: 200 14:47:53 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 14:47:53 INFO - x-amz-cf-id: 5uH-GuLLy04pXxlQZMe0G_g16lz78HXgT-qTv8PZXZRQvjTtbm5peQ== 14:47:53 INFO - x-amz-version-id: B4Tfruy595dWEKRu0ol074lCIKJlLmo3 14:47:53 INFO - Expected file size: 14409038 14:47:53 INFO - Obtained file size: 14409038 14:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:47:54 INFO - trying https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:47:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:47:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/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 14:47:56 INFO - Downloaded 109780211 bytes. 14:47:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:47:56 INFO - mkdir: /builds/slave/test/properties 14:47:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:47:56 INFO - Writing to file /builds/slave/test/properties/build_url 14:47:56 INFO - Contents: 14:47:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:47:57 INFO - Running post-action listener: _resource_record_post_action 14:47:57 INFO - Running post-action listener: set_extra_try_arguments 14:47:57 INFO - [mozharness: 2016-12-01 22:47:57.665995Z] Finished download-and-extract step (success) 14:47:57 INFO - [mozharness: 2016-12-01 22:47:57.666097Z] Running populate-webroot step. 14:47:57 INFO - Running pre-action listener: _resource_record_pre_action 14:47:57 INFO - Running main action method: populate_webroot 14:47:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:47:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:47:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:47:57 INFO - '--tppagecycles', 14:47:57 INFO - '7'], 14:47:57 INFO - 'tests': ['tsvgm']}}, 14:47:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:47:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:47:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:47:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:47:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:47:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['damp', 'tps']}, 14:47:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'tests': ['damp', 'tps']}, 14:47:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['dromaeo_dom']}, 14:47:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:47:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['basic_compositor_video']}, 14:47:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:47:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'other-e10s': {'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['a11yr', 14:47:57 INFO - 'ts_paint', 14:47:57 INFO - 'tpaint', 14:47:57 INFO - 'sessionrestore', 14:47:57 INFO - 'sessionrestore_no_auto_restore', 14:47:57 INFO - 'tabpaint']}, 14:47:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['tsvgx', 14:47:57 INFO - 'tsvgr_opacity', 14:47:57 INFO - 'tart', 14:47:57 INFO - 'tscrollx', 14:47:57 INFO - 'cart']}, 14:47:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:47:57 INFO - 'tsvgr_opacity', 14:47:57 INFO - 'tart', 14:47:57 INFO - 'tscrollx', 14:47:57 INFO - 'cart']}, 14:47:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'talos_options': ['--disable-e10s'], 14:47:57 INFO - 'tests': ['tp5o']}, 14:47:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'tests': ['tp5o']}, 14:47:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'talos_options': ['--disable-e10s', 14:47:57 INFO - '--xperf_path', 14:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:57 INFO - 'tests': ['tp5n']}, 14:47:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:47:57 INFO - 'talos_options': ['--xperf_path', 14:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:47:57 INFO - 'tests': ['tp5n']}}, 14:47:57 INFO - 'talos.zip': {'path': '', 14:47:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:47:57 INFO - Running post-action listener: _resource_record_post_action 14:47:57 INFO - [mozharness: 2016-12-01 22:47:57.684851Z] Finished populate-webroot step (success) 14:47:57 INFO - [mozharness: 2016-12-01 22:47:57.684979Z] Running create-virtualenv step. 14:47:57 INFO - Running pre-action listener: _resource_record_pre_action 14:47:57 INFO - Running main action method: create_virtualenv 14:47:57 INFO - Creating virtualenv /builds/slave/test/build/venv 14:47:57 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 14:47:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:47:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:47:57 INFO - Using real prefix '/tools/python27' 14:47:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:47:58 INFO - Installing distribute.............................................................................................................................................................................................done. 14:48:01 INFO - Installing pip.................done. 14:48:01 INFO - Return code: 0 14:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:48:01 INFO - Reading from file tmpfile_stdout 14:48:01 INFO - Output received: 14:48:01 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) 14:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:48: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')]} 14:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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')]} 14:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48: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', 'pip>=1.5'] in /builds/slave/test/build 14:48: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 pip>=1.5 14:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:01 INFO - 'HOME': '/Users/cltbld', 14:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:01 INFO - 'LOGNAME': 'cltbld', 14:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:01 INFO - 'MOZ_NO_REMOTE': '1', 14:48:01 INFO - 'NO_EM_RESTART': '1', 14:48:01 INFO - 'PAGER': '/bin/cat', 14:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:01 INFO - 'PWD': '/builds/slave/test', 14:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:01 INFO - 'SHELL': '/bin/bash', 14:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:01 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:01 INFO - 'USER': 'cltbld', 14:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:01 INFO - 'XPC_FLAGS': '0x0', 14:48:01 INFO - 'XPC_SERVICE_NAME': '0', 14:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:01 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 14:48:01 INFO - Cleaning up... 14:48:01 INFO - Return code: 0 14:48:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:48: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')]} 14:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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')]} 14:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48: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', '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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:48: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 psutil>=3.1.1 14:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:01 INFO - 'HOME': '/Users/cltbld', 14:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:01 INFO - 'LOGNAME': 'cltbld', 14:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:01 INFO - 'MOZ_NO_REMOTE': '1', 14:48:01 INFO - 'NO_EM_RESTART': '1', 14:48:01 INFO - 'PAGER': '/bin/cat', 14:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:01 INFO - 'PWD': '/builds/slave/test', 14:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:01 INFO - 'SHELL': '/bin/bash', 14:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:01 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:01 INFO - 'USER': 'cltbld', 14:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:01 INFO - 'XPC_FLAGS': '0x0', 14:48:01 INFO - 'XPC_SERVICE_NAME': '0', 14:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:02 INFO - Downloading/unpacking psutil>=3.1.1 14:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:05 INFO - Installing collected packages: psutil 14:48:05 INFO - Running setup.py install for psutil 14:48:05 INFO - building 'psutil._psutil_osx' extension 14:48:05 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 14:48:06 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 14:48:06 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 14:48:06 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 14:48:06 INFO - building 'psutil._psutil_posix' extension 14:48:06 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 14:48:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:48:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:48:06 INFO - ^ 14:48:06 INFO - 1 warning generated. 14:48:06 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 14:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:06 INFO - Successfully installed psutil 14:48:06 INFO - Cleaning up... 14:48:06 INFO - Return code: 0 14:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:48:06 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')]} 14:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:06 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')]} 14:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:06 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:06 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 14:48:06 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 14:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:06 INFO - 'HOME': '/Users/cltbld', 14:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:06 INFO - 'LOGNAME': 'cltbld', 14:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:06 INFO - 'MOZ_NO_REMOTE': '1', 14:48:06 INFO - 'NO_EM_RESTART': '1', 14:48:06 INFO - 'PAGER': '/bin/cat', 14:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:06 INFO - 'PWD': '/builds/slave/test', 14:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:06 INFO - 'SHELL': '/bin/bash', 14:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:06 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:06 INFO - 'USER': 'cltbld', 14:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:06 INFO - 'XPC_FLAGS': '0x0', 14:48:06 INFO - 'XPC_SERVICE_NAME': '0', 14:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:48:06 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 14:48:06 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 14:48:06 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 14:48:06 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 14:48:06 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 14:48:06 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 14:48:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:48:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:48:09 INFO - Installing collected packages: mozsystemmonitor 14:48:09 INFO - Running setup.py install for mozsystemmonitor 14:48:09 INFO - Successfully installed mozsystemmonitor 14:48:09 INFO - Cleaning up... 14:48:09 INFO - Return code: 0 14:48:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:09 INFO - 'HOME': '/Users/cltbld', 14:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:09 INFO - 'LOGNAME': 'cltbld', 14:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:09 INFO - 'MOZ_NO_REMOTE': '1', 14:48:09 INFO - 'NO_EM_RESTART': '1', 14:48:09 INFO - 'PAGER': '/bin/cat', 14:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:09 INFO - 'PWD': '/builds/slave/test', 14:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:09 INFO - 'SHELL': '/bin/bash', 14:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:09 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:09 INFO - 'USER': 'cltbld', 14:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:09 INFO - 'XPC_FLAGS': '0x0', 14:48:09 INFO - 'XPC_SERVICE_NAME': '0', 14:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 14:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:48:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:48:12 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 14:48:12 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 14:48:12 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 14:48:12 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 14:48:12 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 14:48:12 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 14:48:12 INFO - Downloading functools32-3.2.3-2.tar.gz 14:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:48:13 INFO - warning: no files found matching '*.txt' 14:48:13 INFO - no previously-included directories found matching 'build' 14:48:13 INFO - no previously-included directories found matching 'dist' 14:48:13 INFO - no previously-included directories found matching '.git*' 14:48:13 INFO - Installing collected packages: jsonschema, functools32 14:48:13 INFO - Running setup.py install for functools32 14:48:13 INFO - warning: no files found matching '*.txt' 14:48:13 INFO - no previously-included directories found matching 'build' 14:48:13 INFO - no previously-included directories found matching 'dist' 14:48:13 INFO - no previously-included directories found matching '.git*' 14:48:13 INFO - Successfully installed jsonschema functools32 14:48:13 INFO - Cleaning up... 14:48:13 INFO - Return code: 0 14:48:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:13 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:13 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 14:48:13 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 14:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:13 INFO - 'HOME': '/Users/cltbld', 14:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:13 INFO - 'LOGNAME': 'cltbld', 14:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:13 INFO - 'MOZ_NO_REMOTE': '1', 14:48:13 INFO - 'NO_EM_RESTART': '1', 14:48:13 INFO - 'PAGER': '/bin/cat', 14:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:13 INFO - 'PWD': '/builds/slave/test', 14:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:13 INFO - 'SHELL': '/bin/bash', 14:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:13 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:13 INFO - 'USER': 'cltbld', 14:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:13 INFO - 'XPC_FLAGS': '0x0', 14:48:13 INFO - 'XPC_SERVICE_NAME': '0', 14:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:48:13 INFO - Cleaning up... 14:48:13 INFO - Return code: 0 14:48:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:13 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:13 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 14:48:13 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 14:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:13 INFO - 'HOME': '/Users/cltbld', 14:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:13 INFO - 'LOGNAME': 'cltbld', 14:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:13 INFO - 'MOZ_NO_REMOTE': '1', 14:48:13 INFO - 'NO_EM_RESTART': '1', 14:48:13 INFO - 'PAGER': '/bin/cat', 14:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:13 INFO - 'PWD': '/builds/slave/test', 14:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:13 INFO - 'SHELL': '/bin/bash', 14:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:13 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:13 INFO - 'USER': 'cltbld', 14:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:13 INFO - 'XPC_FLAGS': '0x0', 14:48:13 INFO - 'XPC_SERVICE_NAME': '0', 14:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:13 INFO - Downloading/unpacking blobuploader==1.2.4 14:48:13 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 14:48:13 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 14:48:13 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 14:48:13 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 14:48:13 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 14:48:13 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 14:48:16 INFO - Downloading blobuploader-1.2.4.tar.gz 14:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:48:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:48:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:48:17 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 14:48:17 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 14:48:17 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 14:48:17 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 14:48:17 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 14:48:17 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 14:48:17 INFO - Downloading docopt-0.6.1.tar.gz 14:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:48:17 INFO - Installing collected packages: blobuploader, requests, docopt 14:48:17 INFO - Running setup.py install for blobuploader 14:48:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:48:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:48:17 INFO - Running setup.py install for requests 14:48:18 INFO - Running setup.py install for docopt 14:48:18 INFO - Successfully installed blobuploader requests docopt 14:48:18 INFO - Cleaning up... 14:48:18 INFO - Return code: 0 14:48:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:18 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:18 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 14:48:18 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 14:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:18 INFO - 'HOME': '/Users/cltbld', 14:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:18 INFO - 'LOGNAME': 'cltbld', 14:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:18 INFO - 'MOZ_NO_REMOTE': '1', 14:48:18 INFO - 'NO_EM_RESTART': '1', 14:48:18 INFO - 'PAGER': '/bin/cat', 14:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:18 INFO - 'PWD': '/builds/slave/test', 14:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:18 INFO - 'SHELL': '/bin/bash', 14:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:18 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:18 INFO - 'USER': 'cltbld', 14:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:18 INFO - 'XPC_FLAGS': '0x0', 14:48:18 INFO - 'XPC_SERVICE_NAME': '0', 14:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:18 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Fka7cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-osu8c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-VklrHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Oes81u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-offcYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-MH5Ggr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-MJW2xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-xsVIrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-tgTRCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-dMI37J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:19 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-zlYCQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-YMMfiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-XGBKbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-7Tlajq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-81j_5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-18aqcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:20 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-fQL19R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:48:20 INFO - Running setup.py install for manifestparser 14:48:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:48:20 INFO - Running setup.py install for mozcrash 14:48:20 INFO - Running setup.py install for mozdebug 14:48:21 INFO - Running setup.py install for mozdevice 14:48:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Running setup.py install for mozfile 14:48:21 INFO - Running setup.py install for mozhttpd 14:48:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Running setup.py install for mozinfo 14:48:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Running setup.py install for mozInstall 14:48:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:48:21 INFO - Running setup.py install for mozleak 14:48:21 INFO - Running setup.py install for mozlog 14:48:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Running setup.py install for moznetwork 14:48:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Running setup.py install for mozprocess 14:48:22 INFO - Running setup.py install for mozprofile 14:48:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Running setup.py install for mozrunner 14:48:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:48:22 INFO - Running setup.py install for mozscreenshot 14:48:22 INFO - Running setup.py install for moztest 14:48:23 INFO - Running setup.py install for mozversion 14:48:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:48:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:48:23 INFO - Cleaning up... 14:48:23 INFO - Return code: 0 14:48:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:23 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:23 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 14:48:23 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 14:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:23 INFO - 'HOME': '/Users/cltbld', 14:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:23 INFO - 'LOGNAME': 'cltbld', 14:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:23 INFO - 'MOZ_NO_REMOTE': '1', 14:48:23 INFO - 'NO_EM_RESTART': '1', 14:48:23 INFO - 'PAGER': '/bin/cat', 14:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:23 INFO - 'PWD': '/builds/slave/test', 14:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:23 INFO - 'SHELL': '/bin/bash', 14:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:23 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:23 INFO - 'USER': 'cltbld', 14:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:23 INFO - 'XPC_FLAGS': '0x0', 14:48:23 INFO - 'XPC_SERVICE_NAME': '0', 14:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:48:23 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-CXPzf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:48:23 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)) 14:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:48:23 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-_6VEK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:48:23 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)) 14:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:48:23 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-2YP71w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:48:23 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)) 14:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:48:23 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-GTzPnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:48:23 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)) 14:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:48:23 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Mx77Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-sH1u4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-4rDd5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Ff32mO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-NcZr9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-6hJVXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Uem6w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-OulpnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-w9E1f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:48:24 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)) 14:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:48:24 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ZDcOha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:48:25 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)) 14:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:25 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-dgEo8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:48:25 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)) 14:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:48:25 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-U1T6w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:48:25 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)) 14:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:48:25 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TL4BHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:48:25 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)) 14:48:25 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)) 14:48:25 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)) 14:48:25 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)) 14:48:25 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)) 14:48:25 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)) 14:48:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:48:25 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 14:48:25 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 14:48:25 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 14:48:25 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 14:48:25 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 14:48:25 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 14:48:27 INFO - Downloading blessings-1.6.tar.gz 14:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:48:27 INFO - Installing collected packages: blessings 14:48:27 INFO - Running setup.py install for blessings 14:48:28 INFO - Successfully installed blessings 14:48:28 INFO - Cleaning up... 14:48:28 INFO - Return code: 0 14:48:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:48:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:28 INFO - Reading from file tmpfile_stdout 14:48:28 INFO - Current package versions: 14:48:28 INFO - blessings == 1.6 14:48:28 INFO - blobuploader == 1.2.4 14:48:28 INFO - docopt == 0.6.1 14:48:28 INFO - functools32 == 3.2.3-2 14:48:28 INFO - jsonschema == 2.5.1 14:48:28 INFO - manifestparser == 1.1 14:48:28 INFO - mozInstall == 1.12 14:48:28 INFO - mozcrash == 1.0 14:48:28 INFO - mozdebug == 0.1 14:48:28 INFO - mozdevice == 0.48 14:48:28 INFO - mozfile == 1.2 14:48:28 INFO - mozhttpd == 0.7 14:48:28 INFO - mozinfo == 0.9 14:48:28 INFO - mozleak == 0.1 14:48:28 INFO - mozlog == 3.4 14:48:28 INFO - moznetwork == 0.27 14:48:28 INFO - mozprocess == 0.23 14:48:28 INFO - mozprofile == 0.28 14:48:28 INFO - mozrunner == 6.13 14:48:28 INFO - mozscreenshot == 0.1 14:48:28 INFO - mozsystemmonitor == 0.3 14:48:28 INFO - moztest == 0.7 14:48:28 INFO - mozversion == 1.4 14:48:28 INFO - psutil == 3.1.1 14:48:28 INFO - requests == 1.2.3 14:48:28 INFO - wsgiref == 0.1.2 14:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:29 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:29 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 14:48:29 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 14:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:29 INFO - 'HOME': '/Users/cltbld', 14:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:29 INFO - 'LOGNAME': 'cltbld', 14:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:29 INFO - 'MOZ_NO_REMOTE': '1', 14:48:29 INFO - 'NO_EM_RESTART': '1', 14:48:29 INFO - 'PAGER': '/bin/cat', 14:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:29 INFO - 'PWD': '/builds/slave/test', 14:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:29 INFO - 'SHELL': '/bin/bash', 14:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:29 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:29 INFO - 'USER': 'cltbld', 14:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:29 INFO - 'XPC_FLAGS': '0x0', 14:48:29 INFO - 'XPC_SERVICE_NAME': '0', 14:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 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)) 14:48:29 INFO - Cleaning up... 14:48:29 INFO - Return code: 0 14:48:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:48:29 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')]} 14:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:29 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')]} 14:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:29 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 0x10f21b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f191570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc72bdc9920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, '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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 14:48:29 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 14:48:29 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 14:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:29 INFO - 'HOME': '/Users/cltbld', 14:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:29 INFO - 'LOGNAME': 'cltbld', 14:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:29 INFO - 'MOZ_NO_REMOTE': '1', 14:48:29 INFO - 'NO_EM_RESTART': '1', 14:48:29 INFO - 'PAGER': '/bin/cat', 14:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:29 INFO - 'PWD': '/builds/slave/test', 14:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:29 INFO - 'SHELL': '/bin/bash', 14:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:48:29 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:29 INFO - 'USER': 'cltbld', 14:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:30 INFO - 'XPC_FLAGS': '0x0', 14:48:30 INFO - 'XPC_SERVICE_NAME': '0', 14:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:48:30 INFO - Cleaning up... 14:48:30 INFO - Return code: 0 14:48:30 INFO - Running post-action listener: _resource_record_post_action 14:48:30 INFO - Running post-action listener: _start_resource_monitoring 14:48:30 INFO - Starting resource monitoring. 14:48:30 INFO - [mozharness: 2016-12-01 22:48:30.290759Z] Finished create-virtualenv step (success) 14:48:30 INFO - [mozharness: 2016-12-01 22:48:30.291769Z] Running install step. 14:48:30 INFO - Running pre-action listener: _resource_record_pre_action 14:48:30 INFO - Running main action method: install 14:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:48:30 INFO - Reading from file tmpfile_stdout 14:48:30 INFO - Detecting whether we're running mozinstall >=1.0... 14:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:48:30 INFO - Reading from file tmpfile_stdout 14:48:30 INFO - Output received: 14:48:30 INFO - Usage: mozinstall [options] installer 14:48:30 INFO - Options: 14:48:30 INFO - -h, --help show this help message and exit 14:48:30 INFO - -d DEST, --destination=DEST 14:48:30 INFO - Directory to install application into. [default: 14:48:30 INFO - "/builds/slave/test"] 14:48:30 INFO - --app=APP Application being installed. [default: firefox] 14:48:30 INFO - mkdir: /builds/slave/test/build/application 14:48:30 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'] 14:48:30 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 14:49:02 INFO - Reading from file tmpfile_stdout 14:49:02 INFO - Output received: 14:49:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:49:02 INFO - Running post-action listener: _resource_record_post_action 14:49:02 INFO - [mozharness: 2016-12-01 22:49:02.354291Z] Finished install step (success) 14:49:02 INFO - [mozharness: 2016-12-01 22:49:02.354435Z] Running run-tests step. 14:49:02 INFO - Running pre-action listener: _resource_record_pre_action 14:49:02 INFO - Running main action method: run_tests 14:49:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:49:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:49:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:49:02 INFO - 2016-12-01 14:49:02.369 screenresolution[1899:16664] starting screenresolution argv=screenresolution get 14:49:02 INFO - 2016-12-01 14:49:02.372 screenresolution[1899:16664] Display 0: 1600x1200x32@60 14:49:02 INFO - 2016-12-01 14:49:02.378 screenresolution[1900:16667] starting screenresolution argv=screenresolution list 14:49:02 INFO - Available Modes on Display 0 14:49:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:49:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:49:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:49:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:49:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:49:02 INFO - Intel Iris: 14:49:02 INFO - Chipset Model: Intel Iris 14:49:02 INFO - Type: GPU 14:49:02 INFO - Bus: Built-In 14:49:02 INFO - VRAM (Dynamic, Max): 1536 MB 14:49:02 INFO - Vendor: Intel (0x8086) 14:49:02 INFO - Device ID: 0x0a2e 14:49:02 INFO - Revision ID: 0x0009 14:49:02 INFO - Displays: 14:49:02 INFO - Display: 14:49:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:49:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:49:02 INFO - Display Serial Number: 1600x1200 60 14:49:02 INFO - Main Display: Yes 14:49:02 INFO - Mirror: Off 14:49:02 INFO - Online: Yes 14:49:02 INFO - Rotation: Supported 14:49:02 INFO - Return code: 0 14:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:49:02 INFO - Python 2.7.3 14:49:02 INFO - Return code: 0 14:49:02 INFO - grabbing minidump binary from tooltool 14:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:02 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 0x10f02fd30>, '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 0x10ee95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f027b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:49:02 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 14:49:02 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 14:49:02 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 14:49:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:49:02 INFO - Return code: 0 14:49:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:49:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:49:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:49:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:49:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:49:02 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/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 14:49:02 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/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 14:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YDfEB5wjhL/Render', 14:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:02 INFO - 'HOME': '/Users/cltbld', 14:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:02 INFO - 'LOGNAME': 'cltbld', 14:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:02 INFO - 'MOZ_NO_REMOTE': '1', 14:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:49:02 INFO - 'NO_EM_RESTART': '1', 14:49:02 INFO - 'PAGER': '/bin/cat', 14:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:02 INFO - 'PWD': '/builds/slave/test', 14:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:02 INFO - 'SHELL': '/bin/bash', 14:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MnCRlc1jI4/Listeners', 14:49:02 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 14:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:02 INFO - 'USER': 'cltbld', 14:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:02 INFO - 'XPC_FLAGS': '0x0', 14:49:02 INFO - 'XPC_SERVICE_NAME': '0', 14:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:02 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/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 14:49:02 INFO - mozversion application_buildid: 20161201123547 14:49:02 INFO - mozversion application_changeset: ce9addb78dc104b226dd9198f5c91e0f76df693c 14:49:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:49:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:49:02 INFO - mozversion application_name: Firefox 14:49:02 INFO - mozversion application_remotingname: firefox-dev 14:49:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:49:02 INFO - mozversion application_vendor: Mozilla 14:49:02 INFO - mozversion application_version: 52.0a2 14:49:02 INFO - mozversion platform_buildid: 20161201123547 14:49:02 INFO - mozversion platform_changeset: ce9addb78dc104b226dd9198f5c91e0f76df693c 14:49:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:49:02 INFO - mozversion platform_version: 52.0a2 14:49:02 INFO - using testdate: 1480632542 14:49:02 INFO - actual date: 1480632542 14:49:02 INFO - starting webserver on 'localhost:53497' 14:49:02 INFO - SUITE-START | Running 2 tests 14:49:02 INFO - TEST-START | dromaeo_css 14:49:02 INFO - operating with platform_type : mac_ 14:49:02 INFO - Initialising browser for dromaeo_css test... 14:49:02 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpGw7gQ0/profile http://localhost:53497/getInfo.html) 14:49:05 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 14:49:05 INFO - PROCESS | 1910 | colorDepth:24 14:49:05 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 14:49:05 INFO - PROCESS | 1910 | __metrics 14:49:05 INFO - PROCESS | 1910 | 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" 14:49:05 INFO - PROCESS | 1910 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:06 INFO - TEST-INFO | 1910: exit 0 14:49:06 INFO - Browser initialized. 14:49:06 INFO - Running cycle 1/1 for dromaeo_css test... 14:49:06 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpGw7gQ0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:19 INFO - PROCESS | 1912 | RSS: Main: 222011392 14:49:19 INFO - PROCESS | 1912 | 14:50:09 INFO - PROCESS | 1912 | 1480632609999 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] 14:52:24 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53497/tests/dromaeo/cssquery-ext.html) 14:52:27 INFO - PROCESS | 1912 | RSS: Main: 395108352 14:52:27 INFO - PROCESS | 1912 | 14:55:30 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-ext.html (next: http://localhost:53497/tests/dromaeo/cssquery-jquery.html) 14:55:30 INFO - PROCESS | 1912 | RSS: Main: 348479488 14:55:30 INFO - PROCESS | 1912 | 14:58:33 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53497/tests/dromaeo/cssquery-mootools.html) 14:58:33 INFO - PROCESS | 1912 | RSS: Main: 344313856 14:58:33 INFO - PROCESS | 1912 | 15:01:36 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53497/tests/dromaeo/cssquery-prototype.html) 15:01:36 INFO - PROCESS | 1912 | RSS: Main: 329306112 15:01:36 INFO - PROCESS | 1912 | 15:04:40 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53497/tests/dromaeo/cssquery-yui.html) 15:04:40 INFO - PROCESS | 1912 | RSS: Main: 317587456 15:04:40 INFO - PROCESS | 1912 | 15:07:43 INFO - PROCESS | 1912 | Cycle 1(1): loaded http://localhost:53497/tests/dromaeo/cssquery-yui.html (next: http://localhost:53497/tests/dromaeo/cssquery-dojo.html) 15:07:44 INFO - PROCESS | 1912 | RSS: Main: 363057152 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | __start_tp_report 15:07:44 INFO - PROCESS | 1912 | _x_x_mozilla_page_load 15:07:44 INFO - PROCESS | 1912 | _x_x_mozilla_page_load_details 15:07:44 INFO - PROCESS | 1912 | |i|pagename|runs| 15:07:44 INFO - PROCESS | 1912 | |0;dojo.html;3293.5883014623173;3311.321179158272;3320.352531256406;3350.6146793118783;3395.9490607640873;5655.462731040545;5757.740901659425;5813.44772246637;5814.2441482607255;5867;5748.511376533;5762.423757624236;5842.036064049426;5842.795502157425;5877;5225.869353266161;5265.921011184835;5268.8155914542995;5289.471052894718;5322.175062865262;5204.531592156705;5211.166213405856;5215.817446389377;5336.119540275849;5341.225522299264;5269.314989051437;5309.097453432906;5312.787488500475;5315.707636080014;5320.17537281722;5282.947170528303;5283.6037297202865;5288.65623734456;5291.941611677641;5292.259083728281;5153.433122356538;5243.318368612094;5247.790088396459;5251.579873610102;5267.499587539216;5866.413358664142;5883.323417806964;5885.234919460443;5894.616849904743;5896.439838215363;6182.721777520022;6187.041008643668;6198.566100372976;6203.2245969253845;6222.004479283293;6179.907301390483;6203.627782333029;6270.498360131224;6272.6236425814595;6276.027215781515;5809.7095145242565;5825.592208545403;5846.356900740915;5869.853253668693;5891.882162356729;6189.659568723673;6272.372762723737;6278.6860656966965;6281.7173227203975;6287.9685601572155;6045.183900173508;6111.205543279345;6113.633182009094;6116.694165291718;6128.2033335666965;6194.5044396449075;6243.625382477066;6244.469220116341;6250.093736408196;6258.249010118815;6268.09112678659;6280.466160376328;6283.340249273851;6289.8742025159245;6292.748290068437;6116.579253789288;6133.89764526209;6165.198524191826;6197.411245931629;6267.749290028439;4890.902181956341;4910.950890491049;4915.410150781858;4938.111139994764;4939.950600493949;4435.090806384684;4447.822087116544;4449.599536041707;4456.46522417312;4479.484859241164;5720.170575266647;5840.59115861894;5940.161173325855;5941.7623295067715;6002.309734380515;6162.537809664203;6256;6262.3111457739615;6272.435480806749;6292.1505596743855;5964.1650168976375;5965.224520812267;5983.521318294526;6015.849603759941;6022.217111775529;5435.504572036458;5477.534409575231;5478.45215478449;5482.0406428874785;5494.532964697965;6184.443400094013;6311.715972781144;6352;6374.139491168633;6394.104825324365;6169.167162433014;6200.441960223601;6209.906851397323;6224.128621993015;6269.592476489013;6348.111264422892;6358.713857876499;6371.617702937839;6371.74513019474;6373.107764913008;6187.443130118311;6273.686315684289;6275.6862156892885;6277.686115694106;6300.527460440394;4541.68208225421;6127.861529871452;6128.19758601264;6145.955187618205;6153.446189842935;6190.350013248544;6197.9690101551005;6209.658468784261;6224.657643829632;6242.563020588515;6168.228971378578;6192.009278515416;6200.224971878515;6203.286622038523;6205.875882482234;5888.263967004124;5892;6003.159557661844;6004.819855404345;6095.451409373178;6140.723667435065;6192.690365481618;6201.255849297932;6220.7511699533325;6231.314555398993;5754.741036653444;5793.36273009977;5794.043982742969;6031.306399763907;6063.090536419572 15:07:44 INFO - PROCESS | 1912 | |1;ext.html;464.5192226046043;495.5515258690884;500.7846625950844;523.3301374240973;552.8175702018332;27294;38422.615773842255;39167.60832391682;39348.01629959249;41226.58773412265;39716.40425393621;40036.79889603309;40181.79909100458;40732.389014164735;40793;7995.200479952002;9396.389234699722;9401.57692903821;9404.717858464222;9408.482533460658;9303.860442093373;9404.29467789915;9406.482643454607;9414.058594140566;9427.151556359926;9368.531573421336;9412.62349506019;9423.199028082621;9427.717168484956;9437.716868493957;9345.953270233673;9389.671361502315;9413.811723765555;9422.905770942272;9427.764305892339;7923.207679232089;9300.627974880997;9300.767480812967;9304.674336398193;9313.674021409219;38251.85244442671;38519;38550.42174367387;38558.61441385578;38611.61388386136;3307.96692033079;3467.9653203467897;3590.8025058621765;3718.219173973469;3882.650561449483;28833.279168020967;29178.97873574436;29776.8511157445;30128.355074231098;30407.23981900448;3095.1797773590015;3118.98440507796;3154.7160755532113;3213.742900567949;3275.836208189605;485519;491660;492659;493331;498882;58572.70713646404;58923;58996.41003589995;58998;59058.70470647705;34435;35118.82440587781;35189.472157917655;61461;61539;15509.457168999032;15808.628705694007;15916.602084947737;15932.964357316738;15943.760843587355;12181.33002684843;12259.816102758467;12260.141790074787;12264;12290.631281061582;18893.622127557373;18922.24311027571;18929.81070189308;18950.620987580172;18963.525911852314;7494.775156745306;7610.200928902496;7612.439449912755;7655.46411751172;7671.232876712284;868.5309932636432;967.6419724701765;979.1725991537139;1039.417925961464;1105.6130354375905;29548.96578619781;29549.556756648668;29953;30145.397092058476;30248.243793905327;1030.0883717909553;1045.816982028142;1048.905598496139;1093.7156339351825;1099.8680158380885;14875.404983800527;15311.081335119929;15335.463258785889;15356.846431535765;15790.842091579167;680636.5968170128;684616;686376;687854;689587;56384.43615563874;56891;56939.715301424054;56973.86052278931;56989.71505142531;1238.13949305232;1287.5622288421982;1345.9865401345862;1377.5660666890099;1392.3455975691409;29181.854090729412;30240.84879575588;30323.69676303297;30402.695973039987;30565.493937587493;47887.521124788305;48509.75745121393;48519.757401212766;48537;48674.756626216644;543093;545911.2704436452;546006;546093;547469;23266.534669306857;23856.761432385454;23917.16289929844;25433.61849572295;26146;37760;40644.78065658035;44461.333080003176;44472.77763611161;44612.88467788331;36921.81539092287;37351;37487.43768843415;37499.81250093732;38154;37093.25813483769;37324.6267537332;37646.24707505889;38330.42504362493;38429.423558646085;31526.68473315329;36522;36743.448848266766;36771.71299004522;37213.813930931254;31952.881649142168;33306.5004024935;33531.32937341288;33646;33697.663023370435;24009.51980960336;25021.624675629515;25028.87370068388;25659.743402565735;26169.084082057037 15:07:44 INFO - PROCESS | 1912 | |2;jquery.html;322.39068161175385;346.248640450223;362.75151521207994;366.69014682593206;393.4766760208922;14830.555083347503;15016.324265408055;15789.131597762118;16435.4247601334;20141.59716805663;16044.358225670989;18708.906455467702;20014.59970800583;20020.799792002075;20241.696374554394;5518.33779946406;5896.35140134587;5899.557533185029;5907.438793314628;5915.349311575723;4680.976595117019;5738.196652468658;5859;6376.171097757285;6465.482761379102;6448.484121270287;6458.773942911999;6459.612423254596;6460.063290822828;6467.967660161693;6149.108379285001;6356.936430635704;6415.743370265231;6422.518311126684;6425.710843012076;5369.167778994262;5558.44415558445;5573.164025396182;5579.721013949327;5580.386157522669;24627.50744985108;25139;25170.24489265325;25332.860021299086;25561.105361312264;2212.9889350553144;2239.451334423077;2474.752524747511;3081.1372815611667;3157.3369592385397;13959.65100872476;14978.325975331136;15419.22903854814;15448.841336899777;15769.211539422982;1558.9766153507705;1780.323477078702;1790.534461040126;1838.3657638114962;1865.7574515313058;108470;110637;110817.44591277072;111167;111417.44291278573;9370.85943710845;10049.69850904474;10451.059404653617;10595.894041059491;10893.291936024134;13257.138286011379;13259.469621215043;13264.801027984588;13279.203247805162;13284.667883302995;8035.297068907005;8288.875666865002;8294.336453083739;8424.789380265542;8445.408821382443;8003.759417290329;8395.40480857507;8401.327893768485;8511.914880851113;8594.87107693385;5687.7156142192725;6356.269029061625;6362.6500542469685;6369.936300637027;6377.202849643794;1735.2798588585847;1769.3541857222278;1772.1759381887227;1772.645470905824;1773.4325015995016;362.4943204230137;898.7507364762985;941.4257303045136;965.8599503072015;1016.7813920007234;11351.60269390584;11653.59212427561;12034.977026952807;12077.516899324104;12091.274523528617;416.3962254730592;431.4072424727348;431.43266604415095;434.87388657289665;456.3337527210244;5822.7088645567555;6098.72555734993;6194.0708893666315;6208.192934918522;6210.826210826265;301210;303219;303445;304332.4783376069;615219;84535.577322113;111000;130186;132042;140788;918.7657147427363;967.274544091931;985.6156099121348;1033.2767063055892;1033.5452400943561;15553.37786488573;18155.092245387943;18881.339153129575;19155.425337239903;19544;36087.63912360843;47142.764286178346;52981.73509132584;54106.729466352415;54814.45185548093;64303.67848160729;65265.34734652593;80526.59736701279;82245;82518.17481825105;7211.8557628848175;7296.635168241461;7928.32609228199;7988.600569971362;8173.836523269382;9165.679201228191;12059.638210853689;12091.818622720843;12120.454579543766;12431.191972521758;10487.737806554896;10616.363018218932;10829.458527073772;10869.510872010937;10926.515023085502;10306.690799276033;10329.9483502582;10458.529366178693;10512.632057877938;10631.043206111488;9069;10521.053105220566;10629.521671524646;10818.134549235885;11036.061934735637;7851.411144164096;7852.764417067498;7853.175416581405;8087.150849160754;8104.797380065545;9720.222382209548;10230.744231394274;10312.639057632947;10360.378377297386;10361.585536578452 15:07:44 INFO - PROCESS | 1912 | |3;mootools.html;1447.2185020089148;1471.4114354258295;1614.7497137943624;1764.523578633768;1811.5108920591433;7886.369090472763;8318.83362332753;8320.375971802125;8350;8352.331813454924;7111.644417779116;7369.8157546061375;7392.445566582501;7859.214078592138;8089.838203235931;2284.4631511594803;2319.2694301295123;2321.8374713770045;2322.419395151212;2326.7324257710325;2309.9653505197434;2335.0192918973985;2346.2257455039794;2356.234223877238;2358.3278765551822;2277.1688333758225;2284.1662793080573;2309.064828744353;2310.191432998454;2332.1837356925025;2283.7716228377194;2306.9423264418356;2309.734380546242;2311.1448763957274;2314.3404129823;2313.9305820825234;2343.6601692754457;2347.66183275533;2347.941301467477;2348;8177.79555511111;8323.583820808995;8329.875051874227;8343.874841877378;8371.330293576499;4111.753294802322;5120.308758317617;5121.84634460963;5130.127878260667;5137.357830271216;3147.4491963906225;3279.672032796725;3494.2138018945534;3499.422595271777;3509.4034014217646;930.4510338900081;1032.726327523207;1059.3220338983058;1066.9786604267872;1074.3178081918038;10258.435786031765;10581.629642962536;10589.682309530726;10675.41285229312;10690.51892664832;3537.593176784703;4152.37714342851;4182.707210495236;4201.285781417167;4348.869533913988;7321.633918304064;8501.234888860032;8514.403991720521;8538.487690738575;8539.957300213584;4425.601695847389;4927.507249275044;4992.051510213048;4997.275395067695;5005.7997680092385;4212.936805947913;4347.174036932973;4533.637309015271;4705.764711764398;4714.66997310185;3561.71506279497;3604.062943634674;3694.3350196964802;3702.9259414811554;3720.479132921339;986.6842610364614;1113.4877956140267;1131.1629394248362;1142.1205671632963;1145.4158379226487;773.215186585616;847.1020718038899;875.0899064972484;877.6270085213758;910.913463220993;4718.598919091847;5460.699661518575;5666.376698563155;5702.863425116169;5790.420957904176;801.8636831738619;889.9510526920952;894.4499133033138;910.3081657940013;959.5825815770162;3727.366347720894;3749.643783840531;3819.6180381962135;3827.75119617224;3934.1934903344745;9483.478408687588;9941.751456213653;9949.552270147717;10002.249831262685;10022.298439109192;9877.703668889944;9962.750931226778;9970.900290997142;9980.750481237881;10002.799944001079;7372.520786148883;7570.507916985378;7687.115981662068;7709.961450192825;7763.1848655891445;5413.83758487246;5462.481064298885;5615.045442274742;5693.430656934273;5727.828165155052;6902.620355880475;8893.73318800437;9299.116583924517;9364.484953327632;9386.624535018796;8917.464952102635;9344.719658410258;9442.669506567237;9509.809803804024;9516.666916657883;6973.302669732986;7202.49582529218;7450.105987281344;7762.922370776446;7766.10689770661;7283.562986220843;7428.145763237081;7757.612119393895;7757.92242077572;7854.136045035156;6905;7015.193252775996;7035.33164349386;7098.822529436806;7181.281871812777;6825.317468253135;6971.337722916315;6994.125734283215;7019.438444924292;7033.894491582732;6492.902606460812;6538.280789113099;6630.270670226175;6636.203655561363;6638.402543771083;4741.431028276629;4770.332153498583;4797.856064318076;4866.9269960949905;4903.019396120719;4706.858794236178;4729.266963620645;4786.1863483208635;4908.8772780680765;4977.427595826526 15:07:44 INFO - PROCESS | 1912 | |4;prototype.html;286.8254497831381;289.3576260701243;289.703576495184;295.35170301188884;308.61423220973785;4426.734395936246;4985.326980857583;5045.394552653686;5243.528082472577;5290.8412747617585;5168.664036837603;5183.429822719498;5194.142966410538;5201.867234433883;5211.973940130294;3297.4559197732347;3884.7280690351545;3941.4087886817065;3984.2828290907623;4003.4195041718935;3780.7353485256044;4023.1551374211303;4030.3349947258666;4066.0648050948153;4072.490938632671;3332.2669012817264;3908.7882756345625;3956.6241207085423;3961.2671655743634;3996.680265578747;3265.5591495148205;3731.6081811409954;3910.432987216838;3930.528336599598;3966.5438474575362;3285.4743241081314;3309.7352211822995;3395.779274347184;3398.1844357354057;3402.812845293508;4530.977345113253;4651.55810198034;4943.303861331713;5095.388553373582;5120.615953803443;3381.3575420669995;3580.785152890809;4400.273954797454;4408.3607876858;4421.115776844612;4810.100010495869;5052.368453943257;5349.545288650469;5393.2449457076045;5552.724402210316;3097.535369694564;3754.55449651886;3762.811859407019;3764.9058773530605;3766.5103536540073;5302.310699609026;5365.873166635011;5713.343130492152;5717.427707380462;5779.219805326269;3344.34454944799;3592.550931133608;3658.5061016762397;3725.8509659613856;3726.993711697881;3768.09565704229;3772.75477093988;3774.9245015099546;3777.9610607083273;3819.980900095482;3943.8225279862477;4389.429374181336;4411.139827733626;4475.440569928759;4517.819287228474;4736.247588392317;5160.509751573594;5495.560355171577;5497.257870187554;5534.833954981357;1134.478140055567;1146.850909381775;1170.4089434835523;1247;1266.645339304996;753.4763339479119;800.6197056398164;821.219841150909;830.3689196210803;839.2111415269749;4906.58294045003;4963.975180124148;4964.35463389757;4979.377577802775;4994.90010199794;4490.079533695584;5127.102757017507;5417.756200970965;5440.646357986798;5458.645188062763;4830.9275360869615;4864.762209383653;4943.937053533507;4959.603231741452;5123.130057528975;2613.4773045391;2643.643108180371;2708;2722.9591556126675;2803.5934789455414;4847.61219102478;4919.335889654851;5133.5882632276425;5409.24270602116;5647.576431767634;5251.632385733038;5481.793151006706;5560.471755183251;5604.982206405691;5623.634463759842;4783.378160839069;5425.294711687456;5473.0867429787095;5475.67145971243;5540.999999999919;5012.245714000071;5125.74371281427;5305.869536973204;5431.37539182999;5480.671159730429;4618.983823558811;4733.431988161443;5026.607040247745;5350.143976963823;5373;5021.72380519075;5194.935038317211;5594.552435805207;5607.635503692247;5616.66300022;3500.544929159193;3608.440691692792;3639.2721455708433;3675.98910299659;3713.9786558696574;4611.654125940501;5064;5139.486051394831;5181.637285389987;5436.864078398072;5071.112555302881;5506.449355064461;5560.944390556043;5568.610197286151;5575.358833734173;5255.500727430888;5512.889742205053;5555.2500412445415;5638.323401191883;5647.858803529944;5245.527902488641;5274.7098909560345;5424.918626220531;5504.394516603091;5506.284183056177;5077.238414237894;5293.7353132344;5353.892922141457;5471.699056551928;5499.945000549943;4417.933730994102;5021.824236151863;5362.490563396471;5373.865653358697;5460.672359658433 15:07:44 INFO - PROCESS | 1912 | |5;yui.html;697.6442014572568;705.954112982656;719.1657677094568;752.5710345103294;756.8032311598982;13978;14566.563003109924;14580.270986450689;14594.051386659872;14607.853921460795;12829.743405131916;12849.807252891214;12894.484220631164;12916.16044957077;12931.547395841148;642.4667525953447;672.9697163627624;675.1627981303174;776.9339606133457;805.8066064144592;786.8268980824238;802.8715405535115;803.1606970715616;803.3051410529908;804.2399932064202;854.5513605357175;855.0850589868843;855.9914400856005;856.6873091321654;858.3133493205441;679.3003206697085;701.7473709464589;703.6903762344552;706.2478460439667;849.0363437498422;524.1927431755092;612.9816105516842;630.5838146823121;635.7838334966134;637.2512298049772;12177.086718496148;13444.193348399029;13862.722745545134;13903.582892513139;13906.30468476572;13061.151025183428;13171.60485185446;13298.800517992238;13303.935685145165;13319.067665263436;11472.124347152427;11645.883541164565;11920.761584768255;12122;12548.498060077585;4290.206311832321;4396.868093957187;4399.318105693623;4533.886652833673;4581.289900065495;67730.98403523951;78913.42173156505;84154.73767893486;84388.84364012009;85953;9844.950775246221;10458.843117353246;10483.842742358871;10501.737456563495;10670.573177072985;19969.700454493195;19996.000199989943;19997.300094496622;20015.799842001685;20052;7268.27317268269;7370.226126256773;7520.947067410567;7652.273034061755;7698.230176982257;3973.4635824163947;4079.3274757349936;4196.370544418301;4357.520672725997;4398.450193725785;3206.9839650801596;3301;3302.256992176789;3305.0910999474854;3329.334133173375;522.7516929458553;597.375742349246;609.3236507476697;611.7186094396538;613.5674349583533;529;553.3083645443196;592.8764990343275;610.2615834839805;617.9876402471925;8690.78273043179;8978.281737460988;9289.489078100769;9379.577918993662;9503.57233924475;590.8375196820908;666.8266250774833;672.1262358933367;672.8149758816364;686.8866637004938;3969.920601587952;4222.746635201897;4447.2439685253585;4602.102589994919;4623.630109591292;74476.62761686157;100512;117684;121319.39340303242;125194.74805252012;66368.6726265472;66522;91812.16375672449;107869.8426031475;108085;91559;91564.54217728869;94509.39071465268;95774;96792;8007.359411247203;8766.561671916252;8846.513441760764;8959.686410975584;8966.417182883091;56301;57139;57613.71193144008;58969.34637941389;60367.69816150891;69215.30784692088;80706;84036.57981710053;86129;87244.69132963137;11996.280223186288;12082.697932551757;12300.323482208565;12302.630921072383;13106.868931310946;13291.468341266242;13708.725825483236;14385.496507622183;14475.203863787172;14523.346449410014;12310.692232693897;13225.140365876186;13530.661733456742;13550;13914.791278130633;12692.492300308253;13194.934025329812;13213.405396756976;13260.53588124411;13397.464101435833;10700.464976751287;12079.776046302299;13237.669058273621;13261.138026028277;13559;10437.68686939393;11188.825173357005;11308.378039207593;11718.355490448108;11844.28934263947;11197.72005699864;11228.775424491629;11523.596674116367;11589.768204636028;11610.825837612274 15:07:44 INFO - PROCESS | 1912 | __end_tp_report 15:07:44 INFO - PROCESS | 1912 | __start_cc_report 15:07:44 INFO - PROCESS | 1912 | _x_x_mozilla_cycle_collect,2091 15:07:44 INFO - PROCESS | 1912 | __end_cc_report 15:07:44 INFO - PROCESS | 1912 | __startTimestamp1480633664057__endTimestamp 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | ------- Summary: start ------- 15:07:44 INFO - PROCESS | 1912 | Number of tests: 6 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#0] dojo.html Cycles:165 Average:5786.55 Median:6022.22 stddev:644.52 (10.7%) stddev-sans-first:616.11 15:07:44 INFO - PROCESS | 1912 | Values: 3293.6 3311.3 3320.4 3350.6 3395.9 5655.5 5757.7 5813.4 5814.2 5867.0 5748.5 5762.4 5842.0 5842.8 5877.0 5225.9 5265.9 5268.8 5289.5 5322.2 5204.5 5211.2 5215.8 5336.1 5341.2 5269.3 5309.1 5312.8 5315.7 5320.2 5282.9 5283.6 5288.7 5291.9 5292.3 5153.4 5243.3 5247.8 5251.6 5267.5 5866.4 5883.3 5885.2 5894.6 5896.4 6182.7 6187.0 6198.6 6203.2 6222.0 6179.9 6203.6 6270.5 6272.6 6276.0 5809.7 5825.6 5846.4 5869.9 5891.9 6189.7 6272.4 6278.7 6281.7 6288.0 6045.2 6111.2 6113.6 6116.7 6128.2 6194.5 6243.6 6244.5 6250.1 6258.2 6268.1 6280.5 6283.3 6289.9 6292.7 6116.6 6133.9 6165.2 6197.4 6267.7 4890.9 4911.0 4915.4 4938.1 4940.0 4435.1 4447.8 4449.6 4456.5 4479.5 5720.2 5840.6 5940.2 5941.8 6002.3 6162.5 6256.0 6262.3 6272.4 6292.2 5964.2 5965.2 5983.5 6015.8 6022.2 5435.5 5477.5 5478.5 5482.0 5494.5 6184.4 6311.7 6352.0 6374.1 6394.1 6169.2 6200.4 6209.9 6224.1 6269.6 6348.1 6358.7 6371.6 6371.7 6373.1 6187.4 6273.7 6275.7 6277.7 6300.5 4541.7 6127.9 6128.2 6146.0 6153.4 6190.4 6198.0 6209.7 6224.7 6242.6 6168.2 6192.0 6200.2 6203.3 6205.9 5888.3 5892.0 6003.2 6004.8 6095.5 6140.7 6192.7 6201.3 6220.8 6231.3 5754.7 5793.4 5794.0 6031.3 6063.1 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#1] ext.html Cycles:180 Average:69514.43 Median:38376.52 stddev:155435.12 (405.0%) stddev-sans-first:155784.70 15:07:44 INFO - PROCESS | 1912 | Values: 464.5 495.6 500.8 523.3 552.8 27294.0 38422.6 39167.6 39348.0 41226.6 39716.4 40036.8 40181.8 40732.4 40793.0 7995.2 9396.4 9401.6 9404.7 9408.5 9303.9 9404.3 9406.5 9414.1 9427.2 9368.5 9412.6 9423.2 9427.7 9437.7 9346.0 9389.7 9413.8 9422.9 9427.8 7923.2 9300.6 9300.8 9304.7 9313.7 38251.9 38519.0 38550.4 38558.6 38611.6 3308.0 3468.0 3590.8 3718.2 3882.7 28833.3 29179.0 29776.9 30128.4 30407.2 3095.2 3119.0 3154.7 3213.7 3275.8 485519.0 491660.0 492659.0 493331.0 498882.0 58572.7 58923.0 58996.4 58998.0 59058.7 34435.0 35118.8 35189.5 61461.0 61539.0 15509.5 15808.6 15916.6 15933.0 15943.8 12181.3 12259.8 12260.1 12264.0 12290.6 18893.6 18922.2 18929.8 18950.6 18963.5 7494.8 7610.2 7612.4 7655.5 7671.2 868.5 967.6 979.2 1039.4 1105.6 29549.0 29549.6 29953.0 30145.4 30248.2 1030.1 1045.8 1048.9 1093.7 1099.9 14875.4 15311.1 15335.5 15356.8 15790.8 680636.6 684616.0 686376.0 687854.0 689587.0 56384.4 56891.0 56939.7 56973.9 56989.7 1238.1 1287.6 1346.0 1377.6 1392.3 29181.9 30240.8 30323.7 30402.7 30565.5 47887.5 48509.8 48519.8 48537.0 48674.8 543093.0 545911.3 546006.0 546093.0 547469.0 23266.5 23856.8 23917.2 25433.6 26146.0 37760.0 40644.8 44461.3 44472.8 44612.9 36921.8 37351.0 37487.4 37499.8 38154.0 37093.3 37324.6 37646.2 38330.4 38429.4 31526.7 36522.0 36743.4 36771.7 37213.8 31952.9 33306.5 33531.3 33646.0 33697.7 24009.5 25021.6 25028.9 25659.7 26169.1 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#2] jquery.html Cycles:180 Average:27386.40 Median:3119.24 stddev:66983.40 (2147.4%) stddev-sans-first:67140.49 15:07:44 INFO - PROCESS | 1912 | Values: 322.4 346.2 362.8 366.7 393.5 14830.6 15016.3 15789.1 16435.4 20141.6 16044.4 18708.9 20014.6 20020.8 20241.7 5518.3 5896.4 5899.6 5907.4 5915.3 4681.0 5738.2 5859.0 6376.2 6465.5 6448.5 6458.8 6459.6 6460.1 6468.0 6149.1 6356.9 6415.7 6422.5 6425.7 5369.2 5558.4 5573.2 5579.7 5580.4 24627.5 25139.0 25170.2 25332.9 25561.1 2213.0 2239.5 2474.8 3081.1 3157.3 13959.7 14978.3 15419.2 15448.8 15769.2 1559.0 1780.3 1790.5 1838.4 1865.8 108470.0 110637.0 110817.4 111167.0 111417.4 9370.9 10049.7 10451.1 10595.9 10893.3 13257.1 13259.5 13264.8 13279.2 13284.7 8035.3 8288.9 8294.3 8424.8 8445.4 8003.8 8395.4 8401.3 8511.9 8594.9 5687.7 6356.3 6362.7 6369.9 6377.2 1735.3 1769.4 1772.2 1772.6 1773.4 362.5 898.8 941.4 965.9 1016.8 11351.6 11653.6 12035.0 12077.5 12091.3 416.4 431.4 431.4 434.9 456.3 5822.7 6098.7 6194.1 6208.2 6210.8 301210.0 303219.0 303445.0 304332.5 615219.0 84535.6 111000.0 130186.0 132042.0 140788.0 918.8 967.3 985.6 1033.3 1033.5 15553.4 18155.1 18881.3 19155.4 19544.0 36087.6 47142.8 52981.7 54106.7 54814.5 64303.7 65265.3 80526.6 82245.0 82518.2 7211.9 7296.6 7928.3 7988.6 8173.8 9165.7 12059.6 12091.8 12120.5 12431.2 10487.7 10616.4 10829.5 10869.5 10926.5 10306.7 10329.9 10458.5 10512.6 10631.0 9069.0 10521.1 10629.5 10818.1 11036.1 7851.4 7852.8 7853.2 8087.2 8104.8 9720.2 10230.7 10312.6 10360.4 10361.6 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#3] mootools.html Cycles:180 Average:5311.71 Median:5133.74 stddev:2894.61 (56.4%) stddev-sans-first:2888.16 15:07:44 INFO - PROCESS | 1912 | Values: 1447.2 1471.4 1614.7 1764.5 1811.5 7886.4 8318.8 8320.4 8350.0 8352.3 7111.6 7369.8 7392.4 7859.2 8089.8 2284.5 2319.3 2321.8 2322.4 2326.7 2310.0 2335.0 2346.2 2356.2 2358.3 2277.2 2284.2 2309.1 2310.2 2332.2 2283.8 2306.9 2309.7 2311.1 2314.3 2313.9 2343.7 2347.7 2347.9 2348.0 8177.8 8323.6 8329.9 8343.9 8371.3 4111.8 5120.3 5121.8 5130.1 5137.4 3147.4 3279.7 3494.2 3499.4 3509.4 930.5 1032.7 1059.3 1067.0 1074.3 10258.4 10581.6 10589.7 10675.4 10690.5 3537.6 4152.4 4182.7 4201.3 4348.9 7321.6 8501.2 8514.4 8538.5 8540.0 4425.6 4927.5 4992.1 4997.3 5005.8 4212.9 4347.2 4533.6 4705.8 4714.7 3561.7 3604.1 3694.3 3702.9 3720.5 986.7 1113.5 1131.2 1142.1 1145.4 773.2 847.1 875.1 877.6 910.9 4718.6 5460.7 5666.4 5702.9 5790.4 801.9 890.0 894.4 910.3 959.6 3727.4 3749.6 3819.6 3827.8 3934.2 9483.5 9941.8 9949.6 10002.2 10022.3 9877.7 9962.8 9970.9 9980.8 10002.8 7372.5 7570.5 7687.1 7710.0 7763.2 5413.8 5462.5 5615.0 5693.4 5727.8 6902.6 8893.7 9299.1 9364.5 9386.6 8917.5 9344.7 9442.7 9509.8 9516.7 6973.3 7202.5 7450.1 7762.9 7766.1 7283.6 7428.1 7757.6 7757.9 7854.1 6905.0 7015.2 7035.3 7098.8 7181.3 6825.3 6971.3 6994.1 7019.4 7033.9 6492.9 6538.3 6630.3 6636.2 6638.4 4741.4 4770.3 4797.9 4866.9 4903.0 4706.9 4729.3 4786.2 4908.9 4977.4 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#4] prototype.html Cycles:180 Average:4352.73 Median:4971.87 stddev:1344.22 (27.0%) stddev-sans-first:1312.89 15:07:44 INFO - PROCESS | 1912 | Values: 286.8 289.4 289.7 295.4 308.6 4426.7 4985.3 5045.4 5243.5 5290.8 5168.7 5183.4 5194.1 5201.9 5212.0 3297.5 3884.7 3941.4 3984.3 4003.4 3780.7 4023.2 4030.3 4066.1 4072.5 3332.3 3908.8 3956.6 3961.3 3996.7 3265.6 3731.6 3910.4 3930.5 3966.5 3285.5 3309.7 3395.8 3398.2 3402.8 4531.0 4651.6 4943.3 5095.4 5120.6 3381.4 3580.8 4400.3 4408.4 4421.1 4810.1 5052.4 5349.5 5393.2 5552.7 3097.5 3754.6 3762.8 3764.9 3766.5 5302.3 5365.9 5713.3 5717.4 5779.2 3344.3 3592.6 3658.5 3725.9 3727.0 3768.1 3772.8 3774.9 3778.0 3820.0 3943.8 4389.4 4411.1 4475.4 4517.8 4736.2 5160.5 5495.6 5497.3 5534.8 1134.5 1146.9 1170.4 1247.0 1266.6 753.5 800.6 821.2 830.4 839.2 4906.6 4964.0 4964.4 4979.4 4994.9 4490.1 5127.1 5417.8 5440.6 5458.6 4830.9 4864.8 4943.9 4959.6 5123.1 2613.5 2643.6 2708.0 2723.0 2803.6 4847.6 4919.3 5133.6 5409.2 5647.6 5251.6 5481.8 5560.5 5605.0 5623.6 4783.4 5425.3 5473.1 5475.7 5541.0 5012.2 5125.7 5305.9 5431.4 5480.7 4619.0 4733.4 5026.6 5350.1 5373.0 5021.7 5194.9 5594.6 5607.6 5616.7 3500.5 3608.4 3639.3 3676.0 3714.0 4611.7 5064.0 5139.5 5181.6 5436.9 5071.1 5506.4 5560.9 5568.6 5575.4 5255.5 5512.9 5555.3 5638.3 5647.9 5245.5 5274.7 5424.9 5504.4 5506.3 5077.2 5293.7 5353.9 5471.7 5499.9 4417.9 5021.8 5362.5 5373.9 5460.7 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - PROCESS | 1912 | [#5] yui.html Cycles:180 Average:20619.35 Median:4490.57 stddev:30343.67 (675.7%) stddev-sans-first:30391.92 15:07:44 INFO - PROCESS | 1912 | Values: 697.6 706.0 719.2 752.6 756.8 13978.0 14566.6 14580.3 14594.1 14607.9 12829.7 12849.8 12894.5 12916.2 12931.5 642.5 673.0 675.2 776.9 805.8 786.8 802.9 803.2 803.3 804.2 854.6 855.1 856.0 856.7 858.3 679.3 701.7 703.7 706.2 849.0 524.2 613.0 630.6 635.8 637.3 12177.1 13444.2 13862.7 13903.6 13906.3 13061.2 13171.6 13298.8 13303.9 13319.1 11472.1 11645.9 11920.8 12122.0 12548.5 4290.2 4396.9 4399.3 4533.9 4581.3 67731.0 78913.4 84154.7 84388.8 85953.0 9845.0 10458.8 10483.8 10501.7 10670.6 19969.7 19996.0 19997.3 20015.8 20052.0 7268.3 7370.2 7520.9 7652.3 7698.2 3973.5 4079.3 4196.4 4357.5 4398.5 3207.0 3301.0 3302.3 3305.1 3329.3 522.8 597.4 609.3 611.7 613.6 529.0 553.3 592.9 610.3 618.0 8690.8 8978.3 9289.5 9379.6 9503.6 590.8 666.8 672.1 672.8 686.9 3969.9 4222.7 4447.2 4602.1 4623.6 74476.6 100512.0 117684.0 121319.4 125194.7 66368.7 66522.0 91812.2 107869.8 108085.0 91559.0 91564.5 94509.4 95774.0 96792.0 8007.4 8766.6 8846.5 8959.7 8966.4 56301.0 57139.0 57613.7 58969.3 60367.7 69215.3 80706.0 84036.6 86129.0 87244.7 11996.3 12082.7 12300.3 12302.6 13106.9 13291.5 13708.7 14385.5 14475.2 14523.3 12310.7 13225.1 13530.7 13550.0 13914.8 12692.5 13194.9 13213.4 13260.5 13397.5 10700.5 12079.8 13237.7 13261.1 13559.0 10437.7 11188.8 11308.4 11718.4 11844.3 11197.7 11228.8 11523.6 11589.8 11610.8 15:07:44 INFO - PROCESS | 1912 | -------- Summary: end -------- 15:07:44 INFO - PROCESS | 1912 | 15:07:44 INFO - TEST-INFO | 1912: exit 0 15:07:44 INFO - TEST-OK | dromaeo_css | took 1121763ms 15:07:44 INFO - TEST-START | kraken 15:07:44 INFO - operating with platform_type : mac_ 15:07:44 INFO - Initialising browser for kraken test... 15:07:44 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp4YqByC/profile http://localhost:53497/getInfo.html) 15:07:47 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 15:07:47 INFO - PROCESS | 1916 | colorDepth:24 15:07:47 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 15:07:47 INFO - PROCESS | 1916 | __metrics 15:07:47 INFO - PROCESS | 1916 | 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" 15:07:47 INFO - PROCESS | 1916 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:07:48 INFO - TEST-INFO | 1916: exit 0 15:07:48 INFO - Browser initialized. 15:07:48 INFO - Running cycle 1/1 for kraken test... 15:07:48 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp4YqByC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:00 INFO - PROCESS | 1917 | RSS: Main: 222785536 15:08:00 INFO - PROCESS | 1917 | 15:08:03 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-beat-detection) 15:08:04 INFO - PROCESS | 1917 | RSS: Main: 272338944 15:08:04 INFO - PROCESS | 1917 | 15:08:07 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-dft) 15:08:07 INFO - PROCESS | 1917 | RSS: Main: 285364224 15:08:07 INFO - PROCESS | 1917 | 15:08:10 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-fft) 15:08:11 INFO - PROCESS | 1917 | RSS: Main: 381239296 15:08:11 INFO - PROCESS | 1917 | 15:08:13 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53497/tests/kraken/driver.html?testName=audio-oscillator) 15:08:14 INFO - PROCESS | 1917 | RSS: Main: 283889664 15:08:14 INFO - PROCESS | 1917 | 15:08:16 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:08:16 INFO - PROCESS | 1917 | RSS: Main: 286375936 15:08:16 INFO - PROCESS | 1917 | 15:08:19 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-darkroom) 15:08:20 INFO - PROCESS | 1917 | RSS: Main: 327806976 15:08:20 INFO - PROCESS | 1917 | 15:08:23 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53497/tests/kraken/driver.html?testName=imaging-desaturate) 15:08:23 INFO - PROCESS | 1917 | RSS: Main: 327839744 15:08:23 INFO - PROCESS | 1917 | 15:08:26 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53497/tests/kraken/driver.html?testName=json-parse-financial) 15:08:27 INFO - PROCESS | 1917 | RSS: Main: 325308416 15:08:27 INFO - PROCESS | 1917 | 15:08:28 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53497/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:08:29 INFO - PROCESS | 1917 | RSS: Main: 299786240 15:08:29 INFO - PROCESS | 1917 | 15:08:31 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:08:31 INFO - PROCESS | 1917 | RSS: Main: 302694400 15:08:31 INFO - PROCESS | 1917 | 15:08:33 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:08:34 INFO - PROCESS | 1917 | RSS: Main: 303910912 15:08:34 INFO - PROCESS | 1917 | 15:08:37 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:08:38 INFO - PROCESS | 1917 | RSS: Main: 320122880 15:08:38 INFO - PROCESS | 1917 | 15:08:41 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:08:41 INFO - PROCESS | 1917 | RSS: Main: 313507840 15:08:41 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:53497/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53497/tests/kraken/driver.html?testName=ai-astar) 15:08:43 INFO - PROCESS | 1917 | RSS: Main: 357310464 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | __start_tp_report 15:08:43 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 15:08:43 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 15:08:43 INFO - PROCESS | 1917 | |i|pagename|runs| 15:08:43 INFO - PROCESS | 1917 | |0;ai-astar;104;114;114;117;94;156;168;167;125;115 15:08:43 INFO - PROCESS | 1917 | |1;audio-beat-detection;153;126;141;177;115;114;113;114;144;112 15:08:43 INFO - PROCESS | 1917 | |2;audio-dft;184;183;182;184;178;184;182;184;184;184 15:08:43 INFO - PROCESS | 1917 | |3;audio-fft;81;59;59;88;61;59;59;58;82;59 15:08:43 INFO - PROCESS | 1917 | |4;audio-oscillator;87;70;96;72;68;69;89;90;71;125 15:08:43 INFO - PROCESS | 1917 | |5;imaging-gaussian-blur;76;73;100;129;118;89;90;88;89;86 15:08:43 INFO - PROCESS | 1917 | |6;imaging-darkroom;94;95;95;95;94;95;95;94;94;95 15:08:43 INFO - PROCESS | 1917 | |7;imaging-desaturate;84;83;80;81;84;80;86;85;81;84 15:08:43 INFO - PROCESS | 1917 | |8;json-parse-financial;54;53;53;53;55;57;58;56;56;57 15:08:43 INFO - PROCESS | 1917 | |9;json-stringify-tinderbox;64;65;76;76;73;69;69;69;69;71 15:08:43 INFO - PROCESS | 1917 | |10;stanford-crypto-aes;86;85;84;84;85;84;84;86;88;85 15:08:43 INFO - PROCESS | 1917 | |11;stanford-crypto-ccm;151;148;149;150;146;150;153;150;192;162 15:08:43 INFO - PROCESS | 1917 | |12;stanford-crypto-pbkdf2;213;194;169;171;168;168;166;170;169;170 15:08:43 INFO - PROCESS | 1917 | |13;stanford-crypto-sha256-iterative;70;69;70;69;65;79;85;86;74;67 15:08:43 INFO - PROCESS | 1917 | __end_tp_report 15:08:43 INFO - PROCESS | 1917 | __start_cc_report 15:08:43 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,2008 15:08:43 INFO - PROCESS | 1917 | __end_cc_report 15:08:43 INFO - PROCESS | 1917 | __startTimestamp1480633723821__endTimestamp 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | ------- Summary: start ------- 15:08:43 INFO - PROCESS | 1917 | Number of tests: 14 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#0] ai-astar Cycles:10 Average:127.40 Median:140.50 stddev:26.51 (18.9%) stddev-sans-first:26.73 15:08:43 INFO - PROCESS | 1917 | Values: 104.0 114.0 114.0 117.0 94.0 156.0 168.0 167.0 125.0 115.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:133.50 stddev:22.14 (16.6%) stddev-sans-first:21.99 15:08:43 INFO - PROCESS | 1917 | Values: 153.0 126.0 141.0 177.0 115.0 114.0 113.0 114.0 144.0 112.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#2] audio-dft Cycles:10 Average:182.90 Median:184.00 stddev:1.91 (1.0%) stddev-sans-first:1.99 15:08:43 INFO - PROCESS | 1917 | Values: 184.0 183.0 182.0 184.0 178.0 184.0 182.0 184.0 184.0 184.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#3] audio-fft Cycles:10 Average:66.50 Median:60.00 stddev:12.00 (20.0%) stddev-sans-first:11.53 15:08:43 INFO - PROCESS | 1917 | Values: 81.0 59.0 59.0 88.0 61.0 59.0 59.0 58.0 82.0 59.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:79.50 stddev:17.90 (22.5%) stddev-sans-first:18.95 15:08:43 INFO - PROCESS | 1917 | Values: 87.0 70.0 96.0 72.0 68.0 69.0 89.0 90.0 71.0 125.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:87.00 stddev:17.54 (20.2%) stddev-sans-first:17.38 15:08:43 INFO - PROCESS | 1917 | Values: 76.0 73.0 100.0 129.0 118.0 89.0 90.0 88.0 89.0 86.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:08:43 INFO - PROCESS | 1917 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:84.00 stddev:2.15 (2.6%) stddev-sans-first:2.24 15:08:43 INFO - PROCESS | 1917 | Values: 84.0 83.0 80.0 81.0 84.0 80.0 86.0 85.0 81.0 84.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.87 (3.3%) stddev-sans-first:1.94 15:08:43 INFO - PROCESS | 1917 | Values: 54.0 53.0 53.0 53.0 55.0 57.0 58.0 56.0 56.0 57.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:4.04 (5.8%) stddev-sans-first:3.63 15:08:43 INFO - PROCESS | 1917 | Values: 64.0 65.0 76.0 76.0 73.0 69.0 69.0 69.0 69.0 71.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:1.29 (1.5%) stddev-sans-first:1.32 15:08:43 INFO - PROCESS | 1917 | Values: 86.0 85.0 84.0 84.0 85.0 84.0 84.0 86.0 88.0 85.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:150.50 stddev:13.66 (9.1%) stddev-sans-first:14.41 15:08:43 INFO - PROCESS | 1917 | Values: 151.0 148.0 149.0 150.0 146.0 150.0 153.0 150.0 192.0 162.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:170.00 stddev:15.33 (9.0%) stddev-sans-first:8.50 15:08:43 INFO - PROCESS | 1917 | Values: 213.0 194.0 169.0 171.0 168.0 168.0 166.0 170.0 169.0 170.0 15:08:43 INFO - PROCESS | 1917 | 15:08:43 INFO - PROCESS | 1917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:72.00 stddev:7.44 (10.3%) stddev-sans-first:7.79 15:08:43 INFO - PROCESS | 1917 | Values: 70.0 69.0 70.0 69.0 65.0 79.0 85.0 86.0 74.0 67.0 15:08:43 INFO - PROCESS | 1917 | -------- Summary: end -------- 15:08:43 INFO - PROCESS | 1917 | 15:08:44 INFO - TEST-INFO | 1917: exit 0 15:08:44 INFO - TEST-OK | kraken | took 60170ms 15:08:44 INFO - SUITE-END | took 1181s 15:08:44 INFO - Completed test suite (00:19:42) 15:08:44 INFO - javascript benchmark 15:08:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.5883014623173, 3311.321179158272, 3320.352531256406, 3350.6146793118783, 3395.9490607640873, 5655.462731040545, 5757.740901659425, 5813.44772246637, 5814.2441482607255, 5867.0, 5748.511376533, 5762.423757624236, 5842.036064049426, 5842.795502157425, 5877.0, 5225.869353266161, 5265.921011184835, 5268.8155914542995, 5289.471052894718, 5322.175062865262, 5204.531592156705, 5211.166213405856, 5215.817446389377, 5336.119540275849, 5341.225522299264, 5269.314989051437, 5309.097453432906, 5312.787488500475, 5315.707636080014, 5320.17537281722, 5282.947170528303, 5283.6037297202865, 5288.65623734456, 5291.941611677641, 5292.259083728281, 5153.433122356538, 5243.318368612094, 5247.790088396459, 5251.579873610102, 5267.499587539216, 5866.413358664142, 5883.323417806964, 5885.234919460443, 5894.616849904743, 5896.439838215363, 6182.721777520022, 6187.041008643668, 6198.566100372976, 6203.2245969253845, 6222.004479283293, 6179.907301390483, 6203.627782333029, 6270.498360131224, 6272.6236425814595, 6276.027215781515, 5809.7095145242565, 5825.592208545403, 5846.356900740915, 5869.853253668693, 5891.882162356729, 6189.659568723673, 6272.372762723737, 6278.6860656966965, 6281.7173227203975, 6287.9685601572155, 6045.183900173508, 6111.205543279345, 6113.633182009094, 6116.694165291718, 6128.2033335666965, 6194.5044396449075, 6243.625382477066, 6244.469220116341, 6250.093736408196, 6258.249010118815, 6268.09112678659, 6280.466160376328, 6283.340249273851, 6289.8742025159245, 6292.748290068437, 6116.579253789288, 6133.89764526209, 6165.198524191826, 6197.411245931629, 6267.749290028439, 4890.902181956341, 4910.950890491049, 4915.410150781858, 4938.111139994764, 4939.950600493949, 4435.090806384684, 4447.822087116544, 4449.599536041707, 4456.46522417312, 4479.484859241164, 5720.170575266647, 5840.59115861894, 5940.161173325855, 5941.7623295067715, 6002.309734380515, 6162.537809664203, 6256.0, 6262.3111457739615, 6272.435480806749, 6292.1505596743855, 5964.1650168976375, 5965.224520812267, 5983.521318294526, 6015.849603759941, 6022.217111775529, 5435.504572036458, 5477.534409575231, 5478.45215478449, 5482.0406428874785, 5494.532964697965, 6184.443400094013, 6311.715972781144, 6352.0, 6374.139491168633, 6394.104825324365, 6169.167162433014, 6200.441960223601, 6209.906851397323, 6224.128621993015, 6269.592476489013, 6348.111264422892, 6358.713857876499, 6371.617702937839, 6371.74513019474, 6373.107764913008, 6187.443130118311, 6273.686315684289, 6275.6862156892885, 6277.686115694106, 6300.527460440394, 4541.68208225421, 6127.861529871452, 6128.19758601264, 6145.955187618205, 6153.446189842935, 6190.350013248544, 6197.9690101551005, 6209.658468784261, 6224.657643829632, 6242.563020588515, 6168.228971378578, 6192.009278515416, 6200.224971878515, 6203.286622038523, 6205.875882482234, 5888.263967004124, 5892.0, 6003.159557661844, 6004.819855404345, 6095.451409373178, 6140.723667435065, 6192.690365481618, 6201.255849297932, 6220.7511699533325, 6231.314555398993, 5754.741036653444, 5793.36273009977, 5794.043982742969, 6031.306399763907, 6063.090536419572], "value": 5744.7745962595845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.5192226046043, 495.5515258690884, 500.7846625950844, 523.3301374240973, 552.8175702018332, 27294.0, 38422.615773842255, 39167.60832391682, 39348.01629959249, 41226.58773412265, 39716.40425393621, 40036.79889603309, 40181.79909100458, 40732.389014164735, 40793.0, 7995.200479952002, 9396.389234699722, 9401.57692903821, 9404.717858464222, 9408.482533460658, 9303.860442093373, 9404.29467789915, 9406.482643454607, 9414.058594140566, 9427.151556359926, 9368.531573421336, 9412.62349506019, 9423.199028082621, 9427.717168484956, 9437.716868493957, 9345.953270233673, 9389.671361502315, 9413.811723765555, 9422.905770942272, 9427.764305892339, 7923.207679232089, 9300.627974880997, 9300.767480812967, 9304.674336398193, 9313.674021409219, 38251.85244442671, 38519.0, 38550.42174367387, 38558.61441385578, 38611.61388386136, 3307.96692033079, 3467.9653203467897, 3590.8025058621765, 3718.219173973469, 3882.650561449483, 28833.279168020967, 29178.97873574436, 29776.8511157445, 30128.355074231098, 30407.23981900448, 3095.1797773590015, 3118.98440507796, 3154.7160755532113, 3213.742900567949, 3275.836208189605, 485519.0, 491660.0, 492659.0, 493331.0, 498882.0, 58572.70713646404, 58923.0, 58996.41003589995, 58998.0, 59058.70470647705, 34435.0, 35118.82440587781, 35189.472157917655, 61461.0, 61539.0, 15509.457168999032, 15808.628705694007, 15916.602084947737, 15932.964357316738, 15943.760843587355, 12181.33002684843, 12259.816102758467, 12260.141790074787, 12264.0, 12290.631281061582, 18893.622127557373, 18922.24311027571, 18929.81070189308, 18950.620987580172, 18963.525911852314, 7494.775156745306, 7610.200928902496, 7612.439449912755, 7655.46411751172, 7671.232876712284, 868.5309932636432, 967.6419724701765, 979.1725991537139, 1039.417925961464, 1105.6130354375905, 29548.96578619781, 29549.556756648668, 29953.0, 30145.397092058476, 30248.243793905327, 1030.0883717909553, 1045.816982028142, 1048.905598496139, 1093.7156339351825, 1099.8680158380885, 14875.404983800527, 15311.081335119929, 15335.463258785889, 15356.846431535765, 15790.842091579167, 680636.5968170128, 684616.0, 686376.0, 687854.0, 689587.0, 56384.43615563874, 56891.0, 56939.715301424054, 56973.86052278931, 56989.71505142531, 1238.13949305232, 1287.5622288421982, 1345.9865401345862, 1377.5660666890099, 1392.3455975691409, 29181.854090729412, 30240.84879575588, 30323.69676303297, 30402.695973039987, 30565.493937587493, 47887.521124788305, 48509.75745121393, 48519.757401212766, 48537.0, 48674.756626216644, 543093.0, 545911.2704436452, 546006.0, 546093.0, 547469.0, 23266.534669306857, 23856.761432385454, 23917.16289929844, 25433.61849572295, 26146.0, 37760.0, 40644.78065658035, 44461.333080003176, 44472.77763611161, 44612.88467788331, 36921.81539092287, 37351.0, 37487.43768843415, 37499.81250093732, 38154.0, 37093.25813483769, 37324.6267537332, 37646.24707505889, 38330.42504362493, 38429.423558646085, 31526.68473315329, 36522.0, 36743.448848266766, 36771.71299004522, 37213.813930931254, 31952.881649142168, 33306.5004024935, 33531.32937341288, 33646.0, 33697.663023370435, 24009.51980960336, 25021.624675629515, 25028.87370068388, 25659.743402565735, 26169.084082057037], "value": 19376.841595292277, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.39068161175385, 346.248640450223, 362.75151521207994, 366.69014682593206, 393.4766760208922, 14830.555083347503, 15016.324265408055, 15789.131597762118, 16435.4247601334, 20141.59716805663, 16044.358225670989, 18708.906455467702, 20014.59970800583, 20020.799792002075, 20241.696374554394, 5518.33779946406, 5896.35140134587, 5899.557533185029, 5907.438793314628, 5915.349311575723, 4680.976595117019, 5738.196652468658, 5859.0, 6376.171097757285, 6465.482761379102, 6448.484121270287, 6458.773942911999, 6459.612423254596, 6460.063290822828, 6467.967660161693, 6149.108379285001, 6356.936430635704, 6415.743370265231, 6422.518311126684, 6425.710843012076, 5369.167778994262, 5558.44415558445, 5573.164025396182, 5579.721013949327, 5580.386157522669, 24627.50744985108, 25139.0, 25170.24489265325, 25332.860021299086, 25561.105361312264, 2212.9889350553144, 2239.451334423077, 2474.752524747511, 3081.1372815611667, 3157.3369592385397, 13959.65100872476, 14978.325975331136, 15419.22903854814, 15448.841336899777, 15769.211539422982, 1558.9766153507705, 1780.323477078702, 1790.534461040126, 1838.3657638114962, 1865.7574515313058, 108470.0, 110637.0, 110817.44591277072, 111167.0, 111417.44291278573, 9370.85943710845, 10049.69850904474, 10451.059404653617, 10595.894041059491, 10893.291936024134, 13257.138286011379, 13259.469621215043, 13264.801027984588, 13279.203247805162, 13284.667883302995, 8035.297068907005, 8288.875666865002, 8294.336453083739, 8424.789380265542, 8445.408821382443, 8003.759417290329, 8395.40480857507, 8401.327893768485, 8511.914880851113, 8594.87107693385, 5687.7156142192725, 6356.269029061625, 6362.6500542469685, 6369.936300637027, 6377.202849643794, 1735.2798588585847, 1769.3541857222278, 1772.1759381887227, 1772.645470905824, 1773.4325015995016, 362.4943204230137, 898.7507364762985, 941.4257303045136, 965.8599503072015, 1016.7813920007234, 11351.60269390584, 11653.59212427561, 12034.977026952807, 12077.516899324104, 12091.274523528617, 416.3962254730592, 431.4072424727348, 431.43266604415095, 434.87388657289665, 456.3337527210244, 5822.7088645567555, 6098.72555734993, 6194.0708893666315, 6208.192934918522, 6210.826210826265, 301210.0, 303219.0, 303445.0, 304332.4783376069, 615219.0, 84535.577322113, 111000.0, 130186.0, 132042.0, 140788.0, 918.7657147427363, 967.274544091931, 985.6156099121348, 1033.2767063055892, 1033.5452400943561, 15553.37786488573, 18155.092245387943, 18881.339153129575, 19155.425337239903, 19544.0, 36087.63912360843, 47142.764286178346, 52981.73509132584, 54106.729466352415, 54814.45185548093, 64303.67848160729, 65265.34734652593, 80526.59736701279, 82245.0, 82518.17481825105, 7211.8557628848175, 7296.635168241461, 7928.32609228199, 7988.600569971362, 8173.836523269382, 9165.679201228191, 12059.638210853689, 12091.818622720843, 12120.454579543766, 12431.191972521758, 10487.737806554896, 10616.363018218932, 10829.458527073772, 10869.510872010937, 10926.515023085502, 10306.690799276033, 10329.9483502582, 10458.529366178693, 10512.632057877938, 10631.043206111488, 9069.0, 10521.053105220566, 10629.521671524646, 10818.134549235885, 11036.061934735637, 7851.411144164096, 7852.764417067498, 7853.175416581405, 8087.150849160754, 8104.797380065545, 9720.222382209548, 10230.744231394274, 10312.639057632947, 10360.378377297386, 10361.585536578452], "value": 8832.03488289626, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.2185020089148, 1471.4114354258295, 1614.7497137943624, 1764.523578633768, 1811.5108920591433, 7886.369090472763, 8318.83362332753, 8320.375971802125, 8350.0, 8352.331813454924, 7111.644417779116, 7369.8157546061375, 7392.445566582501, 7859.214078592138, 8089.838203235931, 2284.4631511594803, 2319.2694301295123, 2321.8374713770045, 2322.419395151212, 2326.7324257710325, 2309.9653505197434, 2335.0192918973985, 2346.2257455039794, 2356.234223877238, 2358.3278765551822, 2277.1688333758225, 2284.1662793080573, 2309.064828744353, 2310.191432998454, 2332.1837356925025, 2283.7716228377194, 2306.9423264418356, 2309.734380546242, 2311.1448763957274, 2314.3404129823, 2313.9305820825234, 2343.6601692754457, 2347.66183275533, 2347.941301467477, 2348.0, 8177.79555511111, 8323.583820808995, 8329.875051874227, 8343.874841877378, 8371.330293576499, 4111.753294802322, 5120.308758317617, 5121.84634460963, 5130.127878260667, 5137.357830271216, 3147.4491963906225, 3279.672032796725, 3494.2138018945534, 3499.422595271777, 3509.4034014217646, 930.4510338900081, 1032.726327523207, 1059.3220338983058, 1066.9786604267872, 1074.3178081918038, 10258.435786031765, 10581.629642962536, 10589.682309530726, 10675.41285229312, 10690.51892664832, 3537.593176784703, 4152.37714342851, 4182.707210495236, 4201.285781417167, 4348.869533913988, 7321.633918304064, 8501.234888860032, 8514.403991720521, 8538.487690738575, 8539.957300213584, 4425.601695847389, 4927.507249275044, 4992.051510213048, 4997.275395067695, 5005.7997680092385, 4212.936805947913, 4347.174036932973, 4533.637309015271, 4705.764711764398, 4714.66997310185, 3561.71506279497, 3604.062943634674, 3694.3350196964802, 3702.9259414811554, 3720.479132921339, 986.6842610364614, 1113.4877956140267, 1131.1629394248362, 1142.1205671632963, 1145.4158379226487, 773.215186585616, 847.1020718038899, 875.0899064972484, 877.6270085213758, 910.913463220993, 4718.598919091847, 5460.699661518575, 5666.376698563155, 5702.863425116169, 5790.420957904176, 801.8636831738619, 889.9510526920952, 894.4499133033138, 910.3081657940013, 959.5825815770162, 3727.366347720894, 3749.643783840531, 3819.6180381962135, 3827.75119617224, 3934.1934903344745, 9483.478408687588, 9941.751456213653, 9949.552270147717, 10002.249831262685, 10022.298439109192, 9877.703668889944, 9962.750931226778, 9970.900290997142, 9980.750481237881, 10002.799944001079, 7372.520786148883, 7570.507916985378, 7687.115981662068, 7709.961450192825, 7763.1848655891445, 5413.83758487246, 5462.481064298885, 5615.045442274742, 5693.430656934273, 5727.828165155052, 6902.620355880475, 8893.73318800437, 9299.116583924517, 9364.484953327632, 9386.624535018796, 8917.464952102635, 9344.719658410258, 9442.669506567237, 9509.809803804024, 9516.666916657883, 6973.302669732986, 7202.49582529218, 7450.105987281344, 7762.922370776446, 7766.10689770661, 7283.562986220843, 7428.145763237081, 7757.612119393895, 7757.92242077572, 7854.136045035156, 6905.0, 7015.193252775996, 7035.33164349386, 7098.822529436806, 7181.281871812777, 6825.317468253135, 6971.337722916315, 6994.125734283215, 7019.438444924292, 7033.894491582732, 6492.902606460812, 6538.280789113099, 6630.270670226175, 6636.203655561363, 6638.402543771083, 4741.431028276629, 4770.332153498583, 4797.856064318076, 4866.9269960949905, 4903.019396120719, 4706.858794236178, 4729.266963620645, 4786.1863483208635, 4908.8772780680765, 4977.427595826526], "value": 4314.0022179941825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.8254497831381, 289.3576260701243, 289.703576495184, 295.35170301188884, 308.61423220973785, 4426.734395936246, 4985.326980857583, 5045.394552653686, 5243.528082472577, 5290.8412747617585, 5168.664036837603, 5183.429822719498, 5194.142966410538, 5201.867234433883, 5211.973940130294, 3297.4559197732347, 3884.7280690351545, 3941.4087886817065, 3984.2828290907623, 4003.4195041718935, 3780.7353485256044, 4023.1551374211303, 4030.3349947258666, 4066.0648050948153, 4072.490938632671, 3332.2669012817264, 3908.7882756345625, 3956.6241207085423, 3961.2671655743634, 3996.680265578747, 3265.5591495148205, 3731.6081811409954, 3910.432987216838, 3930.528336599598, 3966.5438474575362, 3285.4743241081314, 3309.7352211822995, 3395.779274347184, 3398.1844357354057, 3402.812845293508, 4530.977345113253, 4651.55810198034, 4943.303861331713, 5095.388553373582, 5120.615953803443, 3381.3575420669995, 3580.785152890809, 4400.273954797454, 4408.3607876858, 4421.115776844612, 4810.100010495869, 5052.368453943257, 5349.545288650469, 5393.2449457076045, 5552.724402210316, 3097.535369694564, 3754.55449651886, 3762.811859407019, 3764.9058773530605, 3766.5103536540073, 5302.310699609026, 5365.873166635011, 5713.343130492152, 5717.427707380462, 5779.219805326269, 3344.34454944799, 3592.550931133608, 3658.5061016762397, 3725.8509659613856, 3726.993711697881, 3768.09565704229, 3772.75477093988, 3774.9245015099546, 3777.9610607083273, 3819.980900095482, 3943.8225279862477, 4389.429374181336, 4411.139827733626, 4475.440569928759, 4517.819287228474, 4736.247588392317, 5160.509751573594, 5495.560355171577, 5497.257870187554, 5534.833954981357, 1134.478140055567, 1146.850909381775, 1170.4089434835523, 1247.0, 1266.645339304996, 753.4763339479119, 800.6197056398164, 821.219841150909, 830.3689196210803, 839.2111415269749, 4906.58294045003, 4963.975180124148, 4964.35463389757, 4979.377577802775, 4994.90010199794, 4490.079533695584, 5127.102757017507, 5417.756200970965, 5440.646357986798, 5458.645188062763, 4830.9275360869615, 4864.762209383653, 4943.937053533507, 4959.603231741452, 5123.130057528975, 2613.4773045391, 2643.643108180371, 2708.0, 2722.9591556126675, 2803.5934789455414, 4847.61219102478, 4919.335889654851, 5133.5882632276425, 5409.24270602116, 5647.576431767634, 5251.632385733038, 5481.793151006706, 5560.471755183251, 5604.982206405691, 5623.634463759842, 4783.378160839069, 5425.294711687456, 5473.0867429787095, 5475.67145971243, 5540.999999999919, 5012.245714000071, 5125.74371281427, 5305.869536973204, 5431.37539182999, 5480.671159730429, 4618.983823558811, 4733.431988161443, 5026.607040247745, 5350.143976963823, 5373.0, 5021.72380519075, 5194.935038317211, 5594.552435805207, 5607.635503692247, 5616.66300022, 3500.544929159193, 3608.440691692792, 3639.2721455708433, 3675.98910299659, 3713.9786558696574, 4611.654125940501, 5064.0, 5139.486051394831, 5181.637285389987, 5436.864078398072, 5071.112555302881, 5506.449355064461, 5560.944390556043, 5568.610197286151, 5575.358833734173, 5255.500727430888, 5512.889742205053, 5555.2500412445415, 5638.323401191883, 5647.858803529944, 5245.527902488641, 5274.7098909560345, 5424.918626220531, 5504.394516603091, 5506.284183056177, 5077.238414237894, 5293.7353132344, 5353.892922141457, 5471.699056551928, 5499.945000549943, 4417.933730994102, 5021.824236151863, 5362.490563396471, 5373.865653358697, 5460.672359658433], "value": 3915.6867907205747, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.6442014572568, 705.954112982656, 719.1657677094568, 752.5710345103294, 756.8032311598982, 13978.0, 14566.563003109924, 14580.270986450689, 14594.051386659872, 14607.853921460795, 12829.743405131916, 12849.807252891214, 12894.484220631164, 12916.16044957077, 12931.547395841148, 642.4667525953447, 672.9697163627624, 675.1627981303174, 776.9339606133457, 805.8066064144592, 786.8268980824238, 802.8715405535115, 803.1606970715616, 803.3051410529908, 804.2399932064202, 854.5513605357175, 855.0850589868843, 855.9914400856005, 856.6873091321654, 858.3133493205441, 679.3003206697085, 701.7473709464589, 703.6903762344552, 706.2478460439667, 849.0363437498422, 524.1927431755092, 612.9816105516842, 630.5838146823121, 635.7838334966134, 637.2512298049772, 12177.086718496148, 13444.193348399029, 13862.722745545134, 13903.582892513139, 13906.30468476572, 13061.151025183428, 13171.60485185446, 13298.800517992238, 13303.935685145165, 13319.067665263436, 11472.124347152427, 11645.883541164565, 11920.761584768255, 12122.0, 12548.498060077585, 4290.206311832321, 4396.868093957187, 4399.318105693623, 4533.886652833673, 4581.289900065495, 67730.98403523951, 78913.42173156505, 84154.73767893486, 84388.84364012009, 85953.0, 9844.950775246221, 10458.843117353246, 10483.842742358871, 10501.737456563495, 10670.573177072985, 19969.700454493195, 19996.000199989943, 19997.300094496622, 20015.799842001685, 20052.0, 7268.27317268269, 7370.226126256773, 7520.947067410567, 7652.273034061755, 7698.230176982257, 3973.4635824163947, 4079.3274757349936, 4196.370544418301, 4357.520672725997, 4398.450193725785, 3206.9839650801596, 3301.0, 3302.256992176789, 3305.0910999474854, 3329.334133173375, 522.7516929458553, 597.375742349246, 609.3236507476697, 611.7186094396538, 613.5674349583533, 529.0, 553.3083645443196, 592.8764990343275, 610.2615834839805, 617.9876402471925, 8690.78273043179, 8978.281737460988, 9289.489078100769, 9379.577918993662, 9503.57233924475, 590.8375196820908, 666.8266250774833, 672.1262358933367, 672.8149758816364, 686.8866637004938, 3969.920601587952, 4222.746635201897, 4447.2439685253585, 4602.102589994919, 4623.630109591292, 74476.62761686157, 100512.0, 117684.0, 121319.39340303242, 125194.74805252012, 66368.6726265472, 66522.0, 91812.16375672449, 107869.8426031475, 108085.0, 91559.0, 91564.54217728869, 94509.39071465268, 95774.0, 96792.0, 8007.359411247203, 8766.561671916252, 8846.513441760764, 8959.686410975584, 8966.417182883091, 56301.0, 57139.0, 57613.71193144008, 58969.34637941389, 60367.69816150891, 69215.30784692088, 80706.0, 84036.57981710053, 86129.0, 87244.69132963137, 11996.280223186288, 12082.697932551757, 12300.323482208565, 12302.630921072383, 13106.868931310946, 13291.468341266242, 13708.725825483236, 14385.496507622183, 14475.203863787172, 14523.346449410014, 12310.692232693897, 13225.140365876186, 13530.661733456742, 13550.0, 13914.791278130633, 12692.492300308253, 13194.934025329812, 13213.405396756976, 13260.53588124411, 13397.464101435833, 10700.464976751287, 12079.776046302299, 13237.669058273621, 13261.138026028277, 13559.0, 10437.68686939393, 11188.825173357005, 11308.378039207593, 11718.355490448108, 11844.28934263947, 11197.72005699864, 11228.775424491629, 11523.596674116367, 11589.768204636028, 11610.825837612274], "value": 7217.426541664019, "unit": "score"}], "name": "dromaeo_css", "value": 7021.92003227381, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 117.0, 94.0, 156.0, 168.0, 167.0, 125.0, 115.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 126.0, 141.0, 177.0, 115.0, 114.0, 113.0, 114.0, 144.0, 112.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 184.0, 178.0, 184.0, 182.0, 184.0, 184.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 88.0, 61.0, 59.0, 59.0, 58.0, 82.0, 59.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 96.0, 72.0, 68.0, 69.0, 89.0, 90.0, 71.0, 125.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 100.0, 129.0, 118.0, 89.0, 90.0, 88.0, 89.0, 86.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 80.0, 81.0, 84.0, 80.0, 86.0, 85.0, 81.0, 84.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 55.0, 57.0, 58.0, 56.0, 56.0, 57.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 76.0, 76.0, 73.0, 69.0, 69.0, 69.0, 69.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 84.0, 85.0, 84.0, 84.0, 86.0, 88.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 149.0, 150.0, 146.0, 150.0, 153.0, 150.0, 192.0, 162.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 194.0, 169.0, 171.0, 168.0, 168.0, 166.0, 170.0, 169.0, 170.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 69.0, 65.0, 79.0, 85.0, 86.0, 74.0, 67.0], "value": 73.4, "unit": "score"}], "name": "kraken", "value": 1477.3, "alertThreshold": 2.0}]} 15:08:45 INFO - Return code: 0 15:08:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:08:45 INFO - # TBPL SUCCESS # 15:08:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:08:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:08:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:08:45 INFO - 2016-12-01 15:08:45.133 screenresolution[1921:18860] starting screenresolution argv=screenresolution get 15:08:45 INFO - 2016-12-01 15:08:45.135 screenresolution[1921:18860] Display 0: 1600x1200x32@60 15:08:45 INFO - 2016-12-01 15:08:45.142 screenresolution[1922:18863] starting screenresolution argv=screenresolution list 15:08:45 INFO - Available Modes on Display 0 15:08:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:08:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:08:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:08:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:08:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:08:45 INFO - Intel Iris: 15:08:45 INFO - Chipset Model: Intel Iris 15:08:45 INFO - Type: GPU 15:08:45 INFO - Bus: Built-In 15:08:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:08:45 INFO - Vendor: Intel (0x8086) 15:08:45 INFO - Device ID: 0x0a2e 15:08:45 INFO - Revision ID: 0x0009 15:08:45 INFO - Displays: 15:08:45 INFO - Display: 15:08:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:08:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:08:45 INFO - Display Serial Number: 1600x1200 60 15:08:45 INFO - Main Display: Yes 15:08:45 INFO - Mirror: Off 15:08:45 INFO - Online: Yes 15:08:45 INFO - Rotation: Supported 15:08:45 INFO - Return code: 0 15:08:45 INFO - Running post-action listener: _resource_record_post_action 15:08:45 INFO - [mozharness: 2016-12-01 23:08:45.387661Z] Finished run-tests step (success) 15:08:45 INFO - Running post-run listener: _resource_record_post_run 15:08:45 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 1540096; Write bytes: 651191296; Read time: 15; Write time: 2031 15:08:45 INFO - TinderboxPrint: CPU usage
53.6% 15:08:45 INFO - TinderboxPrint: I/O read bytes / time
1,540,096 / 15 15:08:45 INFO - TinderboxPrint: I/O write bytes / time
651,191,296 / 2,031 15:08:45 INFO - TinderboxPrint: CPU idle
2,252.6 (46.4%) 15:08:45 INFO - TinderboxPrint: CPU system
976.7 (20.1%) 15:08:45 INFO - TinderboxPrint: CPU user
1,628.4 (33.5%) 15:08:45 INFO - TinderboxPrint: Swap in / out
350,408,704 / 0 15:08:45 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 349463040; Write bytes: 352098304; Read time: 27911; Write time: 872 15:08:45 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 499712; Write bytes: 297819136; Read time: 8; Write time: 1152 15:08:45 INFO - Running post-run listener: _upload_blobber_files 15:08:45 INFO - Blob upload gear active. 15:08:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:08:45 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 15:08:45 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'] 15:08:45 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 15:08:46 INFO - (blobuploader) - INFO - Open directory for files ... 15:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:08:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:47 INFO - (blobuploader) - INFO - Done attempting. 15:08:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:08:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:08:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:48 INFO - (blobuploader) - INFO - Done attempting. 15:08:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:08:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:08:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:08:51 INFO - (blobuploader) - INFO - Done attempting. 15:08:51 INFO - (blobuploader) - INFO - Iteration through files over. 15:08:51 INFO - Return code: 0 15:08:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:08:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:08:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/511615f519ee746666ef707de8b4e8bc3206ca9d6b41645f8d6840525b95c1868270fcced25e01a4a127b532444321f8961e7a7bd91c825e04a8b499e8bd1668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8b7de06f3c6a3f410b1860d6f9c9c081048bbf97068803957749af494f70473da32b3e1c1ef03392658bb9bf53b1919ace7ad30aa6822c3f3f187b3a16927d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e81f9782beacbb3596610b8dec9dda51e22b387d01be23cc464a9b7968d154b5a4bf50d8786351e356bb8a7b654f15aed29eeb7b655070a8eda474c0d13a0f4c"} 15:08:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:08:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:08:51 INFO - Contents: 15:08:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/511615f519ee746666ef707de8b4e8bc3206ca9d6b41645f8d6840525b95c1868270fcced25e01a4a127b532444321f8961e7a7bd91c825e04a8b499e8bd1668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8b7de06f3c6a3f410b1860d6f9c9c081048bbf97068803957749af494f70473da32b3e1c1ef03392658bb9bf53b1919ace7ad30aa6822c3f3f187b3a16927d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e81f9782beacbb3596610b8dec9dda51e22b387d01be23cc464a9b7968d154b5a4bf50d8786351e356bb8a7b654f15aed29eeb7b655070a8eda474c0d13a0f4c"} 15:08:51 INFO - Running post-run listener: copy_logs_to_upload_dir 15:08:51 INFO - Copying logs to upload dir... 15:08:51 INFO - mkdir: /builds/slave/test/build/upload/logs 15:08:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.527252 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-01 15:08:51.514300) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 15:08:51.517655) ========= 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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/511615f519ee746666ef707de8b4e8bc3206ca9d6b41645f8d6840525b95c1868270fcced25e01a4a127b532444321f8961e7a7bd91c825e04a8b499e8bd1668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8b7de06f3c6a3f410b1860d6f9c9c081048bbf97068803957749af494f70473da32b3e1c1ef03392658bb9bf53b1919ace7ad30aa6822c3f3f187b3a16927d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e81f9782beacbb3596610b8dec9dda51e22b387d01be23cc464a9b7968d154b5a4bf50d8786351e356bb8a7b654f15aed29eeb7b655070a8eda474c0d13a0f4c"} build_url:https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/Ow9Lpma7S_64fyYKaUunkQ/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/511615f519ee746666ef707de8b4e8bc3206ca9d6b41645f8d6840525b95c1868270fcced25e01a4a127b532444321f8961e7a7bd91c825e04a8b499e8bd1668", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8b7de06f3c6a3f410b1860d6f9c9c081048bbf97068803957749af494f70473da32b3e1c1ef03392658bb9bf53b1919ace7ad30aa6822c3f3f187b3a16927d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e81f9782beacbb3596610b8dec9dda51e22b387d01be23cc464a9b7968d154b5a4bf50d8786351e356bb8a7b654f15aed29eeb7b655070a8eda474c0d13a0f4c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 15:08:51.552938) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:08:51.553321) ========= 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.YDfEB5wjhL/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.MnCRlc1jI4/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005019 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:08:51.602327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 15:08:51.602675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 15:08:51.603027) ========= ========= Total master_lag: 0.21 =========