builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1482153608.79 results: success (0) buildid: 20161219025638 builduid: 68d87f31c1bd4fd7946f4d73d4078dbe revision: d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.785860) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.790785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.791154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.817912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.818252) ========= 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.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-19 05:20:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-12-19 05:20:09 (14.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130830 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.976789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:08.977110) ========= 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.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.026363 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:09.025637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-19 05:20:09.028977) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 --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 d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-19 05:20:09,187 truncating revision to first 12 chars 2016-12-19 05:20:09,188 Setting DEBUG logging. 2016-12-19 05:20:09,188 attempt 1/10 2016-12-19 05:20:09,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d5ae914fb19e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-19 05:20:11,141 unpacking tar archive at: mozilla-aurora-d5ae914fb19e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.212069 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-19 05:20:11.267003) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:11.267313) ========= script_repo_revision: d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:11.267729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:11.267994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-19 05:20:11.288993) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-19 05:20:11.289350) ========= /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.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:11 INFO - MultiFileLogger online at 20161219 05:20:11 in /builds/slave/test 05:20:11 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 05:20:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:20:11 INFO - {'append_to_log': False, 05:20:11 INFO - 'base_work_dir': '/builds/slave/test', 05:20:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:20:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:20:11 INFO - 'branch': 'Mozilla-Aurora', 05:20:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:20:11 INFO - 'config_files': ('talos/mac_config.py',), 05:20:11 INFO - 'default_actions': ('clobber', 05:20:11 INFO - 'read-buildbot-config', 05:20:11 INFO - 'download-and-extract', 05:20:11 INFO - 'populate-webroot', 05:20:11 INFO - 'create-virtualenv', 05:20:11 INFO - 'install', 05:20:11 INFO - 'run-tests'), 05:20:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:20:11 INFO - 'download_minidump_stackwalk': True, 05:20:11 INFO - 'download_symbols': 'ondemand', 05:20:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:20:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:20:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:20:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:20:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:20:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:20:11 INFO - 'installer_path': 'installer.exe', 05:20:11 INFO - 'log_level': 'info', 05:20:11 INFO - 'log_name': 'talos', 05:20:11 INFO - 'log_to_console': True, 05:20:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:20:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:20:11 INFO - 'opt_config_files': (), 05:20:11 INFO - 'pip_index': False, 05:20:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:11 INFO - 'cmd': ('bash', 05:20:11 INFO - '-c', 05:20:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:11 INFO - 'enabled': True, 05:20:11 INFO - 'halt_on_failure': False, 05:20:11 INFO - 'name': 'check_screen_resolution'},), 05:20:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:20:11 INFO - 'cmd': ('bash', 05:20:11 INFO - '-c', 05:20:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:20:11 INFO - 'enabled': True, 05:20:11 INFO - 'halt_on_failure': False, 05:20:11 INFO - 'name': 'check_screen_resolution'},), 05:20:11 INFO - 'run_cmd_checks_enabled': True, 05:20:11 INFO - 'sps_profile': False, 05:20:11 INFO - 'sps_profile_interval': 0, 05:20:11 INFO - 'suite': 'dromaeojs', 05:20:11 INFO - 'system_bits': '32', 05:20:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:20:11 INFO - 'title': 't-yosemite-r7-0171', 05:20:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:20:11 INFO - 'use_talos_json': True, 05:20:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:20:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:20:11 INFO - 'work_dir': 'build'} 05:20:11 INFO - [mozharness: 2016-12-19 13:20:11.562374Z] Running clobber step. 05:20:11 INFO - Running pre-action listener: _resource_record_pre_action 05:20:11 INFO - Running main action method: clobber 05:20:11 INFO - rmtree: /builds/slave/test/build 05:20:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:20:14 INFO - Running post-action listener: _resource_record_post_action 05:20:14 INFO - [mozharness: 2016-12-19 13:20:14.113236Z] Finished clobber step (success) 05:20:14 INFO - [mozharness: 2016-12-19 13:20:14.113368Z] Running read-buildbot-config step. 05:20:14 INFO - Running pre-action listener: _resource_record_pre_action 05:20:14 INFO - Running main action method: read_buildbot_config 05:20:14 INFO - Using buildbot properties: 05:20:14 INFO - { 05:20:14 INFO - "project": "", 05:20:14 INFO - "product": "firefox", 05:20:14 INFO - "script_repo_revision": "production", 05:20:14 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:20:14 INFO - "repository": "", 05:20:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:20:14 INFO - "buildid": "20161219025638", 05:20:14 INFO - "pgo_build": "False", 05:20:14 INFO - "basedir": "/builds/slave/test", 05:20:14 INFO - "buildnumber": 0, 05:20:14 INFO - "slavename": "t-yosemite-r7-0171", 05:20:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:20:14 INFO - "platform": "yosemite_r7", 05:20:14 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:20:14 INFO - "branch": "mozilla-aurora", 05:20:14 INFO - "repo_path": "releases/mozilla-aurora", 05:20:14 INFO - "revision": "d5ae914fb19ece1f456fe85a3ddecdab85dab5f7", 05:20:14 INFO - "stage_platform": "macosx64", 05:20:14 INFO - "builduid": "68d87f31c1bd4fd7946f4d73d4078dbe", 05:20:14 INFO - "slavebuilddir": "test" 05:20:14 INFO - } 05:20:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 05:20:14 INFO - Running post-action listener: _resource_record_post_action 05:20:14 INFO - [mozharness: 2016-12-19 13:20:14.116589Z] Finished read-buildbot-config step (success) 05:20:14 INFO - [mozharness: 2016-12-19 13:20:14.116699Z] Running download-and-extract step. 05:20:14 INFO - Running pre-action listener: _resource_record_pre_action 05:20:14 INFO - Running main action method: download_and_extract 05:20:14 INFO - mkdir: /builds/slave/test/build/tests 05:20:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:14 INFO - trying https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 05:20:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/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 05:20:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/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 05:20:15 INFO - Downloaded 1199 bytes. 05:20:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:20:15 INFO - Using the following test package requirements: 05:20:15 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:20:15 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 05:20:15 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 05:20:15 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'jsshell-mac.zip'], 05:20:15 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 05:20:15 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:20:15 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 05:20:15 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 05:20:15 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 05:20:15 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:20:15 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 05:20:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:20:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:20:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:20:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:20:15 INFO - Http code: 200 05:20:15 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 05:20:15 INFO - x-amz-cf-id: NqQk42D3rwsigsaVc0s5LeMXndjNZ0J0yJOoMgGcjIhzNKjHsEQWsw== 05:20:15 INFO - x-amz-version-id: 9itJr91GFfhEW9hqdR6alR9ziozZigX6 05:20:16 INFO - Expected file size: 16267372 05:20:16 INFO - Obtained file size: 16267372 05:20:22 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 05:20:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:20:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:20:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:20:23 INFO - Http code: 200 05:20:23 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 05:20:23 INFO - x-amz-cf-id: bTORDOxNz7-S_ayoLtOENTDb5evbJTdGupMuMqz6bCWcDyk_xv3MUQ== 05:20:23 INFO - x-amz-version-id: 9itJr91GFfhEW9hqdR6alR9ziozZigX6 05:20:23 INFO - Expected file size: 16267372 05:20:23 INFO - Obtained file size: 16267372 05:20:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 05:20:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:20:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 05:20:30 INFO - Http code: 200 05:20:30 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 05:20:30 INFO - x-amz-cf-id: vBQB9zBAkZm_TuUviqXIWKHmV3NxTCXy1X4jegZNwpZXUFqaNGw83A== 05:20:30 INFO - x-amz-version-id: wKvWK4X017s4IXtHfhygtpG8tPJb9rP3 05:20:31 INFO - Expected file size: 14409038 05:20:31 INFO - Obtained file size: 14409038 05:20:31 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')]} 05:20:31 INFO - trying https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:20:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 05:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/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 05:20:34 INFO - Downloaded 109682182 bytes. 05:20:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:20:34 INFO - mkdir: /builds/slave/test/properties 05:20:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:34 INFO - Contents: 05:20:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:20:35 INFO - Running post-action listener: _resource_record_post_action 05:20:35 INFO - Running post-action listener: set_extra_try_arguments 05:20:35 INFO - [mozharness: 2016-12-19 13:20:35.194547Z] Finished download-and-extract step (success) 05:20:35 INFO - [mozharness: 2016-12-19 13:20:35.194700Z] Running populate-webroot step. 05:20:35 INFO - Running pre-action listener: _resource_record_pre_action 05:20:35 INFO - Running main action method: populate_webroot 05:20:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:35 INFO - '--tppagecycles', 05:20:35 INFO - '7'], 05:20:35 INFO - 'tests': ['tsvgm']}}, 05:20:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['damp', 'tps']}, 05:20:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'tests': ['damp', 'tps']}, 05:20:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['dromaeo_dom']}, 05:20:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['basic_compositor_video']}, 05:20:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['a11yr', 05:20:35 INFO - 'ts_paint', 05:20:35 INFO - 'tpaint', 05:20:35 INFO - 'sessionrestore', 05:20:35 INFO - 'sessionrestore_no_auto_restore', 05:20:35 INFO - 'tabpaint']}, 05:20:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['tsvgx', 05:20:35 INFO - 'tsvgr_opacity', 05:20:35 INFO - 'tart', 05:20:35 INFO - 'tscrollx', 05:20:35 INFO - 'cart']}, 05:20:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:35 INFO - 'tsvgr_opacity', 05:20:35 INFO - 'tart', 05:20:35 INFO - 'tscrollx', 05:20:35 INFO - 'cart']}, 05:20:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'talos_options': ['--disable-e10s'], 05:20:35 INFO - 'tests': ['tp5o']}, 05:20:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'tests': ['tp5o']}, 05:20:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'talos_options': ['--disable-e10s', 05:20:35 INFO - '--xperf_path', 05:20:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:35 INFO - 'tests': ['tp5n']}, 05:20:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:35 INFO - 'talos_options': ['--xperf_path', 05:20:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:35 INFO - 'tests': ['tp5n']}}, 05:20:35 INFO - 'talos.zip': {'path': '', 05:20:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:35 INFO - Running post-action listener: _resource_record_post_action 05:20:35 INFO - [mozharness: 2016-12-19 13:20:35.213467Z] Finished populate-webroot step (success) 05:20:35 INFO - [mozharness: 2016-12-19 13:20:35.213597Z] Running create-virtualenv step. 05:20:35 INFO - Running pre-action listener: _resource_record_pre_action 05:20:35 INFO - Running main action method: create_virtualenv 05:20:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:35 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 05:20:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:35 INFO - Using real prefix '/tools/python27' 05:20:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:36 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:38 INFO - Installing pip.................done. 05:20:38 INFO - Return code: 0 05:20:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:20:39 INFO - Reading from file tmpfile_stdout 05:20:39 INFO - Output received: 05:20:39 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) 05:20:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:39 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')]} 05:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:39 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')]} 05:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:39 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:39 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 05:20:39 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 05:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:39 INFO - 'HOME': '/Users/cltbld', 05:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:39 INFO - 'LOGNAME': 'cltbld', 05:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:39 INFO - 'MOZ_NO_REMOTE': '1', 05:20:39 INFO - 'NO_EM_RESTART': '1', 05:20:39 INFO - 'PAGER': '/bin/cat', 05:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:39 INFO - 'PWD': '/builds/slave/test', 05:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:39 INFO - 'SHELL': '/bin/bash', 05:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:39 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:39 INFO - 'USER': 'cltbld', 05:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:39 INFO - 'XPC_FLAGS': '0x0', 05:20:39 INFO - 'XPC_SERVICE_NAME': '0', 05:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:39 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 05:20:39 INFO - Cleaning up... 05:20:39 INFO - Return code: 0 05:20:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:39 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')]} 05:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:39 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')]} 05:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:39 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:39 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 05:20:39 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 05:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:39 INFO - 'HOME': '/Users/cltbld', 05:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:39 INFO - 'LOGNAME': 'cltbld', 05:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:39 INFO - 'MOZ_NO_REMOTE': '1', 05:20:39 INFO - 'NO_EM_RESTART': '1', 05:20:39 INFO - 'PAGER': '/bin/cat', 05:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:39 INFO - 'PWD': '/builds/slave/test', 05:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:39 INFO - 'SHELL': '/bin/bash', 05:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:39 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:39 INFO - 'USER': 'cltbld', 05:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:39 INFO - 'XPC_FLAGS': '0x0', 05:20:39 INFO - 'XPC_SERVICE_NAME': '0', 05:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:39 INFO - Downloading/unpacking psutil>=3.1.1 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:39 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 05:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:43 INFO - Installing collected packages: psutil 05:20:43 INFO - Running setup.py install for psutil 05:20:43 INFO - building 'psutil._psutil_osx' extension 05:20:43 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 05:20:43 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 05:20:43 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 05:20:44 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 05:20:44 INFO - building 'psutil._psutil_posix' extension 05:20:44 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 05:20:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:20:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:20:44 INFO - ^ 05:20:44 INFO - 1 warning generated. 05:20:44 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 05:20:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:44 INFO - Successfully installed psutil 05:20:44 INFO - Cleaning up... 05:20:44 INFO - Return code: 0 05:20:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:44 INFO - 'HOME': '/Users/cltbld', 05:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:44 INFO - 'LOGNAME': 'cltbld', 05:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:44 INFO - 'MOZ_NO_REMOTE': '1', 05:20:44 INFO - 'NO_EM_RESTART': '1', 05:20:44 INFO - 'PAGER': '/bin/cat', 05:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:44 INFO - 'PWD': '/builds/slave/test', 05:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:44 INFO - 'SHELL': '/bin/bash', 05:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:44 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:44 INFO - 'USER': 'cltbld', 05:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:44 INFO - 'XPC_FLAGS': '0x0', 05:20:44 INFO - 'XPC_SERVICE_NAME': '0', 05:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:47 INFO - Installing collected packages: mozsystemmonitor 05:20:47 INFO - Running setup.py install for mozsystemmonitor 05:20:47 INFO - Successfully installed mozsystemmonitor 05:20:47 INFO - Cleaning up... 05:20:47 INFO - Return code: 0 05:20:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:47 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')]} 05:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:47 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')]} 05:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:47 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:47 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 05:20:47 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 05:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:47 INFO - 'HOME': '/Users/cltbld', 05:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:47 INFO - 'LOGNAME': 'cltbld', 05:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:47 INFO - 'MOZ_NO_REMOTE': '1', 05:20:47 INFO - 'NO_EM_RESTART': '1', 05:20:47 INFO - 'PAGER': '/bin/cat', 05:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:47 INFO - 'PWD': '/builds/slave/test', 05:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:47 INFO - 'SHELL': '/bin/bash', 05:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:47 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:47 INFO - 'USER': 'cltbld', 05:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:47 INFO - 'XPC_FLAGS': '0x0', 05:20:47 INFO - 'XPC_SERVICE_NAME': '0', 05:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:47 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:47 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 05:20:47 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 05:20:47 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 05:20:47 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 05:20:47 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 05:20:47 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 05:20:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:50 INFO - warning: no files found matching '*.txt' 05:20:50 INFO - no previously-included directories found matching 'build' 05:20:50 INFO - no previously-included directories found matching 'dist' 05:20:50 INFO - no previously-included directories found matching '.git*' 05:20:50 INFO - Installing collected packages: jsonschema, functools32 05:20:50 INFO - Running setup.py install for functools32 05:20:50 INFO - warning: no files found matching '*.txt' 05:20:50 INFO - no previously-included directories found matching 'build' 05:20:50 INFO - no previously-included directories found matching 'dist' 05:20:50 INFO - no previously-included directories found matching '.git*' 05:20:51 INFO - Successfully installed jsonschema functools32 05:20:51 INFO - Cleaning up... 05:20:51 INFO - Return code: 0 05:20:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:51 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')]} 05:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:51 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')]} 05:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:51 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:51 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 05:20:51 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 05:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:51 INFO - 'HOME': '/Users/cltbld', 05:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:51 INFO - 'LOGNAME': 'cltbld', 05:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:51 INFO - 'MOZ_NO_REMOTE': '1', 05:20:51 INFO - 'NO_EM_RESTART': '1', 05:20:51 INFO - 'PAGER': '/bin/cat', 05:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:51 INFO - 'PWD': '/builds/slave/test', 05:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:51 INFO - 'SHELL': '/bin/bash', 05:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:51 INFO - 'USER': 'cltbld', 05:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:51 INFO - 'XPC_FLAGS': '0x0', 05:20:51 INFO - 'XPC_SERVICE_NAME': '0', 05:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:51 INFO - Cleaning up... 05:20:51 INFO - Return code: 0 05:20:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:51 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')]} 05:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:51 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')]} 05:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:51 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:51 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 05:20:51 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 05:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:51 INFO - 'HOME': '/Users/cltbld', 05:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:51 INFO - 'LOGNAME': 'cltbld', 05:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:51 INFO - 'MOZ_NO_REMOTE': '1', 05:20:51 INFO - 'NO_EM_RESTART': '1', 05:20:51 INFO - 'PAGER': '/bin/cat', 05:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:51 INFO - 'PWD': '/builds/slave/test', 05:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:51 INFO - 'SHELL': '/bin/bash', 05:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:51 INFO - 'USER': 'cltbld', 05:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:51 INFO - 'XPC_FLAGS': '0x0', 05:20:51 INFO - 'XPC_SERVICE_NAME': '0', 05:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:51 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:51 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 05:20:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:55 INFO - Downloading docopt-0.6.1.tar.gz 05:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:55 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:55 INFO - Running setup.py install for blobuploader 05:20:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:55 INFO - Running setup.py install for requests 05:20:56 INFO - Running setup.py install for docopt 05:20:56 INFO - Successfully installed blobuploader requests docopt 05:20:56 INFO - Cleaning up... 05:20:56 INFO - Return code: 0 05:20:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:56 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')]} 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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')]} 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:56 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:20:56 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 05:20:56 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 05:20:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:20:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:56 INFO - 'HOME': '/Users/cltbld', 05:20:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PAGER': '/bin/cat', 05:20:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:20:56 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:20:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - 'XPC_FLAGS': '0x0', 05:20:56 INFO - 'XPC_SERVICE_NAME': '0', 05:20:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-gAJQ7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-En0LPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:56 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-JXwpK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-0CyP_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-jyRJBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ejv7Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-d4Mz4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-vhsbjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-7Mli2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-GV2H_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-EUAixy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-pBh0pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:57 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-XtFyEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-55NImc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-alL1Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-m2CGhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:58 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-nJ9mKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:58 INFO - Running setup.py install for manifestparser 05:20:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:58 INFO - Running setup.py install for mozcrash 05:20:58 INFO - Running setup.py install for mozdebug 05:20:58 INFO - Running setup.py install for mozdevice 05:20:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Running setup.py install for mozfile 05:20:59 INFO - Running setup.py install for mozhttpd 05:20:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Running setup.py install for mozinfo 05:20:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Running setup.py install for mozInstall 05:20:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:59 INFO - Running setup.py install for mozleak 05:20:59 INFO - Running setup.py install for mozlog 05:20:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Running setup.py install for moznetwork 05:21:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Running setup.py install for mozprocess 05:21:00 INFO - Running setup.py install for mozprofile 05:21:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Running setup.py install for mozrunner 05:21:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:21:00 INFO - Running setup.py install for mozscreenshot 05:21:00 INFO - Running setup.py install for moztest 05:21:00 INFO - Running setup.py install for mozversion 05:21:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:21:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:21:01 INFO - Cleaning up... 05:21:01 INFO - Return code: 0 05:21:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21: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')]} 05:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:01 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:21:01 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 05:21:01 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 05:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:01 INFO - 'HOME': '/Users/cltbld', 05:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:01 INFO - 'LOGNAME': 'cltbld', 05:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:01 INFO - 'MOZ_NO_REMOTE': '1', 05:21:01 INFO - 'NO_EM_RESTART': '1', 05:21:01 INFO - 'PAGER': '/bin/cat', 05:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:01 INFO - 'PWD': '/builds/slave/test', 05:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:01 INFO - 'SHELL': '/bin/bash', 05:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:21:01 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:01 INFO - 'USER': 'cltbld', 05:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:01 INFO - 'XPC_FLAGS': '0x0', 05:21:01 INFO - 'XPC_SERVICE_NAME': '0', 05:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-yO7Tb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:01 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)) 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-faDPjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:01 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)) 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-PRcw6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:01 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)) 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-HM0ZNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:01 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)) 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dsdgxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:01 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)) 05:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-I_Esur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-QOS_q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-4tJ6u1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-IrtuDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-YPz7L7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-amqUZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-TUsNyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-saMwTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-T4ARtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-obK54X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:02 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)) 05:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-g2tCgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:03 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)) 05:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:03 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-zAxR5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:03 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)) 05:21:03 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)) 05:21:03 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)) 05:21:03 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)) 05:21:03 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)) 05:21:03 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)) 05:21:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:21:03 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 05:21:03 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 05:21:03 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 05:21:03 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 05:21:03 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 05:21:03 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 05:21:06 INFO - Downloading blessings-1.6.tar.gz 05:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:21:06 INFO - Installing collected packages: blessings 05:21:06 INFO - Running setup.py install for blessings 05:21:06 INFO - Successfully installed blessings 05:21:06 INFO - Cleaning up... 05:21:06 INFO - Return code: 0 05:21:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:06 INFO - Reading from file tmpfile_stdout 05:21:06 INFO - Current package versions: 05:21:06 INFO - blessings == 1.6 05:21:06 INFO - blobuploader == 1.2.4 05:21:06 INFO - docopt == 0.6.1 05:21:06 INFO - functools32 == 3.2.3-2 05:21:06 INFO - jsonschema == 2.5.1 05:21:06 INFO - manifestparser == 1.1 05:21:06 INFO - mozInstall == 1.12 05:21:06 INFO - mozcrash == 1.0 05:21:06 INFO - mozdebug == 0.1 05:21:06 INFO - mozdevice == 0.48 05:21:06 INFO - mozfile == 1.2 05:21:06 INFO - mozhttpd == 0.7 05:21:06 INFO - mozinfo == 0.9 05:21:06 INFO - mozleak == 0.1 05:21:06 INFO - mozlog == 3.4 05:21:06 INFO - moznetwork == 0.27 05:21:06 INFO - mozprocess == 0.23 05:21:06 INFO - mozprofile == 0.28 05:21:06 INFO - mozrunner == 6.13 05:21:06 INFO - mozscreenshot == 0.1 05:21:06 INFO - mozsystemmonitor == 0.3 05:21:06 INFO - moztest == 0.7 05:21:06 INFO - mozversion == 1.4 05:21:06 INFO - psutil == 3.1.1 05:21:06 INFO - requests == 1.2.3 05:21:06 INFO - wsgiref == 0.1.2 05:21:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21: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')]} 05:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:07 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:21:07 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 05:21:07 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 05:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:07 INFO - 'HOME': '/Users/cltbld', 05:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:07 INFO - 'LOGNAME': 'cltbld', 05:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:07 INFO - 'MOZ_NO_REMOTE': '1', 05:21:07 INFO - 'NO_EM_RESTART': '1', 05:21:07 INFO - 'PAGER': '/bin/cat', 05:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:07 INFO - 'PWD': '/builds/slave/test', 05:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:07 INFO - 'SHELL': '/bin/bash', 05:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:21:07 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:07 INFO - 'USER': 'cltbld', 05:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:07 INFO - 'XPC_FLAGS': '0x0', 05:21:07 INFO - 'XPC_SERVICE_NAME': '0', 05:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 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)) 05:21:07 INFO - Cleaning up... 05:21:07 INFO - Return code: 0 05:21:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:07 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 0x10de98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2f0d59c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, '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.VHwx2qviFG/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.E43Br3DR7I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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 05:21:07 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 05:21:07 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 05:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:07 INFO - 'HOME': '/Users/cltbld', 05:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:07 INFO - 'LOGNAME': 'cltbld', 05:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:07 INFO - 'MOZ_NO_REMOTE': '1', 05:21:07 INFO - 'NO_EM_RESTART': '1', 05:21:07 INFO - 'PAGER': '/bin/cat', 05:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:07 INFO - 'PWD': '/builds/slave/test', 05:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:07 INFO - 'SHELL': '/bin/bash', 05:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:21:07 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:07 INFO - 'USER': 'cltbld', 05:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:07 INFO - 'XPC_FLAGS': '0x0', 05:21:07 INFO - 'XPC_SERVICE_NAME': '0', 05:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:21:07 INFO - Cleaning up... 05:21:07 INFO - Return code: 0 05:21:07 INFO - Running post-action listener: _resource_record_post_action 05:21:07 INFO - Running post-action listener: _start_resource_monitoring 05:21:07 INFO - Starting resource monitoring. 05:21:07 INFO - [mozharness: 2016-12-19 13:21:07.833781Z] Finished create-virtualenv step (success) 05:21:07 INFO - [mozharness: 2016-12-19 13:21:07.834802Z] Running install step. 05:21:07 INFO - Running pre-action listener: _resource_record_pre_action 05:21:07 INFO - Running main action method: install 05:21:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:08 INFO - Reading from file tmpfile_stdout 05:21:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:08 INFO - Reading from file tmpfile_stdout 05:21:08 INFO - Output received: 05:21:08 INFO - Usage: mozinstall [options] installer 05:21:08 INFO - Options: 05:21:08 INFO - -h, --help show this help message and exit 05:21:08 INFO - -d DEST, --destination=DEST 05:21:08 INFO - Directory to install application into. [default: 05:21:08 INFO - "/builds/slave/test"] 05:21:08 INFO - --app=APP Application being installed. [default: firefox] 05:21:08 INFO - mkdir: /builds/slave/test/build/application 05:21:08 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'] 05:21:08 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 05:21:39 INFO - Reading from file tmpfile_stdout 05:21:39 INFO - Output received: 05:21:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:21:39 INFO - Running post-action listener: _resource_record_post_action 05:21:39 INFO - [mozharness: 2016-12-19 13:21:39.562124Z] Finished install step (success) 05:21:39 INFO - [mozharness: 2016-12-19 13:21:39.562264Z] Running run-tests step. 05:21:39 INFO - Running pre-action listener: _resource_record_pre_action 05:21:39 INFO - Running main action method: run_tests 05:21:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:21:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:21:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:21:39 INFO - 2016-12-19 05:21:39.576 screenresolution[1628:7332] starting screenresolution argv=screenresolution get 05:21:39 INFO - 2016-12-19 05:21:39.579 screenresolution[1628:7332] Display 0: 1600x1200x32@60 05:21:39 INFO - 2016-12-19 05:21:39.585 screenresolution[1629:7335] starting screenresolution argv=screenresolution list 05:21:39 INFO - Available Modes on Display 0 05:21:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:21:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:21:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:21:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:21:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:21:39 INFO - Intel Iris: 05:21:39 INFO - Chipset Model: Intel Iris 05:21:39 INFO - Type: GPU 05:21:39 INFO - Bus: Built-In 05:21:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:21:39 INFO - Vendor: Intel (0x8086) 05:21:39 INFO - Device ID: 0x0a2e 05:21:39 INFO - Revision ID: 0x0009 05:21:39 INFO - Displays: 05:21:39 INFO - Display: 05:21:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:21:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:21:39 INFO - Display Serial Number: 1600x1200 60 05:21:39 INFO - Main Display: Yes 05:21:39 INFO - Mirror: Off 05:21:39 INFO - Online: Yes 05:21:39 INFO - Rotation: Supported 05:21:39 INFO - Return code: 0 05:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:39 INFO - Python 2.7.3 05:21:39 INFO - Return code: 0 05:21:39 INFO - grabbing minidump binary from tooltool 05:21:39 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')]} 05:21:39 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 0x10dcacd30>, '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 0x10db12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dca4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:39 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 05:21:39 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 05:21:39 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 05:21:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:39 INFO - Return code: 0 05:21:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:21:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:21:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:39 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/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 05:21:39 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/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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 05:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VHwx2qviFG/Render', 05:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:21:39 INFO - 'HOME': '/Users/cltbld', 05:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:21:39 INFO - 'LOGNAME': 'cltbld', 05:21:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:39 INFO - 'MOZ_NO_REMOTE': '1', 05:21:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:39 INFO - 'NO_EM_RESTART': '1', 05:21:39 INFO - 'PAGER': '/bin/cat', 05:21:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:39 INFO - 'PWD': '/builds/slave/test', 05:21:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:21:39 INFO - 'SHELL': '/bin/bash', 05:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E43Br3DR7I/Listeners', 05:21:39 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 05:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:21:39 INFO - 'USER': 'cltbld', 05:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:39 INFO - 'XPC_FLAGS': '0x0', 05:21:39 INFO - 'XPC_SERVICE_NAME': '0', 05:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:21:39 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/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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 05:21:40 INFO - mozversion application_buildid: 20161219025638 05:21:40 INFO - mozversion application_changeset: d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 05:21:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:21:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:40 INFO - mozversion application_name: Firefox 05:21:40 INFO - mozversion application_remotingname: firefox-dev 05:21:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:40 INFO - mozversion application_vendor: Mozilla 05:21:40 INFO - mozversion application_version: 52.0a2 05:21:40 INFO - mozversion platform_buildid: 20161219025638 05:21:40 INFO - mozversion platform_changeset: d5ae914fb19ece1f456fe85a3ddecdab85dab5f7 05:21:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:40 INFO - mozversion platform_version: 52.0a2 05:21:40 INFO - using testdate: 1482153700 05:21:40 INFO - actual date: 1482153700 05:21:40 INFO - starting webserver on 'localhost:49247' 05:21:40 INFO - SUITE-START | Running 2 tests 05:21:40 INFO - TEST-START | dromaeo_css 05:21:40 INFO - operating with platform_type : mac_ 05:21:40 INFO - Initialising browser for dromaeo_css test... 05:21:40 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpnuHBtE/profile http://localhost:49247/getInfo.html) 05:21:42 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 05:21:42 INFO - PROCESS | 1639 | colorDepth:24 05:21:42 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 05:21:42 INFO - PROCESS | 1639 | __metrics 05:21:43 INFO - PROCESS | 1639 | 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" 05:21:43 INFO - PROCESS | 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:44 INFO - TEST-INFO | 1639: exit 0 05:21:44 INFO - Browser initialized. 05:21:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:44 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpnuHBtE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:56 INFO - PROCESS | 1641 | RSS: Main: 222896128 05:21:56 INFO - PROCESS | 1641 | 05:22:48 INFO - PROCESS | 1641 | 1482153768056 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] 05:25:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 05:25:03 INFO - PROCESS | 1641 | RSS: Main: 454627328 05:25:03 INFO - PROCESS | 1641 | 05:28:06 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 05:28:07 INFO - PROCESS | 1641 | RSS: Main: 347979776 05:28:07 INFO - PROCESS | 1641 | 05:31:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 05:31:10 INFO - PROCESS | 1641 | RSS: Main: 323485696 05:31:10 INFO - PROCESS | 1641 | 05:34:12 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 05:34:13 INFO - PROCESS | 1641 | RSS: Main: 324927488 05:34:13 INFO - PROCESS | 1641 | 05:37:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 05:37:17 INFO - PROCESS | 1641 | RSS: Main: 316755968 05:37:17 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 05:40:20 INFO - PROCESS | 1641 | RSS: Main: 428421120 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | __start_tp_report 05:40:20 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 05:40:20 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 05:40:20 INFO - PROCESS | 1641 | |i|pagename|runs| 05:40:20 INFO - PROCESS | 1641 | |0;dojo.html;3273.770836041477;3301;3306.7023967842906;3331.2837739885917;3349.179451034497;5620.971895140524;5691.174779656958;5712.257406537155;5736.426357364267;5765.481106700396;5650.802221922234;5712.742926568304;5721.513671337931;5721.799737009205;5728.570357223204;4272.700910936236;5167.9483205168035;5174.120399532069;5202.115640341151;5228.424873263938;5132.101882170624;5138.460461651529;5138.640295179339;5272.393674727417;5279.023380674587;5118.07874582575;5168.741562921858;5183.144781111131;5229.7385130743505;5239.1093514102695;5187.325647665817;5279.208118782175;5280.1815718563685;5282.788688452457;5285.7621407036395;5148.510891465305;5195.506426889439;5231.843044708665;5234.659747116463;5291.629585928987;5563.527100196488;5605.243292155547;5605.691686957216;5612.803551875706;5657.858553536194;6064.423879731418;6164.383561643845;6164.458556881609;6177.474914632262;6202.162708034402;6310.495160387158;6319.052142178664;6320.557560970734;6334.778282760128;6340.334264902165;5627.662340259598;5697.715114244271;5735.082386818172;5810.4770570648;5811.331696854873;6316.399942005502;6330.651814150174;6332.525060620472;6336.714847831859;6343.492520598341;6145.600535965148;6157.753689852445;6158.04550294705;6158.753649854045;6169.198004259418;6111.694415279308;6122.530313748553;6139.308154112039;6271.090691849748;6281.403266689657;6111.417759411971;6184.288806787187;6185.165002724574;6234.844128896814;6248.593841400295;5894.351621321651;6096.116063170815;6185.659788711574;6227.377262273736;6289.55973081889;4914.041761856475;4941.332920055746;4942.777575009133;4950.43070046942;4951.084049450863;4376.10289890578;4399.758013309299;4420.359047938031;4429.247028005247;4442.955570444255;5832.650040997553;5862.824115276549;5863.237779088691;5867.413258674099;5896.852578685482;5596.600849787553;6129.448349648553;6298.370162983665;6312.337204593543;6321.620702757849;5658.717064146858;5732.742026608729;5747.827565173052;5799.449052340021;5841.912371314434;5345.198220266913;5367.1680889462195;5386.272853164773;5397;5399.676019438846;6328.272248691275;6330.49356051524;6334.176557047646;6352.396522330371;6355.14205582259;6101.60339577926;6235.00239961604;6288.151099601495;6296.181496405438;6302.275238347465;4950.430700469348;6263.467605253422;6310.905336419865;6321.620702757849;6328.746850125944;6099;6110.830655117547;6149.661768602769;6174.6912654368;6182.938170618237;6126.439927350641;6234;6288.371162883675;6294.370562943669;6295.18162638854;5800.187973683604;5858.765649373977;5864.179014937827;5939.406059394026;6105.114758359924;5912.172295878494;5915.290165180206;5921.3782552831335;5923.703814809156;5925.9407405925385;5788.884222315614;5795.652260864362;5797.565182611405;5810.680412577349;5821.76712931478;5827.825165245049;5916;5916.083007133901;5916.112583112395;5916.467517923407;5759.769609215752;5765.855853603525;5771.394003629558;5774.653520788598;5781.768729250783 05:40:20 INFO - PROCESS | 1641 | |1;ext.html;452.6220605794162;458.79124998125855;486.17350504142945;496.9925451118232;551.481607289148;38163.61836381636;39884.60115398845;40137;40293.99265018376;41589.376159357635;40521.18957620853;40830.59169408305;40859.18281634373;41005;41265.79367103168;8407.159284071606;9287.210587100104;9408.764780880465;9413.388129771578;9492.050794920522;8490.363222758288;10999.175061870352;11326.150538709588;11331.716707082307;11476.942615286953;11311.943440282786;11351.886481135165;11370.545178192862;11371.601993930213;11388.71528211793;8502.702405415843;11297.548098076068;11321.264117832314;11355.091592672648;11386.373749443777;8512.872306915402;10968.780624387467;11238.213325067249;11277.154213433943;11452.942735286353;38784.80607596972;39122.21755564901;39166.41250381246;39166.60833391658;39271.803640981896;3293;3349.547811045502;3512.227309991798;3857.614238576148;3888.6889048876023;28151.155465336073;28203.858980704965;28206.87172513097;29571.852140739375;29710.81156753749;3087.7992930459673;3124.8593813278458;3139.4035133324596;3229.612446506411;3296.5549650797075;490078;494977.5251123721;495473.52263238456;495952;500500;55600.16599750926;56122;56159.71920140373;56166.71916640472;56169.719151404795;58464.12303815446;58478.415215848145;58522;58576;58674.41325586689;15186.316615752317;15438.614034649;15440.691186176438;15443.845561544465;15457.845421545866;11239.213255072067;11449.484773185226;11459.484323205475;12328.19866708661;12401.503939842305;14477.49328773509;14608.707825843423;14690;14935.925320373328;15056.473023444127;5761.193432919395;6619.669016549154;6913.308669133046;6935.930640693528;7001.719931202695;735.4337160386473;891.1445012787797;891.5364010714521;1014.06705830636;1030.0172168575461;28793;28992;29002.27494312659;29652.406951861274;29962.70037299599;999.4652860719625;1022.4632068164274;1071.9410432426134;1079.3362082319318;1147.4893672315739;14610.780838287434;14777.74389176932;14914.850851491346;15024.399024038916;15358.308876100602;630851;639773;658442;658485;829833;46066.76966615146;55046;55678.72160639171;55727.721361392934;55859;1213.4478812140455;1250.9812352814715;1321.3789518926283;1340.1088276296155;1345.5357901523957;28086.2978425541;28945.131646050653;29182;29728.702712972594;29777.702222978358;46561.30158047564;46961;46965;47040.7647961758;47080.76459617817;534161;536634;538784;540182;540197;21078.683819743022;24520.264392068268;24995.12517061894;25695.486090278464;25825.870870645525;42613.786931065144;43051.56948430601;44443;44889.77555112204;44987.55012449834;35034.64965350416;37345.25309493849;37416.62583374132;37570.43644345392;38652.80673596614;36898;37214;37859.24281514299;37869.81065094657;38254.23491530098;35794.82102589471;36236.63763362333;36504;36608.45087323639;36630.450543242405;27270.590941136295;31377.529337059506;33346.33307333888;33679.326413471106;33721.662783372834;24679.38301542476;24878.751212487645;24937.376565586004;25151;25381.111661092513 05:40:20 INFO - PROCESS | 1641 | |2;jquery.html;322.6789344602121;341.42981221360327;351.85749771342614;371.9088823238307;372.98694545690904;15152.166630835314;15822.68354632908;16569.751453728204;19457.708134377997;20087.598248035032;19227.03864806761;20134.69797953032;20635.69046464304;20669;20693.37919862403;5815.331236907757;6010.819675409744;6011.57918945674;6040.154378387029;6066.878662426749;4145.875623731293;4728.598069164125;6100.359462256466;6720.260771315151;6769.661516924159;6407;6607.669616519179;6730.831729206784;6742.325767423267;6745.190577130751;6615.735370585171;6625.436837868763;6653.4677225821815;6659.101021362103;6659.600423974529;5656.632318899258;5732.369439361667;5785.334686511021;5828.883422331572;5829.912551311733;23057.308280751604;23189.9564519597;24336.756632433804;25617.743822561722;25683.871580642164;2313.2415191080795;2317.7102862142233;2325.93022209334;2457.6682147909983;2722.224166112668;14307.141571505743;15573.922130389388;15909;16318.918405408016;16430.75353869693;1737.4874412048475;1836.9816301837077;1842.456475339778;1850.6021205440761;1875.1093230715437;117087;118430;119739;119880.40059799733;120437.99999999913;11448.713782155513;11514.942425287987;11621.07031437483;11646.41767911601;11807.704807379712;14622.415103395957;14641.414343426144;14654.194019328826;14667.266636668124;14682.091328127275;8369.623366948503;8491.872621910676;8519.361047921431;8537.18896704812;8700.34747393948;8202.671893124209;8272.131426200283;8431.915680843236;8562.957185214034;8600.053994060649;4511.796969136397;4519.728816271035;4560.68075234737;4621.353010578522;4679.1577516046755;1858.4610462965588;1911.6750152474112;1915.1381878154887;1953.7948515405963;1959.8040195980288;799.2007992007992;878.446578655443;879.2482427524375;928.1182876267573;966.8211380894558;20601.48496287605;21103;21140.365789026353;21294.89352553258;21315.46711332229;780.605794073989;896.8744375787395;904.2946501728662;933.659214386744;951.8048799995984;8766.867941739234;11147.219694621299;11324.490397932113;11408;11420.600278990196;600934;601932;608208;614172;616055;94781.94506102994;115843;120515.39742301233;135450;142711;888.7911340834899;924.9676261330821;932.3659911260409;932.7015355086307;951.0774548687624;18867.339643112424;19339.613207735485;19585.60828783445;19644.214231431153;19657.935916728537;53875.73062134664;54651;54748;55088;55764;66965.66517167383;77371.45257094713;78366.6081669588;79800.60099699696;81284.59357703173;7921.643526041457;8001.319887809602;8104.189581041848;8152.9184708154535;8193.59032048407;11882.940585297018;12515.311657858905;12546.811797823222;12908.677283067998;12938.547150849676;5126.743662816919;5270.314859068297;10368.703912010998;10732.83900741474;11217.383043932661;10623;10778.568857245937;10789.622363217564;10980.670579882617;11045.33727976324;10719.249652524248;10787.6224332148;11274.436278185894;11534.077273818242;11599.478023488795;7023.68393422287;7034.261402552658;7399.1490978536;7409.333160015624;8108.75673729789;8607.827843443221;10840.945795270973;10871.184661150288;10957;11296.661100167008 05:40:20 INFO - PROCESS | 1641 | |3;mootools.html;1429.0711037825413;1450.5358285348689;1592.5938885584183;1740.6605711886186;1786.2319202742824;7961.92038079619;8381.497110173394;8384.748457546284;8389.412741108124;8399.538025408609;7439.962800186005;7663.271989161035;7950.602469876512;8165.428420010601;8197.057338406084;1957.6671965765784;2269.7843704848015;2307.469282065126;2308.0306271365985;2311.503026849227;2108.4307237046064;2242.461809165805;2300.4363930836967;2327.185485080225;2347.307544274435;2299.632058870581;2301.689271948282;2325.848819826714;2329.9068037278576;2334.9533009339802;2270.6139956207485;2290.5647926894003;2300.2869110575607;2300.470891694903;2302.1021801497513;1987.5627361980341;2295.391721193885;2314.1900334882826;2316.513532158249;2316.860988340705;8219.671213151527;8289;8324.70863519774;8371.58142092893;8432.030316513557;5013.974930125326;5191.299174611417;5217.113090774577;5225;5226.73866306687;2994.895178668736;3080.183751305906;3224.661410551905;3228.3543291341825;3233.8544765485376;962.4802606592432;990.173205373514;992.1864071461405;1000.6097621927454;1002.3935519011039;10790.51442685081;10808.675739727742;10813.08088812444;10820.350778953209;10836.566537338496;3990.461287726178;4390.21466601406;4616.538346165356;4703.976480117578;4723.6929599575915;9282.303827212985;9290.442573445614;9342.97227304996;9369.390989585656;9383.953080234554;5187.870303242374;5187.948120518747;5190.6366554341585;5195.896082078338;5226.764795584208;4554.294084416921;4719.528047195322;4755;4807.471178170398;4811.398575178103;3706.592274849764;3741.1956429367815;3781.1303400218103;3852.3451013327804;3865.555461121951;1224.2654407355515;1226.8650448450662;1235.3390935849277;1237.4555195713858;1253.9373031348396;863.4689665855453;884.7301573020162;886.7251152142857;886.9911300887038;890.844102282098;5482.890342193134;5576.498115169573;5622.859428514238;5678.4605462480995;5830.38780928005;366.3424153644215;425.39593776836966;877.893853744274;949.392388871123;978.5987745024506;3781.111438811877;3788.242351529705;3793.260314238697;3798.9841434573027;3873.69010479161;9805.460699661588;9986.35088719231;10104.090631843022;10138.341007834468;10151.746206344753;9885.110340069285;10057.949710251401;10066.043725846033;10068.39589624625;10068.899311006797;7725.072991241087;7734.4585878989055;7750.651220695082;7884.724034658874;7898.052233731874;5265.6840589564745;5540.445955404427;5571.554275657856;5623.8594035149445;5650.29371328584;9206.631734730803;9301.627934882528;9404.153626173676;9422.95288523553;9429;7295.781126566211;8529.573521324033;8886.4668119913;9380.906190938003;9491.525423728648;6683.565568238049;6870.862582748417;7140.714371425085;7147.892781608176;7211.3870321023305;7379.963100184679;7517.398608111447;7614.505057171266;7688.308052275322;7688.384929205538;6026.306974697791;6862.965685171542;6875.896861547181;6908.1364829396325;6986.056882320821;6804.047433359235;6807.319268073153;6840.3501667341525;6898.310168983062;6911;6450.580712253689;6525.706343214471;6528.967355163194;6543.509236807354;6544.509161812788;4708.882277943078;4751.643626728079;4846.95153048465;4882.9755851221935;4949.257611358325;4355.368471571668;4786.234202527578;4833.782479788348;4856.247281671346;4994.150994331045 05:40:20 INFO - PROCESS | 1641 | |4;prototype.html;299.80662472705103;303.12699425654125;306.5998871472728;313.8650380336454;314.30538509893137;4380.802863871126;5187.429382767892;5232.372115346155;5247.658902171361;5279.683219006863;4851.708897466155;4934.481879402656;5059.709774007632;5107.4892510748905;5131.076406246874;3550.8579656813763;3935.0487134649015;3956.8417263309434;3980.582038885919;4003.0792917628964;3669.834737572847;3983.2033593281317;3993.1414745829643;4007.8797636070963;4039.474868267121;3409.494021893918;3923.3330333843173;3930.6069393060607;3930.9535110289467;3957.5053118360206;3909;3912.4326972588815;3920.411938209263;3921.5490218624936;3945.6251656092627;3421.34994351075;3428.862845486178;3431.3480438716565;3455.7235421166247;3456.2569047654874;4296.076343586144;4485.708428952108;4748.810047598092;4807.9422527044;4834.709917404967;4122.2786012447705;4442.577955094261;4445.777711114463;4452.866414007585;4471.262241730111;4994.92609089044;5411.756470958816;5414.6734050157565;5423.701696406696;5423.888102938888;2900.448914706199;3000.7599392048583;3505.474178873164;3759.285735710206;3793.089658481972;5557.833265002057;5697.6011679182475;5698.088305871082;5699.487046165865;5700.971495142498;3066.003548846589;3790.3556395412847;3800.7149463790324;3800.904977375588;3801.5934104381554;3360.260742636616;3608.675219230282;3699.3896007159055;3880.670143037817;3891.727579069438;3685.9262814743556;4296.2266791977745;4445.444319460067;4460.461140906382;4493.550644935481;5482.314710661168;5489.1217405214975;5489.972550137303;5492.505674489235;5515.117581186991;999.3504222255447;1145.8109411946934;1147.839302497651;1217.3000524698334;1224.155332820351;711.2460791560962;728.8360118973295;834.6577903059716;847.1189962439117;851.8296340731879;4811.927821082687;4844.4186697596515;4853.2720091985775;4854.859108109591;4875.756212189447;4892.828750993769;5011.722010887273;5115.544538621482;5307.522322990872;5308.203769434538;4105.445764821711;4136.586341365839;4446.021875187454;4627.657979186066;4836.927446088312;2475.740047295044;2718.986405067962;2752.862356882148;2776.3475583237923;2781.3324802047364;5112.4120726116225;5140.887290167851;5216.843494695089;5437.32033495813;5468.425815289417;5767.798127065533;5802.823941022117;5812.273465816688;5815.534757219412;5834.5624078194305;4921.434035085939;5143.691378517301;5638.16604708802;5846.269216347957;5903.645781253139;5292.867678307919;5622.409646987171;5658.632188907708;5673.858153546029;5679;4894.632902532253;5595.328560572594;5602.775888964559;5606.355269143937;5610.635308704921;5563.91353242534;5840.532757379485;5850.590458668023;5876.44173803488;5982.910256346246;3718.8512459501317;3746.8946660734605;3768.530273193402;3797.962020379761;3804.486394336729;4773.403324584427;5498.477644623755;5535.723213839211;5620.297462817148;5633.605647604629;4564.748938808398;4640.350350950937;4783.736894470838;5455.481729235717;5486.506214440719;4319.784010799511;4693.765311734468;5074.5686616637995;5166.844994650167;5434.510894019399;5135.435102138687;5276.551493123127;5302.946970530245;5393.056215162409;5467.343918729778;4568.634509239319;4817.614590832655;5392.946070539245;5507.779688812563;5542.334919809648;5375.3280839895015;5391.7034563099405;5400.594955378284;5434.5652347812875;5446.400895901527 05:40:20 INFO - PROCESS | 1641 | |5;yui.html;674.2919934069226;755.1127425275304;767.9193684663114;782.8277778888644;784.5841703896933;13641.454341826327;14383.2808359582;14643.780343294859;14802.25988700566;14824.481143159992;12514.687132821684;12709.872901270985;12936.547220847273;13006.024548158879;13069.869301306984;805.7300804230575;819.4468733604793;820.8809722590221;820.8932838730987;820.9917900821005;640.3020707428925;659.0641289369107;660.6894759463045;805.5408417202198;812.2567850416862;692.8025512728873;696.4846013949691;697.5187120886598;797.8962734844491;844.0293662288386;860.0152825015354;860.423516244118;860.7848037990502;861.452977359375;862.7498025572608;634.0204384226381;635.6281575278467;636.9808905732789;637.1748585581682;637.4708991537013;11041.889581104166;11069.446527673585;11492.770144597145;13396.464141434331;13518.121322114031;10984.505697243643;12727.809082863765;13323.934085273155;13342.33288335589;13514.391852366667;10348.741281467948;10641.467926603716;10756.247062705614;11088.833667494993;11131.387773672444;3637.7999210043436;3653.251083527897;3661.395869681526;3661.6887564557046;3674.6876515496215;88435;89807.55096224543;90163;92212;92809.07190928071;9899.851502227473;10178.949105254427;10202.438865862448;10317.27779055474;10319.535620897077;19809;19975.60048799045;20000.299989500298;20155.899220504096;20166;7182.174049984214;7623.580703061274;7658.540487570764;7678.808029799188;7719.729809456642;4083;4109.815058322382;4216.030313028021;4225.323948168279;4225.7887105644595;3232.2242661761006;3355.4463513519995;3393.2874096440023;3398.5242066110764;3400.2179498715436;651.5341530805402;652.9412352888263;655.7639249870043;663.7709990053423;665.1984358847577;567.2767221792166;574.8131857146444;583.5185971573469;641.5156556799586;653.4739534674537;8724.95637521821;8986;9023.993030289474;9060.864087038699;9085.091490850862;500.6895724650667;600.6246096189882;638.1863124516279;664.7071446037411;668.1848145262771;4169.067821870689;4411.735295882193;4436.82252709888;4474.910501789946;4556.749378784199;63260.683696581225;64157;72103.2789672107;96613.51693241489;101930;61291.387086129456;63379.732405351635;81275.5936220327;88600.5569972146;94580.52709736407;81725.18274817294;82673;83769;83982.99999999878;84361.89095272667;7403.925960740539;8096.190380961857;8354.45696029756;8528.317734581342;9075.364724469224;44094.33858492189;51916.740416297675;52465.7376713114;53843;54739.178912315554;52270.73864630653;65202.02196967144;66448.33551664422;68707.65646171937;74662.62668686622;11907.226030308002;11913.821292680443;12040.578579749666;12231.75536489283;12380.442880070239;12105.031597472005;13557.389917453935;13948.16311021342;14149.504767333507;14593.416263349347;12641.557545485864;13109.672258193239;13134.343282835629;13170.80243796363;13332.333383330988;12790.104692671424;12896.677583060498;13016.637019985155;13040.869591303965;13297.933510332387;11063.114950803756;11575.305481671126;11964.880351196376;12029.4586743595;12469.127161098637;8967.058458861726;10354.948225258826;10636.8936310639;10715.41065241388;11472.94263528677;9581.329306948726;10849.945750271198;11347.813771820984;11435.256708313933;11551.249168804 05:40:20 INFO - PROCESS | 1641 | __end_tp_report 05:40:20 INFO - PROCESS | 1641 | __start_cc_report 05:40:20 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,3084 05:40:20 INFO - PROCESS | 1641 | __end_cc_report 05:40:20 INFO - PROCESS | 1641 | __startTimestamp1482154820688__endTimestamp 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | ------- Summary: start ------- 05:40:20 INFO - PROCESS | 1641 | Number of tests: 6 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5703.94 Median:5827.83 stddev:644.09 (11.1%) stddev-sans-first:617.21 05:40:20 INFO - PROCESS | 1641 | Values: 3273.8 3301.0 3306.7 3331.3 3349.2 5621.0 5691.2 5712.3 5736.4 5765.5 5650.8 5712.7 5721.5 5721.8 5728.6 4272.7 5167.9 5174.1 5202.1 5228.4 5132.1 5138.5 5138.6 5272.4 5279.0 5118.1 5168.7 5183.1 5229.7 5239.1 5187.3 5279.2 5280.2 5282.8 5285.8 5148.5 5195.5 5231.8 5234.7 5291.6 5563.5 5605.2 5605.7 5612.8 5657.9 6064.4 6164.4 6164.5 6177.5 6202.2 6310.5 6319.1 6320.6 6334.8 6340.3 5627.7 5697.7 5735.1 5810.5 5811.3 6316.4 6330.7 6332.5 6336.7 6343.5 6145.6 6157.8 6158.0 6158.8 6169.2 6111.7 6122.5 6139.3 6271.1 6281.4 6111.4 6184.3 6185.2 6234.8 6248.6 5894.4 6096.1 6185.7 6227.4 6289.6 4914.0 4941.3 4942.8 4950.4 4951.1 4376.1 4399.8 4420.4 4429.2 4443.0 5832.7 5862.8 5863.2 5867.4 5896.9 5596.6 6129.4 6298.4 6312.3 6321.6 5658.7 5732.7 5747.8 5799.4 5841.9 5345.2 5367.2 5386.3 5397.0 5399.7 6328.3 6330.5 6334.2 6352.4 6355.1 6101.6 6235.0 6288.2 6296.2 6302.3 4950.4 6263.5 6310.9 6321.6 6328.7 6099.0 6110.8 6149.7 6174.7 6182.9 6126.4 6234.0 6288.4 6294.4 6295.2 5800.2 5858.8 5864.2 5939.4 6105.1 5912.2 5915.3 5921.4 5923.7 5925.9 5788.9 5795.7 5797.6 5810.7 5821.8 5827.8 5916.0 5916.1 5916.1 5916.5 5759.8 5765.9 5771.4 5774.7 5781.8 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:69514.10 Median:36619.45 stddev:155196.97 (423.8%) stddev-sans-first:155545.72 05:40:20 INFO - PROCESS | 1641 | Values: 452.6 458.8 486.2 497.0 551.5 38163.6 39884.6 40137.0 40294.0 41589.4 40521.2 40830.6 40859.2 41005.0 41265.8 8407.2 9287.2 9408.8 9413.4 9492.1 8490.4 10999.2 11326.2 11331.7 11476.9 11311.9 11351.9 11370.5 11371.6 11388.7 8502.7 11297.5 11321.3 11355.1 11386.4 8512.9 10968.8 11238.2 11277.2 11452.9 38784.8 39122.2 39166.4 39166.6 39271.8 3293.0 3349.5 3512.2 3857.6 3888.7 28151.2 28203.9 28206.9 29571.9 29710.8 3087.8 3124.9 3139.4 3229.6 3296.6 490078.0 494977.5 495473.5 495952.0 500500.0 55600.2 56122.0 56159.7 56166.7 56169.7 58464.1 58478.4 58522.0 58576.0 58674.4 15186.3 15438.6 15440.7 15443.8 15457.8 11239.2 11449.5 11459.5 12328.2 12401.5 14477.5 14608.7 14690.0 14935.9 15056.5 5761.2 6619.7 6913.3 6935.9 7001.7 735.4 891.1 891.5 1014.1 1030.0 28793.0 28992.0 29002.3 29652.4 29962.7 999.5 1022.5 1071.9 1079.3 1147.5 14610.8 14777.7 14914.9 15024.4 15358.3 630851.0 639773.0 658442.0 658485.0 829833.0 46066.8 55046.0 55678.7 55727.7 55859.0 1213.4 1251.0 1321.4 1340.1 1345.5 28086.3 28945.1 29182.0 29728.7 29777.7 46561.3 46961.0 46965.0 47040.8 47080.8 534161.0 536634.0 538784.0 540182.0 540197.0 21078.7 24520.3 24995.1 25695.5 25825.9 42613.8 43051.6 44443.0 44889.8 44987.6 35034.6 37345.3 37416.6 37570.4 38652.8 36898.0 37214.0 37859.2 37869.8 38254.2 35794.8 36236.6 36504.0 36608.5 36630.5 27270.6 31377.5 33346.3 33679.3 33721.7 24679.4 24878.8 24937.4 25151.0 25381.1 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:35188.40 Median:332.05 stddev:101334.47 (30517.4%) stddev-sans-first:101584.92 05:40:20 INFO - PROCESS | 1641 | Values: 322.7 341.4 351.9 371.9 373.0 15152.2 15822.7 16569.8 19457.7 20087.6 19227.0 20134.7 20635.7 20669.0 20693.4 5815.3 6010.8 6011.6 6040.2 6066.9 4145.9 4728.6 6100.4 6720.3 6769.7 6407.0 6607.7 6730.8 6742.3 6745.2 6615.7 6625.4 6653.5 6659.1 6659.6 5656.6 5732.4 5785.3 5828.9 5829.9 23057.3 23190.0 24336.8 25617.7 25683.9 2313.2 2317.7 2325.9 2457.7 2722.2 14307.1 15573.9 15909.0 16318.9 16430.8 1737.5 1837.0 1842.5 1850.6 1875.1 117087.0 118430.0 119739.0 119880.4 120438.0 11448.7 11514.9 11621.1 11646.4 11807.7 14622.4 14641.4 14654.2 14667.3 14682.1 8369.6 8491.9 8519.4 8537.2 8700.3 8202.7 8272.1 8431.9 8563.0 8600.1 4511.8 4519.7 4560.7 4621.4 4679.2 1858.5 1911.7 1915.1 1953.8 1959.8 799.2 878.4 879.2 928.1 966.8 20601.5 21103.0 21140.4 21294.9 21315.5 780.6 896.9 904.3 933.7 951.8 8766.9 11147.2 11324.5 11408.0 11420.6 600934.0 601932.0 608208.0 614172.0 616055.0 94781.9 115843.0 120515.4 135450.0 142711.0 888.8 925.0 932.4 932.7 951.1 18867.3 19339.6 19585.6 19644.2 19657.9 53875.7 54651.0 54748.0 55088.0 55764.0 66965.7 77371.5 78366.6 79800.6 81284.6 7921.6 8001.3 8104.2 8152.9 8193.6 11882.9 12515.3 12546.8 12908.7 12938.5 5126.7 5270.3 10368.7 10732.8 11217.4 10623.0 10778.6 10789.6 10980.7 11045.3 10719.2 10787.6 11274.4 11534.1 11599.5 7023.7 7034.3 7399.1 7409.3 8108.8 8607.8 10840.9 10871.2 10957.0 11296.7 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5365.72 Median:5193.60 stddev:2951.53 (56.8%) stddev-sans-first:2944.98 05:40:20 INFO - PROCESS | 1641 | Values: 1429.1 1450.5 1592.6 1740.7 1786.2 7961.9 8381.5 8384.7 8389.4 8399.5 7440.0 7663.3 7950.6 8165.4 8197.1 1957.7 2269.8 2307.5 2308.0 2311.5 2108.4 2242.5 2300.4 2327.2 2347.3 2299.6 2301.7 2325.8 2329.9 2335.0 2270.6 2290.6 2300.3 2300.5 2302.1 1987.6 2295.4 2314.2 2316.5 2316.9 8219.7 8289.0 8324.7 8371.6 8432.0 5014.0 5191.3 5217.1 5225.0 5226.7 2994.9 3080.2 3224.7 3228.4 3233.9 962.5 990.2 992.2 1000.6 1002.4 10790.5 10808.7 10813.1 10820.4 10836.6 3990.5 4390.2 4616.5 4704.0 4723.7 9282.3 9290.4 9343.0 9369.4 9384.0 5187.9 5187.9 5190.6 5195.9 5226.8 4554.3 4719.5 4755.0 4807.5 4811.4 3706.6 3741.2 3781.1 3852.3 3865.6 1224.3 1226.9 1235.3 1237.5 1253.9 863.5 884.7 886.7 887.0 890.8 5482.9 5576.5 5622.9 5678.5 5830.4 366.3 425.4 877.9 949.4 978.6 3781.1 3788.2 3793.3 3799.0 3873.7 9805.5 9986.4 10104.1 10138.3 10151.7 9885.1 10057.9 10066.0 10068.4 10068.9 7725.1 7734.5 7750.7 7884.7 7898.1 5265.7 5540.4 5571.6 5623.9 5650.3 9206.6 9301.6 9404.2 9423.0 9429.0 7295.8 8529.6 8886.5 9380.9 9491.5 6683.6 6870.9 7140.7 7147.9 7211.4 7380.0 7517.4 7614.5 7688.3 7688.4 6026.3 6863.0 6875.9 6908.1 6986.1 6804.0 6807.3 6840.4 6898.3 6911.0 6450.6 6525.7 6529.0 6543.5 6544.5 4708.9 4751.6 4847.0 4883.0 4949.3 4355.4 4786.2 4833.8 4856.2 4994.2 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4372.73 Median:4852.49 stddev:1370.23 (28.2%) stddev-sans-first:1339.54 05:40:20 INFO - PROCESS | 1641 | Values: 299.8 303.1 306.6 313.9 314.3 4380.8 5187.4 5232.4 5247.7 5279.7 4851.7 4934.5 5059.7 5107.5 5131.1 3550.9 3935.0 3956.8 3980.6 4003.1 3669.8 3983.2 3993.1 4007.9 4039.5 3409.5 3923.3 3930.6 3931.0 3957.5 3909.0 3912.4 3920.4 3921.5 3945.6 3421.3 3428.9 3431.3 3455.7 3456.3 4296.1 4485.7 4748.8 4807.9 4834.7 4122.3 4442.6 4445.8 4452.9 4471.3 4994.9 5411.8 5414.7 5423.7 5423.9 2900.4 3000.8 3505.5 3759.3 3793.1 5557.8 5697.6 5698.1 5699.5 5701.0 3066.0 3790.4 3800.7 3800.9 3801.6 3360.3 3608.7 3699.4 3880.7 3891.7 3685.9 4296.2 4445.4 4460.5 4493.6 5482.3 5489.1 5490.0 5492.5 5515.1 999.4 1145.8 1147.8 1217.3 1224.2 711.2 728.8 834.7 847.1 851.8 4811.9 4844.4 4853.3 4854.9 4875.8 4892.8 5011.7 5115.5 5307.5 5308.2 4105.4 4136.6 4446.0 4627.7 4836.9 2475.7 2719.0 2752.9 2776.3 2781.3 5112.4 5140.9 5216.8 5437.3 5468.4 5767.8 5802.8 5812.3 5815.5 5834.6 4921.4 5143.7 5638.2 5846.3 5903.6 5292.9 5622.4 5658.6 5673.9 5679.0 4894.6 5595.3 5602.8 5606.4 5610.6 5563.9 5840.5 5850.6 5876.4 5982.9 3718.9 3746.9 3768.5 3798.0 3804.5 4773.4 5498.5 5535.7 5620.3 5633.6 4564.7 4640.4 4783.7 5455.5 5486.5 4319.8 4693.8 5074.6 5166.8 5434.5 5135.4 5276.6 5302.9 5393.1 5467.3 4568.6 4817.6 5392.9 5507.8 5542.3 5375.3 5391.7 5400.6 5434.6 5446.4 05:40:20 INFO - PROCESS | 1641 | 05:40:20 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:18704.99 Median:52093.74 stddev:26444.52 (50.8%) stddev-sans-first:26484.05 05:40:20 INFO - PROCESS | 1641 | Values: 674.3 755.1 767.9 782.8 784.6 13641.5 14383.3 14643.8 14802.3 14824.5 12514.7 12709.9 12936.5 13006.0 13069.9 805.7 819.4 820.9 820.9 821.0 640.3 659.1 660.7 805.5 812.3 692.8 696.5 697.5 797.9 844.0 860.0 860.4 860.8 861.5 862.7 634.0 635.6 637.0 637.2 637.5 11041.9 11069.4 11492.8 13396.5 13518.1 10984.5 12727.8 13323.9 13342.3 13514.4 10348.7 10641.5 10756.2 11088.8 11131.4 3637.8 3653.3 3661.4 3661.7 3674.7 88435.0 89807.6 90163.0 92212.0 92809.1 9899.9 10178.9 10202.4 10317.3 10319.5 19809.0 19975.6 20000.3 20155.9 20166.0 7182.2 7623.6 7658.5 7678.8 7719.7 4083.0 4109.8 4216.0 4225.3 4225.8 3232.2 3355.4 3393.3 3398.5 3400.2 651.5 652.9 655.8 663.8 665.2 567.3 574.8 583.5 641.5 653.5 8725.0 8986.0 9024.0 9060.9 9085.1 500.7 600.6 638.2 664.7 668.2 4169.1 4411.7 4436.8 4474.9 4556.7 63260.7 64157.0 72103.3 96613.5 101930.0 61291.4 63379.7 81275.6 88600.6 94580.5 81725.2 82673.0 83769.0 83983.0 84361.9 7403.9 8096.2 8354.5 8528.3 9075.4 44094.3 51916.7 52465.7 53843.0 54739.2 52270.7 65202.0 66448.3 68707.7 74662.6 11907.2 11913.8 12040.6 12231.8 12380.4 12105.0 13557.4 13948.2 14149.5 14593.4 12641.6 13109.7 13134.3 13170.8 13332.3 12790.1 12896.7 13016.6 13040.9 13297.9 11063.1 11575.3 11964.9 12029.5 12469.1 8967.1 10354.9 10636.9 10715.4 11472.9 9581.3 10849.9 11347.8 11435.3 11551.2 05:40:20 INFO - PROCESS | 1641 | -------- Summary: end -------- 05:40:20 INFO - PROCESS | 1641 | 05:40:21 INFO - TEST-INFO | 1641: exit 0 05:40:21 INFO - TEST-OK | dromaeo_css | took 1121170ms 05:40:21 INFO - TEST-START | kraken 05:40:21 INFO - operating with platform_type : mac_ 05:40:21 INFO - Initialising browser for kraken test... 05:40:21 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpy3igJT/profile http://localhost:49247/getInfo.html) 05:40:23 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 05:40:23 INFO - PROCESS | 1651 | colorDepth:24 05:40:23 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 05:40:23 INFO - PROCESS | 1651 | __metrics 05:40:24 INFO - PROCESS | 1651 | 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" 05:40:24 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:40:24 INFO - TEST-INFO | 1651: exit 0 05:40:24 INFO - Browser initialized. 05:40:24 INFO - Running cycle 1/1 for kraken test... 05:40:24 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmpy3igJT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:37 INFO - PROCESS | 1652 | RSS: Main: 222597120 05:40:37 INFO - PROCESS | 1652 | 05:40:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:41 INFO - PROCESS | 1652 | RSS: Main: 271728640 05:40:41 INFO - PROCESS | 1652 | 05:40:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 05:40:44 INFO - PROCESS | 1652 | RSS: Main: 282353664 05:40:44 INFO - PROCESS | 1652 | 05:40:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 05:40:47 INFO - PROCESS | 1652 | RSS: Main: 379441152 05:40:47 INFO - PROCESS | 1652 | 05:40:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 05:40:50 INFO - PROCESS | 1652 | RSS: Main: 281669632 05:40:50 INFO - PROCESS | 1652 | 05:40:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:53 INFO - PROCESS | 1652 | RSS: Main: 283734016 05:40:53 INFO - PROCESS | 1652 | 05:40:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:56 INFO - PROCESS | 1652 | RSS: Main: 325554176 05:40:56 INFO - PROCESS | 1652 | 05:40:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:00 INFO - PROCESS | 1652 | RSS: Main: 325320704 05:41:00 INFO - PROCESS | 1652 | 05:41:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 05:41:03 INFO - PROCESS | 1652 | RSS: Main: 323911680 05:41:03 INFO - PROCESS | 1652 | 05:41:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:05 INFO - PROCESS | 1652 | RSS: Main: 298565632 05:41:05 INFO - PROCESS | 1652 | 05:41:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:08 INFO - PROCESS | 1652 | RSS: Main: 300986368 05:41:08 INFO - PROCESS | 1652 | 05:41:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:41:11 INFO - PROCESS | 1652 | RSS: Main: 302485504 05:41:11 INFO - PROCESS | 1652 | 05:41:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:41:14 INFO - PROCESS | 1652 | RSS: Main: 314372096 05:41:14 INFO - PROCESS | 1652 | 05:41:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:41:18 INFO - PROCESS | 1652 | RSS: Main: 314044416 05:41:18 INFO - PROCESS | 1652 | 05:41:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 05:41:20 INFO - PROCESS | 1652 | RSS: Main: 357990400 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | __start_tp_report 05:41:20 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:41:20 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:41:20 INFO - PROCESS | 1652 | |i|pagename|runs| 05:41:20 INFO - PROCESS | 1652 | |0;ai-astar;115;120;125;115;114;116;114;115;114;113 05:41:20 INFO - PROCESS | 1652 | |1;audio-beat-detection;143;111;107;143;112;112;113;109;140;111 05:41:20 INFO - PROCESS | 1652 | |2;audio-dft;184;192;220;182;183;181;183;185;184;185 05:41:20 INFO - PROCESS | 1652 | |3;audio-fft;81;59;59;87;61;58;58;58;82;58 05:41:20 INFO - PROCESS | 1652 | |4;audio-oscillator;82;71;93;69;68;68;90;66;68;90 05:41:20 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;85;88;85;89;86;134;86;90;71 05:41:20 INFO - PROCESS | 1652 | |6;imaging-darkroom;93;92;94;93;92;92;102;140;93;93 05:41:20 INFO - PROCESS | 1652 | |7;imaging-desaturate;82;82;81;87;123;81;85;86;83;83 05:41:20 INFO - PROCESS | 1652 | |8;json-parse-financial;56;56;54;54;56;59;58;57;57;57 05:41:20 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;64;67;83;73;74;68;69;70;70;71 05:41:20 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;92;87;89;83;83;83;83;82;83;83 05:41:20 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;142;139;140;138;138;142;144;141;183;150 05:41:20 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;166;166;168;167;169;168;165;168;167;169 05:41:20 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;68;69;69;67;69;69;68;68;67 05:41:20 INFO - PROCESS | 1652 | __end_tp_report 05:41:20 INFO - PROCESS | 1652 | __start_cc_report 05:41:20 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2043 05:41:20 INFO - PROCESS | 1652 | __end_cc_report 05:41:20 INFO - PROCESS | 1652 | __startTimestamp1482154880089__endTimestamp 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:41:20 INFO - PROCESS | 1652 | Number of tests: 14 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:116.10 Median:115.00 stddev:3.67 (3.2%) stddev-sans-first:3.87 05:41:20 INFO - PROCESS | 1652 | Values: 115.0 120.0 125.0 115.0 114.0 116.0 114.0 115.0 114.0 113.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:120.10 Median:112.50 stddev:15.23 (13.5%) stddev-sans-first:13.71 05:41:20 INFO - PROCESS | 1652 | Values: 143.0 111.0 107.0 143.0 112.0 112.0 113.0 109.0 140.0 111.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:187.90 Median:184.50 stddev:11.67 (6.3%) stddev-sans-first:12.29 05:41:20 INFO - PROCESS | 1652 | Values: 184.0 192.0 220.0 182.0 183.0 181.0 183.0 185.0 184.0 185.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.02 (20.0%) stddev-sans-first:11.48 05:41:20 INFO - PROCESS | 1652 | Values: 81.0 59.0 59.0 87.0 61.0 58.0 58.0 58.0 82.0 58.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:76.50 stddev:10.96 (14.3%) stddev-sans-first:11.44 05:41:20 INFO - PROCESS | 1652 | Values: 82.0 71.0 93.0 69.0 68.0 68.0 90.0 66.0 68.0 90.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:87.00 stddev:16.29 (18.7%) stddev-sans-first:17.26 05:41:20 INFO - PROCESS | 1652 | Values: 88.0 85.0 88.0 85.0 89.0 86.0 134.0 86.0 90.0 71.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:93.00 stddev:14.92 (16.0%) stddev-sans-first:15.69 05:41:20 INFO - PROCESS | 1652 | Values: 93.0 92.0 94.0 93.0 92.0 92.0 102.0 140.0 93.0 93.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:87.30 Median:83.00 stddev:12.71 (15.3%) stddev-sans-first:13.34 05:41:20 INFO - PROCESS | 1652 | Values: 82.0 82.0 81.0 87.0 123.0 81.0 85.0 86.0 83.0 83.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.67 05:41:20 INFO - PROCESS | 1652 | Values: 56.0 56.0 54.0 54.0 56.0 59.0 58.0 57.0 57.0 57.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:5.13 (7.3%) stddev-sans-first:4.80 05:41:20 INFO - PROCESS | 1652 | Values: 64.0 67.0 83.0 73.0 74.0 68.0 69.0 70.0 70.0 71.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:83.00 stddev:3.36 (4.0%) stddev-sans-first:2.35 05:41:20 INFO - PROCESS | 1652 | Values: 92.0 87.0 89.0 83.0 83.0 83.0 83.0 82.0 83.0 83.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:142.00 stddev:13.57 (9.6%) stddev-sans-first:14.33 05:41:20 INFO - PROCESS | 1652 | Values: 142.0 139.0 140.0 138.0 138.0 142.0 144.0 141.0 183.0 150.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:168.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 05:41:20 INFO - PROCESS | 1652 | Values: 166.0 166.0 168.0 167.0 169.0 168.0 165.0 168.0 167.0 169.0 05:41:20 INFO - PROCESS | 1652 | 05:41:20 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.50 stddev:1.05 (1.5%) stddev-sans-first:0.83 05:41:20 INFO - PROCESS | 1652 | Values: 66.0 68.0 69.0 69.0 67.0 69.0 69.0 68.0 68.0 67.0 05:41:20 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:41:20 INFO - PROCESS | 1652 | 05:41:21 INFO - TEST-INFO | 1652: exit 0 05:41:21 INFO - TEST-OK | kraken | took 59827ms 05:41:21 INFO - SUITE-END | took 1180s 05:41:21 INFO - Completed test suite (00:19:41) 05:41:21 INFO - javascript benchmark 05:41:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3273.770836041477, 3301.0, 3306.7023967842906, 3331.2837739885917, 3349.179451034497, 5620.971895140524, 5691.174779656958, 5712.257406537155, 5736.426357364267, 5765.481106700396, 5650.802221922234, 5712.742926568304, 5721.513671337931, 5721.799737009205, 5728.570357223204, 4272.700910936236, 5167.9483205168035, 5174.120399532069, 5202.115640341151, 5228.424873263938, 5132.101882170624, 5138.460461651529, 5138.640295179339, 5272.393674727417, 5279.023380674587, 5118.07874582575, 5168.741562921858, 5183.144781111131, 5229.7385130743505, 5239.1093514102695, 5187.325647665817, 5279.208118782175, 5280.1815718563685, 5282.788688452457, 5285.7621407036395, 5148.510891465305, 5195.506426889439, 5231.843044708665, 5234.659747116463, 5291.629585928987, 5563.527100196488, 5605.243292155547, 5605.691686957216, 5612.803551875706, 5657.858553536194, 6064.423879731418, 6164.383561643845, 6164.458556881609, 6177.474914632262, 6202.162708034402, 6310.495160387158, 6319.052142178664, 6320.557560970734, 6334.778282760128, 6340.334264902165, 5627.662340259598, 5697.715114244271, 5735.082386818172, 5810.4770570648, 5811.331696854873, 6316.399942005502, 6330.651814150174, 6332.525060620472, 6336.714847831859, 6343.492520598341, 6145.600535965148, 6157.753689852445, 6158.04550294705, 6158.753649854045, 6169.198004259418, 6111.694415279308, 6122.530313748553, 6139.308154112039, 6271.090691849748, 6281.403266689657, 6111.417759411971, 6184.288806787187, 6185.165002724574, 6234.844128896814, 6248.593841400295, 5894.351621321651, 6096.116063170815, 6185.659788711574, 6227.377262273736, 6289.55973081889, 4914.041761856475, 4941.332920055746, 4942.777575009133, 4950.43070046942, 4951.084049450863, 4376.10289890578, 4399.758013309299, 4420.359047938031, 4429.247028005247, 4442.955570444255, 5832.650040997553, 5862.824115276549, 5863.237779088691, 5867.413258674099, 5896.852578685482, 5596.600849787553, 6129.448349648553, 6298.370162983665, 6312.337204593543, 6321.620702757849, 5658.717064146858, 5732.742026608729, 5747.827565173052, 5799.449052340021, 5841.912371314434, 5345.198220266913, 5367.1680889462195, 5386.272853164773, 5397.0, 5399.676019438846, 6328.272248691275, 6330.49356051524, 6334.176557047646, 6352.396522330371, 6355.14205582259, 6101.60339577926, 6235.00239961604, 6288.151099601495, 6296.181496405438, 6302.275238347465, 4950.430700469348, 6263.467605253422, 6310.905336419865, 6321.620702757849, 6328.746850125944, 6099.0, 6110.830655117547, 6149.661768602769, 6174.6912654368, 6182.938170618237, 6126.439927350641, 6234.0, 6288.371162883675, 6294.370562943669, 6295.18162638854, 5800.187973683604, 5858.765649373977, 5864.179014937827, 5939.406059394026, 6105.114758359924, 5912.172295878494, 5915.290165180206, 5921.3782552831335, 5923.703814809156, 5925.9407405925385, 5788.884222315614, 5795.652260864362, 5797.565182611405, 5810.680412577349, 5821.76712931478, 5827.825165245049, 5916.0, 5916.083007133901, 5916.112583112395, 5916.467517923407, 5759.769609215752, 5765.855853603525, 5771.394003629558, 5774.653520788598, 5781.768729250783], "value": 5662.514019136624, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.6220605794162, 458.79124998125855, 486.17350504142945, 496.9925451118232, 551.481607289148, 38163.61836381636, 39884.60115398845, 40137.0, 40293.99265018376, 41589.376159357635, 40521.18957620853, 40830.59169408305, 40859.18281634373, 41005.0, 41265.79367103168, 8407.159284071606, 9287.210587100104, 9408.764780880465, 9413.388129771578, 9492.050794920522, 8490.363222758288, 10999.175061870352, 11326.150538709588, 11331.716707082307, 11476.942615286953, 11311.943440282786, 11351.886481135165, 11370.545178192862, 11371.601993930213, 11388.71528211793, 8502.702405415843, 11297.548098076068, 11321.264117832314, 11355.091592672648, 11386.373749443777, 8512.872306915402, 10968.780624387467, 11238.213325067249, 11277.154213433943, 11452.942735286353, 38784.80607596972, 39122.21755564901, 39166.41250381246, 39166.60833391658, 39271.803640981896, 3293.0, 3349.547811045502, 3512.227309991798, 3857.614238576148, 3888.6889048876023, 28151.155465336073, 28203.858980704965, 28206.87172513097, 29571.852140739375, 29710.81156753749, 3087.7992930459673, 3124.8593813278458, 3139.4035133324596, 3229.612446506411, 3296.5549650797075, 490078.0, 494977.5251123721, 495473.52263238456, 495952.0, 500500.0, 55600.16599750926, 56122.0, 56159.71920140373, 56166.71916640472, 56169.719151404795, 58464.12303815446, 58478.415215848145, 58522.0, 58576.0, 58674.41325586689, 15186.316615752317, 15438.614034649, 15440.691186176438, 15443.845561544465, 15457.845421545866, 11239.213255072067, 11449.484773185226, 11459.484323205475, 12328.19866708661, 12401.503939842305, 14477.49328773509, 14608.707825843423, 14690.0, 14935.925320373328, 15056.473023444127, 5761.193432919395, 6619.669016549154, 6913.308669133046, 6935.930640693528, 7001.719931202695, 735.4337160386473, 891.1445012787797, 891.5364010714521, 1014.06705830636, 1030.0172168575461, 28793.0, 28992.0, 29002.27494312659, 29652.406951861274, 29962.70037299599, 999.4652860719625, 1022.4632068164274, 1071.9410432426134, 1079.3362082319318, 1147.4893672315739, 14610.780838287434, 14777.74389176932, 14914.850851491346, 15024.399024038916, 15358.308876100602, 630851.0, 639773.0, 658442.0, 658485.0, 829833.0, 46066.76966615146, 55046.0, 55678.72160639171, 55727.721361392934, 55859.0, 1213.4478812140455, 1250.9812352814715, 1321.3789518926283, 1340.1088276296155, 1345.5357901523957, 28086.2978425541, 28945.131646050653, 29182.0, 29728.702712972594, 29777.702222978358, 46561.30158047564, 46961.0, 46965.0, 47040.7647961758, 47080.76459617817, 534161.0, 536634.0, 538784.0, 540182.0, 540197.0, 21078.683819743022, 24520.264392068268, 24995.12517061894, 25695.486090278464, 25825.870870645525, 42613.786931065144, 43051.56948430601, 44443.0, 44889.77555112204, 44987.55012449834, 35034.64965350416, 37345.25309493849, 37416.62583374132, 37570.43644345392, 38652.80673596614, 36898.0, 37214.0, 37859.24281514299, 37869.81065094657, 38254.23491530098, 35794.82102589471, 36236.63763362333, 36504.0, 36608.45087323639, 36630.450543242405, 27270.590941136295, 31377.529337059506, 33346.33307333888, 33679.326413471106, 33721.662783372834, 24679.38301542476, 24878.751212487645, 24937.376565586004, 25151.0, 25381.111661092513], "value": 19442.011293404055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.6789344602121, 341.42981221360327, 351.85749771342614, 371.9088823238307, 372.98694545690904, 15152.166630835314, 15822.68354632908, 16569.751453728204, 19457.708134377997, 20087.598248035032, 19227.03864806761, 20134.69797953032, 20635.69046464304, 20669.0, 20693.37919862403, 5815.331236907757, 6010.819675409744, 6011.57918945674, 6040.154378387029, 6066.878662426749, 4145.875623731293, 4728.598069164125, 6100.359462256466, 6720.260771315151, 6769.661516924159, 6407.0, 6607.669616519179, 6730.831729206784, 6742.325767423267, 6745.190577130751, 6615.735370585171, 6625.436837868763, 6653.4677225821815, 6659.101021362103, 6659.600423974529, 5656.632318899258, 5732.369439361667, 5785.334686511021, 5828.883422331572, 5829.912551311733, 23057.308280751604, 23189.9564519597, 24336.756632433804, 25617.743822561722, 25683.871580642164, 2313.2415191080795, 2317.7102862142233, 2325.93022209334, 2457.6682147909983, 2722.224166112668, 14307.141571505743, 15573.922130389388, 15909.0, 16318.918405408016, 16430.75353869693, 1737.4874412048475, 1836.9816301837077, 1842.456475339778, 1850.6021205440761, 1875.1093230715437, 117087.0, 118430.0, 119739.0, 119880.40059799733, 120437.99999999913, 11448.713782155513, 11514.942425287987, 11621.07031437483, 11646.41767911601, 11807.704807379712, 14622.415103395957, 14641.414343426144, 14654.194019328826, 14667.266636668124, 14682.091328127275, 8369.623366948503, 8491.872621910676, 8519.361047921431, 8537.18896704812, 8700.34747393948, 8202.671893124209, 8272.131426200283, 8431.915680843236, 8562.957185214034, 8600.053994060649, 4511.796969136397, 4519.728816271035, 4560.68075234737, 4621.353010578522, 4679.1577516046755, 1858.4610462965588, 1911.6750152474112, 1915.1381878154887, 1953.7948515405963, 1959.8040195980288, 799.2007992007992, 878.446578655443, 879.2482427524375, 928.1182876267573, 966.8211380894558, 20601.48496287605, 21103.0, 21140.365789026353, 21294.89352553258, 21315.46711332229, 780.605794073989, 896.8744375787395, 904.2946501728662, 933.659214386744, 951.8048799995984, 8766.867941739234, 11147.219694621299, 11324.490397932113, 11408.0, 11420.600278990196, 600934.0, 601932.0, 608208.0, 614172.0, 616055.0, 94781.94506102994, 115843.0, 120515.39742301233, 135450.0, 142711.0, 888.7911340834899, 924.9676261330821, 932.3659911260409, 932.7015355086307, 951.0774548687624, 18867.339643112424, 19339.613207735485, 19585.60828783445, 19644.214231431153, 19657.935916728537, 53875.73062134664, 54651.0, 54748.0, 55088.0, 55764.0, 66965.66517167383, 77371.45257094713, 78366.6081669588, 79800.60099699696, 81284.59357703173, 7921.643526041457, 8001.319887809602, 8104.189581041848, 8152.9184708154535, 8193.59032048407, 11882.940585297018, 12515.311657858905, 12546.811797823222, 12908.677283067998, 12938.547150849676, 5126.743662816919, 5270.314859068297, 10368.703912010998, 10732.83900741474, 11217.383043932661, 10623.0, 10778.568857245937, 10789.622363217564, 10980.670579882617, 11045.33727976324, 10719.249652524248, 10787.6224332148, 11274.436278185894, 11534.077273818242, 11599.478023488795, 7023.68393422287, 7034.261402552658, 7399.1490978536, 7409.333160015624, 8108.75673729789, 8607.827843443221, 10840.945795270973, 10871.184661150288, 10957.0, 11296.661100167008], "value": 9557.467744551452, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.0711037825413, 1450.5358285348689, 1592.5938885584183, 1740.6605711886186, 1786.2319202742824, 7961.92038079619, 8381.497110173394, 8384.748457546284, 8389.412741108124, 8399.538025408609, 7439.962800186005, 7663.271989161035, 7950.602469876512, 8165.428420010601, 8197.057338406084, 1957.6671965765784, 2269.7843704848015, 2307.469282065126, 2308.0306271365985, 2311.503026849227, 2108.4307237046064, 2242.461809165805, 2300.4363930836967, 2327.185485080225, 2347.307544274435, 2299.632058870581, 2301.689271948282, 2325.848819826714, 2329.9068037278576, 2334.9533009339802, 2270.6139956207485, 2290.5647926894003, 2300.2869110575607, 2300.470891694903, 2302.1021801497513, 1987.5627361980341, 2295.391721193885, 2314.1900334882826, 2316.513532158249, 2316.860988340705, 8219.671213151527, 8289.0, 8324.70863519774, 8371.58142092893, 8432.030316513557, 5013.974930125326, 5191.299174611417, 5217.113090774577, 5225.0, 5226.73866306687, 2994.895178668736, 3080.183751305906, 3224.661410551905, 3228.3543291341825, 3233.8544765485376, 962.4802606592432, 990.173205373514, 992.1864071461405, 1000.6097621927454, 1002.3935519011039, 10790.51442685081, 10808.675739727742, 10813.08088812444, 10820.350778953209, 10836.566537338496, 3990.461287726178, 4390.21466601406, 4616.538346165356, 4703.976480117578, 4723.6929599575915, 9282.303827212985, 9290.442573445614, 9342.97227304996, 9369.390989585656, 9383.953080234554, 5187.870303242374, 5187.948120518747, 5190.6366554341585, 5195.896082078338, 5226.764795584208, 4554.294084416921, 4719.528047195322, 4755.0, 4807.471178170398, 4811.398575178103, 3706.592274849764, 3741.1956429367815, 3781.1303400218103, 3852.3451013327804, 3865.555461121951, 1224.2654407355515, 1226.8650448450662, 1235.3390935849277, 1237.4555195713858, 1253.9373031348396, 863.4689665855453, 884.7301573020162, 886.7251152142857, 886.9911300887038, 890.844102282098, 5482.890342193134, 5576.498115169573, 5622.859428514238, 5678.4605462480995, 5830.38780928005, 366.3424153644215, 425.39593776836966, 877.893853744274, 949.392388871123, 978.5987745024506, 3781.111438811877, 3788.242351529705, 3793.260314238697, 3798.9841434573027, 3873.69010479161, 9805.460699661588, 9986.35088719231, 10104.090631843022, 10138.341007834468, 10151.746206344753, 9885.110340069285, 10057.949710251401, 10066.043725846033, 10068.39589624625, 10068.899311006797, 7725.072991241087, 7734.4585878989055, 7750.651220695082, 7884.724034658874, 7898.052233731874, 5265.6840589564745, 5540.445955404427, 5571.554275657856, 5623.8594035149445, 5650.29371328584, 9206.631734730803, 9301.627934882528, 9404.153626173676, 9422.95288523553, 9429.0, 7295.781126566211, 8529.573521324033, 8886.4668119913, 9380.906190938003, 9491.525423728648, 6683.565568238049, 6870.862582748417, 7140.714371425085, 7147.892781608176, 7211.3870321023305, 7379.963100184679, 7517.398608111447, 7614.505057171266, 7688.308052275322, 7688.384929205538, 6026.306974697791, 6862.965685171542, 6875.896861547181, 6908.1364829396325, 6986.056882320821, 6804.047433359235, 6807.319268073153, 6840.3501667341525, 6898.310168983062, 6911.0, 6450.580712253689, 6525.706343214471, 6528.967355163194, 6543.509236807354, 6544.509161812788, 4708.882277943078, 4751.643626728079, 4846.95153048465, 4882.9755851221935, 4949.257611358325, 4355.368471571668, 4786.234202527578, 4833.782479788348, 4856.247281671346, 4994.150994331045], "value": 4325.1572523616915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.80662472705103, 303.12699425654125, 306.5998871472728, 313.8650380336454, 314.30538509893137, 4380.802863871126, 5187.429382767892, 5232.372115346155, 5247.658902171361, 5279.683219006863, 4851.708897466155, 4934.481879402656, 5059.709774007632, 5107.4892510748905, 5131.076406246874, 3550.8579656813763, 3935.0487134649015, 3956.8417263309434, 3980.582038885919, 4003.0792917628964, 3669.834737572847, 3983.2033593281317, 3993.1414745829643, 4007.8797636070963, 4039.474868267121, 3409.494021893918, 3923.3330333843173, 3930.6069393060607, 3930.9535110289467, 3957.5053118360206, 3909.0, 3912.4326972588815, 3920.411938209263, 3921.5490218624936, 3945.6251656092627, 3421.34994351075, 3428.862845486178, 3431.3480438716565, 3455.7235421166247, 3456.2569047654874, 4296.076343586144, 4485.708428952108, 4748.810047598092, 4807.9422527044, 4834.709917404967, 4122.2786012447705, 4442.577955094261, 4445.777711114463, 4452.866414007585, 4471.262241730111, 4994.92609089044, 5411.756470958816, 5414.6734050157565, 5423.701696406696, 5423.888102938888, 2900.448914706199, 3000.7599392048583, 3505.474178873164, 3759.285735710206, 3793.089658481972, 5557.833265002057, 5697.6011679182475, 5698.088305871082, 5699.487046165865, 5700.971495142498, 3066.003548846589, 3790.3556395412847, 3800.7149463790324, 3800.904977375588, 3801.5934104381554, 3360.260742636616, 3608.675219230282, 3699.3896007159055, 3880.670143037817, 3891.727579069438, 3685.9262814743556, 4296.2266791977745, 4445.444319460067, 4460.461140906382, 4493.550644935481, 5482.314710661168, 5489.1217405214975, 5489.972550137303, 5492.505674489235, 5515.117581186991, 999.3504222255447, 1145.8109411946934, 1147.839302497651, 1217.3000524698334, 1224.155332820351, 711.2460791560962, 728.8360118973295, 834.6577903059716, 847.1189962439117, 851.8296340731879, 4811.927821082687, 4844.4186697596515, 4853.2720091985775, 4854.859108109591, 4875.756212189447, 4892.828750993769, 5011.722010887273, 5115.544538621482, 5307.522322990872, 5308.203769434538, 4105.445764821711, 4136.586341365839, 4446.021875187454, 4627.657979186066, 4836.927446088312, 2475.740047295044, 2718.986405067962, 2752.862356882148, 2776.3475583237923, 2781.3324802047364, 5112.4120726116225, 5140.887290167851, 5216.843494695089, 5437.32033495813, 5468.425815289417, 5767.798127065533, 5802.823941022117, 5812.273465816688, 5815.534757219412, 5834.5624078194305, 4921.434035085939, 5143.691378517301, 5638.16604708802, 5846.269216347957, 5903.645781253139, 5292.867678307919, 5622.409646987171, 5658.632188907708, 5673.858153546029, 5679.0, 4894.632902532253, 5595.328560572594, 5602.775888964559, 5606.355269143937, 5610.635308704921, 5563.91353242534, 5840.532757379485, 5850.590458668023, 5876.44173803488, 5982.910256346246, 3718.8512459501317, 3746.8946660734605, 3768.530273193402, 3797.962020379761, 3804.486394336729, 4773.403324584427, 5498.477644623755, 5535.723213839211, 5620.297462817148, 5633.605647604629, 4564.748938808398, 4640.350350950937, 4783.736894470838, 5455.481729235717, 5486.506214440719, 4319.784010799511, 4693.765311734468, 5074.5686616637995, 5166.844994650167, 5434.510894019399, 5135.435102138687, 5276.551493123127, 5302.946970530245, 5393.056215162409, 5467.343918729778, 4568.634509239319, 4817.614590832655, 5392.946070539245, 5507.779688812563, 5542.334919809648, 5375.3280839895015, 5391.7034563099405, 5400.594955378284, 5434.5652347812875, 5446.400895901527], "value": 3928.7083645989833, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.2919934069226, 755.1127425275304, 767.9193684663114, 782.8277778888644, 784.5841703896933, 13641.454341826327, 14383.2808359582, 14643.780343294859, 14802.25988700566, 14824.481143159992, 12514.687132821684, 12709.872901270985, 12936.547220847273, 13006.024548158879, 13069.869301306984, 805.7300804230575, 819.4468733604793, 820.8809722590221, 820.8932838730987, 820.9917900821005, 640.3020707428925, 659.0641289369107, 660.6894759463045, 805.5408417202198, 812.2567850416862, 692.8025512728873, 696.4846013949691, 697.5187120886598, 797.8962734844491, 844.0293662288386, 860.0152825015354, 860.423516244118, 860.7848037990502, 861.452977359375, 862.7498025572608, 634.0204384226381, 635.6281575278467, 636.9808905732789, 637.1748585581682, 637.4708991537013, 11041.889581104166, 11069.446527673585, 11492.770144597145, 13396.464141434331, 13518.121322114031, 10984.505697243643, 12727.809082863765, 13323.934085273155, 13342.33288335589, 13514.391852366667, 10348.741281467948, 10641.467926603716, 10756.247062705614, 11088.833667494993, 11131.387773672444, 3637.7999210043436, 3653.251083527897, 3661.395869681526, 3661.6887564557046, 3674.6876515496215, 88435.0, 89807.55096224543, 90163.0, 92212.0, 92809.07190928071, 9899.851502227473, 10178.949105254427, 10202.438865862448, 10317.27779055474, 10319.535620897077, 19809.0, 19975.60048799045, 20000.299989500298, 20155.899220504096, 20166.0, 7182.174049984214, 7623.580703061274, 7658.540487570764, 7678.808029799188, 7719.729809456642, 4083.0, 4109.815058322382, 4216.030313028021, 4225.323948168279, 4225.7887105644595, 3232.2242661761006, 3355.4463513519995, 3393.2874096440023, 3398.5242066110764, 3400.2179498715436, 651.5341530805402, 652.9412352888263, 655.7639249870043, 663.7709990053423, 665.1984358847577, 567.2767221792166, 574.8131857146444, 583.5185971573469, 641.5156556799586, 653.4739534674537, 8724.95637521821, 8986.0, 9023.993030289474, 9060.864087038699, 9085.091490850862, 500.6895724650667, 600.6246096189882, 638.1863124516279, 664.7071446037411, 668.1848145262771, 4169.067821870689, 4411.735295882193, 4436.82252709888, 4474.910501789946, 4556.749378784199, 63260.683696581225, 64157.0, 72103.2789672107, 96613.51693241489, 101930.0, 61291.387086129456, 63379.732405351635, 81275.5936220327, 88600.5569972146, 94580.52709736407, 81725.18274817294, 82673.0, 83769.0, 83982.99999999878, 84361.89095272667, 7403.925960740539, 8096.190380961857, 8354.45696029756, 8528.317734581342, 9075.364724469224, 44094.33858492189, 51916.740416297675, 52465.7376713114, 53843.0, 54739.178912315554, 52270.73864630653, 65202.02196967144, 66448.33551664422, 68707.65646171937, 74662.62668686622, 11907.226030308002, 11913.821292680443, 12040.578579749666, 12231.75536489283, 12380.442880070239, 12105.031597472005, 13557.389917453935, 13948.16311021342, 14149.504767333507, 14593.416263349347, 12641.557545485864, 13109.672258193239, 13134.343282835629, 13170.80243796363, 13332.333383330988, 12790.104692671424, 12896.677583060498, 13016.637019985155, 13040.869591303965, 13297.933510332387, 11063.114950803756, 11575.305481671126, 11964.880351196376, 12029.4586743595, 12469.127161098637, 8967.058458861726, 10354.948225258826, 10636.8936310639, 10715.41065241388, 11472.94263528677, 9581.329306948726, 10849.945750271198, 11347.813771820984, 11435.256708313933, 11551.249168804], "value": 6958.255362178727, "unit": "score"}], "name": "dromaeo_css", "value": 7065.600348461389, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 125.0, 115.0, 114.0, 116.0, 114.0, 115.0, 114.0, 113.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 107.0, 143.0, 112.0, 112.0, 113.0, 109.0, 140.0, 111.0], "value": 120.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 192.0, 220.0, 182.0, 183.0, 181.0, 183.0, 185.0, 184.0, 185.0], "value": 187.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 87.0, 61.0, 58.0, 58.0, 58.0, 82.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 93.0, 69.0, 68.0, 68.0, 90.0, 66.0, 68.0, 90.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 134.0, 86.0, 90.0, 71.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 93.0, 92.0, 92.0, 102.0, 140.0, 93.0, 93.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 87.0, 123.0, 81.0, 85.0, 86.0, 83.0, 83.0], "value": 87.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 54.0, 56.0, 59.0, 58.0, 57.0, 57.0, 57.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 83.0, 73.0, 74.0, 68.0, 69.0, 70.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 89.0, 83.0, 83.0, 83.0, 83.0, 82.0, 83.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 140.0, 138.0, 138.0, 142.0, 144.0, 141.0, 183.0, 150.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 168.0, 167.0, 169.0, 168.0, 165.0, 168.0, 167.0, 169.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 69.0, 67.0, 69.0, 69.0, 68.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1435.7, "alertThreshold": 2.0}]} 05:41:21 INFO - Return code: 0 05:41:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:41:21 INFO - # TBPL SUCCESS # 05:41:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:21 INFO - 2016-12-19 05:41:21.424 screenresolution[1656:9724] starting screenresolution argv=screenresolution get 05:41:21 INFO - 2016-12-19 05:41:21.426 screenresolution[1656:9724] Display 0: 1600x1200x32@60 05:41:21 INFO - 2016-12-19 05:41:21.432 screenresolution[1657:9727] starting screenresolution argv=screenresolution list 05:41:21 INFO - Available Modes on Display 0 05:41:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:21 INFO - Intel Iris: 05:41:21 INFO - Chipset Model: Intel Iris 05:41:21 INFO - Type: GPU 05:41:21 INFO - Bus: Built-In 05:41:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:21 INFO - Vendor: Intel (0x8086) 05:41:21 INFO - Device ID: 0x0a2e 05:41:21 INFO - Revision ID: 0x0009 05:41:21 INFO - Displays: 05:41:21 INFO - Display: 05:41:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:21 INFO - Display Serial Number: 1600x1200 60 05:41:21 INFO - Main Display: Yes 05:41:21 INFO - Mirror: Off 05:41:21 INFO - Online: Yes 05:41:21 INFO - Rotation: Supported 05:41:21 INFO - Return code: 0 05:41:21 INFO - Running post-action listener: _resource_record_post_action 05:41:21 INFO - [mozharness: 2016-12-19 13:41:21.675457Z] Finished run-tests step (success) 05:41:21 INFO - Running post-run listener: _resource_record_post_run 05:41:21 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38650880; Write bytes: 657718272; Read time: 380; Write time: 1847 05:41:21 INFO - TinderboxPrint: CPU usage
53.5% 05:41:21 INFO - TinderboxPrint: I/O read bytes / time
38,650,880 / 380 05:41:21 INFO - TinderboxPrint: I/O write bytes / time
657,718,272 / 1,847 05:41:21 INFO - TinderboxPrint: CPU idle
2,257.1 (46.5%) 05:41:21 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 05:41:21 INFO - TinderboxPrint: CPU user
1,624.5 (33.5%) 05:41:21 INFO - TinderboxPrint: Swap in / out
386,985,984 / 0 05:41:21 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340470272; Write bytes: 335050752; Read time: 27314; Write time: 660 05:41:21 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 37671936; Write bytes: 306021376; Read time: 364; Write time: 1138 05:41:22 INFO - Running post-run listener: _upload_blobber_files 05:41:22 INFO - Blob upload gear active. 05:41:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:41:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:41:22 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'] 05:41:22 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 05:41:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:41:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:41:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:41:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:23 INFO - (blobuploader) - INFO - Done attempting. 05:41:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:41:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:41:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:24 INFO - (blobuploader) - INFO - Done attempting. 05:41:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:41:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:27 INFO - (blobuploader) - INFO - Done attempting. 05:41:27 INFO - (blobuploader) - INFO - Iteration through files over. 05:41:27 INFO - Return code: 0 05:41:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:41:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:41:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad9e9b4e2a197fd7a0ad902f2b7557fb9bcaf38ab8832969e6a947123adc90a51beeb62f4dddc92cda962abc3d15bb2541d8519a57204a8a81dfb9c7b100a5d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e54b7e0d9701bb92f9676b906d552f9251e80c0f9daf6af58a2d856f7e965317cd2f26feadc07ea8b3a7ad40c3531ce73d7aa29a22b4df1c4a5ec00633d1b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30ec516321922ced62be240b1b15f404225d23766ab059f7087996f2f870c2d7f27ef7a05c447a51426a4a136f026d15ca6505938fa91f9786cd7b6b42c812d3"} 05:41:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:41:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:41:27 INFO - Contents: 05:41:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad9e9b4e2a197fd7a0ad902f2b7557fb9bcaf38ab8832969e6a947123adc90a51beeb62f4dddc92cda962abc3d15bb2541d8519a57204a8a81dfb9c7b100a5d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e54b7e0d9701bb92f9676b906d552f9251e80c0f9daf6af58a2d856f7e965317cd2f26feadc07ea8b3a7ad40c3531ce73d7aa29a22b4df1c4a5ec00633d1b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30ec516321922ced62be240b1b15f404225d23766ab059f7087996f2f870c2d7f27ef7a05c447a51426a4a136f026d15ca6505938fa91f9786cd7b6b42c812d3"} 05:41:27 INFO - Running post-run listener: copy_logs_to_upload_dir 05:41:27 INFO - Copying logs to upload dir... 05:41:27 INFO - mkdir: /builds/slave/test/build/upload/logs 05:41:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.648610 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-19 05:41:27.981183) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 05:41:27.985411) ========= 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.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/ad9e9b4e2a197fd7a0ad902f2b7557fb9bcaf38ab8832969e6a947123adc90a51beeb62f4dddc92cda962abc3d15bb2541d8519a57204a8a81dfb9c7b100a5d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e54b7e0d9701bb92f9676b906d552f9251e80c0f9daf6af58a2d856f7e965317cd2f26feadc07ea8b3a7ad40c3531ce73d7aa29a22b4df1c4a5ec00633d1b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30ec516321922ced62be240b1b15f404225d23766ab059f7087996f2f870c2d7f27ef7a05c447a51426a4a136f026d15ca6505938fa91f9786cd7b6b42c812d3"} build_url:https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/Q84E25GtQbOX2wLnx_Nv2w/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/ad9e9b4e2a197fd7a0ad902f2b7557fb9bcaf38ab8832969e6a947123adc90a51beeb62f4dddc92cda962abc3d15bb2541d8519a57204a8a81dfb9c7b100a5d7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06e54b7e0d9701bb92f9676b906d552f9251e80c0f9daf6af58a2d856f7e965317cd2f26feadc07ea8b3a7ad40c3531ce73d7aa29a22b4df1c4a5ec00633d1b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30ec516321922ced62be240b1b15f404225d23766ab059f7087996f2f870c2d7f27ef7a05c447a51426a4a136f026d15ca6505938fa91f9786cd7b6b42c812d3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-19 05:41:28.019183) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:41:28.019498) ========= 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.VHwx2qviFG/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.E43Br3DR7I/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005142 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-19 05:41:28.071647) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 05:41:28.071947) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-19 05:41:28.074277) ========= ========= Total master_lag: 0.19 =========