builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0117 starttime: 1475666449.43 results: success (0) buildid: 20161005022600 builduid: 3928946ae9084ae08307f52d1d3a2bcc revision: 4dc7f22c700fdbfd0328e64e20be6b65408bb098 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.434735) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.435304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.435622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.462131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.462558) ========= 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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-05 04:20:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 178M=0s 2016-10-05 04:20:49 (178 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122759 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.608878) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.609257) ========= 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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.033300 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.666442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:49.666859) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4dc7f22c700fdbfd0328e64e20be6b65408bb098 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4dc7f22c700fdbfd0328e64e20be6b65408bb098 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-05 04:20:49,736 truncating revision to first 12 chars 2016-10-05 04:20:49,736 Setting DEBUG logging. 2016-10-05 04:20:49,736 attempt 1/10 2016-10-05 04:20:49,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4dc7f22c700f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 04:20:50,018 unpacking tar archive at: autoland-4dc7f22c700f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540165 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:50.229025) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:50.229339) ========= script_repo_revision: 4dc7f22c700fdbfd0328e64e20be6b65408bb098 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:50.229731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:50.233079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 04:20:50.253595) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 04:20:50.254034) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:20:50 INFO - MultiFileLogger online at 20161005 04:20:50 in /builds/slave/test 04:20:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 04:20:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:20:50 INFO - {'append_to_log': False, 04:20:50 INFO - 'base_work_dir': '/builds/slave/test', 04:20:50 INFO - 'blob_upload_branch': 'Autoland', 04:20:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:20:50 INFO - 'branch': 'Autoland', 04:20:50 INFO - 'buildbot_json_path': 'buildprops.json', 04:20:50 INFO - 'config_files': ('talos/mac_config.py',), 04:20:50 INFO - 'default_actions': ('clobber', 04:20:50 INFO - 'read-buildbot-config', 04:20:50 INFO - 'download-and-extract', 04:20:50 INFO - 'populate-webroot', 04:20:50 INFO - 'create-virtualenv', 04:20:50 INFO - 'install', 04:20:50 INFO - 'run-tests'), 04:20:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:20:50 INFO - 'download_minidump_stackwalk': True, 04:20:50 INFO - 'download_symbols': 'ondemand', 04:20:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:20:50 INFO - 'tooltool.py': '/tools/tooltool.py', 04:20:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:20:50 INFO - '/tools/misc-python/virtualenv.py')}, 04:20:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:20:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:20:50 INFO - 'installer_path': 'installer.exe', 04:20:50 INFO - 'log_level': 'info', 04:20:50 INFO - 'log_name': 'talos', 04:20:50 INFO - 'log_to_console': True, 04:20:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:20:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:20:50 INFO - 'opt_config_files': (), 04:20:50 INFO - 'pip_index': False, 04:20:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:50 INFO - 'cmd': ('bash', 04:20:50 INFO - '-c', 04:20:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:50 INFO - 'enabled': True, 04:20:50 INFO - 'halt_on_failure': False, 04:20:50 INFO - 'name': 'check_screen_resolution'},), 04:20:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:20:50 INFO - 'cmd': ('bash', 04:20:50 INFO - '-c', 04:20:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:20:50 INFO - 'enabled': True, 04:20:50 INFO - 'halt_on_failure': False, 04:20:50 INFO - 'name': 'check_screen_resolution'},), 04:20:50 INFO - 'run_cmd_checks_enabled': True, 04:20:50 INFO - 'sps_profile': False, 04:20:50 INFO - 'sps_profile_interval': 0, 04:20:50 INFO - 'suite': 'dromaeojs', 04:20:50 INFO - 'system_bits': '32', 04:20:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:20:50 INFO - 'title': 't-yosemite-r7-0117', 04:20:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:20:50 INFO - 'use_talos_json': True, 04:20:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:20:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:20:50 INFO - 'work_dir': 'build'} 04:20:50 INFO - [mozharness: 2016-10-05 11:20:50.456768Z] Running clobber step. 04:20:50 INFO - Running pre-action listener: _resource_record_pre_action 04:20:50 INFO - Running main action method: clobber 04:20:50 INFO - rmtree: /builds/slave/test/build 04:20:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:20:52 INFO - Running post-action listener: _resource_record_post_action 04:20:52 INFO - [mozharness: 2016-10-05 11:20:52.874589Z] Finished clobber step (success) 04:20:52 INFO - [mozharness: 2016-10-05 11:20:52.874712Z] Running read-buildbot-config step. 04:20:52 INFO - Running pre-action listener: _resource_record_pre_action 04:20:52 INFO - Running main action method: read_buildbot_config 04:20:52 INFO - Using buildbot properties: 04:20:52 INFO - { 04:20:52 INFO - "project": "", 04:20:52 INFO - "product": "firefox", 04:20:52 INFO - "script_repo_revision": "production", 04:20:52 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 04:20:52 INFO - "repository": "", 04:20:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 04:20:52 INFO - "buildid": "20161005022600", 04:20:52 INFO - "pgo_build": "False", 04:20:52 INFO - "basedir": "/builds/slave/test", 04:20:52 INFO - "buildnumber": 222, 04:20:52 INFO - "slavename": "t-yosemite-r7-0117", 04:20:52 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:20:52 INFO - "platform": "yosemite_r7", 04:20:52 INFO - "scheduler": "tests-autoland-macosx64-talos", 04:20:52 INFO - "branch": "autoland", 04:20:52 INFO - "repo_path": "integration/autoland", 04:20:52 INFO - "revision": "4dc7f22c700fdbfd0328e64e20be6b65408bb098", 04:20:52 INFO - "stage_platform": "macosx64", 04:20:52 INFO - "builduid": "3928946ae9084ae08307f52d1d3a2bcc", 04:20:52 INFO - "slavebuilddir": "test" 04:20:52 INFO - } 04:20:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:20:52 INFO - Running post-action listener: _resource_record_post_action 04:20:52 INFO - [mozharness: 2016-10-05 11:20:52.877943Z] Finished read-buildbot-config step (success) 04:20:52 INFO - [mozharness: 2016-10-05 11:20:52.878045Z] Running download-and-extract step. 04:20:52 INFO - Running pre-action listener: _resource_record_pre_action 04:20:52 INFO - Running main action method: download_and_extract 04:20:52 INFO - mkdir: /builds/slave/test/build/tests 04:20:52 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')]} 04:20:52 INFO - trying https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:20:52 INFO - Downloading https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/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 04:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/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 04:20:53 INFO - Downloaded 1199 bytes. 04:20:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:20:53 INFO - Using the following test package requirements: 04:20:53 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:20:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:20:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:20:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'jsshell-mac.zip'], 04:20:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:20:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:20:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:20:53 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:20:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:20:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:20:53 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:20:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:20:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:20:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:20:53 INFO - Fetch https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:20:54 INFO - Http code: 200 04:20:54 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 04:20:54 INFO - x-amz-cf-id: zxcxO0NJlhhMV70yvK0M3L-OQYSnol2cv8hKulhQ6-m2wXNJb6FiCw== 04:20:54 INFO - x-amz-version-id: t_ELeBvgejDUTHIUiBeKdjySisK33Pu8 04:20:54 INFO - Expected file size: 16476884 04:20:54 INFO - Obtained file size: 16476884 04:21:01 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 04:21:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:21:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:21:01 INFO - Fetch https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:21:02 INFO - Http code: 200 04:21:02 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 04:21:02 INFO - x-amz-cf-id: iQqzb0ibpEyLj_SXfrga3pfReV6v0LUYLlQmUrM_5QyXQU5BjkdLRg== 04:21:02 INFO - x-amz-version-id: t_ELeBvgejDUTHIUiBeKdjySisK33Pu8 04:21:02 INFO - Expected file size: 16476884 04:21:02 INFO - Obtained file size: 16476884 04:21:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:21:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:21:09 INFO - Fetch https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:21:09 INFO - Http code: 200 04:21:09 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 04:21:09 INFO - x-amz-cf-id: GRIOTk-wg4TmfdwasAgFgfCibY6bWgImEEKsq22OCyRpjVe3J9PFng== 04:21:09 INFO - x-amz-version-id: wIvUbk_zBvaMAK60dwCb5yTnFnzSIdxA 04:21:09 INFO - Expected file size: 14409006 04:21:09 INFO - Obtained file size: 14409006 04:21: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')]} 04:21:10 INFO - trying https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:21:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:21:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/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 04:21:13 INFO - Downloaded 111649088 bytes. 04:21:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:21:13 INFO - mkdir: /builds/slave/test/properties 04:21:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:21:13 INFO - Writing to file /builds/slave/test/properties/build_url 04:21:13 INFO - Contents: 04:21:13 INFO - build_url:https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:21:13 INFO - Running post-action listener: _resource_record_post_action 04:21:13 INFO - Running post-action listener: set_extra_try_arguments 04:21:13 INFO - [mozharness: 2016-10-05 11:21:13.666112Z] Finished download-and-extract step (success) 04:21:13 INFO - [mozharness: 2016-10-05 11:21:13.666220Z] Running populate-webroot step. 04:21:13 INFO - Running pre-action listener: _resource_record_pre_action 04:21:13 INFO - Running main action method: populate_webroot 04:21:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:21:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:21:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:21:13 INFO - '--tppagecycles', 04:21:13 INFO - '7'], 04:21:13 INFO - 'tests': ['tsvgm']}}, 04:21:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:21:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:21:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:21:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:21:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:21:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['damp', 'tps']}, 04:21:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'tests': ['damp', 'tps']}, 04:21:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['dromaeo_dom']}, 04:21:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:21:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['basic_compositor_video']}, 04:21:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:21:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'other-e10s': {'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['a11yr', 04:21:13 INFO - 'ts_paint', 04:21:13 INFO - 'tpaint', 04:21:13 INFO - 'sessionrestore', 04:21:13 INFO - 'sessionrestore_no_auto_restore', 04:21:13 INFO - 'tabpaint']}, 04:21:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['tsvgx', 04:21:13 INFO - 'tsvgr_opacity', 04:21:13 INFO - 'tart', 04:21:13 INFO - 'tscrollx', 04:21:13 INFO - 'cart']}, 04:21:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:21:13 INFO - 'tsvgr_opacity', 04:21:13 INFO - 'tart', 04:21:13 INFO - 'tscrollx', 04:21:13 INFO - 'cart']}, 04:21:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'talos_options': ['--disable-e10s'], 04:21:13 INFO - 'tests': ['tp5o']}, 04:21:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'tests': ['tp5o']}, 04:21:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'talos_options': ['--disable-e10s', 04:21:13 INFO - '--xperf_path', 04:21:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:13 INFO - 'tests': ['tp5n']}, 04:21:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:21:13 INFO - 'talos_options': ['--xperf_path', 04:21:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:21:13 INFO - 'tests': ['tp5n']}}, 04:21:13 INFO - 'talos.zip': {'path': '', 04:21:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:21:13 INFO - Running post-action listener: _resource_record_post_action 04:21:13 INFO - [mozharness: 2016-10-05 11:21:13.684910Z] Finished populate-webroot step (success) 04:21:13 INFO - [mozharness: 2016-10-05 11:21:13.685038Z] Running create-virtualenv step. 04:21:13 INFO - Running pre-action listener: _resource_record_pre_action 04:21:13 INFO - Running main action method: create_virtualenv 04:21:13 INFO - Creating virtualenv /builds/slave/test/build/venv 04:21:13 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 04:21:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:21:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:21:13 INFO - Using real prefix '/tools/python27' 04:21:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:21:14 INFO - Installing distribute.............................................................................................................................................................................................done. 04:21:17 INFO - Installing pip.................done. 04:21:17 INFO - Return code: 0 04:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:21:17 INFO - Reading from file tmpfile_stdout 04:21:17 INFO - Output received: 04:21:17 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) 04:21:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:17 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 04:21:17 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 04:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:17 INFO - 'HOME': '/Users/cltbld', 04:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:17 INFO - 'LOGNAME': 'cltbld', 04:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:17 INFO - 'MOZ_NO_REMOTE': '1', 04:21:17 INFO - 'NO_EM_RESTART': '1', 04:21:17 INFO - 'PAGER': '/bin/cat', 04:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:17 INFO - 'PWD': '/builds/slave/test', 04:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:17 INFO - 'SHELL': '/bin/bash', 04:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:17 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:17 INFO - 'USER': 'cltbld', 04:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:17 INFO - 'XPC_FLAGS': '0x0', 04:21:17 INFO - 'XPC_SERVICE_NAME': '0', 04:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:17 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 04:21:17 INFO - Cleaning up... 04:21:17 INFO - Return code: 0 04:21:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:17 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:17 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 04:21:17 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 04:21:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:17 INFO - 'HOME': '/Users/cltbld', 04:21:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:17 INFO - 'LOGNAME': 'cltbld', 04:21:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:17 INFO - 'MOZ_NO_REMOTE': '1', 04:21:17 INFO - 'NO_EM_RESTART': '1', 04:21:17 INFO - 'PAGER': '/bin/cat', 04:21:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:17 INFO - 'PWD': '/builds/slave/test', 04:21:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:17 INFO - 'SHELL': '/bin/bash', 04:21:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:17 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:17 INFO - 'USER': 'cltbld', 04:21:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:17 INFO - 'XPC_FLAGS': '0x0', 04:21:17 INFO - 'XPC_SERVICE_NAME': '0', 04:21:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:18 INFO - Downloading/unpacking psutil>=3.1.1 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:18 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 04:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:21:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:21 INFO - Installing collected packages: psutil 04:21:21 INFO - Running setup.py install for psutil 04:21:21 INFO - building 'psutil._psutil_osx' extension 04:21:21 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 04:21:22 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 04:21:22 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 04:21:22 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 04:21:22 INFO - building 'psutil._psutil_posix' extension 04:21:22 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 04:21:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:21:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:21:22 INFO - ^ 04:21:22 INFO - 1 warning generated. 04:21:22 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 04:21:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:21:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:21:22 INFO - Successfully installed psutil 04:21:22 INFO - Cleaning up... 04:21:22 INFO - Return code: 0 04:21:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:21:22 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')]} 04:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 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')]} 04:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:22 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:22 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 04:21:22 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 04:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:22 INFO - 'HOME': '/Users/cltbld', 04:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:22 INFO - 'LOGNAME': 'cltbld', 04:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:22 INFO - 'MOZ_NO_REMOTE': '1', 04:21:22 INFO - 'NO_EM_RESTART': '1', 04:21:22 INFO - 'PAGER': '/bin/cat', 04:21:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:22 INFO - 'PWD': '/builds/slave/test', 04:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:22 INFO - 'SHELL': '/bin/bash', 04:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:22 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:22 INFO - 'USER': 'cltbld', 04:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:22 INFO - 'XPC_FLAGS': '0x0', 04:21:22 INFO - 'XPC_SERVICE_NAME': '0', 04:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:21:23 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 04:21:23 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 04:21:23 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 04:21:23 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 04:21:23 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 04:21:23 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 04:21:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:21:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:21:25 INFO - Installing collected packages: mozsystemmonitor 04:21:25 INFO - Running setup.py install for mozsystemmonitor 04:21:25 INFO - Successfully installed mozsystemmonitor 04:21:25 INFO - Cleaning up... 04:21:25 INFO - Return code: 0 04:21:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:25 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:25 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 04:21:25 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 04:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:25 INFO - 'HOME': '/Users/cltbld', 04:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:25 INFO - 'LOGNAME': 'cltbld', 04:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:25 INFO - 'MOZ_NO_REMOTE': '1', 04:21:25 INFO - 'NO_EM_RESTART': '1', 04:21:25 INFO - 'PAGER': '/bin/cat', 04:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:25 INFO - 'PWD': '/builds/slave/test', 04:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:25 INFO - 'SHELL': '/bin/bash', 04:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:25 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:25 INFO - 'USER': 'cltbld', 04:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:25 INFO - 'XPC_FLAGS': '0x0', 04:21:25 INFO - 'XPC_SERVICE_NAME': '0', 04:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:26 INFO - Downloading/unpacking jsonschema==2.5.1 04:21:26 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 04:21:26 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 04:21:26 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 04:21:26 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 04:21:26 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 04:21:26 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 04:21:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:21:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:21:28 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 04:21:28 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 04:21:28 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 04:21:28 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 04:21:28 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 04:21:28 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 04:21:28 INFO - Downloading functools32-3.2.3-2.tar.gz 04:21:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:21:28 INFO - warning: no files found matching '*.txt' 04:21:28 INFO - no previously-included directories found matching 'build' 04:21:28 INFO - no previously-included directories found matching 'dist' 04:21:28 INFO - no previously-included directories found matching '.git*' 04:21:28 INFO - Installing collected packages: jsonschema, functools32 04:21:28 INFO - Running setup.py install for functools32 04:21:29 INFO - warning: no files found matching '*.txt' 04:21:29 INFO - no previously-included directories found matching 'build' 04:21:29 INFO - no previously-included directories found matching 'dist' 04:21:29 INFO - no previously-included directories found matching '.git*' 04:21:29 INFO - Successfully installed jsonschema functools32 04:21:29 INFO - Cleaning up... 04:21:29 INFO - Return code: 0 04:21:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:21:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:29 INFO - 'HOME': '/Users/cltbld', 04:21:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:29 INFO - 'LOGNAME': 'cltbld', 04:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:29 INFO - 'MOZ_NO_REMOTE': '1', 04:21:29 INFO - 'NO_EM_RESTART': '1', 04:21:29 INFO - 'PAGER': '/bin/cat', 04:21:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:29 INFO - 'PWD': '/builds/slave/test', 04:21:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:29 INFO - 'SHELL': '/bin/bash', 04:21:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:29 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:29 INFO - 'USER': 'cltbld', 04:21:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:29 INFO - 'XPC_FLAGS': '0x0', 04:21:29 INFO - 'XPC_SERVICE_NAME': '0', 04:21:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:21:29 INFO - Cleaning up... 04:21:29 INFO - Return code: 0 04:21:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:21:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:29 INFO - 'HOME': '/Users/cltbld', 04:21:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:29 INFO - 'LOGNAME': 'cltbld', 04:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:29 INFO - 'MOZ_NO_REMOTE': '1', 04:21:29 INFO - 'NO_EM_RESTART': '1', 04:21:29 INFO - 'PAGER': '/bin/cat', 04:21:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:29 INFO - 'PWD': '/builds/slave/test', 04:21:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:29 INFO - 'SHELL': '/bin/bash', 04:21:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:29 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:29 INFO - 'USER': 'cltbld', 04:21:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:29 INFO - 'XPC_FLAGS': '0x0', 04:21:29 INFO - 'XPC_SERVICE_NAME': '0', 04:21:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:29 INFO - Downloading/unpacking blobuploader==1.2.4 04:21:29 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 04:21:29 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 04:21:29 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 04:21:29 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 04:21:29 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 04:21:29 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 04:21:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:21:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:21:32 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 04:21:32 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 04:21:32 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 04:21:32 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 04:21:32 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 04:21:32 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 04:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:21:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:33 INFO - Downloading docopt-0.6.1.tar.gz 04:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:21:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:21:33 INFO - Running setup.py install for blobuploader 04:21:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:21:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:21:33 INFO - Running setup.py install for requests 04:21:34 INFO - Running setup.py install for docopt 04:21:34 INFO - Successfully installed blobuploader requests docopt 04:21:34 INFO - Cleaning up... 04:21:34 INFO - Return code: 0 04:21:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:34 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:34 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 04:21:34 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 04:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:34 INFO - 'HOME': '/Users/cltbld', 04:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:34 INFO - 'LOGNAME': 'cltbld', 04:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:34 INFO - 'MOZ_NO_REMOTE': '1', 04:21:34 INFO - 'NO_EM_RESTART': '1', 04:21:34 INFO - 'PAGER': '/bin/cat', 04:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:34 INFO - 'PWD': '/builds/slave/test', 04:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:34 INFO - 'SHELL': '/bin/bash', 04:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:34 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:34 INFO - 'USER': 'cltbld', 04:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:34 INFO - 'XPC_FLAGS': '0x0', 04:21:34 INFO - 'XPC_SERVICE_NAME': '0', 04:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:34 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-D24EC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:34 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-F1V5Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:34 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ycw0oS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZvjC9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-jLx3w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-kBZ9z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Iy7vQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-XnaA8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-T8bPVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-G9ZFWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-S3yv1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-A94Wua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:35 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-lGSslj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:36 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fotITE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:36 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-w2UEqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:36 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-i4Hyof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:36 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-HblGf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:21:36 INFO - Running setup.py install for manifestparser 04:21:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:21:36 INFO - Running setup.py install for mozcrash 04:21:36 INFO - Running setup.py install for mozdebug 04:21:36 INFO - Running setup.py install for mozdevice 04:21:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozfile 04:21:37 INFO - Running setup.py install for mozhttpd 04:21:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozinfo 04:21:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozInstall 04:21:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:21:37 INFO - Running setup.py install for mozleak 04:21:37 INFO - Running setup.py install for mozlog 04:21:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for moznetwork 04:21:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozprocess 04:21:38 INFO - Running setup.py install for mozprofile 04:21:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozrunner 04:21:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:21:38 INFO - Running setup.py install for mozscreenshot 04:21:38 INFO - Running setup.py install for moztest 04:21:38 INFO - Running setup.py install for mozversion 04:21:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:21:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:21:39 INFO - Cleaning up... 04:21:39 INFO - Return code: 0 04:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:39 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:39 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 04:21:39 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 04:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:39 INFO - 'HOME': '/Users/cltbld', 04:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:39 INFO - 'LOGNAME': 'cltbld', 04:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:39 INFO - 'MOZ_NO_REMOTE': '1', 04:21:39 INFO - 'NO_EM_RESTART': '1', 04:21:39 INFO - 'PAGER': '/bin/cat', 04:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:39 INFO - 'PWD': '/builds/slave/test', 04:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:39 INFO - 'SHELL': '/bin/bash', 04:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:39 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:39 INFO - 'USER': 'cltbld', 04:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:39 INFO - 'XPC_FLAGS': '0x0', 04:21:39 INFO - 'XPC_SERVICE_NAME': '0', 04:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-7xDwkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:21:39 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)) 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-0CnE8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:21:39 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)) 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qzx6E0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:21:39 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)) 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-yzCpV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:21:39 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)) 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-riVDGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:21:39 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)) 04:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:21:39 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Hrxa2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fKpSV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-AQqJ0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-3n06OG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-TKyDP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-jABEXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-y0vxFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-WbEhrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-gmhT2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FvHkmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:21:40 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)) 04:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:21:40 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-wPTKkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:21:41 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)) 04:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:21:41 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-FHEbRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:21:41 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)) 04:21:41 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)) 04:21:41 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)) 04:21:41 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)) 04:21:41 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)) 04:21:41 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)) 04:21:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:21:43 INFO - Downloading blessings-1.6.tar.gz 04:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:21:43 INFO - Installing collected packages: blessings 04:21:43 INFO - Running setup.py install for blessings 04:21:43 INFO - Successfully installed blessings 04:21:43 INFO - Cleaning up... 04:21:44 INFO - Return code: 0 04:21:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:44 INFO - Reading from file tmpfile_stdout 04:21:44 INFO - Current package versions: 04:21:44 INFO - blessings == 1.6 04:21:44 INFO - blobuploader == 1.2.4 04:21:44 INFO - docopt == 0.6.1 04:21:44 INFO - functools32 == 3.2.3-2 04:21:44 INFO - jsonschema == 2.5.1 04:21:44 INFO - manifestparser == 1.1 04:21:44 INFO - mozInstall == 1.12 04:21:44 INFO - mozcrash == 0.17 04:21:44 INFO - mozdebug == 0.1 04:21:44 INFO - mozdevice == 0.48 04:21:44 INFO - mozfile == 1.2 04:21:44 INFO - mozhttpd == 0.7 04:21:44 INFO - mozinfo == 0.9 04:21:44 INFO - mozleak == 0.1 04:21:44 INFO - mozlog == 3.4 04:21:44 INFO - moznetwork == 0.27 04:21:44 INFO - mozprocess == 0.23 04:21:44 INFO - mozprofile == 0.28 04:21:44 INFO - mozrunner == 6.12 04:21:44 INFO - mozscreenshot == 0.1 04:21:44 INFO - mozsystemmonitor == 0.3 04:21:44 INFO - moztest == 0.7 04:21:44 INFO - mozversion == 1.4 04:21:44 INFO - psutil == 3.1.1 04:21:44 INFO - requests == 1.2.3 04:21:44 INFO - wsgiref == 0.1.2 04:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 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 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:44 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 04:21:44 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 04:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:44 INFO - 'HOME': '/Users/cltbld', 04:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:44 INFO - 'LOGNAME': 'cltbld', 04:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:44 INFO - 'MOZ_NO_REMOTE': '1', 04:21:44 INFO - 'NO_EM_RESTART': '1', 04:21:44 INFO - 'PAGER': '/bin/cat', 04:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:44 INFO - 'PWD': '/builds/slave/test', 04:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:44 INFO - 'SHELL': '/bin/bash', 04:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:44 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:44 INFO - 'USER': 'cltbld', 04:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:44 INFO - 'XPC_FLAGS': '0x0', 04:21:44 INFO - 'XPC_SERVICE_NAME': '0', 04:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 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)) 04:21:44 INFO - Cleaning up... 04:21:44 INFO - Return code: 0 04:21:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:21:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cfc7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaa9dd7e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, '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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 04:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:21:44 INFO - 'HOME': '/Users/cltbld', 04:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:21:44 INFO - 'LOGNAME': 'cltbld', 04:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:21:44 INFO - 'MOZ_NO_REMOTE': '1', 04:21:44 INFO - 'NO_EM_RESTART': '1', 04:21:44 INFO - 'PAGER': '/bin/cat', 04:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:21:44 INFO - 'PWD': '/builds/slave/test', 04:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:21:44 INFO - 'SHELL': '/bin/bash', 04:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:21:44 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:21:44 INFO - 'USER': 'cltbld', 04:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:21:44 INFO - 'XPC_FLAGS': '0x0', 04:21:44 INFO - 'XPC_SERVICE_NAME': '0', 04:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:21:44 INFO - Cleaning up... 04:21:44 INFO - Return code: 0 04:21:44 INFO - Running post-action listener: _resource_record_post_action 04:21:44 INFO - Running post-action listener: _start_resource_monitoring 04:21:44 INFO - Starting resource monitoring. 04:21:44 INFO - [mozharness: 2016-10-05 11:21:44.823388Z] Finished create-virtualenv step (success) 04:21:44 INFO - [mozharness: 2016-10-05 11:21:44.824376Z] Running install step. 04:21:44 INFO - Running pre-action listener: _resource_record_pre_action 04:21:44 INFO - Running main action method: install 04:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:21:45 INFO - Reading from file tmpfile_stdout 04:21:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:21:45 INFO - Reading from file tmpfile_stdout 04:21:45 INFO - Output received: 04:21:45 INFO - Usage: mozinstall [options] installer 04:21:45 INFO - Options: 04:21:45 INFO - -h, --help show this help message and exit 04:21:45 INFO - -d DEST, --destination=DEST 04:21:45 INFO - Directory to install application into. [default: 04:21:45 INFO - "/builds/slave/test"] 04:21:45 INFO - --app=APP Application being installed. [default: firefox] 04:21:45 INFO - mkdir: /builds/slave/test/build/application 04:21:45 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'] 04:21:45 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 04:22:19 INFO - Reading from file tmpfile_stdout 04:22:19 INFO - Output received: 04:22:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:22:19 INFO - Running post-action listener: _resource_record_post_action 04:22:19 INFO - [mozharness: 2016-10-05 11:22:19.525207Z] Finished install step (success) 04:22:19 INFO - [mozharness: 2016-10-05 11:22:19.525369Z] Running run-tests step. 04:22:19 INFO - Running pre-action listener: _resource_record_pre_action 04:22:19 INFO - Running main action method: run_tests 04:22:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:19 INFO - 2016-10-05 04:22:19.539 screenresolution[1643:7405] starting screenresolution argv=screenresolution get 04:22:19 INFO - 2016-10-05 04:22:19.542 screenresolution[1643:7405] Display 0: 1600x1200x32@60 04:22:19 INFO - 2016-10-05 04:22:19.548 screenresolution[1644:7407] starting screenresolution argv=screenresolution list 04:22:19 INFO - Available Modes on Display 0 04:22:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:19 INFO - Intel Iris: 04:22:19 INFO - Chipset Model: Intel Iris 04:22:19 INFO - Type: GPU 04:22:19 INFO - Bus: Built-In 04:22:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:19 INFO - Vendor: Intel (0x8086) 04:22:19 INFO - Device ID: 0x0a2e 04:22:19 INFO - Revision ID: 0x0009 04:22:19 INFO - Displays: 04:22:19 INFO - Display: 04:22:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:19 INFO - Display Serial Number: 1600x1200 60 04:22:19 INFO - Main Display: Yes 04:22:19 INFO - Mirror: Off 04:22:19 INFO - Online: Yes 04:22:19 INFO - Rotation: Supported 04:22:19 INFO - Return code: 0 04:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:22:19 INFO - Python 2.7.3 04:22:19 INFO - Return code: 0 04:22:19 INFO - grabbing minidump binary from tooltool 04:22:19 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')]} 04:22:19 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 0x10cddbd30>, '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 0x10cc01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cdd3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:22:19 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 04:22:19 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 04:22:19 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 04:22:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:22:19 INFO - Return code: 0 04:22:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:22:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:22:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:22:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:22:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:22:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:22:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --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 04:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZ0j2eLnfY/Render', 04:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:22:19 INFO - 'HOME': '/Users/cltbld', 04:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:22:19 INFO - 'LOGNAME': 'cltbld', 04:22:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:22:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:19 INFO - 'MOZ_NO_REMOTE': '1', 04:22:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:22:19 INFO - 'NO_EM_RESTART': '1', 04:22:19 INFO - 'PAGER': '/bin/cat', 04:22:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:19 INFO - 'PWD': '/builds/slave/test', 04:22:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:22:19 INFO - 'SHELL': '/bin/bash', 04:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DWlI1uodL1/Listeners', 04:22:19 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 04:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:22:19 INFO - 'USER': 'cltbld', 04:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:19 INFO - 'XPC_FLAGS': '0x0', 04:22:19 INFO - 'XPC_SERVICE_NAME': '0', 04:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:22:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--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 04:22:20 INFO - mozversion application_buildid: 20161005022600 04:22:20 INFO - mozversion application_changeset: 4dc7f22c700fdbfd0328e64e20be6b65408bb098 04:22:20 INFO - mozversion application_display_name: Nightly 04:22:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:22:20 INFO - mozversion application_name: Firefox 04:22:20 INFO - mozversion application_remotingname: firefox 04:22:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:22:20 INFO - mozversion application_vendor: Mozilla 04:22:20 INFO - mozversion application_version: 52.0a1 04:22:20 INFO - mozversion platform_buildid: 20161005022600 04:22:20 INFO - mozversion platform_changeset: 4dc7f22c700fdbfd0328e64e20be6b65408bb098 04:22:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:22:20 INFO - mozversion platform_version: 52.0a1 04:22:20 INFO - using testdate: 1475666540 04:22:20 INFO - actual date: 1475666540 04:22:20 INFO - starting webserver on 'localhost:49277' 04:22:20 INFO - SUITE-START | Running 2 tests 04:22:20 INFO - TEST-START | dromaeo_css 04:22:20 INFO - operating with platform_type : mac_ 04:22:20 INFO - Initialising browser for dromaeo_css test... 04:22:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpT4qdBG/profile http://localhost:49277/getInfo.html) 04:22:22 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 04:22:22 INFO - PROCESS | 1654 | colorDepth:24 04:22:22 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 04:22:22 INFO - PROCESS | 1654 | __metrics 04:22:22 INFO - PROCESS | 1654 | 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" 04:22:22 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:22:23 INFO - TEST-INFO | 1654: exit 0 04:22:23 INFO - Browser initialized. 04:22:23 INFO - Running cycle 1/1 for dromaeo_css test... 04:22:23 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpT4qdBG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:35 INFO - PROCESS | 1656 | RSS: Main: 235732992 04:22:35 INFO - PROCESS | 1656 | 04:23:27 INFO - PROCESS | 1656 | 1475666607449 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] 04:25:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 04:25:41 INFO - PROCESS | 1656 | RSS: Main: 392011776 04:25:41 INFO - PROCESS | 1656 | 04:28:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 04:28:45 INFO - PROCESS | 1656 | RSS: Main: 373116928 04:28:45 INFO - PROCESS | 1656 | 04:31:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 04:31:48 INFO - PROCESS | 1656 | RSS: Main: 355704832 04:31:48 INFO - PROCESS | 1656 | 04:34:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:34:51 INFO - PROCESS | 1656 | RSS: Main: 342659072 04:34:51 INFO - PROCESS | 1656 | 04:37:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 04:37:55 INFO - PROCESS | 1656 | RSS: Main: 329129984 04:37:55 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:40:58 INFO - PROCESS | 1656 | RSS: Main: 441081856 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | __start_tp_report 04:40:58 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 04:40:58 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 04:40:58 INFO - PROCESS | 1656 | |i|pagename|runs| 04:40:58 INFO - PROCESS | 1656 | |0;dojo.html;3230.2731885325807;3264.412405766962;3278.9016329510123;3280.1307653471818;3328.5839270091237;5468.863278418032;5540.833774986751;5571.86070348241;5597.468240517144;5628.577856660746;5504.119340905456;5563.165525171216;5563.527100196488;5565.6104072714925;5575.191597218401;5072.695638261716;5104.438511763703;5131.563817075535;5140.023395554851;5181.6113791465605;5105.591552675795;5181.559567436772;5191.818286359978;5193.974030129844;5202.219667049935;5077.339945807058;5137.717425541594;5146.588272938156;5170.767315470813;5188.455212202721;4465.972826249948;5151.124308867464;5161.9483805162035;5162.638615296931;5177.974110129463;4983.9252411213865;4987.526185012418;5042.117629414838;5086.21163719624;5086.720230387327;5445.237666726622;5455.099908515091;5510.311211098613;5534.501894829445;5555.250041244421;6015.488683461911;6021.337652858182;6023.427774361428;6026.819195424145;6034.487068599175;5932.169496270526;6040.395960403968;6048.96975515124;6113.510919126459;6113.877722445571;5548.167774833807;5598.720063996784;5602.7758889644365;5614.9438505615235;5682.54539636828;6183.072539119168;6199.938000620026;6204.286507051746;6214.844628884314;6215.160953271251;5810.157527158538;5849.736761845642;5866.530677545786;5878.588498805128;5887.116932460165;5873.442023007893;5930.970345148246;5975.133605627247;6000.189974353494;6051.062085376773;5940.46535811779;5983.341832398432;5993.370696076936;6004.819855404345;6032.577719559677;6009.759609615654;6018.548608854353;6033.064874944391;6042.3655516170165;6056.06131049688;4724.692894961817;4773.618110551148;4783.162946484351;4786.353842231324;4811.975940120277;4297.8495752648505;4337.392765012901;4341.957930096817;4355.1289742051085;4396.9120617588105;5575.693336866511;5615.438456154434;5626.718664066781;5635.605507614509;5662.178984047372;5927.73325200367;6127.754889804358;6131.526791107749;6146.494108943338;6167.074938759222;5460.372057213392;5567;5586.888262234814;5589.832305030855;5602.971985140048;5267.920981185285;5277.076511610453;5284.788608455696;5320.946790532123;5335.733213339395;6319.494440444661;6337.112804207414;6337.2395312562785;6351.650659213788;6352.205974253218;5926.288845338587;6032.54755893301;6054.424829641177;6137.96931015342;6149.108379285068;6183.938160618336;6202.503799695924;6269.279032911274;6273.74905003813;6290.905636415366;5941.91087133702;5949.315828679757;5950.047992321381;5955.344912059583;6013.128096426081;5985.4912332450485;6035.36628653985;6064.757409703739;6097.75608975636;6107.4197951194565;5737.139429085671;5805.883882322245;5810.070388737781;5815.04051831443;5861.120831875082;5721.685307308138;5871.853203669943;5873.530117590668;5875.529957603467;5878.29460464747;5637.520810731134;5743.1385292206505;5745;5761.625494342854;5781.335146458043;5746.023176060164;5798.420157984167;5835.241418615553;5866.970665146647;5872.765089396547;5724;5770.855728606818;5781.161731548817;5834.708264586839;5848.356680765198 04:40:58 INFO - PROCESS | 1656 | |1;ext.html;474.9857504274869;513.8797421620867;513.9152039913414;530.8858595401988;554.1134185303515;30912.84543577282;39590.60409395905;40853.182936341254;40954.7952260239;41066.58933410665;41961.37057944133;42240.788796056055;42383.78808105955;42386.94032649185;42696.57303426965;9599.904000959988;9822.656207032755;9826.754331141707;9830.90169098307;9856.162226210745;9598.904010959906;9696.46669433181;9697.030296970283;9697.030296970317;9697.563609637584;9648.75878103046;9739.318247722662;9744.317897747125;9763.365381250196;9765;9622.326437149402;9679.903200968041;9685.273604479693;9698.127168554793;9701.175400091;9673.129418352311;9750.512474376254;9752.26857985654;9769.413835169842;9780.364276322087;39437.60562394368;39782.40326395106;39840;39893.80053099745;39935.201295974206;3254.886078987248;3301.3067255876294;3311.4536101543217;3323.2854936188837;3417.8120203388803;28228.012019579422;30603.387932241454;30677;31458.37083258345;31792.841035794903;3098.0396077216137;3284.1953721338423;3308.9503657445157;3414;3416.333814906094;481414;484510;484523.99999999645;485195.99999999645;495917;57383;60279.69860150671;60599;60973;60977.39022609806;63249;63252.367476324645;63278;63345.683271583344;63384;15541.300641471162;15740.68518629621;16212.027278363335;16246;16607.335706571845;12334.629961101182;12432.875671243171;12466.626001219978;12469.937650311871;12480.625581232578;15080.924595377171;18681.252749890125;18706.625867482846;18733.81266187348;18775.530611734815;6363.745450182034;7509.7371591994015;7612.543247405173;7650.732224372232;7711.807204819812;711.3029231353199;878.3149143667949;946.7727745341206;1028.9624710944836;1048.496721573649;28376.148715538566;29710.702892971225;30094.24764380864;30123.849380752956;30575.38849223003;1036.616451912782;1091.2088735666578;1113.933164010162;1131.191198293208;1146.644540192543;15348.386064557293;15554.533363999099;15593.376264949502;15653.98249113804;16023.67952640964;667394;668740;673825.6308718523;673859;677506;56537.71731141318;57044.714776426685;57147.714261428424;57218.42781572214;57226.98527757709;1228.502456505117;1238.9690257743628;1260.6407173955379;1298.1626850681068;1373.4506197520873;24415.145469908468;28854;29662.555061673662;30708;31160.844195778875;47932.5206747928;48702.51297487121;48731.269030965275;48775.51224487852;48859;502042;503199;505821;506923;508068;23172.18897338585;23720.762792371854;23924.282271531883;24768.876155619106;26644.934202631677;45827.54172458233;45964.31053534133;46136.76931615321;46485.28099910821;47011;38338;38777.6122238774;38897.80551097226;39802.80098599489;40117.39823902585;37513;38003.66987155436;38256.80871595624;39170.804145980226;39781.80109099436;37001.44497832588;37245.25509489849;37992.24015519619;38050.619493804705;38098.047548811504;28486.715132848407;32211.51682724808;32400.83799580987;33955.81154659575;34407.483887741204;24737.752622474265;25237.99048038058;25266.36834079163;25396.87301563554;25907.740922591285 04:40:58 INFO - PROCESS | 1656 | |2;jquery.html;365.32050386281526;367.6323676323676;373.30751456049046;377.6223776223777;398.23141337219175;19426.320078797246;20509.897450512763;20900.58198836024;20931.47671308216;21132.788672113275;20759.273425430074;20923.37229883106;20995.685064724003;21178;21274;5802.448767367094;5803.767849286023;6081.756729730805;6119.632822030692;6186.690665466731;3895.182011777516;6150.261968563756;6216.471599914013;6224.159738435321;6233.750649973996;4018.8794336169817;4093.9795301023455;4119;6703.597784132943;6787.4570034397375;5120.871978200537;6733.562318449285;6750.763723269686;6767.526273160881;6772.627505487197;5675.801346952837;5758.395368486333;5769.249997500298;5773.797917072924;5787.971060144714;24545.772711364538;25134.67134223685;25494.74505254961;25500;25584.744152558607;2099.737532808399;2118.7987141221715;2178.923737669174;2226.955460890773;2297.425643589103;9881.505924703735;9976.152027077707;10206.591736330538;10264.846027309597;14933.328000240015;1824.2064701854736;1915.5402703351235;1918.7121931710215;1938.2440848069264;1954.775200851892;103213.48393257985;111011;120080;123143.91704746615;123621.38189309176;11144.498497567629;11155.498002589902;11381.317120972768;11652.825207621892;11680.649580512598;14074.929625352013;14091.78862317066;14430.134191948517;14547.636309092357;14597.78103328451;8420.4947703138;8551.401401901932;8552.828943421096;8554.18735220153;8746.344024198082;8422.873656895152;8484.575771211537;8619.956900215459;8642.049374568793;8660.133986601415;4452.198604251266;4523.411956445707;4533.795979180944;4538.1831270370985;4610.40064791575;1830.3044842959857;1858.200973581373;1919.3570153998287;1947.3963071447897;1963.3717210492505;384.2085304273206;385.1930206217969;839.3830534557096;929.4376901974208;1010.6311196413403;10334.659443033945;11262.831057534144;11564.884351156381;11802.822957655642;12056.21634593766;409.82787229363737;424.35921758145423;428.52433798483656;434.98912527187076;438.2155940865878;4784.569388755029;5968.820935371946;6000.159977603139;8999.145081217404;10825.729356766144;520888;524600;569697;575154;597520;93475;120263;123940;134474;136558.63441365657;995.5818556206556;1009.1926458832904;1055.8046761349021;1058.2645061682058;1059.5285098131412;19295.51761205981;19557.60884782325;19685.901570492057;19697.606047878675;19803.504912377306;54385;54477.45522544724;54600.453995461125;55077.44922550723;56224.71887640536;72041.91937121052;78489;78804.21195787968;81646;82500.76248856152;7262.600556969417;7770.494917830323;7984.361251100015;8378.53918034514;8473.95763021181;9656.806863862825;12912.677183070498;12979.935100324816;13417.262050587311;13447.529336473177;10781.9460902695;11088.5564577416;11192.496337664661;11748;11900;10815.729606759895;11389.202755807013;11469.31184128955;11624.128190385585;11835.940820296188;11251.943740281245;11459.770804584028;11990.940045299718;11993.64019079429;12104.152709310616;8088.676452941816;8251.463654862415;8288.129746376784;8330.375221858507;8365.790855228668;9576.425414475154;11232.045276151619;11760.941195294312;11775.646730598095;11966.64100076999 04:40:58 INFO - PROCESS | 1656 | |3;mootools.html;1511.4860947277923;1543.3595058050905;1625.5367220342205;1710.461204720513;1830.8374182394184;8723.869141962869;9293.721188364352;9307.441553506795;9318.953405232982;9324.766880827983;8141.91858081419;8374.455660382071;8381.413301068927;8474.576271186432;9033.72898813035;1859.981400186001;2145.0883374565824;2186.4643162425227;2374.3351861478814;2376.3583832365252;2371.3241726108063;2379.9286021419384;2380.880955952204;2383.833131680783;2388.0805889732405;2245.3264020793777;2288.9198878039188;2290.3930458428445;2293.174457195408;2296.8047715944167;2322.442613772699;2330.8951097200666;2349.1308215960034;2349.894254758523;2354.717433907942;2307.907683692667;2326.069572171128;2331.708536432946;2340.578695834749;2342.543204075206;8947.955260223722;9005.639774409015;9057.139571740674;9058.954705226432;9070.86393704095;5299.814506492293;5500.779968801243;5503.422140675211;5523.061079616475;5527.309086364205;3534.929301413957;3647.1429214134655;3741.5884252732176;3742.925141497182;3743.3074881146804;1040.3341861208833;1088.7223757941674;1112.471576001401;1112.7663190729957;1113.9275947063495;12050.216735912138;12246.938765306206;12268.018558515298;12272.38638068093;12280.201786883914;4478.760619690155;4674.509176536415;4873.220284754422;4887.437944636341;4897.14299997499;10148.797024059477;10348.689539313833;10348.741281468023;10359.274850760525;10360.792784144274;5485.040117979417;5493.7802487900835;5494.532964697965;5512.255845460892;5517.1172612382625;4950.851474455772;4968.205087186033;4977.377827771528;5005.89988200234;5032.949670503322;3455.2398472336045;3691.446283057509;3829.1767270036516;3838.05967537955;3879.127196380848;1265.1017777378165;1273.5797186928291;1273.6497463197509;1284.101129209557;1287.7488889666613;669.9397054265139;878.8813510176172;892.7054072156172;916.7295647783919;918.9908100918905;4588.9541104588525;4658.510856360033;5074.695518268842;6039.637621742621;6121.4490695837585;929.3262384771122;937.9390339628039;942.8161508505775;959.9760005999906;982.886967998675;3496.4056110461283;3924.5486769021927;3989.960100399017;4037.152198038386;4145.854895078658;10715.35707857531;11242.988131068243;11272.985431311057;11279.984801367917;11286.379249141211;11170.776584468427;11191.32852028881;11195.776084478264;11199.440027998568;11206.551737930555;8315.37850119221;8359.665613375397;8379.329653627818;8522.147785221428;8523.445976011539;4943.406791185081;5754.712264386848;5950.702464876652;5972.761089556543;6030.48740857032;10192.796144077225;10367.63713270032;10397.480125993821;10474.581016759244;10503.527341269813;9928.602855885683;10431.269811113149;10454.215933805148;10557.366558006544;10604.257701960789;7729.729459468892;7876.4092693047105;7893.526388416713;7897.210278972057;7905.56519391439;7604.80987975305;7939.523628582073;7954.284114429729;8128.105908350195;8482.279006284534;7432.442566807403;7552.962235188789;7577.5453472791805;7598.354139898171;7746.225377462434;7489.962550187214;7535.058117735283;7556.093268807558;7558.811029724301;7589.506682065648;5360.70516121617;6334.651594162366;6786.84623613977;7083.7520686775715;7171.426285897011;5015.17249653803;5040.34475518195;5059.974700126476;5099.490050994871;5202.73986300691;5015.523525265094;5090.236464530202;5136.15253483186;5189.558887494454;5214.348206474191 04:40:58 INFO - PROCESS | 1656 | |4;prototype.html;303.4007834526809;303.78431313767237;312.7560502807809;317.8633187729277;320.0957295639818;5100.464451232613;5309.522143007129;5328.8401347959625;5350.919736203955;5354.170103633934;5138.7173705446185;5180.989707007135;5206.999999999991;5252;5290;4095.6313931746135;4095.815688294019;4103.261412945676;4140.399642051916;4145.901336145924;3602.6397360263895;3892.0075380778003;3916.2167566486673;4109.363048727452;4118.485189351331;3857.9421308680394;3948.644621984021;3963.6036396360278;4028.395740638913;4081.1837632473475;3896.103896103898;4054.878353649395;4066.2274167908004;4068.1660259646997;4068.6948478864206;3568.1436455250814;3569.964300356989;3571.6964058055096;3575.1419659281846;3582.42681171014;4076.613951256587;4304.827806887721;4724.984128412373;5269.815556455505;5275.657082289677;4578.587927086578;4619.3763841881255;4663.300504924255;4679.15775160471;4698.553637404441;4171.831887071625;4395.846145384929;5502.9174562381595;5640.379558248589;5655;3929.1945151244213;3934.980325098385;3946.980265098685;3960.2277555876617;3964.226975739732;4849;5042.84871453857;5315.309009828737;5575.637583557056;5786.88426231473;3000.3099287164073;3571.892713258898;3975.6223158799867;3984.601539845992;4009.67922566194;3375.729941604666;4041.9692978290936;4050.23045621331;4052.4064598656882;4060.7766572838186;4496.775161241925;4643.791029403617;4675.672702910763;4682.41469816273;4701.979807264808;5591.7483713233005;5598.272224610774;5623.594101474631;5641.928033673589;5648.774049038075;1213.6905089202373;1264.4246867675186;1353.9323033848268;1354.2686949047602;1385.6328073060647;770.1989930472358;784.5057613703331;808.2967817998261;846.7205822078822;857.2927334948693;3976.151089743283;4011.3782363733667;4059.65492933104;4434.445694288214;4459.68096589778;4740.288956656529;4767.829393453315;4774.328984855342;4812.06164797868;4870.8782280442565;4136.296829538986;4439.958628339597;4572.565606267399;5047.293378926953;5240.292560504359;2977.5856468177876;3006.3836913433133;3023.818570885754;3024.8052019452193;3032.453448741158;4975.959856458834;5762.4525670061275;5845.327787304429;5912.615679980788;5952.375000624958;5836.620619659709;5843.503302219274;5866.2749154286485;5874.823755287348;5919.4080591940465;4912.828051018198;5186.533212010937;5838.744669895993;5912.645241285537;5962.821115366546;5227.320448341691;5378;5658.801941932013;5670.5180059695385;5747.488221745909;5587.8882422352135;5641.15382692583;5647.887042259214;5659.575531835211;5662.320521537277;4903.754812259444;5630.239917611234;5950.910736339045;5957.553183511167;5965.731542080704;3339.348826978716;3765.45400916871;3993.4209539617173;4027.872195785185;4031.3549832026733;5732;5736.013470637867;5757.568182386254;5772.9711351442975;5799.623024503394;5188.667462668714;5239.611502951797;5431.5926305526455;5648.2092507049865;5650.29371328584;4190.099128687347;4750.501197374226;4825.927611085906;5507.284053073075;5553.666779993213;4520.796564154555;4867.829625963074;5438.75525601341;5456.727163641723;5496.279341725712;5118.43697193301;5569.443055694398;5612.382637909745;5628.63413878113;5654.773809047592;5187;5404.83785486436;5433.239346491416;5470.781168753205;5479.726013699379 04:40:58 INFO - PROCESS | 1656 | |5;yui.html;696.3002182806279;700.5516469459546;739.5562662402556;753.3709352690503;805.8468890910726;15125.319360628771;15673.216339183053;15721.619851731875;15735.921320393381;15752.212389380527;13966.162030278165;14063.296835158228;14068.718625627482;14099.295035248248;14099.506517271897;675.7465950268662;696.7491702986946;701.9961455119178;838.375410319313;863.8531449653574;856.3534531428762;872.6858331000834;873.6112429968666;874.2394117118084;875.9562021898879;920.3143657974819;922.524899676667;923.224491427201;924.4407133684125;925.9629614815399;714.5105602662193;730.6931088942657;883.2977782662798;915.97410899792;917.7430319510547;685.4721864164616;686.8763622547939;687.6321168175052;687.8417963868288;689.6930865764737;14867.959242853005;14904.70190596182;14913.403463861541;14925.477608283658;14932.178730169837;12394.380280985915;12930.612081637471;13487.460501579924;13526.256055916921;14234.857651423457;11986.520539178422;12288;12404.937975310157;12449.626511204679;12870.356482175854;3993.900152496182;4464.352668862996;4486.641068714495;4492.056668099703;4675.672702910763;116244;119036;122147;123362;124331;9017.954910225408;10713.83929241062;10791;10796.622118225825;11200.831987520194;20794.48013799667;20873.791262087183;20982.475438113863;21061.89469052645;21253.68119478209;7601.163871974079;7693;7704.922950770532;7711.614419279013;7905.249001344863;4183.6234738873645;4196.853110141131;4229.746215227096;4251.766152861623;4360.018995726;3326.2183386904057;3440.8795692151157;3441.3117376524797;3444.070101072696;3444.9827750861587;608.8112685067643;610.9572329936861;611.0254144639293;614.6803662095685;625.3277726443999;540.8539694282522;562.4488001758218;598.7545106506311;612.895807712681;617.586217234454;8533.74398768038;8685.392022558486;8728.519931403707;8852.159044890854;9214.63141474347;570.8934033416679;573.2032474859865;620.1287191495956;622.6295354264206;637.8086574027866;4294.227039132986;4382.233109205877;4385.51759306476;4420.911581768347;4517.75152366623;105578.47210763897;124077;128011;131931.34034329766;139215;60611.39388606058;119456;122040.38979805044;125441;128142.35928820296;103146.45280320801;111822;112533.43733281444;113608.43195783968;114759;8920.598573064357;9500.524973751146;9605.37565058269;9752;9770.120689138012;37983.620163798005;55159.72420137873;56887.71556142193;57281.71359143178;63089;81029;95118.52440737752;96230;97854;98414;12362.567310144103;12573;12635.241885486901;12940;13151.473941042252;14492.420303187755;14551.854481455473;14557;14709.92645036768;15229.771553426928;13432.529861454803;13545.932270338586;13557.525486607921;13843.584692459242;13998;13148.73702525925;13306.600801975956;13340.933295333462;13383.665408364868;13428.462861485432;13069.934650326688;13081.934590326988;13149.21104733719;13737.793933090812;13772.10481318711;10143.543540540546;10968.341899486057;11043.889561104286;11132.276402033842;11409.60066397672;10988.230823842574;11054.353383869362;11432.771344573228;11511.251768635011;11533.135014873751 04:40:58 INFO - PROCESS | 1656 | __end_tp_report 04:40:58 INFO - PROCESS | 1656 | __start_cc_report 04:40:58 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1615 04:40:58 INFO - PROCESS | 1656 | __end_cc_report 04:40:58 INFO - PROCESS | 1656 | __startTimestamp1475667658586__endTimestamp 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | ------- Summary: start ------- 04:40:58 INFO - PROCESS | 1656 | Number of tests: 6 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5590.92 Median:5761.63 stddev:615.21 (10.7%) stddev-sans-first:588.56 04:40:58 INFO - PROCESS | 1656 | Values: 3230.3 3264.4 3278.9 3280.1 3328.6 5468.9 5540.8 5571.9 5597.5 5628.6 5504.1 5563.2 5563.5 5565.6 5575.2 5072.7 5104.4 5131.6 5140.0 5181.6 5105.6 5181.6 5191.8 5194.0 5202.2 5077.3 5137.7 5146.6 5170.8 5188.5 4466.0 5151.1 5161.9 5162.6 5178.0 4983.9 4987.5 5042.1 5086.2 5086.7 5445.2 5455.1 5510.3 5534.5 5555.3 6015.5 6021.3 6023.4 6026.8 6034.5 5932.2 6040.4 6049.0 6113.5 6113.9 5548.2 5598.7 5602.8 5614.9 5682.5 6183.1 6199.9 6204.3 6214.8 6215.2 5810.2 5849.7 5866.5 5878.6 5887.1 5873.4 5931.0 5975.1 6000.2 6051.1 5940.5 5983.3 5993.4 6004.8 6032.6 6009.8 6018.5 6033.1 6042.4 6056.1 4724.7 4773.6 4783.2 4786.4 4812.0 4297.8 4337.4 4342.0 4355.1 4396.9 5575.7 5615.4 5626.7 5635.6 5662.2 5927.7 6127.8 6131.5 6146.5 6167.1 5460.4 5567.0 5586.9 5589.8 5603.0 5267.9 5277.1 5284.8 5320.9 5335.7 6319.5 6337.1 6337.2 6351.7 6352.2 5926.3 6032.5 6054.4 6138.0 6149.1 6183.9 6202.5 6269.3 6273.7 6290.9 5941.9 5949.3 5950.0 5955.3 6013.1 5985.5 6035.4 6064.8 6097.8 6107.4 5737.1 5805.9 5810.1 5815.0 5861.1 5721.7 5871.9 5873.5 5875.5 5878.3 5637.5 5743.1 5745.0 5761.6 5781.3 5746.0 5798.4 5835.2 5867.0 5872.8 5724.0 5770.9 5781.2 5834.7 5848.4 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68754.48 Median:39866.90 stddev:149928.54 (376.1%) stddev-sans-first:150261.48 04:40:58 INFO - PROCESS | 1656 | Values: 475.0 513.9 513.9 530.9 554.1 30912.8 39590.6 40853.2 40954.8 41066.6 41961.4 42240.8 42383.8 42386.9 42696.6 9599.9 9822.7 9826.8 9830.9 9856.2 9598.9 9696.5 9697.0 9697.0 9697.6 9648.8 9739.3 9744.3 9763.4 9765.0 9622.3 9679.9 9685.3 9698.1 9701.2 9673.1 9750.5 9752.3 9769.4 9780.4 39437.6 39782.4 39840.0 39893.8 39935.2 3254.9 3301.3 3311.5 3323.3 3417.8 28228.0 30603.4 30677.0 31458.4 31792.8 3098.0 3284.2 3309.0 3414.0 3416.3 481414.0 484510.0 484524.0 485196.0 495917.0 57383.0 60279.7 60599.0 60973.0 60977.4 63249.0 63252.4 63278.0 63345.7 63384.0 15541.3 15740.7 16212.0 16246.0 16607.3 12334.6 12432.9 12466.6 12469.9 12480.6 15080.9 18681.3 18706.6 18733.8 18775.5 6363.7 7509.7 7612.5 7650.7 7711.8 711.3 878.3 946.8 1029.0 1048.5 28376.1 29710.7 30094.2 30123.8 30575.4 1036.6 1091.2 1113.9 1131.2 1146.6 15348.4 15554.5 15593.4 15654.0 16023.7 667394.0 668740.0 673825.6 673859.0 677506.0 56537.7 57044.7 57147.7 57218.4 57227.0 1228.5 1239.0 1260.6 1298.2 1373.5 24415.1 28854.0 29662.6 30708.0 31160.8 47932.5 48702.5 48731.3 48775.5 48859.0 502042.0 503199.0 505821.0 506923.0 508068.0 23172.2 23720.8 23924.3 24768.9 26644.9 45827.5 45964.3 46136.8 46485.3 47011.0 38338.0 38777.6 38897.8 39802.8 40117.4 37513.0 38003.7 38256.8 39170.8 39781.8 37001.4 37245.3 37992.2 38050.6 38098.0 28486.7 32211.5 32400.8 33955.8 34407.5 24737.8 25238.0 25266.4 25396.9 25907.7 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33471.12 Median:12975.03 stddev:93506.34 (720.7%) stddev-sans-first:93735.61 04:40:58 INFO - PROCESS | 1656 | Values: 365.3 367.6 373.3 377.6 398.2 19426.3 20509.9 20900.6 20931.5 21132.8 20759.3 20923.4 20995.7 21178.0 21274.0 5802.4 5803.8 6081.8 6119.6 6186.7 3895.2 6150.3 6216.5 6224.2 6233.8 4018.9 4094.0 4119.0 6703.6 6787.5 5120.9 6733.6 6750.8 6767.5 6772.6 5675.8 5758.4 5769.2 5773.8 5788.0 24545.8 25134.7 25494.7 25500.0 25584.7 2099.7 2118.8 2178.9 2227.0 2297.4 9881.5 9976.2 10206.6 10264.8 14933.3 1824.2 1915.5 1918.7 1938.2 1954.8 103213.5 111011.0 120080.0 123143.9 123621.4 11144.5 11155.5 11381.3 11652.8 11680.6 14074.9 14091.8 14430.1 14547.6 14597.8 8420.5 8551.4 8552.8 8554.2 8746.3 8422.9 8484.6 8620.0 8642.0 8660.1 4452.2 4523.4 4533.8 4538.2 4610.4 1830.3 1858.2 1919.4 1947.4 1963.4 384.2 385.2 839.4 929.4 1010.6 10334.7 11262.8 11564.9 11802.8 12056.2 409.8 424.4 428.5 435.0 438.2 4784.6 5968.8 6000.2 8999.1 10825.7 520888.0 524600.0 569697.0 575154.0 597520.0 93475.0 120263.0 123940.0 134474.0 136558.6 995.6 1009.2 1055.8 1058.3 1059.5 19295.5 19557.6 19685.9 19697.6 19803.5 54385.0 54477.5 54600.5 55077.4 56224.7 72041.9 78489.0 78804.2 81646.0 82500.8 7262.6 7770.5 7984.4 8378.5 8474.0 9656.8 12912.7 12979.9 13417.3 13447.5 10781.9 11088.6 11192.5 11748.0 11900.0 10815.7 11389.2 11469.3 11624.1 11835.9 11251.9 11459.8 11990.9 11993.6 12104.2 8088.7 8251.5 8288.1 8330.4 8365.8 9576.4 11232.0 11760.9 11775.6 11966.6 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5805.44 Median:4920.27 stddev:3340.72 (67.9%) stddev-sans-first:3334.51 04:40:58 INFO - PROCESS | 1656 | Values: 1511.5 1543.4 1625.5 1710.5 1830.8 8723.9 9293.7 9307.4 9319.0 9324.8 8141.9 8374.5 8381.4 8474.6 9033.7 1860.0 2145.1 2186.5 2374.3 2376.4 2371.3 2379.9 2380.9 2383.8 2388.1 2245.3 2288.9 2290.4 2293.2 2296.8 2322.4 2330.9 2349.1 2349.9 2354.7 2307.9 2326.1 2331.7 2340.6 2342.5 8948.0 9005.6 9057.1 9059.0 9070.9 5299.8 5500.8 5503.4 5523.1 5527.3 3534.9 3647.1 3741.6 3742.9 3743.3 1040.3 1088.7 1112.5 1112.8 1113.9 12050.2 12246.9 12268.0 12272.4 12280.2 4478.8 4674.5 4873.2 4887.4 4897.1 10148.8 10348.7 10348.7 10359.3 10360.8 5485.0 5493.8 5494.5 5512.3 5517.1 4950.9 4968.2 4977.4 5005.9 5032.9 3455.2 3691.4 3829.2 3838.1 3879.1 1265.1 1273.6 1273.6 1284.1 1287.7 669.9 878.9 892.7 916.7 919.0 4589.0 4658.5 5074.7 6039.6 6121.4 929.3 937.9 942.8 960.0 982.9 3496.4 3924.5 3990.0 4037.2 4145.9 10715.4 11243.0 11273.0 11280.0 11286.4 11170.8 11191.3 11195.8 11199.4 11206.6 8315.4 8359.7 8379.3 8522.1 8523.4 4943.4 5754.7 5950.7 5972.8 6030.5 10192.8 10367.6 10397.5 10474.6 10503.5 9928.6 10431.3 10454.2 10557.4 10604.3 7729.7 7876.4 7893.5 7897.2 7905.6 7604.8 7939.5 7954.3 8128.1 8482.3 7432.4 7553.0 7577.5 7598.4 7746.2 7490.0 7535.1 7556.1 7558.8 7589.5 5360.7 6334.7 6786.8 7083.8 7171.4 5015.2 5040.3 5060.0 5099.5 5202.7 5015.5 5090.2 5136.2 5189.6 5214.3 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4468.90 Median:4837.46 stddev:1359.92 (28.1%) stddev-sans-first:1327.31 04:40:58 INFO - PROCESS | 1656 | Values: 303.4 303.8 312.8 317.9 320.1 5100.5 5309.5 5328.8 5350.9 5354.2 5138.7 5181.0 5207.0 5252.0 5290.0 4095.6 4095.8 4103.3 4140.4 4145.9 3602.6 3892.0 3916.2 4109.4 4118.5 3857.9 3948.6 3963.6 4028.4 4081.2 3896.1 4054.9 4066.2 4068.2 4068.7 3568.1 3570.0 3571.7 3575.1 3582.4 4076.6 4304.8 4725.0 5269.8 5275.7 4578.6 4619.4 4663.3 4679.2 4698.6 4171.8 4395.8 5502.9 5640.4 5655.0 3929.2 3935.0 3947.0 3960.2 3964.2 4849.0 5042.8 5315.3 5575.6 5786.9 3000.3 3571.9 3975.6 3984.6 4009.7 3375.7 4042.0 4050.2 4052.4 4060.8 4496.8 4643.8 4675.7 4682.4 4702.0 5591.7 5598.3 5623.6 5641.9 5648.8 1213.7 1264.4 1353.9 1354.3 1385.6 770.2 784.5 808.3 846.7 857.3 3976.2 4011.4 4059.7 4434.4 4459.7 4740.3 4767.8 4774.3 4812.1 4870.9 4136.3 4440.0 4572.6 5047.3 5240.3 2977.6 3006.4 3023.8 3024.8 3032.5 4976.0 5762.5 5845.3 5912.6 5952.4 5836.6 5843.5 5866.3 5874.8 5919.4 4912.8 5186.5 5838.7 5912.6 5962.8 5227.3 5378.0 5658.8 5670.5 5747.5 5587.9 5641.2 5647.9 5659.6 5662.3 4903.8 5630.2 5950.9 5957.6 5965.7 3339.3 3765.5 3993.4 4027.9 4031.4 5732.0 5736.0 5757.6 5773.0 5799.6 5188.7 5239.6 5431.6 5648.2 5650.3 4190.1 4750.5 4825.9 5507.3 5553.7 4520.8 4867.8 5438.8 5456.7 5496.3 5118.4 5569.4 5612.4 5628.6 5654.8 5187.0 5404.8 5433.2 5470.8 5479.7 04:40:58 INFO - PROCESS | 1656 | 04:40:58 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:23829.71 Median:3442.69 stddev:37510.62 (1089.6%) stddev-sans-first:37575.63 04:40:58 INFO - PROCESS | 1656 | Values: 696.3 700.6 739.6 753.4 805.8 15125.3 15673.2 15721.6 15735.9 15752.2 13966.2 14063.3 14068.7 14099.3 14099.5 675.7 696.7 702.0 838.4 863.9 856.4 872.7 873.6 874.2 876.0 920.3 922.5 923.2 924.4 926.0 714.5 730.7 883.3 916.0 917.7 685.5 686.9 687.6 687.8 689.7 14868.0 14904.7 14913.4 14925.5 14932.2 12394.4 12930.6 13487.5 13526.3 14234.9 11986.5 12288.0 12404.9 12449.6 12870.4 3993.9 4464.4 4486.6 4492.1 4675.7 116244.0 119036.0 122147.0 123362.0 124331.0 9018.0 10713.8 10791.0 10796.6 11200.8 20794.5 20873.8 20982.5 21061.9 21253.7 7601.2 7693.0 7704.9 7711.6 7905.2 4183.6 4196.9 4229.7 4251.8 4360.0 3326.2 3440.9 3441.3 3444.1 3445.0 608.8 611.0 611.0 614.7 625.3 540.9 562.4 598.8 612.9 617.6 8533.7 8685.4 8728.5 8852.2 9214.6 570.9 573.2 620.1 622.6 637.8 4294.2 4382.2 4385.5 4420.9 4517.8 105578.5 124077.0 128011.0 131931.3 139215.0 60611.4 119456.0 122040.4 125441.0 128142.4 103146.5 111822.0 112533.4 113608.4 114759.0 8920.6 9500.5 9605.4 9752.0 9770.1 37983.6 55159.7 56887.7 57281.7 63089.0 81029.0 95118.5 96230.0 97854.0 98414.0 12362.6 12573.0 12635.2 12940.0 13151.5 14492.4 14551.9 14557.0 14709.9 15229.8 13432.5 13545.9 13557.5 13843.6 13998.0 13148.7 13306.6 13340.9 13383.7 13428.5 13069.9 13081.9 13149.2 13737.8 13772.1 10143.5 10968.3 11043.9 11132.3 11409.6 10988.2 11054.4 11432.8 11511.3 11533.1 04:40:58 INFO - PROCESS | 1656 | -------- Summary: end -------- 04:40:58 INFO - PROCESS | 1656 | 04:40:59 INFO - TEST-INFO | 1656: exit 0 04:40:59 INFO - TEST-OK | dromaeo_css | took 1119097ms 04:40:59 INFO - TEST-START | kraken 04:40:59 INFO - operating with platform_type : mac_ 04:40:59 INFO - Initialising browser for kraken test... 04:40:59 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpGNkkjv/profile http://localhost:49277/getInfo.html) 04:41:01 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:41:01 INFO - PROCESS | 1667 | colorDepth:24 04:41:01 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:41:01 INFO - PROCESS | 1667 | __metrics 04:41:01 INFO - PROCESS | 1667 | 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" 04:41:01 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:41:02 INFO - TEST-INFO | 1667: exit 0 04:41:02 INFO - Browser initialized. 04:41:02 INFO - Running cycle 1/1 for kraken test... 04:41:02 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpGNkkjv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:14 INFO - PROCESS | 1668 | RSS: Main: 236052480 04:41:14 INFO - PROCESS | 1668 | 04:41:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:41:17 INFO - PROCESS | 1668 | RSS: Main: 294776832 04:41:17 INFO - PROCESS | 1668 | 04:41:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:41:20 INFO - PROCESS | 1668 | RSS: Main: 293715968 04:41:20 INFO - PROCESS | 1668 | 04:41:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:41:25 INFO - PROCESS | 1668 | RSS: Main: 382611456 04:41:25 INFO - PROCESS | 1668 | 04:41:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:41:28 INFO - PROCESS | 1668 | RSS: Main: 285106176 04:41:28 INFO - PROCESS | 1668 | 04:41:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:41:30 INFO - PROCESS | 1668 | RSS: Main: 290525184 04:41:30 INFO - PROCESS | 1668 | 04:41:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:41:34 INFO - PROCESS | 1668 | RSS: Main: 327417856 04:41:34 INFO - PROCESS | 1668 | 04:41:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:41:37 INFO - PROCESS | 1668 | RSS: Main: 329805824 04:41:37 INFO - PROCESS | 1668 | 04:41:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:41:41 INFO - PROCESS | 1668 | RSS: Main: 330170368 04:41:41 INFO - PROCESS | 1668 | 04:41:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:41:43 INFO - PROCESS | 1668 | RSS: Main: 302813184 04:41:43 INFO - PROCESS | 1668 | 04:41:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:41:46 INFO - PROCESS | 1668 | RSS: Main: 305078272 04:41:46 INFO - PROCESS | 1668 | 04:41:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:41:49 INFO - PROCESS | 1668 | RSS: Main: 309833728 04:41:49 INFO - PROCESS | 1668 | 04:41:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:41:53 INFO - PROCESS | 1668 | RSS: Main: 319668224 04:41:53 INFO - PROCESS | 1668 | 04:41:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:41:56 INFO - PROCESS | 1668 | RSS: Main: 319352832 04:41:56 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:41:58 INFO - PROCESS | 1668 | RSS: Main: 363163648 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | __start_tp_report 04:41:58 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 04:41:58 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 04:41:58 INFO - PROCESS | 1668 | |i|pagename|runs| 04:41:58 INFO - PROCESS | 1668 | |0;ai-astar;112;113;114;112;112;112;111;114;112;111 04:41:58 INFO - PROCESS | 1668 | |1;audio-beat-detection;172;143;113;148;114;113;113;112;145;114 04:41:58 INFO - PROCESS | 1668 | |2;audio-dft;182;182;184;199;261;183;204;260;183;183 04:41:58 INFO - PROCESS | 1668 | |3;audio-fft;80;60;49;85;70;75;82;90;71;75 04:41:58 INFO - PROCESS | 1668 | |4;audio-oscillator;92;71;91;69;69;70;92;67;75;121 04:41:58 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;88;85;88;85;87;85;87;86;88;86 04:41:58 INFO - PROCESS | 1668 | |6;imaging-darkroom;98;97;117;169;115;154;164;97;96;96 04:41:58 INFO - PROCESS | 1668 | |7;imaging-desaturate;83;86;85;85;105;135;141;87;85;83 04:41:58 INFO - PROCESS | 1668 | |8;json-parse-financial;54;54;55;54;54;53;55;54;54;54 04:41:58 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;74;66;79;74;73;68;69;69;69 04:41:58 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;90;89;89;99;105;109;103;86;92;87 04:41:58 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;146;146;145;145;172;139;155;158;209;147 04:41:58 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;174;184;174;179;174;174;175;174;174;174 04:41:58 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;70;70;69;69;68;68;68;70;68 04:41:58 INFO - PROCESS | 1668 | __end_tp_report 04:41:58 INFO - PROCESS | 1668 | __start_cc_report 04:41:58 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2086 04:41:58 INFO - PROCESS | 1668 | __end_cc_report 04:41:58 INFO - PROCESS | 1668 | __startTimestamp1475667718562__endTimestamp 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | ------- Summary: start ------- 04:41:58 INFO - PROCESS | 1668 | Number of tests: 14 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 04:41:58 INFO - PROCESS | 1668 | Values: 112.0 113.0 114.0 112.0 112.0 112.0 111.0 114.0 112.0 111.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:128.50 stddev:21.52 (16.7%) stddev-sans-first:16.14 04:41:58 INFO - PROCESS | 1668 | Values: 172.0 143.0 113.0 148.0 114.0 113.0 113.0 112.0 145.0 114.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:202.10 Median:191.50 stddev:31.74 (16.6%) stddev-sans-first:32.82 04:41:58 INFO - PROCESS | 1668 | Values: 182.0 182.0 184.0 199.0 261.0 183.0 204.0 260.0 183.0 183.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:73.70 Median:77.50 stddev:12.13 (15.7%) stddev-sans-first:12.65 04:41:58 INFO - PROCESS | 1668 | Values: 80.0 60.0 49.0 85.0 70.0 75.0 82.0 90.0 71.0 75.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:73.00 stddev:17.25 (23.6%) stddev-sans-first:17.89 04:41:58 INFO - PROCESS | 1668 | Values: 92.0 71.0 91.0 69.0 69.0 70.0 92.0 67.0 75.0 121.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:1.27 (1.5%) stddev-sans-first:1.22 04:41:58 INFO - PROCESS | 1668 | Values: 88.0 85.0 88.0 85.0 87.0 85.0 87.0 86.0 88.0 86.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:120.30 Median:96.00 stddev:30.22 (31.5%) stddev-sans-first:30.96 04:41:58 INFO - PROCESS | 1668 | Values: 98.0 97.0 117.0 169.0 115.0 154.0 164.0 97.0 96.0 96.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:85.00 stddev:22.33 (26.3%) stddev-sans-first:23.06 04:41:58 INFO - PROCESS | 1668 | Values: 83.0 86.0 85.0 85.0 105.0 135.0 141.0 87.0 85.0 83.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 04:41:58 INFO - PROCESS | 1668 | Values: 54.0 54.0 55.0 54.0 54.0 53.0 55.0 54.0 54.0 54.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:4.45 (6.3%) stddev-sans-first:4.06 04:41:58 INFO - PROCESS | 1668 | Values: 64.0 74.0 66.0 79.0 74.0 73.0 68.0 69.0 69.0 69.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:94.90 Median:89.00 stddev:8.35 (9.4%) stddev-sans-first:8.66 04:41:58 INFO - PROCESS | 1668 | Values: 90.0 89.0 89.0 99.0 105.0 109.0 103.0 86.0 92.0 87.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:151.00 stddev:20.77 (13.8%) stddev-sans-first:21.70 04:41:58 INFO - PROCESS | 1668 | Values: 146.0 146.0 145.0 145.0 172.0 139.0 155.0 158.0 209.0 147.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:174.00 stddev:3.34 (1.9%) stddev-sans-first:3.49 04:41:58 INFO - PROCESS | 1668 | Values: 174.0 184.0 174.0 179.0 174.0 174.0 175.0 174.0 174.0 174.0 04:41:58 INFO - PROCESS | 1668 | 04:41:58 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 04:41:58 INFO - PROCESS | 1668 | Values: 68.0 70.0 70.0 69.0 69.0 68.0 68.0 68.0 70.0 68.0 04:41:58 INFO - PROCESS | 1668 | -------- Summary: end -------- 04:41:58 INFO - PROCESS | 1668 | 04:41:59 INFO - TEST-INFO | 1668: exit 0 04:41:59 INFO - TEST-OK | kraken | took 60319ms 04:41:59 INFO - SUITE-END | took 1179s 04:41:59 INFO - Completed test suite (00:19:39) 04:41:59 INFO - javascript benchmark 04:41:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.2731885325807, 3264.412405766962, 3278.9016329510123, 3280.1307653471818, 3328.5839270091237, 5468.863278418032, 5540.833774986751, 5571.86070348241, 5597.468240517144, 5628.577856660746, 5504.119340905456, 5563.165525171216, 5563.527100196488, 5565.6104072714925, 5575.191597218401, 5072.695638261716, 5104.438511763703, 5131.563817075535, 5140.023395554851, 5181.6113791465605, 5105.591552675795, 5181.559567436772, 5191.818286359978, 5193.974030129844, 5202.219667049935, 5077.339945807058, 5137.717425541594, 5146.588272938156, 5170.767315470813, 5188.455212202721, 4465.972826249948, 5151.124308867464, 5161.9483805162035, 5162.638615296931, 5177.974110129463, 4983.9252411213865, 4987.526185012418, 5042.117629414838, 5086.21163719624, 5086.720230387327, 5445.237666726622, 5455.099908515091, 5510.311211098613, 5534.501894829445, 5555.250041244421, 6015.488683461911, 6021.337652858182, 6023.427774361428, 6026.819195424145, 6034.487068599175, 5932.169496270526, 6040.395960403968, 6048.96975515124, 6113.510919126459, 6113.877722445571, 5548.167774833807, 5598.720063996784, 5602.7758889644365, 5614.9438505615235, 5682.54539636828, 6183.072539119168, 6199.938000620026, 6204.286507051746, 6214.844628884314, 6215.160953271251, 5810.157527158538, 5849.736761845642, 5866.530677545786, 5878.588498805128, 5887.116932460165, 5873.442023007893, 5930.970345148246, 5975.133605627247, 6000.189974353494, 6051.062085376773, 5940.46535811779, 5983.341832398432, 5993.370696076936, 6004.819855404345, 6032.577719559677, 6009.759609615654, 6018.548608854353, 6033.064874944391, 6042.3655516170165, 6056.06131049688, 4724.692894961817, 4773.618110551148, 4783.162946484351, 4786.353842231324, 4811.975940120277, 4297.8495752648505, 4337.392765012901, 4341.957930096817, 4355.1289742051085, 4396.9120617588105, 5575.693336866511, 5615.438456154434, 5626.718664066781, 5635.605507614509, 5662.178984047372, 5927.73325200367, 6127.754889804358, 6131.526791107749, 6146.494108943338, 6167.074938759222, 5460.372057213392, 5567.0, 5586.888262234814, 5589.832305030855, 5602.971985140048, 5267.920981185285, 5277.076511610453, 5284.788608455696, 5320.946790532123, 5335.733213339395, 6319.494440444661, 6337.112804207414, 6337.2395312562785, 6351.650659213788, 6352.205974253218, 5926.288845338587, 6032.54755893301, 6054.424829641177, 6137.96931015342, 6149.108379285068, 6183.938160618336, 6202.503799695924, 6269.279032911274, 6273.74905003813, 6290.905636415366, 5941.91087133702, 5949.315828679757, 5950.047992321381, 5955.344912059583, 6013.128096426081, 5985.4912332450485, 6035.36628653985, 6064.757409703739, 6097.75608975636, 6107.4197951194565, 5737.139429085671, 5805.883882322245, 5810.070388737781, 5815.04051831443, 5861.120831875082, 5721.685307308138, 5871.853203669943, 5873.530117590668, 5875.529957603467, 5878.29460464747, 5637.520810731134, 5743.1385292206505, 5745.0, 5761.625494342854, 5781.335146458043, 5746.023176060164, 5798.420157984167, 5835.241418615553, 5866.970665146647, 5872.765089396547, 5724.0, 5770.855728606818, 5781.161731548817, 5834.708264586839, 5848.356680765198], "value": 5551.282480988303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.9857504274869, 513.8797421620867, 513.9152039913414, 530.8858595401988, 554.1134185303515, 30912.84543577282, 39590.60409395905, 40853.182936341254, 40954.7952260239, 41066.58933410665, 41961.37057944133, 42240.788796056055, 42383.78808105955, 42386.94032649185, 42696.57303426965, 9599.904000959988, 9822.656207032755, 9826.754331141707, 9830.90169098307, 9856.162226210745, 9598.904010959906, 9696.46669433181, 9697.030296970283, 9697.030296970317, 9697.563609637584, 9648.75878103046, 9739.318247722662, 9744.317897747125, 9763.365381250196, 9765.0, 9622.326437149402, 9679.903200968041, 9685.273604479693, 9698.127168554793, 9701.175400091, 9673.129418352311, 9750.512474376254, 9752.26857985654, 9769.413835169842, 9780.364276322087, 39437.60562394368, 39782.40326395106, 39840.0, 39893.80053099745, 39935.201295974206, 3254.886078987248, 3301.3067255876294, 3311.4536101543217, 3323.2854936188837, 3417.8120203388803, 28228.012019579422, 30603.387932241454, 30677.0, 31458.37083258345, 31792.841035794903, 3098.0396077216137, 3284.1953721338423, 3308.9503657445157, 3414.0, 3416.333814906094, 481414.0, 484510.0, 484523.99999999645, 485195.99999999645, 495917.0, 57383.0, 60279.69860150671, 60599.0, 60973.0, 60977.39022609806, 63249.0, 63252.367476324645, 63278.0, 63345.683271583344, 63384.0, 15541.300641471162, 15740.68518629621, 16212.027278363335, 16246.0, 16607.335706571845, 12334.629961101182, 12432.875671243171, 12466.626001219978, 12469.937650311871, 12480.625581232578, 15080.924595377171, 18681.252749890125, 18706.625867482846, 18733.81266187348, 18775.530611734815, 6363.745450182034, 7509.7371591994015, 7612.543247405173, 7650.732224372232, 7711.807204819812, 711.3029231353199, 878.3149143667949, 946.7727745341206, 1028.9624710944836, 1048.496721573649, 28376.148715538566, 29710.702892971225, 30094.24764380864, 30123.849380752956, 30575.38849223003, 1036.616451912782, 1091.2088735666578, 1113.933164010162, 1131.191198293208, 1146.644540192543, 15348.386064557293, 15554.533363999099, 15593.376264949502, 15653.98249113804, 16023.67952640964, 667394.0, 668740.0, 673825.6308718523, 673859.0, 677506.0, 56537.71731141318, 57044.714776426685, 57147.714261428424, 57218.42781572214, 57226.98527757709, 1228.502456505117, 1238.9690257743628, 1260.6407173955379, 1298.1626850681068, 1373.4506197520873, 24415.145469908468, 28854.0, 29662.555061673662, 30708.0, 31160.844195778875, 47932.5206747928, 48702.51297487121, 48731.269030965275, 48775.51224487852, 48859.0, 502042.0, 503199.0, 505821.0, 506923.0, 508068.0, 23172.18897338585, 23720.762792371854, 23924.282271531883, 24768.876155619106, 26644.934202631677, 45827.54172458233, 45964.31053534133, 46136.76931615321, 46485.28099910821, 47011.0, 38338.0, 38777.6122238774, 38897.80551097226, 39802.80098599489, 40117.39823902585, 37513.0, 38003.66987155436, 38256.80871595624, 39170.804145980226, 39781.80109099436, 37001.44497832588, 37245.25509489849, 37992.24015519619, 38050.619493804705, 38098.047548811504, 28486.715132848407, 32211.51682724808, 32400.83799580987, 33955.81154659575, 34407.483887741204, 24737.752622474265, 25237.99048038058, 25266.36834079163, 25396.87301563554, 25907.740922591285], "value": 19730.047899995017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.32050386281526, 367.6323676323676, 373.30751456049046, 377.6223776223777, 398.23141337219175, 19426.320078797246, 20509.897450512763, 20900.58198836024, 20931.47671308216, 21132.788672113275, 20759.273425430074, 20923.37229883106, 20995.685064724003, 21178.0, 21274.0, 5802.448767367094, 5803.767849286023, 6081.756729730805, 6119.632822030692, 6186.690665466731, 3895.182011777516, 6150.261968563756, 6216.471599914013, 6224.159738435321, 6233.750649973996, 4018.8794336169817, 4093.9795301023455, 4119.0, 6703.597784132943, 6787.4570034397375, 5120.871978200537, 6733.562318449285, 6750.763723269686, 6767.526273160881, 6772.627505487197, 5675.801346952837, 5758.395368486333, 5769.249997500298, 5773.797917072924, 5787.971060144714, 24545.772711364538, 25134.67134223685, 25494.74505254961, 25500.0, 25584.744152558607, 2099.737532808399, 2118.7987141221715, 2178.923737669174, 2226.955460890773, 2297.425643589103, 9881.505924703735, 9976.152027077707, 10206.591736330538, 10264.846027309597, 14933.328000240015, 1824.2064701854736, 1915.5402703351235, 1918.7121931710215, 1938.2440848069264, 1954.775200851892, 103213.48393257985, 111011.0, 120080.0, 123143.91704746615, 123621.38189309176, 11144.498497567629, 11155.498002589902, 11381.317120972768, 11652.825207621892, 11680.649580512598, 14074.929625352013, 14091.78862317066, 14430.134191948517, 14547.636309092357, 14597.78103328451, 8420.4947703138, 8551.401401901932, 8552.828943421096, 8554.18735220153, 8746.344024198082, 8422.873656895152, 8484.575771211537, 8619.956900215459, 8642.049374568793, 8660.133986601415, 4452.198604251266, 4523.411956445707, 4533.795979180944, 4538.1831270370985, 4610.40064791575, 1830.3044842959857, 1858.200973581373, 1919.3570153998287, 1947.3963071447897, 1963.3717210492505, 384.2085304273206, 385.1930206217969, 839.3830534557096, 929.4376901974208, 1010.6311196413403, 10334.659443033945, 11262.831057534144, 11564.884351156381, 11802.822957655642, 12056.21634593766, 409.82787229363737, 424.35921758145423, 428.52433798483656, 434.98912527187076, 438.2155940865878, 4784.569388755029, 5968.820935371946, 6000.159977603139, 8999.145081217404, 10825.729356766144, 520888.0, 524600.0, 569697.0, 575154.0, 597520.0, 93475.0, 120263.0, 123940.0, 134474.0, 136558.63441365657, 995.5818556206556, 1009.1926458832904, 1055.8046761349021, 1058.2645061682058, 1059.5285098131412, 19295.51761205981, 19557.60884782325, 19685.901570492057, 19697.606047878675, 19803.504912377306, 54385.0, 54477.45522544724, 54600.453995461125, 55077.44922550723, 56224.71887640536, 72041.91937121052, 78489.0, 78804.21195787968, 81646.0, 82500.76248856152, 7262.600556969417, 7770.494917830323, 7984.361251100015, 8378.53918034514, 8473.95763021181, 9656.806863862825, 12912.677183070498, 12979.935100324816, 13417.262050587311, 13447.529336473177, 10781.9460902695, 11088.5564577416, 11192.496337664661, 11748.0, 11900.0, 10815.729606759895, 11389.202755807013, 11469.31184128955, 11624.128190385585, 11835.940820296188, 11251.943740281245, 11459.770804584028, 11990.940045299718, 11993.64019079429, 12104.152709310616, 8088.676452941816, 8251.463654862415, 8288.129746376784, 8330.375221858507, 8365.790855228668, 9576.425414475154, 11232.045276151619, 11760.941195294312, 11775.646730598095, 11966.64100076999], "value": 9089.115931959484, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1511.4860947277923, 1543.3595058050905, 1625.5367220342205, 1710.461204720513, 1830.8374182394184, 8723.869141962869, 9293.721188364352, 9307.441553506795, 9318.953405232982, 9324.766880827983, 8141.91858081419, 8374.455660382071, 8381.413301068927, 8474.576271186432, 9033.72898813035, 1859.981400186001, 2145.0883374565824, 2186.4643162425227, 2374.3351861478814, 2376.3583832365252, 2371.3241726108063, 2379.9286021419384, 2380.880955952204, 2383.833131680783, 2388.0805889732405, 2245.3264020793777, 2288.9198878039188, 2290.3930458428445, 2293.174457195408, 2296.8047715944167, 2322.442613772699, 2330.8951097200666, 2349.1308215960034, 2349.894254758523, 2354.717433907942, 2307.907683692667, 2326.069572171128, 2331.708536432946, 2340.578695834749, 2342.543204075206, 8947.955260223722, 9005.639774409015, 9057.139571740674, 9058.954705226432, 9070.86393704095, 5299.814506492293, 5500.779968801243, 5503.422140675211, 5523.061079616475, 5527.309086364205, 3534.929301413957, 3647.1429214134655, 3741.5884252732176, 3742.925141497182, 3743.3074881146804, 1040.3341861208833, 1088.7223757941674, 1112.471576001401, 1112.7663190729957, 1113.9275947063495, 12050.216735912138, 12246.938765306206, 12268.018558515298, 12272.38638068093, 12280.201786883914, 4478.760619690155, 4674.509176536415, 4873.220284754422, 4887.437944636341, 4897.14299997499, 10148.797024059477, 10348.689539313833, 10348.741281468023, 10359.274850760525, 10360.792784144274, 5485.040117979417, 5493.7802487900835, 5494.532964697965, 5512.255845460892, 5517.1172612382625, 4950.851474455772, 4968.205087186033, 4977.377827771528, 5005.89988200234, 5032.949670503322, 3455.2398472336045, 3691.446283057509, 3829.1767270036516, 3838.05967537955, 3879.127196380848, 1265.1017777378165, 1273.5797186928291, 1273.6497463197509, 1284.101129209557, 1287.7488889666613, 669.9397054265139, 878.8813510176172, 892.7054072156172, 916.7295647783919, 918.9908100918905, 4588.9541104588525, 4658.510856360033, 5074.695518268842, 6039.637621742621, 6121.4490695837585, 929.3262384771122, 937.9390339628039, 942.8161508505775, 959.9760005999906, 982.886967998675, 3496.4056110461283, 3924.5486769021927, 3989.960100399017, 4037.152198038386, 4145.854895078658, 10715.35707857531, 11242.988131068243, 11272.985431311057, 11279.984801367917, 11286.379249141211, 11170.776584468427, 11191.32852028881, 11195.776084478264, 11199.440027998568, 11206.551737930555, 8315.37850119221, 8359.665613375397, 8379.329653627818, 8522.147785221428, 8523.445976011539, 4943.406791185081, 5754.712264386848, 5950.702464876652, 5972.761089556543, 6030.48740857032, 10192.796144077225, 10367.63713270032, 10397.480125993821, 10474.581016759244, 10503.527341269813, 9928.602855885683, 10431.269811113149, 10454.215933805148, 10557.366558006544, 10604.257701960789, 7729.729459468892, 7876.4092693047105, 7893.526388416713, 7897.210278972057, 7905.56519391439, 7604.80987975305, 7939.523628582073, 7954.284114429729, 8128.105908350195, 8482.279006284534, 7432.442566807403, 7552.962235188789, 7577.5453472791805, 7598.354139898171, 7746.225377462434, 7489.962550187214, 7535.058117735283, 7556.093268807558, 7558.811029724301, 7589.506682065648, 5360.70516121617, 6334.651594162366, 6786.84623613977, 7083.7520686775715, 7171.426285897011, 5015.17249653803, 5040.34475518195, 5059.974700126476, 5099.490050994871, 5202.73986300691, 5015.523525265094, 5090.236464530202, 5136.15253483186, 5189.558887494454, 5214.348206474191], "value": 4625.490960355478, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.4007834526809, 303.78431313767237, 312.7560502807809, 317.8633187729277, 320.0957295639818, 5100.464451232613, 5309.522143007129, 5328.8401347959625, 5350.919736203955, 5354.170103633934, 5138.7173705446185, 5180.989707007135, 5206.999999999991, 5252.0, 5290.0, 4095.6313931746135, 4095.815688294019, 4103.261412945676, 4140.399642051916, 4145.901336145924, 3602.6397360263895, 3892.0075380778003, 3916.2167566486673, 4109.363048727452, 4118.485189351331, 3857.9421308680394, 3948.644621984021, 3963.6036396360278, 4028.395740638913, 4081.1837632473475, 3896.103896103898, 4054.878353649395, 4066.2274167908004, 4068.1660259646997, 4068.6948478864206, 3568.1436455250814, 3569.964300356989, 3571.6964058055096, 3575.1419659281846, 3582.42681171014, 4076.613951256587, 4304.827806887721, 4724.984128412373, 5269.815556455505, 5275.657082289677, 4578.587927086578, 4619.3763841881255, 4663.300504924255, 4679.15775160471, 4698.553637404441, 4171.831887071625, 4395.846145384929, 5502.9174562381595, 5640.379558248589, 5655.0, 3929.1945151244213, 3934.980325098385, 3946.980265098685, 3960.2277555876617, 3964.226975739732, 4849.0, 5042.84871453857, 5315.309009828737, 5575.637583557056, 5786.88426231473, 3000.3099287164073, 3571.892713258898, 3975.6223158799867, 3984.601539845992, 4009.67922566194, 3375.729941604666, 4041.9692978290936, 4050.23045621331, 4052.4064598656882, 4060.7766572838186, 4496.775161241925, 4643.791029403617, 4675.672702910763, 4682.41469816273, 4701.979807264808, 5591.7483713233005, 5598.272224610774, 5623.594101474631, 5641.928033673589, 5648.774049038075, 1213.6905089202373, 1264.4246867675186, 1353.9323033848268, 1354.2686949047602, 1385.6328073060647, 770.1989930472358, 784.5057613703331, 808.2967817998261, 846.7205822078822, 857.2927334948693, 3976.151089743283, 4011.3782363733667, 4059.65492933104, 4434.445694288214, 4459.68096589778, 4740.288956656529, 4767.829393453315, 4774.328984855342, 4812.06164797868, 4870.8782280442565, 4136.296829538986, 4439.958628339597, 4572.565606267399, 5047.293378926953, 5240.292560504359, 2977.5856468177876, 3006.3836913433133, 3023.818570885754, 3024.8052019452193, 3032.453448741158, 4975.959856458834, 5762.4525670061275, 5845.327787304429, 5912.615679980788, 5952.375000624958, 5836.620619659709, 5843.503302219274, 5866.2749154286485, 5874.823755287348, 5919.4080591940465, 4912.828051018198, 5186.533212010937, 5838.744669895993, 5912.645241285537, 5962.821115366546, 5227.320448341691, 5378.0, 5658.801941932013, 5670.5180059695385, 5747.488221745909, 5587.8882422352135, 5641.15382692583, 5647.887042259214, 5659.575531835211, 5662.320521537277, 4903.754812259444, 5630.239917611234, 5950.910736339045, 5957.553183511167, 5965.731542080704, 3339.348826978716, 3765.45400916871, 3993.4209539617173, 4027.872195785185, 4031.3549832026733, 5732.0, 5736.013470637867, 5757.568182386254, 5772.9711351442975, 5799.623024503394, 5188.667462668714, 5239.611502951797, 5431.5926305526455, 5648.2092507049865, 5650.29371328584, 4190.099128687347, 4750.501197374226, 4825.927611085906, 5507.284053073075, 5553.666779993213, 4520.796564154555, 4867.829625963074, 5438.75525601341, 5456.727163641723, 5496.279341725712, 5118.43697193301, 5569.443055694398, 5612.382637909745, 5628.63413878113, 5654.773809047592, 5187.0, 5404.83785486436, 5433.239346491416, 5470.781168753205, 5479.726013699379], "value": 4034.8297232803266, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.3002182806279, 700.5516469459546, 739.5562662402556, 753.3709352690503, 805.8468890910726, 15125.319360628771, 15673.216339183053, 15721.619851731875, 15735.921320393381, 15752.212389380527, 13966.162030278165, 14063.296835158228, 14068.718625627482, 14099.295035248248, 14099.506517271897, 675.7465950268662, 696.7491702986946, 701.9961455119178, 838.375410319313, 863.8531449653574, 856.3534531428762, 872.6858331000834, 873.6112429968666, 874.2394117118084, 875.9562021898879, 920.3143657974819, 922.524899676667, 923.224491427201, 924.4407133684125, 925.9629614815399, 714.5105602662193, 730.6931088942657, 883.2977782662798, 915.97410899792, 917.7430319510547, 685.4721864164616, 686.8763622547939, 687.6321168175052, 687.8417963868288, 689.6930865764737, 14867.959242853005, 14904.70190596182, 14913.403463861541, 14925.477608283658, 14932.178730169837, 12394.380280985915, 12930.612081637471, 13487.460501579924, 13526.256055916921, 14234.857651423457, 11986.520539178422, 12288.0, 12404.937975310157, 12449.626511204679, 12870.356482175854, 3993.900152496182, 4464.352668862996, 4486.641068714495, 4492.056668099703, 4675.672702910763, 116244.0, 119036.0, 122147.0, 123362.0, 124331.0, 9017.954910225408, 10713.83929241062, 10791.0, 10796.622118225825, 11200.831987520194, 20794.48013799667, 20873.791262087183, 20982.475438113863, 21061.89469052645, 21253.68119478209, 7601.163871974079, 7693.0, 7704.922950770532, 7711.614419279013, 7905.249001344863, 4183.6234738873645, 4196.853110141131, 4229.746215227096, 4251.766152861623, 4360.018995726, 3326.2183386904057, 3440.8795692151157, 3441.3117376524797, 3444.070101072696, 3444.9827750861587, 608.8112685067643, 610.9572329936861, 611.0254144639293, 614.6803662095685, 625.3277726443999, 540.8539694282522, 562.4488001758218, 598.7545106506311, 612.895807712681, 617.586217234454, 8533.74398768038, 8685.392022558486, 8728.519931403707, 8852.159044890854, 9214.63141474347, 570.8934033416679, 573.2032474859865, 620.1287191495956, 622.6295354264206, 637.8086574027866, 4294.227039132986, 4382.233109205877, 4385.51759306476, 4420.911581768347, 4517.75152366623, 105578.47210763897, 124077.0, 128011.0, 131931.34034329766, 139215.0, 60611.39388606058, 119456.0, 122040.38979805044, 125441.0, 128142.35928820296, 103146.45280320801, 111822.0, 112533.43733281444, 113608.43195783968, 114759.0, 8920.598573064357, 9500.524973751146, 9605.37565058269, 9752.0, 9770.120689138012, 37983.620163798005, 55159.72420137873, 56887.71556142193, 57281.71359143178, 63089.0, 81029.0, 95118.52440737752, 96230.0, 97854.0, 98414.0, 12362.567310144103, 12573.0, 12635.241885486901, 12940.0, 13151.473941042252, 14492.420303187755, 14551.854481455473, 14557.0, 14709.92645036768, 15229.771553426928, 13432.529861454803, 13545.932270338586, 13557.525486607921, 13843.584692459242, 13998.0, 13148.73702525925, 13306.600801975956, 13340.933295333462, 13383.665408364868, 13428.462861485432, 13069.934650326688, 13081.934590326988, 13149.21104733719, 13737.793933090812, 13772.10481318711, 10143.543540540546, 10968.341899486057, 11043.889561104286, 11132.276402033842, 11409.60066397672, 10988.230823842574, 11054.353383869362, 11432.771344573228, 11511.251768635011, 11533.135014873751], "value": 7653.9664259451065, "unit": "score"}], "name": "dromaeo_css", "value": 7224.809285954593, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 112.0, 112.0, 112.0, 111.0, 114.0, 112.0, 111.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 143.0, 113.0, 148.0, 114.0, 113.0, 113.0, 112.0, 145.0, 114.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 184.0, 199.0, 261.0, 183.0, 204.0, 260.0, 183.0, 183.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 49.0, 85.0, 70.0, 75.0, 82.0, 90.0, 71.0, 75.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 91.0, 69.0, 69.0, 70.0, 92.0, 67.0, 75.0, 121.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 85.0, 87.0, 86.0, 88.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 117.0, 169.0, 115.0, 154.0, 164.0, 97.0, 96.0, 96.0], "value": 120.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 85.0, 105.0, 135.0, 141.0, 87.0, 85.0, 83.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 54.0, 53.0, 55.0, 54.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 66.0, 79.0, 74.0, 73.0, 68.0, 69.0, 69.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 99.0, 105.0, 109.0, 103.0, 86.0, 92.0, 87.0], "value": 94.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 172.0, 139.0, 155.0, 158.0, 209.0, 147.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 184.0, 174.0, 179.0, 174.0, 174.0, 175.0, 174.0, 174.0, 174.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 69.0, 69.0, 68.0, 68.0, 68.0, 70.0, 68.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1522.9, "alertThreshold": 2.0}]} 04:41:59 INFO - Return code: 0 04:41:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:41:59 INFO - # TBPL SUCCESS # 04:41:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:41:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:41:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:41:59 INFO - 2016-10-05 04:41:59.893 screenresolution[1672:9688] starting screenresolution argv=screenresolution get 04:41:59 INFO - 2016-10-05 04:41:59.896 screenresolution[1672:9688] Display 0: 1600x1200x32@60 04:41:59 INFO - 2016-10-05 04:41:59.902 screenresolution[1673:9691] starting screenresolution argv=screenresolution list 04:41:59 INFO - Available Modes on Display 0 04:41:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:41:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:41:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:41:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:42:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:42:00 INFO - Intel Iris: 04:42:00 INFO - Chipset Model: Intel Iris 04:42:00 INFO - Type: GPU 04:42:00 INFO - Bus: Built-In 04:42:00 INFO - VRAM (Dynamic, Max): 1536 MB 04:42:00 INFO - Vendor: Intel (0x8086) 04:42:00 INFO - Device ID: 0x0a2e 04:42:00 INFO - Revision ID: 0x0009 04:42:00 INFO - Displays: 04:42:00 INFO - Display: 04:42:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:42:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:42:00 INFO - Display Serial Number: 1600x1200 60 04:42:00 INFO - Main Display: Yes 04:42:00 INFO - Mirror: Off 04:42:00 INFO - Online: Yes 04:42:00 INFO - Rotation: Supported 04:42:00 INFO - Return code: 0 04:42:00 INFO - Running post-action listener: _resource_record_post_action 04:42:00 INFO - [mozharness: 2016-10-05 11:42:00.150369Z] Finished run-tests step (success) 04:42:00 INFO - Running post-run listener: _resource_record_post_run 04:42:00 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38466560; Write bytes: 705358336; Read time: 380; Write time: 1911 04:42:00 INFO - TinderboxPrint: CPU usage
53.5% 04:42:00 INFO - TinderboxPrint: I/O read bytes / time
38,466,560 / 380 04:42:00 INFO - TinderboxPrint: I/O write bytes / time
705,358,336 / 1,911 04:42:00 INFO - TinderboxPrint: CPU idle
2,259.1 (46.5%) 04:42:00 INFO - TinderboxPrint: CPU system
968.0 (19.9%) 04:42:00 INFO - TinderboxPrint: CPU user
1,630.6 (33.6%) 04:42:00 INFO - TinderboxPrint: Swap in / out
446,115,840 / 0 04:42:00 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 393961984; Write bytes: 388741120; Read time: 29261; Write time: 708 04:42:00 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 37483520; Write bytes: 294375936; Read time: 361; Write time: 1154 04:42:00 INFO - Running post-run listener: _upload_blobber_files 04:42:00 INFO - Blob upload gear active. 04:42:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:42:00 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 04:42:00 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:42:00 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:42:01 INFO - (blobuploader) - INFO - Open directory for files ... 04:42:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:42:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:42:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:02 INFO - (blobuploader) - INFO - Done attempting. 04:42:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:42:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:42:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:03 INFO - (blobuploader) - INFO - Done attempting. 04:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:42:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:42:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:42:05 INFO - (blobuploader) - INFO - Done attempting. 04:42:05 INFO - (blobuploader) - INFO - Iteration through files over. 04:42:05 INFO - Return code: 0 04:42:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:42:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:42:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb79bddc9499753c17065ad7826ba830e6028243b80100531dfb9d95b29b2abdd6b48d0dcffb286730a374a68b514094d06a2b403961bbe18a3b454455d5dcb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f211d72f7b6dc741903255b3890920890dc264e12667970586da4edcb1fb1768c51d2b0158963ffef3410c52d1ebde09d6a50c1ac951e4a4d8d37248d6474628", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/144d2bb8581601c526178ed4c2495b0df529985b626324ebe78ae8ce1c74385ee24a01134384e223033776235859782a0f24060e9af5a2315bb70fe789e9ada5"} 04:42:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:42:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:42:05 INFO - Contents: 04:42:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb79bddc9499753c17065ad7826ba830e6028243b80100531dfb9d95b29b2abdd6b48d0dcffb286730a374a68b514094d06a2b403961bbe18a3b454455d5dcb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f211d72f7b6dc741903255b3890920890dc264e12667970586da4edcb1fb1768c51d2b0158963ffef3410c52d1ebde09d6a50c1ac951e4a4d8d37248d6474628", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/144d2bb8581601c526178ed4c2495b0df529985b626324ebe78ae8ce1c74385ee24a01134384e223033776235859782a0f24060e9af5a2315bb70fe789e9ada5"} 04:42:05 INFO - Running post-run listener: copy_logs_to_upload_dir 04:42:05 INFO - Copying logs to upload dir... 04:42:05 INFO - mkdir: /builds/slave/test/build/upload/logs 04:42:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.245534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-05 04:42:05.535916) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 04:42:05.539194) ========= 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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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/Autoland/sha512/fb79bddc9499753c17065ad7826ba830e6028243b80100531dfb9d95b29b2abdd6b48d0dcffb286730a374a68b514094d06a2b403961bbe18a3b454455d5dcb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f211d72f7b6dc741903255b3890920890dc264e12667970586da4edcb1fb1768c51d2b0158963ffef3410c52d1ebde09d6a50c1ac951e4a4d8d37248d6474628", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/144d2bb8581601c526178ed4c2495b0df529985b626324ebe78ae8ce1c74385ee24a01134384e223033776235859782a0f24060e9af5a2315bb70fe789e9ada5"} build_url:https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011072 build_url: 'https://queue.taskcluster.net/v1/task/TVPSIKcVSTSlRhk5KIhMLQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fb79bddc9499753c17065ad7826ba830e6028243b80100531dfb9d95b29b2abdd6b48d0dcffb286730a374a68b514094d06a2b403961bbe18a3b454455d5dcb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f211d72f7b6dc741903255b3890920890dc264e12667970586da4edcb1fb1768c51d2b0158963ffef3410c52d1ebde09d6a50c1ac951e4a4d8d37248d6474628", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/144d2bb8581601c526178ed4c2495b0df529985b626324ebe78ae8ce1c74385ee24a01134384e223033776235859782a0f24060e9af5a2315bb70fe789e9ada5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 04:42:05.571885) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:42:05.572205) ========= 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.qZ0j2eLnfY/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.DWlI1uodL1/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.005006 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 04:42:05.620797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 04:42:05.621110) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 04:42:05.621454) ========= ========= Total master_lag: 0.17 =========