builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0183 starttime: 1476307753.61 results: success (0) buildid: 20161012121646 builduid: 3d8cabcf75cf4b86a7d85127993de3e1 revision: 38171e4619fb49c80f0342b6c4a91970c5faccf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.612138) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.612537) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.612821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.636671) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.637027) ========= 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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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-10-12 14:29:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.55M=0.001s 2016-10-12 14:29:13 (9.55 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.753504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.753832) ========= 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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.028048 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:13.802549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 14:29:13.802900) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 38171e4619fb49c80f0342b6c4a91970c5faccf5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 38171e4619fb49c80f0342b6c4a91970c5faccf5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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-10-12 14:29:13,875 truncating revision to first 12 chars 2016-10-12 14:29:13,875 Setting DEBUG logging. 2016-10-12 14:29:13,875 attempt 1/10 2016-10-12 14:29:13,875 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/38171e4619fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 14:29:15,315 unpacking tar archive at: mozilla-central-38171e4619fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.696799 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 14:29:15.522026) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:15.522344) ========= script_repo_revision: 38171e4619fb49c80f0342b6c4a91970c5faccf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:15.522724) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:15.523002) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 14:29:15.540703) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 14:29:15.541038) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:29:15 INFO - MultiFileLogger online at 20161012 14:29:15 in /builds/slave/test 14:29:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:29:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:29:15 INFO - {'append_to_log': False, 14:29:15 INFO - 'base_work_dir': '/builds/slave/test', 14:29:15 INFO - 'blob_upload_branch': 'Firefox', 14:29:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:29:15 INFO - 'branch': 'Firefox', 14:29:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:29:15 INFO - 'config_files': ('talos/mac_config.py',), 14:29:15 INFO - 'default_actions': ('clobber', 14:29:15 INFO - 'read-buildbot-config', 14:29:15 INFO - 'download-and-extract', 14:29:15 INFO - 'populate-webroot', 14:29:15 INFO - 'create-virtualenv', 14:29:15 INFO - 'install', 14:29:15 INFO - 'run-tests'), 14:29:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:29:15 INFO - 'download_minidump_stackwalk': True, 14:29:15 INFO - 'download_symbols': 'ondemand', 14:29:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:29:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:29:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:29:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:29:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:29:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:29:15 INFO - 'installer_path': 'installer.exe', 14:29:15 INFO - 'log_level': 'info', 14:29:15 INFO - 'log_name': 'talos', 14:29:15 INFO - 'log_to_console': True, 14:29:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:29:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:29:15 INFO - 'opt_config_files': (), 14:29:15 INFO - 'pip_index': False, 14:29:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:29:15 INFO - 'cmd': ('bash', 14:29:15 INFO - '-c', 14:29:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:29:15 INFO - 'enabled': True, 14:29:15 INFO - 'halt_on_failure': False, 14:29:15 INFO - 'name': 'check_screen_resolution'},), 14:29:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:29:15 INFO - 'cmd': ('bash', 14:29:15 INFO - '-c', 14:29:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:29:15 INFO - 'enabled': True, 14:29:15 INFO - 'halt_on_failure': False, 14:29:15 INFO - 'name': 'check_screen_resolution'},), 14:29:15 INFO - 'run_cmd_checks_enabled': True, 14:29:15 INFO - 'sps_profile': False, 14:29:15 INFO - 'sps_profile_interval': 0, 14:29:15 INFO - 'suite': 'dromaeojs', 14:29:15 INFO - 'system_bits': '32', 14:29:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:29:15 INFO - 'title': 't-yosemite-r7-0183', 14:29:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:29:15 INFO - 'use_talos_json': True, 14:29:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:29:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:29:15 INFO - 'work_dir': 'build'} 14:29:15 INFO - [mozharness: 2016-10-12 21:29:15.743675Z] Running clobber step. 14:29:15 INFO - Running pre-action listener: _resource_record_pre_action 14:29:15 INFO - Running main action method: clobber 14:29:15 INFO - rmtree: /builds/slave/test/build 14:29:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:29:18 INFO - Running post-action listener: _resource_record_post_action 14:29:18 INFO - [mozharness: 2016-10-12 21:29:18.027982Z] Finished clobber step (success) 14:29:18 INFO - [mozharness: 2016-10-12 21:29:18.028099Z] Running read-buildbot-config step. 14:29:18 INFO - Running pre-action listener: _resource_record_pre_action 14:29:18 INFO - Running main action method: read_buildbot_config 14:29:18 INFO - Using buildbot properties: 14:29:18 INFO - { 14:29:18 INFO - "project": "", 14:29:18 INFO - "product": "firefox", 14:29:18 INFO - "script_repo_revision": "production", 14:29:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:29:18 INFO - "repository": "", 14:29:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:29:18 INFO - "buildid": "20161012121646", 14:29:18 INFO - "pgo_build": "False", 14:29:18 INFO - "basedir": "/builds/slave/test", 14:29:18 INFO - "buildnumber": 3, 14:29:18 INFO - "slavename": "t-yosemite-r7-0183", 14:29:18 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:29:18 INFO - "platform": "yosemite_r7", 14:29:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:29:18 INFO - "branch": "mozilla-central", 14:29:18 INFO - "repo_path": "mozilla-central", 14:29:18 INFO - "revision": "38171e4619fb49c80f0342b6c4a91970c5faccf5", 14:29:18 INFO - "stage_platform": "macosx64", 14:29:18 INFO - "builduid": "3d8cabcf75cf4b86a7d85127993de3e1", 14:29:18 INFO - "slavebuilddir": "test" 14:29:18 INFO - } 14:29:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:29:18 INFO - Running post-action listener: _resource_record_post_action 14:29:18 INFO - [mozharness: 2016-10-12 21:29:18.031553Z] Finished read-buildbot-config step (success) 14:29:18 INFO - [mozharness: 2016-10-12 21:29:18.031699Z] Running download-and-extract step. 14:29:18 INFO - Running pre-action listener: _resource_record_pre_action 14:29:18 INFO - Running main action method: download_and_extract 14:29:18 INFO - mkdir: /builds/slave/test/build/tests 14:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:18 INFO - trying https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 14:29:18 INFO - Downloaded 1199 bytes. 14:29:19 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:29:19 INFO - Using the following test package requirements: 14:29:19 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:29:19 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:29:19 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:29:19 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'jsshell-mac.zip'], 14:29:19 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:29:19 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:29:19 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:29:19 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:29:19 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:29:19 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:29:19 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:29:19 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:29:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:29:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:29:19 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:29:19 INFO - Http code: 200 14:29:19 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 14:29:19 INFO - x-amz-cf-id: NbQms_OemoCVNNYVvpdwkUXkm1IEM7WJKBYipKVKCzc_qEpuY86GSw== 14:29:19 INFO - x-amz-version-id: Mwd.RAKNDUXvnRCgYXISiUYCRWNMLxy_ 14:29:19 INFO - Expected file size: 16510586 14:29:19 INFO - Obtained file size: 16510586 14:29:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:29:27 INFO - Http code: 200 14:29:27 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 14:29:27 INFO - x-amz-cf-id: qt1F-PBMAmbzLg1i55L7Kv475IfVIUiyQMircxlk-HPY1avUUUvSnA== 14:29:27 INFO - x-amz-version-id: Mwd.RAKNDUXvnRCgYXISiUYCRWNMLxy_ 14:29:27 INFO - Expected file size: 16510586 14:29:27 INFO - Obtained file size: 16510586 14:29:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:29:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:29:34 INFO - Fetch https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:29:34 INFO - Http code: 200 14:29:34 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 14:29:34 INFO - x-amz-cf-id: Mfz95KFr_yEOZG5kg8mv-lEwuQagw2SZiSggxA2D30SrViLUIUFu5w== 14:29:34 INFO - x-amz-version-id: j03pzTwMLEnb5LsBFP3a0eV8mPX4zJaL 14:29:34 INFO - Expected file size: 14409006 14:29:34 INFO - Obtained file size: 14409006 14:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:35 INFO - trying https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:35 INFO - Downloading https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 14:29:38 INFO - Downloaded 111720131 bytes. 14:29:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:38 INFO - mkdir: /builds/slave/test/properties 14:29:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:29:38 INFO - Writing to file /builds/slave/test/properties/build_url 14:29:38 INFO - Contents: 14:29:38 INFO - build_url:https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:29:38 INFO - Running post-action listener: _resource_record_post_action 14:29:38 INFO - Running post-action listener: set_extra_try_arguments 14:29:38 INFO - [mozharness: 2016-10-12 21:29:38.856453Z] Finished download-and-extract step (success) 14:29:38 INFO - [mozharness: 2016-10-12 21:29:38.856572Z] Running populate-webroot step. 14:29:38 INFO - Running pre-action listener: _resource_record_pre_action 14:29:38 INFO - Running main action method: populate_webroot 14:29:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:29:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:29:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:29:38 INFO - '--tppagecycles', 14:29:38 INFO - '7'], 14:29:38 INFO - 'tests': ['tsvgm']}}, 14:29:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:29:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:29:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:29:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:29:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:29:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['damp', 'tps']}, 14:29:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'tests': ['damp', 'tps']}, 14:29:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['dromaeo_dom']}, 14:29:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:29:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['basic_compositor_video']}, 14:29:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:29:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'other-e10s': {'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['a11yr', 14:29:38 INFO - 'ts_paint', 14:29:38 INFO - 'tpaint', 14:29:38 INFO - 'sessionrestore', 14:29:38 INFO - 'sessionrestore_no_auto_restore', 14:29:38 INFO - 'tabpaint']}, 14:29:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['tsvgx', 14:29:38 INFO - 'tsvgr_opacity', 14:29:38 INFO - 'tart', 14:29:38 INFO - 'tscrollx', 14:29:38 INFO - 'cart']}, 14:29:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:29:38 INFO - 'tsvgr_opacity', 14:29:38 INFO - 'tart', 14:29:38 INFO - 'tscrollx', 14:29:38 INFO - 'cart']}, 14:29:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'talos_options': ['--disable-e10s'], 14:29:38 INFO - 'tests': ['tp5o']}, 14:29:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'tests': ['tp5o']}, 14:29:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'talos_options': ['--disable-e10s', 14:29:38 INFO - '--xperf_path', 14:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:38 INFO - 'tests': ['tp5n']}, 14:29:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:29:38 INFO - 'talos_options': ['--xperf_path', 14:29:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:29:38 INFO - 'tests': ['tp5n']}}, 14:29:38 INFO - 'talos.zip': {'path': '', 14:29:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:29:38 INFO - Running post-action listener: _resource_record_post_action 14:29:38 INFO - [mozharness: 2016-10-12 21:29:38.874970Z] Finished populate-webroot step (success) 14:29:38 INFO - [mozharness: 2016-10-12 21:29:38.875101Z] Running create-virtualenv step. 14:29:38 INFO - Running pre-action listener: _resource_record_pre_action 14:29:38 INFO - Running main action method: create_virtualenv 14:29:38 INFO - Creating virtualenv /builds/slave/test/build/venv 14:29:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:29:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:29:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:29:39 INFO - Using real prefix '/tools/python27' 14:29:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:29:39 INFO - Installing distribute.............................................................................................................................................................................................done. 14:29:42 INFO - Installing pip.................done. 14:29:42 INFO - Return code: 0 14:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:29:43 INFO - Reading from file tmpfile_stdout 14:29:43 INFO - Output received: 14:29:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:29:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:43 INFO - 'HOME': '/Users/cltbld', 14:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:43 INFO - 'LOGNAME': 'cltbld', 14:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:43 INFO - 'MOZ_NO_REMOTE': '1', 14:29:43 INFO - 'NO_EM_RESTART': '1', 14:29:43 INFO - 'PAGER': '/bin/cat', 14:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:43 INFO - 'PWD': '/builds/slave/test', 14:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:43 INFO - 'SHELL': '/bin/bash', 14:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:43 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:43 INFO - 'USER': 'cltbld', 14:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:43 INFO - 'XPC_FLAGS': '0x0', 14:29:43 INFO - 'XPC_SERVICE_NAME': '0', 14:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:29:43 INFO - Cleaning up... 14:29:43 INFO - Return code: 0 14:29:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:43 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:43 INFO - 'HOME': '/Users/cltbld', 14:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:43 INFO - 'LOGNAME': 'cltbld', 14:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:43 INFO - 'MOZ_NO_REMOTE': '1', 14:29:43 INFO - 'NO_EM_RESTART': '1', 14:29:43 INFO - 'PAGER': '/bin/cat', 14:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:43 INFO - 'PWD': '/builds/slave/test', 14:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:43 INFO - 'SHELL': '/bin/bash', 14:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:43 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:43 INFO - 'USER': 'cltbld', 14:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:43 INFO - 'XPC_FLAGS': '0x0', 14:29:43 INFO - 'XPC_SERVICE_NAME': '0', 14:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:43 INFO - Downloading/unpacking psutil>=3.1.1 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:29:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:47 INFO - Installing collected packages: psutil 14:29:47 INFO - Running setup.py install for psutil 14:29:47 INFO - building 'psutil._psutil_osx' extension 14:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:29:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:29:47 INFO - building 'psutil._psutil_posix' extension 14:29:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:29:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:29:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:29:48 INFO - ^ 14:29:48 INFO - 1 warning generated. 14:29:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:29:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:29:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:29:48 INFO - Successfully installed psutil 14:29:48 INFO - Cleaning up... 14:29:48 INFO - Return code: 0 14:29:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:48 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:48 INFO - 'HOME': '/Users/cltbld', 14:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:48 INFO - 'LOGNAME': 'cltbld', 14:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:48 INFO - 'MOZ_NO_REMOTE': '1', 14:29:48 INFO - 'NO_EM_RESTART': '1', 14:29:48 INFO - 'PAGER': '/bin/cat', 14:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:48 INFO - 'PWD': '/builds/slave/test', 14:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:48 INFO - 'SHELL': '/bin/bash', 14:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:48 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:48 INFO - 'USER': 'cltbld', 14:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:48 INFO - 'XPC_FLAGS': '0x0', 14:29:48 INFO - 'XPC_SERVICE_NAME': '0', 14:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:29:51 INFO - Installing collected packages: mozsystemmonitor 14:29:51 INFO - Running setup.py install for mozsystemmonitor 14:29:51 INFO - Successfully installed mozsystemmonitor 14:29:51 INFO - Cleaning up... 14:29:51 INFO - Return code: 0 14:29:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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', '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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:29: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 jsonschema==2.5.1 14:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:51 INFO - 'HOME': '/Users/cltbld', 14:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:51 INFO - 'LOGNAME': 'cltbld', 14:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:51 INFO - 'MOZ_NO_REMOTE': '1', 14:29:51 INFO - 'NO_EM_RESTART': '1', 14:29:51 INFO - 'PAGER': '/bin/cat', 14:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:51 INFO - 'PWD': '/builds/slave/test', 14:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:51 INFO - 'SHELL': '/bin/bash', 14:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:51 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:51 INFO - 'USER': 'cltbld', 14:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:51 INFO - 'XPC_FLAGS': '0x0', 14:29:51 INFO - 'XPC_SERVICE_NAME': '0', 14:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:51 INFO - Downloading/unpacking jsonschema==2.5.1 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:29:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:54 INFO - Downloading functools32-3.2.3-2.tar.gz 14:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:29:54 INFO - warning: no files found matching '*.txt' 14:29:54 INFO - no previously-included directories found matching 'build' 14:29:54 INFO - no previously-included directories found matching 'dist' 14:29:54 INFO - no previously-included directories found matching '.git*' 14:29:54 INFO - Installing collected packages: jsonschema, functools32 14:29:54 INFO - Running setup.py install for functools32 14:29:54 INFO - warning: no files found matching '*.txt' 14:29:54 INFO - no previously-included directories found matching 'build' 14:29:54 INFO - no previously-included directories found matching 'dist' 14:29:54 INFO - no previously-included directories found matching '.git*' 14:29:54 INFO - Successfully installed jsonschema functools32 14:29:54 INFO - Cleaning up... 14:29:54 INFO - Return code: 0 14:29:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:29:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:54 INFO - 'HOME': '/Users/cltbld', 14:29:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:54 INFO - 'LOGNAME': 'cltbld', 14:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:54 INFO - 'MOZ_NO_REMOTE': '1', 14:29:54 INFO - 'NO_EM_RESTART': '1', 14:29:54 INFO - 'PAGER': '/bin/cat', 14:29:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:54 INFO - 'PWD': '/builds/slave/test', 14:29:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:54 INFO - 'SHELL': '/bin/bash', 14:29:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:54 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:54 INFO - 'USER': 'cltbld', 14:29:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:54 INFO - 'XPC_FLAGS': '0x0', 14:29:54 INFO - 'XPC_SERVICE_NAME': '0', 14:29:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:29:55 INFO - Cleaning up... 14:29:55 INFO - Return code: 0 14:29:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:29:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:29:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:55 INFO - 'HOME': '/Users/cltbld', 14:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:55 INFO - 'LOGNAME': 'cltbld', 14:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:55 INFO - 'MOZ_NO_REMOTE': '1', 14:29:55 INFO - 'NO_EM_RESTART': '1', 14:29:55 INFO - 'PAGER': '/bin/cat', 14:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:55 INFO - 'PWD': '/builds/slave/test', 14:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:55 INFO - 'SHELL': '/bin/bash', 14:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:29:55 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:55 INFO - 'USER': 'cltbld', 14:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:55 INFO - 'XPC_FLAGS': '0x0', 14:29:55 INFO - 'XPC_SERVICE_NAME': '0', 14:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:55 INFO - Downloading/unpacking blobuploader==1.2.4 14:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:57 INFO - Downloading blobuploader-1.2.4.tar.gz 14:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:29:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:29:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:59 INFO - Downloading docopt-0.6.1.tar.gz 14:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:29:59 INFO - Installing collected packages: blobuploader, requests, docopt 14:29:59 INFO - Running setup.py install for blobuploader 14:29:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:29:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:29:59 INFO - Running setup.py install for requests 14:29:59 INFO - Running setup.py install for docopt 14:30:00 INFO - Successfully installed blobuploader requests docopt 14:30:00 INFO - Cleaning up... 14:30:00 INFO - Return code: 0 14:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:00 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:00 INFO - 'HOME': '/Users/cltbld', 14:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:00 INFO - 'LOGNAME': 'cltbld', 14:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:00 INFO - 'MOZ_NO_REMOTE': '1', 14:30:00 INFO - 'NO_EM_RESTART': '1', 14:30:00 INFO - 'PAGER': '/bin/cat', 14:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:00 INFO - 'PWD': '/builds/slave/test', 14:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:00 INFO - 'SHELL': '/bin/bash', 14:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:30:00 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:00 INFO - 'USER': 'cltbld', 14:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:00 INFO - 'XPC_FLAGS': '0x0', 14:30:00 INFO - 'XPC_SERVICE_NAME': '0', 14:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-pEV09w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-okSXdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-SQWvD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-ggY3mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-pgXovf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:00 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-IctYUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-yfZl57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-HbefSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-dRedhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-REjhoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-Bx5joy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-5SwFQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-NgnfYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-AKB4Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:01 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-EO5p1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:02 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-1wybZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:02 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-eBQ32N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:02 INFO - Running setup.py install for manifestparser 14:30:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:02 INFO - Running setup.py install for mozcrash 14:30:02 INFO - Running setup.py install for mozdebug 14:30:02 INFO - Running setup.py install for mozdevice 14:30:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:02 INFO - Running setup.py install for mozfile 14:30:02 INFO - Running setup.py install for mozhttpd 14:30:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Running setup.py install for mozinfo 14:30:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Running setup.py install for mozInstall 14:30:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Running setup.py install for mozleak 14:30:03 INFO - Running setup.py install for mozlog 14:30:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Running setup.py install for moznetwork 14:30:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:30:03 INFO - Running setup.py install for mozprocess 14:30:04 INFO - Running setup.py install for mozprofile 14:30:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:30:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:30:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:30:04 INFO - Running setup.py install for mozrunner 14:30:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:30:04 INFO - Running setup.py install for mozscreenshot 14:30:04 INFO - Running setup.py install for moztest 14:30:04 INFO - Running setup.py install for mozversion 14:30:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:30:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:30:04 INFO - Cleaning up... 14:30:04 INFO - Return code: 0 14:30:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:04 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:04 INFO - 'HOME': '/Users/cltbld', 14:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:04 INFO - 'LOGNAME': 'cltbld', 14:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:04 INFO - 'MOZ_NO_REMOTE': '1', 14:30:04 INFO - 'NO_EM_RESTART': '1', 14:30:04 INFO - 'PAGER': '/bin/cat', 14:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:04 INFO - 'PWD': '/builds/slave/test', 14:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:04 INFO - 'SHELL': '/bin/bash', 14:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:30:04 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:04 INFO - 'USER': 'cltbld', 14:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:04 INFO - 'XPC_FLAGS': '0x0', 14:30:04 INFO - 'XPC_SERVICE_NAME': '0', 14:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-xG8f0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-TGVyeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-lwQSCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-zs8fr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-EgJYRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-D1qEDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-xqaWfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-X3EgBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:05 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-fpkdoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-JVSN_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-whD7Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-lQZ2ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-US2Y3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-P16EmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-LhagrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-fbneeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:06 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-p96CLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:30:09 INFO - Downloading blessings-1.6.tar.gz 14:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:30:09 INFO - Installing collected packages: blessings 14:30:09 INFO - Running setup.py install for blessings 14:30:10 INFO - Successfully installed blessings 14:30:10 INFO - Cleaning up... 14:30:10 INFO - Return code: 0 14:30:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:10 INFO - Reading from file tmpfile_stdout 14:30:10 INFO - Current package versions: 14:30:10 INFO - blessings == 1.6 14:30:10 INFO - blobuploader == 1.2.4 14:30:10 INFO - docopt == 0.6.1 14:30:10 INFO - functools32 == 3.2.3-2 14:30:10 INFO - jsonschema == 2.5.1 14:30:10 INFO - manifestparser == 1.1 14:30:10 INFO - mozInstall == 1.12 14:30:10 INFO - mozcrash == 0.17 14:30:10 INFO - mozdebug == 0.1 14:30:10 INFO - mozdevice == 0.48 14:30:10 INFO - mozfile == 1.2 14:30:10 INFO - mozhttpd == 0.7 14:30:10 INFO - mozinfo == 0.9 14:30:10 INFO - mozleak == 0.1 14:30:10 INFO - mozlog == 3.4 14:30:10 INFO - moznetwork == 0.27 14:30:10 INFO - mozprocess == 0.23 14:30:10 INFO - mozprofile == 0.28 14:30:10 INFO - mozrunner == 6.12 14:30:10 INFO - mozscreenshot == 0.1 14:30:10 INFO - mozsystemmonitor == 0.3 14:30:10 INFO - moztest == 0.7 14:30:10 INFO - mozversion == 1.4 14:30:10 INFO - psutil == 3.1.1 14:30:10 INFO - requests == 1.2.3 14:30:10 INFO - wsgiref == 0.1.2 14:30:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:10 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:10 INFO - 'HOME': '/Users/cltbld', 14:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:10 INFO - 'LOGNAME': 'cltbld', 14:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:10 INFO - 'MOZ_NO_REMOTE': '1', 14:30:10 INFO - 'NO_EM_RESTART': '1', 14:30:10 INFO - 'PAGER': '/bin/cat', 14:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:10 INFO - 'PWD': '/builds/slave/test', 14:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:10 INFO - 'SHELL': '/bin/bash', 14:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:30:10 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:10 INFO - 'USER': 'cltbld', 14:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:10 INFO - 'XPC_FLAGS': '0x0', 14:30:10 INFO - 'XPC_SERVICE_NAME': '0', 14:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:30:11 INFO - Cleaning up... 14:30:11 INFO - Return code: 0 14:30:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:11 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 0x10cd5f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2d8db9780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, '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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:30:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:11 INFO - 'HOME': '/Users/cltbld', 14:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:11 INFO - 'LOGNAME': 'cltbld', 14:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:11 INFO - 'MOZ_NO_REMOTE': '1', 14:30:11 INFO - 'NO_EM_RESTART': '1', 14:30:11 INFO - 'PAGER': '/bin/cat', 14:30:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:11 INFO - 'PWD': '/builds/slave/test', 14:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:11 INFO - 'SHELL': '/bin/bash', 14:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:30:11 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:11 INFO - 'USER': 'cltbld', 14:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:11 INFO - 'XPC_FLAGS': '0x0', 14:30:11 INFO - 'XPC_SERVICE_NAME': '0', 14:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:30:11 INFO - Cleaning up... 14:30:11 INFO - Return code: 0 14:30:11 INFO - Running post-action listener: _resource_record_post_action 14:30:11 INFO - Running post-action listener: _start_resource_monitoring 14:30:11 INFO - Starting resource monitoring. 14:30:11 INFO - [mozharness: 2016-10-12 21:30:11.435125Z] Finished create-virtualenv step (success) 14:30:11 INFO - [mozharness: 2016-10-12 21:30:11.436131Z] Running install step. 14:30:11 INFO - Running pre-action listener: _resource_record_pre_action 14:30:11 INFO - Running main action method: install 14:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:11 INFO - Reading from file tmpfile_stdout 14:30:11 INFO - Detecting whether we're running mozinstall >=1.0... 14:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:30:11 INFO - Reading from file tmpfile_stdout 14:30:11 INFO - Output received: 14:30:11 INFO - Usage: mozinstall [options] installer 14:30:11 INFO - Options: 14:30:11 INFO - -h, --help show this help message and exit 14:30:11 INFO - -d DEST, --destination=DEST 14:30:11 INFO - Directory to install application into. [default: 14:30:11 INFO - "/builds/slave/test"] 14:30:11 INFO - --app=APP Application being installed. [default: firefox] 14:30:11 INFO - mkdir: /builds/slave/test/build/application 14:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:30:46 INFO - Reading from file tmpfile_stdout 14:30:46 INFO - Output received: 14:30:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:30:46 INFO - Running post-action listener: _resource_record_post_action 14:30:46 INFO - [mozharness: 2016-10-12 21:30:46.409683Z] Finished install step (success) 14:30:46 INFO - [mozharness: 2016-10-12 21:30:46.409816Z] Running run-tests step. 14:30:46 INFO - Running pre-action listener: _resource_record_pre_action 14:30:46 INFO - Running main action method: run_tests 14:30:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:30:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:30:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:30:46 INFO - 2016-10-12 14:30:46.424 screenresolution[1642:7474] starting screenresolution argv=screenresolution get 14:30:46 INFO - 2016-10-12 14:30:46.427 screenresolution[1642:7474] Display 0: 1600x1200x32@60 14:30:46 INFO - 2016-10-12 14:30:46.433 screenresolution[1643:7476] starting screenresolution argv=screenresolution list 14:30:46 INFO - Available Modes on Display 0 14:30:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:30:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:30:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:30:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:30:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:30:46 INFO - Intel Iris: 14:30:46 INFO - Chipset Model: Intel Iris 14:30:46 INFO - Type: GPU 14:30:46 INFO - Bus: Built-In 14:30:46 INFO - VRAM (Dynamic, Max): 1536 MB 14:30:46 INFO - Vendor: Intel (0x8086) 14:30:46 INFO - Device ID: 0x0a2e 14:30:46 INFO - Revision ID: 0x0009 14:30:46 INFO - Displays: 14:30:46 INFO - Display: 14:30:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:30:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:30:46 INFO - Display Serial Number: 1600x1200 60 14:30:46 INFO - Main Display: Yes 14:30:46 INFO - Mirror: Off 14:30:46 INFO - Online: Yes 14:30:46 INFO - Rotation: Supported 14:30:46 INFO - Return code: 0 14:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:30:46 INFO - Python 2.7.3 14:30:46 INFO - Return code: 0 14:30:46 INFO - grabbing minidump binary from tooltool 14:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:30:46 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 0x10cb73d30>, '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 0x10c999db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cb6bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:30:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:30:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:30:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:30:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:30:46 INFO - Return code: 0 14:30:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:30:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:30:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:30:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:30:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0183 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XPNNU6TTGz/Render', 14:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:30:46 INFO - 'HOME': '/Users/cltbld', 14:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:30:46 INFO - 'LOGNAME': 'cltbld', 14:30:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:30:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:46 INFO - 'MOZ_NO_REMOTE': '1', 14:30:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:30:46 INFO - 'NO_EM_RESTART': '1', 14:30:46 INFO - 'PAGER': '/bin/cat', 14:30:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:46 INFO - 'PWD': '/builds/slave/test', 14:30:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:30:46 INFO - 'SHELL': '/bin/bash', 14:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JeN6rCx9UZ/Listeners', 14:30:46 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 14:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:30:46 INFO - 'USER': 'cltbld', 14:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:46 INFO - 'XPC_FLAGS': '0x0', 14:30:46 INFO - 'XPC_SERVICE_NAME': '0', 14:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:30:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:30:47 INFO - mozversion application_buildid: 20161012121646 14:30:47 INFO - mozversion application_changeset: 38171e4619fb49c80f0342b6c4a91970c5faccf5 14:30:47 INFO - mozversion application_display_name: Nightly 14:30:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:30:47 INFO - mozversion application_name: Firefox 14:30:47 INFO - mozversion application_remotingname: firefox 14:30:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:30:47 INFO - mozversion application_vendor: Mozilla 14:30:47 INFO - mozversion application_version: 52.0a1 14:30:47 INFO - mozversion platform_buildid: 20161012121646 14:30:47 INFO - mozversion platform_changeset: 38171e4619fb49c80f0342b6c4a91970c5faccf5 14:30:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:30:47 INFO - mozversion platform_version: 52.0a1 14:30:47 INFO - using testdate: 1476307847 14:30:47 INFO - actual date: 1476307847 14:30:47 INFO - starting webserver on 'localhost:49273' 14:30:47 INFO - SUITE-START | Running 2 tests 14:30:47 INFO - TEST-START | dromaeo_css 14:30:47 INFO - operating with platform_type : mac_ 14:30:47 INFO - Initialising browser for dromaeo_css test... 14:30:47 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpIdqDS3/profile http://localhost:49273/getInfo.html) 14:30:49 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 14:30:49 INFO - PROCESS | 1653 | colorDepth:24 14:30:49 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 14:30:49 INFO - PROCESS | 1653 | __metrics 14:30:49 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:30:49 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:30:50 INFO - TEST-INFO | 1653: exit 0 14:30:50 INFO - Browser initialized. 14:30:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:30:50 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpIdqDS3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:31:02 INFO - PROCESS | 1658 | RSS: Main: 234967040 14:31:02 INFO - PROCESS | 1658 | 14:31:53 INFO - PROCESS | 1658 | 1476307913601 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:34:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 14:34:08 INFO - PROCESS | 1658 | RSS: Main: 370487296 14:34:08 INFO - PROCESS | 1658 | 14:37:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 14:37:12 INFO - PROCESS | 1658 | RSS: Main: 369930240 14:37:12 INFO - PROCESS | 1658 | 14:40:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 14:40:14 INFO - PROCESS | 1658 | RSS: Main: 342970368 14:40:14 INFO - PROCESS | 1658 | 14:43:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 14:43:18 INFO - PROCESS | 1658 | RSS: Main: 352710656 14:43:18 INFO - PROCESS | 1658 | 14:46:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 14:46:21 INFO - PROCESS | 1658 | RSS: Main: 333283328 14:46:21 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 14:49:25 INFO - PROCESS | 1658 | RSS: Main: 459042816 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | __start_tp_report 14:49:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:49:25 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:49:25 INFO - PROCESS | 1658 | |i|pagename|runs| 14:49:25 INFO - PROCESS | 1658 | |0;dojo.html;2906.287959449935;3190.34597907429;3357.6978071973517;3396.6942975132233;3408.642092580279;5608.186812912125;5634.40838711935;5679.290088738908;5699.829005129843;5725.828225153242;4796.880077998052;5629.437056294369;5796.07262837946;5840.97782887995;5841.065429531276;5038.647294689373;5052.191649336107;5091.389033316007;5100.311457953185;5246.160614301693;4863.927041094369;4870.707757534542;5197.714125723083;5290.523852853243;5364.892702145955;5238.13570760824;5244.790208391679;5254.106801843657;5269.394019687747;5324.281222035033;5110.872228194325;5192.506711862367;5196.090684130262;5197.7401129943355;5247.527722504993;5165.896682066375;5165.9225111623355;5184.118699821001;5218.373795144607;5263.289455923439;5686.3744988051285;5738.598298119134;5769.740361683735;5770.04794208955;5801.448862358069;6101.786437474711;6107.977837958716;6132.539076726587;6211.596246243979;6233;5219.791208351661;5254.894902101974;6130.264368275835;6269.122322874801;6288.151099601541;5798.623089499211;5799.94200057994;5811.418858114197;5821.330546987066;5825.766969321265;6201.751929922752;6202.193714817045;6206.937930620727;6240.906386404207;6272.1219029335925;6093.006878561393;6165.537584681167;6172.969135154295;6178.938210617926;6184.412480814315;6213.1922850029205;6246.500279977591;6250.218722659668;6270.028145637433;6277.591956522811;5955.82132536016;6143.201383820075;6148.6925653717135;6200.782972596027;6235.127082208494;6043.7280322385595;6119.816405507842;6121.051237058263;6148.52435415506;6215.378462153839;4970.304157417964;4978.178600530872;4981.800727970913;4991.276264941635;5020.548150666457;4365.192439398721;4440.200763862471;4456.532064133284;4462.286034234507;4481.507034226298;5708.885822283531;5752.683602401824;5778.913316300259;5829.912551311733;5874.0014197586515;6179.227596550431;6222.62664240147;6233.719482623293;6271.717772700239;6272.18461599989;5823.388544202798;5832.416758324133;5835.795747148914;5859.5019423349495;5877.353491115974;5245.501677340647;5432.348118225759;5435.021696094662;5450.727463626803;5453.263809385682;6216.7824126155365;6256.405641464054;6259.624422534572;6286.088517164894;6291.307956124914;6240.095186198067;6242.094896240111;6246.875062498815;6259.186305780219;6262.624242545462;6311.715972781328;6369.45859601939;6370.55406121567;6380.521460890544;6391.8721625566295;6103;6130.061839217998;6135.202044401974;6141.332587233645;6207.037909124093;6231.034189700604;6248.937510624835;6255.155554000152;6263.373662633882;6271.090691849748;6111.633302001894;6153.630782153085;6162.476189523762;6165.938340616536;6173.104899789416;5879.20630714848;6013.458788709037;6094.634321940698;6129.570930034854;6184.072389141665;5996.190514280516;6019.759209631566;6077.402948305363;6088.847778805565;6116.449519543263;6005.6396616203165;6161.753529858935;6162.044883043135;6164.753409863555;6184.134221208944;5890.145928840208;5962.25471816023;5966.940330596812;6013.188219590299;6039.365866583946 14:49:25 INFO - PROCESS | 1658 | |1;ext.html;459.0566386076611;500.0973243295852;517.2577351500595;519.6570263626006;524.1377933299722;30234.69765302349;39872.60127398729;41014.79492602533;41250.38124428136;41261;34466;41996;42407;42553;42558.14883702324;8503.022152452455;8733.694320698776;9313.254939604816;9516.904830951706;9530.428174309529;11292.378919159442;11622.012128969047;11624.883751162506;11643.243189192717;11833.526658933632;11445.427728613578;11525.884741152566;11526.366049867254;11544.53821847125;11552.3646199459;8614;8816;11290.661280161527;11480.885191148065;11559.190856640038;9377.578008989612;11372.715682107932;11390.60132895353;11406.828897566544;11413.771724565462;39365.80317098396;39778.403323949875;39791.8010409949;39800.60199397998;39920.60079399227;3244.853981570835;3453.4129198036394;3690.022144131807;3697.944530832013;4086.2849001424634;29349.706502935125;30510.694893051008;31347.05958821239;31393.058208253562;31531.684683153104;3180.316232010106;3300.5214243934497;3323.9501407478906;3359.4288970874763;3360.327934413103;456859;459469;460171;462228;462728;59810.10284845731;60272;60313.69843150756;60330;60371;62959.370406296264;63148.68425657934;63210;63210.67893210642;63323;16231.269592868113;16507.009579425034;16515.33938642441;16518.091504967102;16548.089855057846;11362.091032717362;11635.185537012328;11657.883421165849;12412.689682757822;12635.747285054247;18294.817051829577;18705.625887482445;18713;18728.344507942158;18771;6158.551211321585;7468.925310746823;7615.068198743524;7728.884066739003;7767.883481747778;707.3033062433499;728.621117019147;927.758782716485;1024.0476356987924;1057.4659796802719;24115.276541703777;28776.71223287782;29737.702622973495;29768.2557936049;30449.391012179633;925.842606756853;1040.604570263295;1043.2697112021615;1044.4046893270915;1062.7088087972995;15461;15519.922400387926;15551.922240388725;15787.605309867116;15816.841831581767;629503.8524807347;631032;632990;633542;636546;56755;57175.14237286444;57256;57415.13877291844;57453.84003879827;1045.6862941117786;1104.7127746785734;1176.8705442401329;1224.0635913526337;1318.8351456067992;25972.610410843474;28386.574201387408;29045.564316535692;30944.690553095083;31352.646156148865;37890.43164352592;49656.75171624263;49683.751581241864;49730;49808.501914980385;512254;512672;513306;516655;603622;34287.82856085704;39129.41305880357;39532.80233598813;41618.95952601209;41667.58332416637;45456.86357840948;45594;45786.54213457956;46455.07089858252;46797;37624;38103.618963810004;38103.80948095242;38625;39612.60387396204;37028.629713703594;37709.622903770614;38518.42222366723;38695.80652096721;38979.805100974314;36652.63347366492;36672.44991325079;36955.81522092372;37271.44092838556;37528.624713753605;27188.592171117052;29568;32371.51442728408;33356.499652505714;33699.15752106217;24418;24880.824693123268;24936.127235546668;24948.875255623607;25022.74977250204 14:49:25 INFO - PROCESS | 1658 | |2;jquery.html;346.21063974138974;366.45581311752045;372.20348454307776;372.5454944967139;393.205724436638;14363.35364908579;14585.416583336668;15422.768658470131;19503.902480487577;19965.700514492295;19502.21991120357;19977.700334494995;20614.89692551539;20679.896600517015;20890.68663970038;5809.186713860063;6116.908246376308;6119.816405507842;6123.357047510014;6125.693715314216;4678.368420263255;6702.564333318343;6776.491763117786;6795.2185498667795;6871.896921546181;5666.660000399968;5975.940240597582;6772.4582033437455;6779.186497620268;6785;6016.187814645011;6062.54498920256;6062.575619706622;6069.15031895535;6080.422359875849;5660.122680984454;5731.426857314277;5848.210491583624;5905.261842269716;5921.378255283176;13439.395227214798;13651;13684.110532815335;22810.31569052931;23872.88063559671;2209.5470428562107;2237.9664305035276;2280.372897453197;2500.7874330681734;2735.3161709572605;9656.95171524138;9971.75070623233;10238.180945524415;10512;10552.736181595445;1824.9452516424528;1872.1855992643243;1911.808819118077;1936.2351871011012;1975.5061234691327;115759;121448.99999999911;127178;127318;129201;10875.238733288594;11065.612703555336;11360.48877800501;11392.886071139348;11489.712757181138;13975;14275.14349139055;14488.565343039725;14492;14494.420223190955;6929.549579277331;7115.71537138519;8359.623816928253;8552.315814734806;8680.392372533988;6923.6884340204815;8504.53225072627;8505.489670619783;8519.23326900582;8713.346499012472;4391.604755572014;4469.374287599739;4492.034212644297;4563.110193512233;4584.404027476474;1488.8833374968774;1758.947231583029;1851.6759567075708;1870.5417172792481;1916.537249634241;805;850.6767428377177;910.5083255042204;916.6333466613409;1030.6753372687729;19468.610627787366;21463.570728585342;21522.139114435246;21548.892255538936;21598.460038498848;811.959402029908;898.9324553526862;909.667971190524;929.5863340813273;931.3992474853682;10524.789649190441;11006.301811025212;11116.277441966246;11237.662870113909;11314;559925;560629;564124;580709;589903;109489;110683;111039;113548.29677554843;116153.4192329033;964.1467301438278;977.1205914676848;1007.6120693533043;1034.0745033195249;1034.374203606807;18281.81718182801;18479.630407392047;18552.350667726565;18592.535186620444;18598.16308266104;50531.494685052676;52393.47606523886;53434;53601.19598205952;54547;68988.37645294338;71247;71725;72123;72739.27260727537;6552;7959.801004974922;8080.676772929017;8111.310538604284;8282.834343313221;9841.753956151153;12059.57791477294;12831.871681283068;13006.609801705965;13060.759227873717;10511.316764410289;11220.438978050901;11285.943570282096;11480.507534020524;11515.884841151816;10877.456127193767;11036.448177591248;11211.537216586494;11234.213605047567;11362.488688008894;11104.389258590854;11282.774344513227;11504.884951150381;11685.182037257651;11697.941510292394;7655.46411751172;7998.880016799636;8146.429749917449;8173.509589424653;8410.158984101541;10826.296290741077;11363.431828408711;11516.769664606494;11591.88408115908;11591.88408115908 14:49:25 INFO - PROCESS | 1658 | |3;mootools.html;1259.1815320041978;1441.4378392426956;1443.8772704320131;1480.9111453312803;1858.5074955136884;7679.769606911795;7682.0397450318715;8132.91867081329;8168.183181681837;8596.570171491432;7415.369693576039;7767.650455729492;8284.33725301976;8517.616707248173;8699.130086991314;2239.6864438978555;2265.1279257485903;2267.342470683508;2268.8088753404513;2271.114265436481;2220.322801545528;2255.4925141843037;2263.151318255654;2265.796078352948;2278.829087818412;2253.43664083979;2258.525709600986;2260.8304377171694;2267.2744721689064;2282.9657555136687;2218.8557743746605;2232.3191426614876;2236.4185311818883;2237.3064350051536;2239.070785623956;2244.8204143668468;2247.370736193868;2252.831037672181;2253.481699209191;2256.2852232303403;8646.437981531244;8660.783480412978;8719.302455803521;8731.738047858575;8739.825203495895;5080.618953578496;5309.230161626542;5332.280142180794;5352.678839269656;5385.542228910547;3354.9496757548654;3505.7721248119046;3583.7491375603718;3585.623509531488;3585.87449439271;1034.8137335279646;1067.6370034188412;1070.2829104499926;1089.6567581211893;1092.5356723392606;11494.597689080842;11596;11627.06983441331;11635.534578616845;11673;4291.957080429218;4338.197433474754;4521.570450807168;4531;4535.365048893158;9066.78129264115;9099.727008189764;9105.317101217435;9122.407043542149;9140.451572905515;4416.514183439819;5166.793328266903;5171.689698618095;5172.482751724798;5174.741262936838;4106.10991472966;4312.245357062501;4471.128130014616;4484.439445069366;4854.393200849894;3526.2771131918407;3814.504114465102;3814.752041117374;3814.923701525954;3817.6373244541724;1105.4914739220048;1130.7116685245373;1134.7787181499527;1144.3591588710349;1146.621614867092;641.0800501280677;789.4513313247238;796.7291121018882;895.0735988252128;904.9773755656162;5700.572457065737;5803.129530570448;5825.825225243165;5856.912146317809;5868.119782032729;363.65452819821274;438.1652951128106;441.2586854085168;970.257752819094;995.8058178655093;3563.465480177942;3754.455603937414;3889.3193691103943;3960.20795840833;4014.3777714454304;10341.37951722899;10529.526171322308;10630.946845265724;10638.946805265925;10647.041766241075;9868.654597089067;10247.487625618689;10593.788124237626;10603.84094238587;10606.098481629147;7992.5204487730925;8196.590170491452;8205.917940820635;8210.466319689202;8248.422610417334;4915.4592994771265;5813.563982701399;5846.210761547307;6070.757169713162;6086.360932102066;9813.018698130416;9946.104850563484;10016.849747253651;10038.548265327932;10052.195824334181;9067.138621830916;9500;10115.190784737057;10119.696409107739;10285.897141028794;6973.4072603829245;7226.674799633924;7340.302671246223;7441.218672039358;7560.697572097274;8176.67293308261;8201.056878459054;8202.58987050657;8299.08710041907;8324.791880203044;7380;7461.552306861606;7469.701211951461;7527;7530.472866899484;7161.57030578167;7233.710651573878;7267.890981635174;7361.374283185775;7386.667599957907;6862.451003919774;6904.585724856524;7011.368976792113;7028.402585780266;7032.542884712478;4807.61539076866;4881.707097574157;4893.584045356185;4925;5047.722375269395;4641.071785642817;4764.285357196448;4766.904661906673;4959;5127.41034781005 14:49:25 INFO - PROCESS | 1658 | |4;prototype.html;297.69188889499367;298.4895828133891;307.0282555711174;313.3435452726537;313.7709472085376;4362.869113926579;5012.3985121785345;5202.55778258848;5265;5297.655652382602;5045.64680472367;5153.046686363025;5163.514627626347;5200.659703133596;5213.244079608464;3340.599128104631;4030.455888455065;4051.7163798534116;4054.8378064877443;4082.5509193988787;3555.4133567961258;3557.5908770491337;3744.117029886985;4064.268431682296;4090.141070375225;3675.7426980111404;3713.9442908356395;3989;4015.9799201003952;4033.7378070425475;3986.402039694069;4016.2770701273757;4026.8187931543152;4028.7582745035247;4032.1532478179615;3208.51872219169;3256.8926564967787;3275.606927168731;3460.771426143733;3481.704055155315;4029.53660329063;4928.211486162197;4929.507049295078;5167.457416971239;5224.320838291036;4534.251848445036;4565.908681826378;4593.517680643551;4617.607503362218;4625.676202665814;4450.421445212101;5356.196570514415;5372.435894231088;5548;5563.944360556383;3491.7953306109353;3504.197945229429;3778.886633401002;3779.7543159694533;3798.651478725068;5784.334801497839;5833.912491312633;5853.795117170878;5895.941040589625;5900.321463031721;3684.7236457265813;3812.370958791824;3823.655870971626;3831.214601006787;3836.55879573847;3866.4007078902814;3881.7282790204413;3885.9805700971315;3900.063984643665;3928.9607103929166;4503.594676479067;4508.977455112704;4518.706284091523;4530.909381812346;4537.75042372666;5037.244413337955;5521.917171242435;5537.594379456606;5557.166425036277;5558.499735023867;1162.6918866500384;1181.539199712113;1314.1326724361875;1325.4631874091008;1337.7458282926214;722.5375759513915;752.8143174500184;796.2714116583377;806.4435539477741;814.7067055859885;3951.3480275754755;4209.389638502461;4269.563001783532;4311.698181127354;4329.532166665033;3929.76421414716;4511.879416674158;4690.859274221779;4722.574968252874;4765.332853400527;3727.956172271768;3949.9190631307624;4293.420388247547;4297.78511074445;4341.196878577473;2112.310151878491;2150.6666466697375;2278.646809744492;2333.358326460203;2556.514262290159;4109.445224894601;4213.852515162016;4257.808398622069;4641.419822522184;4851.380254662513;4039.091853865212;4158.355454904495;4203.70574059819;4662;4876.463913867121;4446.466424029137;4495.954340775005;4871.780769865364;4879.4388645305535;4883.853484395474;4253.511695658075;4540.61617650736;4701.341812146234;4710.858674239778;4730.905381892274;4096.897577560585;4218.873433796991;4473.792076139359;4639.559241872017;4685.390899183084;3864.787436691009;4408.603225709701;4500.469840254329;4610.585047345621;4619.6507532039;3058.847057647153;3285.9791228393888;3339.2653616204398;3542.98050111434;3713;5278.340207474066;5507.9449205507435;5711.371749107512;5735.827925162252;5742.626729262584;5525.9723701382845;5550.056490396723;5600.215969764155;5610.551155907436;5650.0677388232125;5253.50091741284;5288.68267903927;5619.634723742944;5678.488935995781;5687.488126068673;4968.527989840959;5251.474852514718;5566.888662226814;5568.972155139199;5571.275734154534;5241.423443421297;5648.181013753065;5655.830325090254;5682.261306030189;5724.570657200643;4657.743824089707;4844.8304309349005;5500.257465242141;5500.532454741232;5557.027255324463 14:49:25 INFO - PROCESS | 1658 | |5;yui.html;679.7518905599457;688.1260798785546;717.1824120502628;717.5443593318241;728.6065524616707;12044.759104817898;15723.449679261214;15743.291551880164;15745.212739363014;16097.597560061004;13995.020348575605;14120.293985300745;14130.434782608683;14143.292835358217;14144.363503642335;657.3689258312027;679.2290749998738;681.4207923265235;806.1575653442167;806.4072906413796;754.4605606991;804.4449329962337;829.6225217526011;835.5195762436623;847.0555330806168;863.5984267315697;883.8541640629288;885.0618344554761;885.291766586728;887.3389324952886;691.3466773898667;712.7719129878441;875.7766769473775;880.5861245214771;881.2333270055029;628.7422156915688;647.0876064748729;652.6540933305356;654.5614438326332;656.002875629046;14362.569122926328;14685.045472044283;14871.553853384416;14932.70134597302;14960.62598435037;13634;13695.383707733175;13735.45058197671;13768.105073170213;14199.36102875373;12200;12304.323262220574;12427.75144497105;12882.80675789864;12973;4421.115776844644;4429.712068715556;4465.419495465601;4645.117427688729;4657.767111644404;111758.44120779425;112540.43729781298;113654.99999999917;113887;117295;10719.356838589709;10752.247342685936;10764.623238186627;10812.459377031117;11119.66641000755;21236;21356.35930922075;21387.8930605346;21397.14411423557;21411.67882481764;7588.317051465395;7805.921940780519;7960.363170946311;7962.084360298524;7984.600769961478;4056.3915412687743;4224.8098835551855;4266.5946735060115;4299.054208074219;4438.068005718836;3406.9659303407143;3451.188970591909;3512.84192211346;3516.630753770817;3517.2765344980776;525.3538148077886;602.6715440085164;605.678990135229;620.8478922663976;622.4895585619839;545.8771776350369;560.2996254681648;622.8474023864183;630.2970050950665;647.1554621219354;8733.781655458664;9260.398074125294;9424.764380890532;9552.37909535878;9589.848628665717;586.8503531599415;657.3623585122424;660.2143449295323;661.3783043938778;665.3778716899715;4323.783810809447;4453.75504347264;4635.536446355338;4705.670603057822;4758.547937945889;122081;145802;148119.11285322916;157816;164568;127152;131732;134083;134383;142166;100546;118188.8181118195;120392;120433;124032;8808.95595522044;8938.553072346487;8951.686690965786;8996.50519221449;9154.943530810435;52260.21609675934;56791.71604141953;61915.6904215476;64625;65488.67255663691;60164.39835601763;67963.96108116764;92143;93096.5345173297;100690.98618027451;11543.94228028888;11557.24877882935;11706.824397634213;11770.234934729215;11878.762424751629;11657.650270491898;12111.81832272499;12282.87717122853;12950.222986620833;13318.467261309439;10786.298890572089;11646.718860925122;11835.644930652094;11905.702357441134;11947.103967202669;11891.227070240753;11933.940330298294;12068.637940861789;12295.200811947196;12331.383430828602;11829.467673954865;11898.810118988033;13988.58034258974;14238.074525156246;14259.572212833631;10945.890541094486;10999.670009899715;11304.43477826089;11520.94239528797;11642.70893227676;10710.314150681086;10889.346639201673;11139.944300278446;11473.311601303949;11505.539778408769 14:49:25 INFO - PROCESS | 1658 | __end_tp_report 14:49:25 INFO - PROCESS | 1658 | __start_cc_report 14:49:25 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1424 14:49:25 INFO - PROCESS | 1658 | __end_cc_report 14:49:25 INFO - PROCESS | 1658 | __startTimestamp1476308965810__endTimestamp 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:49:25 INFO - PROCESS | 1658 | Number of tests: 6 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5733.25 Median:5996.19 stddev:655.83 (10.9%) stddev-sans-first:619.21 14:49:25 INFO - PROCESS | 1658 | Values: 2906.3 3190.3 3357.7 3396.7 3408.6 5608.2 5634.4 5679.3 5699.8 5725.8 4796.9 5629.4 5796.1 5841.0 5841.1 5038.6 5052.2 5091.4 5100.3 5246.2 4863.9 4870.7 5197.7 5290.5 5364.9 5238.1 5244.8 5254.1 5269.4 5324.3 5110.9 5192.5 5196.1 5197.7 5247.5 5165.9 5165.9 5184.1 5218.4 5263.3 5686.4 5738.6 5769.7 5770.0 5801.4 6101.8 6108.0 6132.5 6211.6 6233.0 5219.8 5254.9 6130.3 6269.1 6288.2 5798.6 5799.9 5811.4 5821.3 5825.8 6201.8 6202.2 6206.9 6240.9 6272.1 6093.0 6165.5 6173.0 6178.9 6184.4 6213.2 6246.5 6250.2 6270.0 6277.6 5955.8 6143.2 6148.7 6200.8 6235.1 6043.7 6119.8 6121.1 6148.5 6215.4 4970.3 4978.2 4981.8 4991.3 5020.5 4365.2 4440.2 4456.5 4462.3 4481.5 5708.9 5752.7 5778.9 5829.9 5874.0 6179.2 6222.6 6233.7 6271.7 6272.2 5823.4 5832.4 5835.8 5859.5 5877.4 5245.5 5432.3 5435.0 5450.7 5453.3 6216.8 6256.4 6259.6 6286.1 6291.3 6240.1 6242.1 6246.9 6259.2 6262.6 6311.7 6369.5 6370.6 6380.5 6391.9 6103.0 6130.1 6135.2 6141.3 6207.0 6231.0 6248.9 6255.2 6263.4 6271.1 6111.6 6153.6 6162.5 6165.9 6173.1 5879.2 6013.5 6094.6 6129.6 6184.1 5996.2 6019.8 6077.4 6088.8 6116.4 6005.6 6161.8 6162.0 6164.8 6184.1 5890.1 5962.3 5966.9 6013.2 6039.4 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:68043.30 Median:37576.31 stddev:145829.56 (388.1%) stddev-sans-first:146150.37 14:49:25 INFO - PROCESS | 1658 | Values: 459.1 500.1 517.3 519.7 524.1 30234.7 39872.6 41014.8 41250.4 41261.0 34466.0 41996.0 42407.0 42553.0 42558.1 8503.0 8733.7 9313.3 9516.9 9530.4 11292.4 11622.0 11624.9 11643.2 11833.5 11445.4 11525.9 11526.4 11544.5 11552.4 8614.0 8816.0 11290.7 11480.9 11559.2 9377.6 11372.7 11390.6 11406.8 11413.8 39365.8 39778.4 39791.8 39800.6 39920.6 3244.9 3453.4 3690.0 3697.9 4086.3 29349.7 30510.7 31347.1 31393.1 31531.7 3180.3 3300.5 3324.0 3359.4 3360.3 456859.0 459469.0 460171.0 462228.0 462728.0 59810.1 60272.0 60313.7 60330.0 60371.0 62959.4 63148.7 63210.0 63210.7 63323.0 16231.3 16507.0 16515.3 16518.1 16548.1 11362.1 11635.2 11657.9 12412.7 12635.7 18294.8 18705.6 18713.0 18728.3 18771.0 6158.6 7468.9 7615.1 7728.9 7767.9 707.3 728.6 927.8 1024.0 1057.5 24115.3 28776.7 29737.7 29768.3 30449.4 925.8 1040.6 1043.3 1044.4 1062.7 15461.0 15519.9 15551.9 15787.6 15816.8 629503.9 631032.0 632990.0 633542.0 636546.0 56755.0 57175.1 57256.0 57415.1 57453.8 1045.7 1104.7 1176.9 1224.1 1318.8 25972.6 28386.6 29045.6 30944.7 31352.6 37890.4 49656.8 49683.8 49730.0 49808.5 512254.0 512672.0 513306.0 516655.0 603622.0 34287.8 39129.4 39532.8 41619.0 41667.6 45456.9 45594.0 45786.5 46455.1 46797.0 37624.0 38103.6 38103.8 38625.0 39612.6 37028.6 37709.6 38518.4 38695.8 38979.8 36652.6 36672.4 36955.8 37271.4 37528.6 27188.6 29568.0 32371.5 33356.5 33699.2 24418.0 24880.8 24936.1 24948.9 25022.7 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:33514.53 Median:2259.17 stddev:95318.95 (4219.2%) stddev-sans-first:95553.81 14:49:25 INFO - PROCESS | 1658 | Values: 346.2 366.5 372.2 372.5 393.2 14363.4 14585.4 15422.8 19503.9 19965.7 19502.2 19977.7 20614.9 20679.9 20890.7 5809.2 6116.9 6119.8 6123.4 6125.7 4678.4 6702.6 6776.5 6795.2 6871.9 5666.7 5975.9 6772.5 6779.2 6785.0 6016.2 6062.5 6062.6 6069.2 6080.4 5660.1 5731.4 5848.2 5905.3 5921.4 13439.4 13651.0 13684.1 22810.3 23872.9 2209.5 2238.0 2280.4 2500.8 2735.3 9657.0 9971.8 10238.2 10512.0 10552.7 1824.9 1872.2 1911.8 1936.2 1975.5 115759.0 121449.0 127178.0 127318.0 129201.0 10875.2 11065.6 11360.5 11392.9 11489.7 13975.0 14275.1 14488.6 14492.0 14494.4 6929.5 7115.7 8359.6 8552.3 8680.4 6923.7 8504.5 8505.5 8519.2 8713.3 4391.6 4469.4 4492.0 4563.1 4584.4 1488.9 1758.9 1851.7 1870.5 1916.5 805.0 850.7 910.5 916.6 1030.7 19468.6 21463.6 21522.1 21548.9 21598.5 812.0 898.9 909.7 929.6 931.4 10524.8 11006.3 11116.3 11237.7 11314.0 559925.0 560629.0 564124.0 580709.0 589903.0 109489.0 110683.0 111039.0 113548.3 116153.4 964.1 977.1 1007.6 1034.1 1034.4 18281.8 18479.6 18552.4 18592.5 18598.2 50531.5 52393.5 53434.0 53601.2 54547.0 68988.4 71247.0 71725.0 72123.0 72739.3 6552.0 7959.8 8080.7 8111.3 8282.8 9841.8 12059.6 12831.9 13006.6 13060.8 10511.3 11220.4 11285.9 11480.5 11515.9 10877.5 11036.4 11211.5 11234.2 11362.5 11104.4 11282.8 11504.9 11685.2 11697.9 7655.5 7998.9 8146.4 8173.5 8410.2 10826.3 11363.4 11516.8 11591.9 11591.9 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5550.86 Median:4787.26 stddev:3156.21 (65.9%) stddev-sans-first:3148.58 14:49:25 INFO - PROCESS | 1658 | Values: 1259.2 1441.4 1443.9 1480.9 1858.5 7679.8 7682.0 8132.9 8168.2 8596.6 7415.4 7767.7 8284.3 8517.6 8699.1 2239.7 2265.1 2267.3 2268.8 2271.1 2220.3 2255.5 2263.2 2265.8 2278.8 2253.4 2258.5 2260.8 2267.3 2283.0 2218.9 2232.3 2236.4 2237.3 2239.1 2244.8 2247.4 2252.8 2253.5 2256.3 8646.4 8660.8 8719.3 8731.7 8739.8 5080.6 5309.2 5332.3 5352.7 5385.5 3354.9 3505.8 3583.7 3585.6 3585.9 1034.8 1067.6 1070.3 1089.7 1092.5 11494.6 11596.0 11627.1 11635.5 11673.0 4292.0 4338.2 4521.6 4531.0 4535.4 9066.8 9099.7 9105.3 9122.4 9140.5 4416.5 5166.8 5171.7 5172.5 5174.7 4106.1 4312.2 4471.1 4484.4 4854.4 3526.3 3814.5 3814.8 3814.9 3817.6 1105.5 1130.7 1134.8 1144.4 1146.6 641.1 789.5 796.7 895.1 905.0 5700.6 5803.1 5825.8 5856.9 5868.1 363.7 438.2 441.3 970.3 995.8 3563.5 3754.5 3889.3 3960.2 4014.4 10341.4 10529.5 10630.9 10638.9 10647.0 9868.7 10247.5 10593.8 10603.8 10606.1 7992.5 8196.6 8205.9 8210.5 8248.4 4915.5 5813.6 5846.2 6070.8 6086.4 9813.0 9946.1 10016.8 10038.5 10052.2 9067.1 9500.0 10115.2 10119.7 10285.9 6973.4 7226.7 7340.3 7441.2 7560.7 8176.7 8201.1 8202.6 8299.1 8324.8 7380.0 7461.6 7469.7 7527.0 7530.5 7161.6 7233.7 7267.9 7361.4 7386.7 6862.5 6904.6 7011.4 7028.4 7032.5 4807.6 4881.7 4893.6 4925.0 5047.7 4641.1 4764.3 4766.9 4959.0 5127.4 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4214.05 Median:4510.43 stddev:1310.10 (29.0%) stddev-sans-first:1280.37 14:49:25 INFO - PROCESS | 1658 | Values: 297.7 298.5 307.0 313.3 313.8 4362.9 5012.4 5202.6 5265.0 5297.7 5045.6 5153.0 5163.5 5200.7 5213.2 3340.6 4030.5 4051.7 4054.8 4082.6 3555.4 3557.6 3744.1 4064.3 4090.1 3675.7 3713.9 3989.0 4016.0 4033.7 3986.4 4016.3 4026.8 4028.8 4032.2 3208.5 3256.9 3275.6 3460.8 3481.7 4029.5 4928.2 4929.5 5167.5 5224.3 4534.3 4565.9 4593.5 4617.6 4625.7 4450.4 5356.2 5372.4 5548.0 5563.9 3491.8 3504.2 3778.9 3779.8 3798.7 5784.3 5833.9 5853.8 5895.9 5900.3 3684.7 3812.4 3823.7 3831.2 3836.6 3866.4 3881.7 3886.0 3900.1 3929.0 4503.6 4509.0 4518.7 4530.9 4537.8 5037.2 5521.9 5537.6 5557.2 5558.5 1162.7 1181.5 1314.1 1325.5 1337.7 722.5 752.8 796.3 806.4 814.7 3951.3 4209.4 4269.6 4311.7 4329.5 3929.8 4511.9 4690.9 4722.6 4765.3 3728.0 3949.9 4293.4 4297.8 4341.2 2112.3 2150.7 2278.6 2333.4 2556.5 4109.4 4213.9 4257.8 4641.4 4851.4 4039.1 4158.4 4203.7 4662.0 4876.5 4446.5 4496.0 4871.8 4879.4 4883.9 4253.5 4540.6 4701.3 4710.9 4730.9 4096.9 4218.9 4473.8 4639.6 4685.4 3864.8 4408.6 4500.5 4610.6 4619.7 3058.8 3286.0 3339.3 3543.0 3713.0 5278.3 5507.9 5711.4 5735.8 5742.6 5526.0 5550.1 5600.2 5610.6 5650.1 5253.5 5288.7 5619.6 5678.5 5687.5 4968.5 5251.5 5566.9 5569.0 5571.3 5241.4 5648.2 5655.8 5682.3 5724.6 4657.7 4844.8 5500.3 5500.5 5557.0 14:49:25 INFO - PROCESS | 1658 | 14:49:25 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24739.15 Median:12409.32 stddev:40328.83 (325.0%) stddev-sans-first:40401.50 14:49:25 INFO - PROCESS | 1658 | Values: 679.8 688.1 717.2 717.5 728.6 12044.8 15723.4 15743.3 15745.2 16097.6 13995.0 14120.3 14130.4 14143.3 14144.4 657.4 679.2 681.4 806.2 806.4 754.5 804.4 829.6 835.5 847.1 863.6 883.9 885.1 885.3 887.3 691.3 712.8 875.8 880.6 881.2 628.7 647.1 652.7 654.6 656.0 14362.6 14685.0 14871.6 14932.7 14960.6 13634.0 13695.4 13735.5 13768.1 14199.4 12200.0 12304.3 12427.8 12882.8 12973.0 4421.1 4429.7 4465.4 4645.1 4657.8 111758.4 112540.4 113655.0 113887.0 117295.0 10719.4 10752.2 10764.6 10812.5 11119.7 21236.0 21356.4 21387.9 21397.1 21411.7 7588.3 7805.9 7960.4 7962.1 7984.6 4056.4 4224.8 4266.6 4299.1 4438.1 3407.0 3451.2 3512.8 3516.6 3517.3 525.4 602.7 605.7 620.8 622.5 545.9 560.3 622.8 630.3 647.2 8733.8 9260.4 9424.8 9552.4 9589.8 586.9 657.4 660.2 661.4 665.4 4323.8 4453.8 4635.5 4705.7 4758.5 122081.0 145802.0 148119.1 157816.0 164568.0 127152.0 131732.0 134083.0 134383.0 142166.0 100546.0 118188.8 120392.0 120433.0 124032.0 8809.0 8938.6 8951.7 8996.5 9154.9 52260.2 56791.7 61915.7 64625.0 65488.7 60164.4 67964.0 92143.0 93096.5 100691.0 11543.9 11557.2 11706.8 11770.2 11878.8 11657.7 12111.8 12282.9 12950.2 13318.5 10786.3 11646.7 11835.6 11905.7 11947.1 11891.2 11933.9 12068.6 12295.2 12331.4 11829.5 11898.8 13988.6 14238.1 14259.6 10945.9 10999.7 11304.4 11520.9 11642.7 10710.3 10889.3 11139.9 11473.3 11505.5 14:49:25 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:49:25 INFO - PROCESS | 1658 | 14:49:26 INFO - TEST-INFO | 1658: exit 0 14:49:26 INFO - TEST-OK | dromaeo_css | took 1119583ms 14:49:26 INFO - TEST-START | kraken 14:49:26 INFO - operating with platform_type : mac_ 14:49:26 INFO - Initialising browser for kraken test... 14:49:26 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpRgOBlr/profile http://localhost:49273/getInfo.html) 14:49:28 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 14:49:28 INFO - PROCESS | 1664 | colorDepth:24 14:49:28 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 14:49:28 INFO - PROCESS | 1664 | __metrics 14:49:28 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:49:28 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:29 INFO - TEST-INFO | 1664: exit 0 14:49:29 INFO - Browser initialized. 14:49:29 INFO - Running cycle 1/1 for kraken test... 14:49:29 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpRgOBlr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:41 INFO - PROCESS | 1665 | RSS: Main: 234844160 14:49:41 INFO - PROCESS | 1665 | 14:49:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 14:49:45 INFO - PROCESS | 1665 | RSS: Main: 292884480 14:49:45 INFO - PROCESS | 1665 | 14:49:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 14:49:48 INFO - PROCESS | 1665 | RSS: Main: 292745216 14:49:48 INFO - PROCESS | 1665 | 14:49:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 14:49:52 INFO - PROCESS | 1665 | RSS: Main: 383991808 14:49:52 INFO - PROCESS | 1665 | 14:49:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 14:49:55 INFO - PROCESS | 1665 | RSS: Main: 284086272 14:49:55 INFO - PROCESS | 1665 | 14:49:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:49:58 INFO - PROCESS | 1665 | RSS: Main: 288464896 14:49:58 INFO - PROCESS | 1665 | 14:50:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 14:50:01 INFO - PROCESS | 1665 | RSS: Main: 327155712 14:50:01 INFO - PROCESS | 1665 | 14:50:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 14:50:04 INFO - PROCESS | 1665 | RSS: Main: 326295552 14:50:04 INFO - PROCESS | 1665 | 14:50:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 14:50:08 INFO - PROCESS | 1665 | RSS: Main: 324583424 14:50:08 INFO - PROCESS | 1665 | 14:50:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:50:10 INFO - PROCESS | 1665 | RSS: Main: 300052480 14:50:10 INFO - PROCESS | 1665 | 14:50:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:50:12 INFO - PROCESS | 1665 | RSS: Main: 305143808 14:50:12 INFO - PROCESS | 1665 | 14:50:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:50:15 INFO - PROCESS | 1665 | RSS: Main: 315346944 14:50:15 INFO - PROCESS | 1665 | 14:50:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:19 INFO - PROCESS | 1665 | RSS: Main: 321134592 14:50:19 INFO - PROCESS | 1665 | 14:50:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:23 INFO - PROCESS | 1665 | RSS: Main: 320585728 14:50:23 INFO - PROCESS | 1665 | 14:50:24 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 14:50:25 INFO - PROCESS | 1665 | RSS: Main: 364797952 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | __start_tp_report 14:50:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 14:50:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 14:50:25 INFO - PROCESS | 1665 | |i|pagename|runs| 14:50:25 INFO - PROCESS | 1665 | |0;ai-astar;113;114;114;113;115;113;114;114;113;114 14:50:25 INFO - PROCESS | 1665 | |1;audio-beat-detection;141;107;121;182;141;114;119;101;168;141 14:50:25 INFO - PROCESS | 1665 | |2;audio-dft;183;182;182;186;183;182;183;180;184;180 14:50:25 INFO - PROCESS | 1665 | |3;audio-fft;74;58;58;86;62;65;68;82;109;67 14:50:25 INFO - PROCESS | 1665 | |4;audio-oscillator;84;71;90;69;67;68;93;67;68;91 14:50:25 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;86;90;86;89;86;91;86;88;85 14:50:25 INFO - PROCESS | 1665 | |6;imaging-darkroom;96;95;96;96;95;106;96;96;96;95 14:50:25 INFO - PROCESS | 1665 | |7;imaging-desaturate;85;84;86;87;86;83;83;84;84;85 14:50:25 INFO - PROCESS | 1665 | |8;json-parse-financial;58;54;55;55;56;56;54;53;54;55 14:50:25 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;71;64;71;75;71;69;69;70;70;72 14:50:25 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;90;86;86;90;87;88;89;86;92;92 14:50:25 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;147;143;163;153;152;143;143;145;234;156 14:50:25 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;172;175;174;172;171;172;173;170;173;172 14:50:25 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;69;69;68;70;70;67;68;69;70 14:50:25 INFO - PROCESS | 1665 | __end_tp_report 14:50:25 INFO - PROCESS | 1665 | __start_cc_report 14:50:25 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2046 14:50:25 INFO - PROCESS | 1665 | __end_cc_report 14:50:25 INFO - PROCESS | 1665 | __startTimestamp1476309025081__endTimestamp 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | ------- Summary: start ------- 14:50:25 INFO - PROCESS | 1665 | Number of tests: 14 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 14:50:25 INFO - PROCESS | 1665 | Values: 113.0 114.0 114.0 113.0 115.0 113.0 114.0 114.0 113.0 114.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:141.00 stddev:26.28 (18.6%) stddev-sans-first:27.74 14:50:25 INFO - PROCESS | 1665 | Values: 141.0 107.0 121.0 182.0 141.0 114.0 119.0 101.0 168.0 141.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.78 (1.0%) stddev-sans-first:1.88 14:50:25 INFO - PROCESS | 1665 | Values: 183.0 182.0 182.0 186.0 183.0 182.0 183.0 180.0 184.0 180.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:72.90 Median:67.50 stddev:15.79 (23.4%) stddev-sans-first:16.74 14:50:25 INFO - PROCESS | 1665 | Values: 74.0 58.0 58.0 86.0 62.0 65.0 68.0 82.0 109.0 67.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.50 stddev:11.21 (14.5%) stddev-sans-first:11.59 14:50:25 INFO - PROCESS | 1665 | Values: 84.0 71.0 90.0 69.0 67.0 68.0 93.0 67.0 68.0 91.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:2.07 (2.3%) stddev-sans-first:2.13 14:50:25 INFO - PROCESS | 1665 | Values: 89.0 86.0 90.0 86.0 89.0 86.0 91.0 86.0 88.0 85.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:3.30 (3.4%) stddev-sans-first:3.49 14:50:25 INFO - PROCESS | 1665 | Values: 96.0 95.0 96.0 96.0 95.0 106.0 96.0 96.0 96.0 95.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.41 14:50:25 INFO - PROCESS | 1665 | Values: 85.0 84.0 86.0 87.0 86.0 83.0 83.0 84.0 84.0 85.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.41 (2.6%) stddev-sans-first:1.00 14:50:25 INFO - PROCESS | 1665 | Values: 58.0 54.0 55.0 55.0 56.0 56.0 54.0 53.0 54.0 55.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:2.78 (3.9%) stddev-sans-first:2.93 14:50:25 INFO - PROCESS | 1665 | Values: 71.0 64.0 71.0 75.0 71.0 69.0 69.0 70.0 70.0 72.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:89.50 stddev:2.37 (2.6%) stddev-sans-first:2.46 14:50:25 INFO - PROCESS | 1665 | Values: 90.0 86.0 86.0 90.0 87.0 88.0 89.0 86.0 92.0 92.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:152.50 stddev:27.55 (18.1%) stddev-sans-first:28.94 14:50:25 INFO - PROCESS | 1665 | Values: 147.0 143.0 163.0 153.0 152.0 143.0 143.0 145.0 234.0 156.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:172.50 stddev:1.43 (0.8%) stddev-sans-first:1.51 14:50:25 INFO - PROCESS | 1665 | Values: 172.0 175.0 174.0 172.0 171.0 172.0 173.0 170.0 173.0 172.0 14:50:25 INFO - PROCESS | 1665 | 14:50:25 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:0.99 (1.4%) stddev-sans-first:1.05 14:50:25 INFO - PROCESS | 1665 | Values: 69.0 69.0 69.0 68.0 70.0 70.0 67.0 68.0 69.0 70.0 14:50:25 INFO - PROCESS | 1665 | -------- Summary: end -------- 14:50:25 INFO - PROCESS | 1665 | 14:50:26 INFO - TEST-INFO | 1665: exit 0 14:50:26 INFO - TEST-OK | kraken | took 59466ms 14:50:26 INFO - SUITE-END | took 1179s 14:50:26 INFO - Completed test suite (00:19:39) 14:50:26 INFO - javascript benchmark 14:50:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2906.287959449935, 3190.34597907429, 3357.6978071973517, 3396.6942975132233, 3408.642092580279, 5608.186812912125, 5634.40838711935, 5679.290088738908, 5699.829005129843, 5725.828225153242, 4796.880077998052, 5629.437056294369, 5796.07262837946, 5840.97782887995, 5841.065429531276, 5038.647294689373, 5052.191649336107, 5091.389033316007, 5100.311457953185, 5246.160614301693, 4863.927041094369, 4870.707757534542, 5197.714125723083, 5290.523852853243, 5364.892702145955, 5238.13570760824, 5244.790208391679, 5254.106801843657, 5269.394019687747, 5324.281222035033, 5110.872228194325, 5192.506711862367, 5196.090684130262, 5197.7401129943355, 5247.527722504993, 5165.896682066375, 5165.9225111623355, 5184.118699821001, 5218.373795144607, 5263.289455923439, 5686.3744988051285, 5738.598298119134, 5769.740361683735, 5770.04794208955, 5801.448862358069, 6101.786437474711, 6107.977837958716, 6132.539076726587, 6211.596246243979, 6233.0, 5219.791208351661, 5254.894902101974, 6130.264368275835, 6269.122322874801, 6288.151099601541, 5798.623089499211, 5799.94200057994, 5811.418858114197, 5821.330546987066, 5825.766969321265, 6201.751929922752, 6202.193714817045, 6206.937930620727, 6240.906386404207, 6272.1219029335925, 6093.006878561393, 6165.537584681167, 6172.969135154295, 6178.938210617926, 6184.412480814315, 6213.1922850029205, 6246.500279977591, 6250.218722659668, 6270.028145637433, 6277.591956522811, 5955.82132536016, 6143.201383820075, 6148.6925653717135, 6200.782972596027, 6235.127082208494, 6043.7280322385595, 6119.816405507842, 6121.051237058263, 6148.52435415506, 6215.378462153839, 4970.304157417964, 4978.178600530872, 4981.800727970913, 4991.276264941635, 5020.548150666457, 4365.192439398721, 4440.200763862471, 4456.532064133284, 4462.286034234507, 4481.507034226298, 5708.885822283531, 5752.683602401824, 5778.913316300259, 5829.912551311733, 5874.0014197586515, 6179.227596550431, 6222.62664240147, 6233.719482623293, 6271.717772700239, 6272.18461599989, 5823.388544202798, 5832.416758324133, 5835.795747148914, 5859.5019423349495, 5877.353491115974, 5245.501677340647, 5432.348118225759, 5435.021696094662, 5450.727463626803, 5453.263809385682, 6216.7824126155365, 6256.405641464054, 6259.624422534572, 6286.088517164894, 6291.307956124914, 6240.095186198067, 6242.094896240111, 6246.875062498815, 6259.186305780219, 6262.624242545462, 6311.715972781328, 6369.45859601939, 6370.55406121567, 6380.521460890544, 6391.8721625566295, 6103.0, 6130.061839217998, 6135.202044401974, 6141.332587233645, 6207.037909124093, 6231.034189700604, 6248.937510624835, 6255.155554000152, 6263.373662633882, 6271.090691849748, 6111.633302001894, 6153.630782153085, 6162.476189523762, 6165.938340616536, 6173.104899789416, 5879.20630714848, 6013.458788709037, 6094.634321940698, 6129.570930034854, 6184.072389141665, 5996.190514280516, 6019.759209631566, 6077.402948305363, 6088.847778805565, 6116.449519543263, 6005.6396616203165, 6161.753529858935, 6162.044883043135, 6164.753409863555, 6184.134221208944, 5890.145928840208, 5962.25471816023, 5966.940330596812, 6013.188219590299, 6039.365866583946], "value": 5689.765093993193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.0566386076611, 500.0973243295852, 517.2577351500595, 519.6570263626006, 524.1377933299722, 30234.69765302349, 39872.60127398729, 41014.79492602533, 41250.38124428136, 41261.0, 34466.0, 41996.0, 42407.0, 42553.0, 42558.14883702324, 8503.022152452455, 8733.694320698776, 9313.254939604816, 9516.904830951706, 9530.428174309529, 11292.378919159442, 11622.012128969047, 11624.883751162506, 11643.243189192717, 11833.526658933632, 11445.427728613578, 11525.884741152566, 11526.366049867254, 11544.53821847125, 11552.3646199459, 8614.0, 8816.0, 11290.661280161527, 11480.885191148065, 11559.190856640038, 9377.578008989612, 11372.715682107932, 11390.60132895353, 11406.828897566544, 11413.771724565462, 39365.80317098396, 39778.403323949875, 39791.8010409949, 39800.60199397998, 39920.60079399227, 3244.853981570835, 3453.4129198036394, 3690.022144131807, 3697.944530832013, 4086.2849001424634, 29349.706502935125, 30510.694893051008, 31347.05958821239, 31393.058208253562, 31531.684683153104, 3180.316232010106, 3300.5214243934497, 3323.9501407478906, 3359.4288970874763, 3360.327934413103, 456859.0, 459469.0, 460171.0, 462228.0, 462728.0, 59810.10284845731, 60272.0, 60313.69843150756, 60330.0, 60371.0, 62959.370406296264, 63148.68425657934, 63210.0, 63210.67893210642, 63323.0, 16231.269592868113, 16507.009579425034, 16515.33938642441, 16518.091504967102, 16548.089855057846, 11362.091032717362, 11635.185537012328, 11657.883421165849, 12412.689682757822, 12635.747285054247, 18294.817051829577, 18705.625887482445, 18713.0, 18728.344507942158, 18771.0, 6158.551211321585, 7468.925310746823, 7615.068198743524, 7728.884066739003, 7767.883481747778, 707.3033062433499, 728.621117019147, 927.758782716485, 1024.0476356987924, 1057.4659796802719, 24115.276541703777, 28776.71223287782, 29737.702622973495, 29768.2557936049, 30449.391012179633, 925.842606756853, 1040.604570263295, 1043.2697112021615, 1044.4046893270915, 1062.7088087972995, 15461.0, 15519.922400387926, 15551.922240388725, 15787.605309867116, 15816.841831581767, 629503.8524807347, 631032.0, 632990.0, 633542.0, 636546.0, 56755.0, 57175.14237286444, 57256.0, 57415.13877291844, 57453.84003879827, 1045.6862941117786, 1104.7127746785734, 1176.8705442401329, 1224.0635913526337, 1318.8351456067992, 25972.610410843474, 28386.574201387408, 29045.564316535692, 30944.690553095083, 31352.646156148865, 37890.43164352592, 49656.75171624263, 49683.751581241864, 49730.0, 49808.501914980385, 512254.0, 512672.0, 513306.0, 516655.0, 603622.0, 34287.82856085704, 39129.41305880357, 39532.80233598813, 41618.95952601209, 41667.58332416637, 45456.86357840948, 45594.0, 45786.54213457956, 46455.07089858252, 46797.0, 37624.0, 38103.618963810004, 38103.80948095242, 38625.0, 39612.60387396204, 37028.629713703594, 37709.622903770614, 38518.42222366723, 38695.80652096721, 38979.805100974314, 36652.63347366492, 36672.44991325079, 36955.81522092372, 37271.44092838556, 37528.624713753605, 27188.592171117052, 29568.0, 32371.51442728408, 33356.499652505714, 33699.15752106217, 24418.0, 24880.824693123268, 24936.127235546668, 24948.875255623607, 25022.74977250204], "value": 20042.844377740534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.21063974138974, 366.45581311752045, 372.20348454307776, 372.5454944967139, 393.205724436638, 14363.35364908579, 14585.416583336668, 15422.768658470131, 19503.902480487577, 19965.700514492295, 19502.21991120357, 19977.700334494995, 20614.89692551539, 20679.896600517015, 20890.68663970038, 5809.186713860063, 6116.908246376308, 6119.816405507842, 6123.357047510014, 6125.693715314216, 4678.368420263255, 6702.564333318343, 6776.491763117786, 6795.2185498667795, 6871.896921546181, 5666.660000399968, 5975.940240597582, 6772.4582033437455, 6779.186497620268, 6785.0, 6016.187814645011, 6062.54498920256, 6062.575619706622, 6069.15031895535, 6080.422359875849, 5660.122680984454, 5731.426857314277, 5848.210491583624, 5905.261842269716, 5921.378255283176, 13439.395227214798, 13651.0, 13684.110532815335, 22810.31569052931, 23872.88063559671, 2209.5470428562107, 2237.9664305035276, 2280.372897453197, 2500.7874330681734, 2735.3161709572605, 9656.95171524138, 9971.75070623233, 10238.180945524415, 10512.0, 10552.736181595445, 1824.9452516424528, 1872.1855992643243, 1911.808819118077, 1936.2351871011012, 1975.5061234691327, 115759.0, 121448.99999999911, 127178.0, 127318.0, 129201.0, 10875.238733288594, 11065.612703555336, 11360.48877800501, 11392.886071139348, 11489.712757181138, 13975.0, 14275.14349139055, 14488.565343039725, 14492.0, 14494.420223190955, 6929.549579277331, 7115.71537138519, 8359.623816928253, 8552.315814734806, 8680.392372533988, 6923.6884340204815, 8504.53225072627, 8505.489670619783, 8519.23326900582, 8713.346499012472, 4391.604755572014, 4469.374287599739, 4492.034212644297, 4563.110193512233, 4584.404027476474, 1488.8833374968774, 1758.947231583029, 1851.6759567075708, 1870.5417172792481, 1916.537249634241, 805.0, 850.6767428377177, 910.5083255042204, 916.6333466613409, 1030.6753372687729, 19468.610627787366, 21463.570728585342, 21522.139114435246, 21548.892255538936, 21598.460038498848, 811.959402029908, 898.9324553526862, 909.667971190524, 929.5863340813273, 931.3992474853682, 10524.789649190441, 11006.301811025212, 11116.277441966246, 11237.662870113909, 11314.0, 559925.0, 560629.0, 564124.0, 580709.0, 589903.0, 109489.0, 110683.0, 111039.0, 113548.29677554843, 116153.4192329033, 964.1467301438278, 977.1205914676848, 1007.6120693533043, 1034.0745033195249, 1034.374203606807, 18281.81718182801, 18479.630407392047, 18552.350667726565, 18592.535186620444, 18598.16308266104, 50531.494685052676, 52393.47606523886, 53434.0, 53601.19598205952, 54547.0, 68988.37645294338, 71247.0, 71725.0, 72123.0, 72739.27260727537, 6552.0, 7959.801004974922, 8080.676772929017, 8111.310538604284, 8282.834343313221, 9841.753956151153, 12059.57791477294, 12831.871681283068, 13006.609801705965, 13060.759227873717, 10511.316764410289, 11220.438978050901, 11285.943570282096, 11480.507534020524, 11515.884841151816, 10877.456127193767, 11036.448177591248, 11211.537216586494, 11234.213605047567, 11362.488688008894, 11104.389258590854, 11282.774344513227, 11504.884951150381, 11685.182037257651, 11697.941510292394, 7655.46411751172, 7998.880016799636, 8146.429749917449, 8173.509589424653, 8410.158984101541, 10826.296290741077, 11363.431828408711, 11516.769664606494, 11591.88408115908, 11591.88408115908], "value": 9377.197169295625, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1259.1815320041978, 1441.4378392426956, 1443.8772704320131, 1480.9111453312803, 1858.5074955136884, 7679.769606911795, 7682.0397450318715, 8132.91867081329, 8168.183181681837, 8596.570171491432, 7415.369693576039, 7767.650455729492, 8284.33725301976, 8517.616707248173, 8699.130086991314, 2239.6864438978555, 2265.1279257485903, 2267.342470683508, 2268.8088753404513, 2271.114265436481, 2220.322801545528, 2255.4925141843037, 2263.151318255654, 2265.796078352948, 2278.829087818412, 2253.43664083979, 2258.525709600986, 2260.8304377171694, 2267.2744721689064, 2282.9657555136687, 2218.8557743746605, 2232.3191426614876, 2236.4185311818883, 2237.3064350051536, 2239.070785623956, 2244.8204143668468, 2247.370736193868, 2252.831037672181, 2253.481699209191, 2256.2852232303403, 8646.437981531244, 8660.783480412978, 8719.302455803521, 8731.738047858575, 8739.825203495895, 5080.618953578496, 5309.230161626542, 5332.280142180794, 5352.678839269656, 5385.542228910547, 3354.9496757548654, 3505.7721248119046, 3583.7491375603718, 3585.623509531488, 3585.87449439271, 1034.8137335279646, 1067.6370034188412, 1070.2829104499926, 1089.6567581211893, 1092.5356723392606, 11494.597689080842, 11596.0, 11627.06983441331, 11635.534578616845, 11673.0, 4291.957080429218, 4338.197433474754, 4521.570450807168, 4531.0, 4535.365048893158, 9066.78129264115, 9099.727008189764, 9105.317101217435, 9122.407043542149, 9140.451572905515, 4416.514183439819, 5166.793328266903, 5171.689698618095, 5172.482751724798, 5174.741262936838, 4106.10991472966, 4312.245357062501, 4471.128130014616, 4484.439445069366, 4854.393200849894, 3526.2771131918407, 3814.504114465102, 3814.752041117374, 3814.923701525954, 3817.6373244541724, 1105.4914739220048, 1130.7116685245373, 1134.7787181499527, 1144.3591588710349, 1146.621614867092, 641.0800501280677, 789.4513313247238, 796.7291121018882, 895.0735988252128, 904.9773755656162, 5700.572457065737, 5803.129530570448, 5825.825225243165, 5856.912146317809, 5868.119782032729, 363.65452819821274, 438.1652951128106, 441.2586854085168, 970.257752819094, 995.8058178655093, 3563.465480177942, 3754.455603937414, 3889.3193691103943, 3960.20795840833, 4014.3777714454304, 10341.37951722899, 10529.526171322308, 10630.946845265724, 10638.946805265925, 10647.041766241075, 9868.654597089067, 10247.487625618689, 10593.788124237626, 10603.84094238587, 10606.098481629147, 7992.5204487730925, 8196.590170491452, 8205.917940820635, 8210.466319689202, 8248.422610417334, 4915.4592994771265, 5813.563982701399, 5846.210761547307, 6070.757169713162, 6086.360932102066, 9813.018698130416, 9946.104850563484, 10016.849747253651, 10038.548265327932, 10052.195824334181, 9067.138621830916, 9500.0, 10115.190784737057, 10119.696409107739, 10285.897141028794, 6973.4072603829245, 7226.674799633924, 7340.302671246223, 7441.218672039358, 7560.697572097274, 8176.67293308261, 8201.056878459054, 8202.58987050657, 8299.08710041907, 8324.791880203044, 7380.0, 7461.552306861606, 7469.701211951461, 7527.0, 7530.472866899484, 7161.57030578167, 7233.710651573878, 7267.890981635174, 7361.374283185775, 7386.667599957907, 6862.451003919774, 6904.585724856524, 7011.368976792113, 7028.402585780266, 7032.542884712478, 4807.61539076866, 4881.707097574157, 4893.584045356185, 4925.0, 5047.722375269395, 4641.071785642817, 4764.285357196448, 4766.904661906673, 4959.0, 5127.41034781005], "value": 4394.942630707874, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.69188889499367, 298.4895828133891, 307.0282555711174, 313.3435452726537, 313.7709472085376, 4362.869113926579, 5012.3985121785345, 5202.55778258848, 5265.0, 5297.655652382602, 5045.64680472367, 5153.046686363025, 5163.514627626347, 5200.659703133596, 5213.244079608464, 3340.599128104631, 4030.455888455065, 4051.7163798534116, 4054.8378064877443, 4082.5509193988787, 3555.4133567961258, 3557.5908770491337, 3744.117029886985, 4064.268431682296, 4090.141070375225, 3675.7426980111404, 3713.9442908356395, 3989.0, 4015.9799201003952, 4033.7378070425475, 3986.402039694069, 4016.2770701273757, 4026.8187931543152, 4028.7582745035247, 4032.1532478179615, 3208.51872219169, 3256.8926564967787, 3275.606927168731, 3460.771426143733, 3481.704055155315, 4029.53660329063, 4928.211486162197, 4929.507049295078, 5167.457416971239, 5224.320838291036, 4534.251848445036, 4565.908681826378, 4593.517680643551, 4617.607503362218, 4625.676202665814, 4450.421445212101, 5356.196570514415, 5372.435894231088, 5548.0, 5563.944360556383, 3491.7953306109353, 3504.197945229429, 3778.886633401002, 3779.7543159694533, 3798.651478725068, 5784.334801497839, 5833.912491312633, 5853.795117170878, 5895.941040589625, 5900.321463031721, 3684.7236457265813, 3812.370958791824, 3823.655870971626, 3831.214601006787, 3836.55879573847, 3866.4007078902814, 3881.7282790204413, 3885.9805700971315, 3900.063984643665, 3928.9607103929166, 4503.594676479067, 4508.977455112704, 4518.706284091523, 4530.909381812346, 4537.75042372666, 5037.244413337955, 5521.917171242435, 5537.594379456606, 5557.166425036277, 5558.499735023867, 1162.6918866500384, 1181.539199712113, 1314.1326724361875, 1325.4631874091008, 1337.7458282926214, 722.5375759513915, 752.8143174500184, 796.2714116583377, 806.4435539477741, 814.7067055859885, 3951.3480275754755, 4209.389638502461, 4269.563001783532, 4311.698181127354, 4329.532166665033, 3929.76421414716, 4511.879416674158, 4690.859274221779, 4722.574968252874, 4765.332853400527, 3727.956172271768, 3949.9190631307624, 4293.420388247547, 4297.78511074445, 4341.196878577473, 2112.310151878491, 2150.6666466697375, 2278.646809744492, 2333.358326460203, 2556.514262290159, 4109.445224894601, 4213.852515162016, 4257.808398622069, 4641.419822522184, 4851.380254662513, 4039.091853865212, 4158.355454904495, 4203.70574059819, 4662.0, 4876.463913867121, 4446.466424029137, 4495.954340775005, 4871.780769865364, 4879.4388645305535, 4883.853484395474, 4253.511695658075, 4540.61617650736, 4701.341812146234, 4710.858674239778, 4730.905381892274, 4096.897577560585, 4218.873433796991, 4473.792076139359, 4639.559241872017, 4685.390899183084, 3864.787436691009, 4408.603225709701, 4500.469840254329, 4610.585047345621, 4619.6507532039, 3058.847057647153, 3285.9791228393888, 3339.2653616204398, 3542.98050111434, 3713.0, 5278.340207474066, 5507.9449205507435, 5711.371749107512, 5735.827925162252, 5742.626729262584, 5525.9723701382845, 5550.056490396723, 5600.215969764155, 5610.551155907436, 5650.0677388232125, 5253.50091741284, 5288.68267903927, 5619.634723742944, 5678.488935995781, 5687.488126068673, 4968.527989840959, 5251.474852514718, 5566.888662226814, 5568.972155139199, 5571.275734154534, 5241.423443421297, 5648.181013753065, 5655.830325090254, 5682.261306030189, 5724.570657200643, 4657.743824089707, 4844.8304309349005, 5500.257465242141, 5500.532454741232, 5557.027255324463], "value": 3800.033178547006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.7518905599457, 688.1260798785546, 717.1824120502628, 717.5443593318241, 728.6065524616707, 12044.759104817898, 15723.449679261214, 15743.291551880164, 15745.212739363014, 16097.597560061004, 13995.020348575605, 14120.293985300745, 14130.434782608683, 14143.292835358217, 14144.363503642335, 657.3689258312027, 679.2290749998738, 681.4207923265235, 806.1575653442167, 806.4072906413796, 754.4605606991, 804.4449329962337, 829.6225217526011, 835.5195762436623, 847.0555330806168, 863.5984267315697, 883.8541640629288, 885.0618344554761, 885.291766586728, 887.3389324952886, 691.3466773898667, 712.7719129878441, 875.7766769473775, 880.5861245214771, 881.2333270055029, 628.7422156915688, 647.0876064748729, 652.6540933305356, 654.5614438326332, 656.002875629046, 14362.569122926328, 14685.045472044283, 14871.553853384416, 14932.70134597302, 14960.62598435037, 13634.0, 13695.383707733175, 13735.45058197671, 13768.105073170213, 14199.36102875373, 12200.0, 12304.323262220574, 12427.75144497105, 12882.80675789864, 12973.0, 4421.115776844644, 4429.712068715556, 4465.419495465601, 4645.117427688729, 4657.767111644404, 111758.44120779425, 112540.43729781298, 113654.99999999917, 113887.0, 117295.0, 10719.356838589709, 10752.247342685936, 10764.623238186627, 10812.459377031117, 11119.66641000755, 21236.0, 21356.35930922075, 21387.8930605346, 21397.14411423557, 21411.67882481764, 7588.317051465395, 7805.921940780519, 7960.363170946311, 7962.084360298524, 7984.600769961478, 4056.3915412687743, 4224.8098835551855, 4266.5946735060115, 4299.054208074219, 4438.068005718836, 3406.9659303407143, 3451.188970591909, 3512.84192211346, 3516.630753770817, 3517.2765344980776, 525.3538148077886, 602.6715440085164, 605.678990135229, 620.8478922663976, 622.4895585619839, 545.8771776350369, 560.2996254681648, 622.8474023864183, 630.2970050950665, 647.1554621219354, 8733.781655458664, 9260.398074125294, 9424.764380890532, 9552.37909535878, 9589.848628665717, 586.8503531599415, 657.3623585122424, 660.2143449295323, 661.3783043938778, 665.3778716899715, 4323.783810809447, 4453.75504347264, 4635.536446355338, 4705.670603057822, 4758.547937945889, 122081.0, 145802.0, 148119.11285322916, 157816.0, 164568.0, 127152.0, 131732.0, 134083.0, 134383.0, 142166.0, 100546.0, 118188.8181118195, 120392.0, 120433.0, 124032.0, 8808.95595522044, 8938.553072346487, 8951.686690965786, 8996.50519221449, 9154.943530810435, 52260.21609675934, 56791.71604141953, 61915.6904215476, 64625.0, 65488.67255663691, 60164.39835601763, 67963.96108116764, 92143.0, 93096.5345173297, 100690.98618027451, 11543.94228028888, 11557.24877882935, 11706.824397634213, 11770.234934729215, 11878.762424751629, 11657.650270491898, 12111.81832272499, 12282.87717122853, 12950.222986620833, 13318.467261309439, 10786.298890572089, 11646.718860925122, 11835.644930652094, 11905.702357441134, 11947.103967202669, 11891.227070240753, 11933.940330298294, 12068.637940861789, 12295.200811947196, 12331.383430828602, 11829.467673954865, 11898.810118988033, 13988.58034258974, 14238.074525156246, 14259.572212833631, 10945.890541094486, 10999.670009899715, 11304.43477826089, 11520.94239528797, 11642.70893227676, 10710.314150681086, 10889.346639201673, 11139.944300278446, 11473.311601303949, 11505.539778408769], "value": 7599.486482689743, "unit": "score"}], "name": "dromaeo_css", "value": 7168.865651711189, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 115.0, 113.0, 114.0, 114.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 121.0, 182.0, 141.0, 114.0, 119.0, 101.0, 168.0, 141.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 182.0, 186.0, 183.0, 182.0, 183.0, 180.0, 184.0, 180.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 58.0, 58.0, 86.0, 62.0, 65.0, 68.0, 82.0, 109.0, 67.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 90.0, 69.0, 67.0, 68.0, 93.0, 67.0, 68.0, 91.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 91.0, 86.0, 88.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 106.0, 96.0, 96.0, 96.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 87.0, 86.0, 83.0, 83.0, 84.0, 84.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 55.0, 55.0, 56.0, 56.0, 54.0, 53.0, 54.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 71.0, 75.0, 71.0, 69.0, 69.0, 70.0, 70.0, 72.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 86.0, 90.0, 87.0, 88.0, 89.0, 86.0, 92.0, 92.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 143.0, 163.0, 153.0, 152.0, 143.0, 143.0, 145.0, 234.0, 156.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 174.0, 172.0, 171.0, 172.0, 173.0, 170.0, 173.0, 172.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 68.0, 70.0, 70.0, 67.0, 68.0, 69.0, 70.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1461.4000000000003, "alertThreshold": 2.0}]} 14:50:26 INFO - Return code: 0 14:50:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:26 INFO - # TBPL SUCCESS # 14:50:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:50:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:50:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:50:26 INFO - 2016-10-12 14:50:26.381 screenresolution[1669:9766] starting screenresolution argv=screenresolution get 14:50:26 INFO - 2016-10-12 14:50:26.383 screenresolution[1669:9766] Display 0: 1600x1200x32@60 14:50:26 INFO - 2016-10-12 14:50:26.389 screenresolution[1670:9769] starting screenresolution argv=screenresolution list 14:50:26 INFO - Available Modes on Display 0 14:50:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:50:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:50:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:50:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:50:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:50:26 INFO - Intel Iris: 14:50:26 INFO - Chipset Model: Intel Iris 14:50:26 INFO - Type: GPU 14:50:26 INFO - Bus: Built-In 14:50:26 INFO - VRAM (Dynamic, Max): 1536 MB 14:50:26 INFO - Vendor: Intel (0x8086) 14:50:26 INFO - Device ID: 0x0a2e 14:50:26 INFO - Revision ID: 0x0009 14:50:26 INFO - Displays: 14:50:26 INFO - Display: 14:50:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:50:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:50:26 INFO - Display Serial Number: 1600x1200 60 14:50:26 INFO - Main Display: Yes 14:50:26 INFO - Mirror: Off 14:50:26 INFO - Online: Yes 14:50:26 INFO - Rotation: Supported 14:50:26 INFO - Return code: 0 14:50:26 INFO - Running post-action listener: _resource_record_post_action 14:50:26 INFO - [mozharness: 2016-10-12 21:50:26.635389Z] Finished run-tests step (success) 14:50:26 INFO - Running post-run listener: _resource_record_post_run 14:50:26 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38446080; Write bytes: 706521088; Read time: 397; Write time: 2371 14:50:26 INFO - TinderboxPrint: CPU usage
53.6% 14:50:26 INFO - TinderboxPrint: I/O read bytes / time
38,446,080 / 397 14:50:26 INFO - TinderboxPrint: I/O write bytes / time
706,521,088 / 2,371 14:50:26 INFO - TinderboxPrint: CPU idle
2,254.5 (46.4%) 14:50:26 INFO - TinderboxPrint: CPU system
973.5 (20.0%) 14:50:26 INFO - TinderboxPrint: CPU user
1,629.8 (33.5%) 14:50:26 INFO - TinderboxPrint: Swap in / out
445,415,424 / 0 14:50:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 384954880; Write bytes: 379799552; Read time: 29370; Write time: 757 14:50:26 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37491712; Write bytes: 296030208; Read time: 383; Write time: 1555 14:50:27 INFO - Running post-run listener: _upload_blobber_files 14:50:27 INFO - Blob upload gear active. 14:50:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:27 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 14:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:50:27 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:50:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:28 INFO - (blobuploader) - INFO - Done attempting. 14:50:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:50:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:29 INFO - (blobuploader) - INFO - Done attempting. 14:50:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:50:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:31 INFO - (blobuploader) - INFO - Done attempting. 14:50:31 INFO - (blobuploader) - INFO - Iteration through files over. 14:50:31 INFO - Return code: 0 14:50:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:50:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:50:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1b0878dbf76fefbbc09e1d071a7f9bcdedbb83aa35b56f5e7d28ce8a5532a36cc38bc06eaf287ef4675bdd6ae478a7d2c14c984e26902a2411a8afb0b95b1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f042f03f65f409e991f054976686711c23b1af6e99e6f644bd180e24a49af6f348b3cfaf33f7a4f1162cca535395df35817e9a56a7b280d52c8e7c5e307eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a98318a4853aa7decd3b54069b0ff9b2d423b0cc9ffd6944ed9d9f439136e54af250c6ffb4c1055074850570d6137986c421efc6e07883db356e75f5c4b8f4"} 14:50:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:50:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:50:31 INFO - Contents: 14:50:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1b0878dbf76fefbbc09e1d071a7f9bcdedbb83aa35b56f5e7d28ce8a5532a36cc38bc06eaf287ef4675bdd6ae478a7d2c14c984e26902a2411a8afb0b95b1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f042f03f65f409e991f054976686711c23b1af6e99e6f644bd180e24a49af6f348b3cfaf33f7a4f1162cca535395df35817e9a56a7b280d52c8e7c5e307eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a98318a4853aa7decd3b54069b0ff9b2d423b0cc9ffd6944ed9d9f439136e54af250c6ffb4c1055074850570d6137986c421efc6e07883db356e75f5c4b8f4"} 14:50:31 INFO - Running post-run listener: copy_logs_to_upload_dir 14:50:31 INFO - Copying logs to upload dir... 14:50:31 INFO - mkdir: /builds/slave/test/build/upload/logs 14:50:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.858694 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-12 14:50:31.434994) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:31.438428) ========= 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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1b0878dbf76fefbbc09e1d071a7f9bcdedbb83aa35b56f5e7d28ce8a5532a36cc38bc06eaf287ef4675bdd6ae478a7d2c14c984e26902a2411a8afb0b95b1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f042f03f65f409e991f054976686711c23b1af6e99e6f644bd180e24a49af6f348b3cfaf33f7a4f1162cca535395df35817e9a56a7b280d52c8e7c5e307eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a98318a4853aa7decd3b54069b0ff9b2d423b0cc9ffd6944ed9d9f439136e54af250c6ffb4c1055074850570d6137986c421efc6e07883db356e75f5c4b8f4"} build_url:https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/KXZ6cOL9T6CnQoi0yV594w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e1b0878dbf76fefbbc09e1d071a7f9bcdedbb83aa35b56f5e7d28ce8a5532a36cc38bc06eaf287ef4675bdd6ae478a7d2c14c984e26902a2411a8afb0b95b1a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4f042f03f65f409e991f054976686711c23b1af6e99e6f644bd180e24a49af6f348b3cfaf33f7a4f1162cca535395df35817e9a56a7b280d52c8e7c5e307eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a98318a4853aa7decd3b54069b0ff9b2d423b0cc9ffd6944ed9d9f439136e54af250c6ffb4c1055074850570d6137986c421efc6e07883db356e75f5c4b8f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:31.474337) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:31.474689) ========= 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.XPNNU6TTGz/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.JeN6rCx9UZ/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.005260 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 14:50:31.522620) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 14:50:31.522989) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 14:50:31.523316) ========= ========= Total master_lag: 0.17 =========