builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1475797056.28 results: success (0) buildid: 20161006150000 builduid: 41e23adb98db465393728f1328598cef revision: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.276967) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.277550) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.277880) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.313346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.313739) ========= 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.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-06 16:37:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.3M=0.001s 2016-10-06 16:37:36 (16.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.470314 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.807526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.807884) ========= 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.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.029399 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:36.858169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 16:37:36.858834) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d8e1f5cf0a70a53e8a5532809096a0a5bf729196 --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 d8e1f5cf0a70a53e8a5532809096a0a5bf729196 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-06 16:37:36,931 truncating revision to first 12 chars 2016-10-06 16:37:36,931 Setting DEBUG logging. 2016-10-06 16:37:36,931 attempt 1/10 2016-10-06 16:37:36,931 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d8e1f5cf0a70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 16:37:38,970 unpacking tar archive at: autoland-d8e1f5cf0a70/testing/mozharness/ program finished with exit code 0 elapsedTime=2.310192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-06 16:37:39.191522) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:39.191947) ========= script_repo_revision: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:39.192577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:39.192973) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 16:37:39.208704) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-06 16:37:39.209142) ========= /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.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:37:39 INFO - MultiFileLogger online at 20161006 16:37:39 in /builds/slave/test 16:37:39 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 16:37:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:37:39 INFO - {'append_to_log': False, 16:37:39 INFO - 'base_work_dir': '/builds/slave/test', 16:37:39 INFO - 'blob_upload_branch': 'Autoland', 16:37:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:37:39 INFO - 'branch': 'Autoland', 16:37:39 INFO - 'buildbot_json_path': 'buildprops.json', 16:37:39 INFO - 'config_files': ('talos/mac_config.py',), 16:37:39 INFO - 'default_actions': ('clobber', 16:37:39 INFO - 'read-buildbot-config', 16:37:39 INFO - 'download-and-extract', 16:37:39 INFO - 'populate-webroot', 16:37:39 INFO - 'create-virtualenv', 16:37:39 INFO - 'install', 16:37:39 INFO - 'run-tests'), 16:37:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:37:39 INFO - 'download_minidump_stackwalk': True, 16:37:39 INFO - 'download_symbols': 'ondemand', 16:37:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:37:39 INFO - 'tooltool.py': '/tools/tooltool.py', 16:37:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:37:39 INFO - '/tools/misc-python/virtualenv.py')}, 16:37:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:37:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:37:39 INFO - 'installer_path': 'installer.exe', 16:37:39 INFO - 'log_level': 'info', 16:37:39 INFO - 'log_name': 'talos', 16:37:39 INFO - 'log_to_console': True, 16:37:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:37:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:37:39 INFO - 'opt_config_files': (), 16:37:39 INFO - 'pip_index': False, 16:37:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:37:39 INFO - 'cmd': ('bash', 16:37:39 INFO - '-c', 16:37:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:37:39 INFO - 'enabled': True, 16:37:39 INFO - 'halt_on_failure': False, 16:37:39 INFO - 'name': 'check_screen_resolution'},), 16:37:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:37:39 INFO - 'cmd': ('bash', 16:37:39 INFO - '-c', 16:37:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:37:39 INFO - 'enabled': True, 16:37:39 INFO - 'halt_on_failure': False, 16:37:39 INFO - 'name': 'check_screen_resolution'},), 16:37:39 INFO - 'run_cmd_checks_enabled': True, 16:37:39 INFO - 'sps_profile': False, 16:37:39 INFO - 'sps_profile_interval': 0, 16:37:39 INFO - 'suite': 'dromaeojs', 16:37:39 INFO - 'system_bits': '32', 16:37:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:37:39 INFO - 'title': 't-yosemite-r7-0007', 16:37:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:37:39 INFO - 'use_talos_json': True, 16:37:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:37:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:37:39 INFO - 'work_dir': 'build'} 16:37:39 INFO - [mozharness: 2016-10-06 23:37:39.417525Z] Running clobber step. 16:37:39 INFO - Running pre-action listener: _resource_record_pre_action 16:37:39 INFO - Running main action method: clobber 16:37:39 INFO - rmtree: /builds/slave/test/build 16:37:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:37:41 INFO - Running post-action listener: _resource_record_post_action 16:37:41 INFO - [mozharness: 2016-10-06 23:37:41.580685Z] Finished clobber step (success) 16:37:41 INFO - [mozharness: 2016-10-06 23:37:41.580810Z] Running read-buildbot-config step. 16:37:41 INFO - Running pre-action listener: _resource_record_pre_action 16:37:41 INFO - Running main action method: read_buildbot_config 16:37:41 INFO - Using buildbot properties: 16:37:41 INFO - { 16:37:41 INFO - "project": "", 16:37:41 INFO - "product": "firefox", 16:37:41 INFO - "script_repo_revision": "production", 16:37:41 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:37:41 INFO - "repository": "", 16:37:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:37:41 INFO - "buildid": "20161006150000", 16:37:41 INFO - "pgo_build": "False", 16:37:41 INFO - "basedir": "/builds/slave/test", 16:37:41 INFO - "buildnumber": 401, 16:37:41 INFO - "slavename": "t-yosemite-r7-0007", 16:37:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:37:41 INFO - "platform": "yosemite_r7", 16:37:41 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:37:41 INFO - "branch": "autoland", 16:37:41 INFO - "repo_path": "integration/autoland", 16:37:41 INFO - "revision": "d8e1f5cf0a70a53e8a5532809096a0a5bf729196", 16:37:41 INFO - "stage_platform": "macosx64", 16:37:41 INFO - "builduid": "41e23adb98db465393728f1328598cef", 16:37:41 INFO - "slavebuilddir": "test" 16:37:41 INFO - } 16:37:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:37:41 INFO - Running post-action listener: _resource_record_post_action 16:37:41 INFO - [mozharness: 2016-10-06 23:37:41.584117Z] Finished read-buildbot-config step (success) 16:37:41 INFO - [mozharness: 2016-10-06 23:37:41.584232Z] Running download-and-extract step. 16:37:41 INFO - Running pre-action listener: _resource_record_pre_action 16:37:41 INFO - Running main action method: download_and_extract 16:37:41 INFO - mkdir: /builds/slave/test/build/tests 16:37:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:37:41 INFO - trying https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:37:41 INFO - Downloading https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 16:37:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 16:37:42 INFO - Downloaded 1199 bytes. 16:37:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:37:42 INFO - Using the following test package requirements: 16:37:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:37:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:37:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:37:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'jsshell-mac.zip'], 16:37:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:37:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:37:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:37:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:37:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:37:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:37:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:37:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:37:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:37:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:42 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:37:43 INFO - Http code: 200 16:37:43 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 16:37:43 INFO - x-amz-cf-id: tsv_8R0oNA0XG_J1a1j7nN5YCS7qOoj0sl7kIZLa4UntFRtIcR_YiQ== 16:37:43 INFO - x-amz-version-id: a3y4aYbvFVmBHtIyh0vAJmlLEzrpiNI4 16:37:43 INFO - Expected file size: 16478254 16:37:43 INFO - Obtained file size: 16478254 16:37:50 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 16:37:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:37:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:37:50 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:37:50 INFO - Http code: 200 16:37:50 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 16:37:50 INFO - x-amz-cf-id: ZgLDfFor151QpwMtexBHfL78wr-JQjNiyaNRd9MWjRZLKlkNWmhaow== 16:37:50 INFO - x-amz-version-id: a3y4aYbvFVmBHtIyh0vAJmlLEzrpiNI4 16:37:51 INFO - Expected file size: 16478254 16:37:51 INFO - Obtained file size: 16478254 16:37:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:37:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:37:57 INFO - Fetch https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:37:58 INFO - Http code: 200 16:37:58 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 16:37:58 INFO - x-amz-cf-id: a2ZqmOErgfossavL36ddl1PJtB8LDEGJjrvDhFTAGHw-yvjGDWYGDQ== 16:37:58 INFO - x-amz-version-id: Ioi0b1QeQixIXglrQfZA2nfZfUqTgLZi 16:37:58 INFO - Expected file size: 14409006 16:37:58 INFO - Obtained file size: 14409006 16:37:59 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')]} 16:37:59 INFO - trying https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:37:59 INFO - Downloading https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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 16:38:02 INFO - Downloaded 111761123 bytes. 16:38:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:38:02 INFO - mkdir: /builds/slave/test/properties 16:38:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:38:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:38:02 INFO - Contents: 16:38:02 INFO - build_url:https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:38:02 INFO - Running post-action listener: _resource_record_post_action 16:38:02 INFO - Running post-action listener: set_extra_try_arguments 16:38:02 INFO - [mozharness: 2016-10-06 23:38:02.849326Z] Finished download-and-extract step (success) 16:38:02 INFO - [mozharness: 2016-10-06 23:38:02.849454Z] Running populate-webroot step. 16:38:02 INFO - Running pre-action listener: _resource_record_pre_action 16:38:02 INFO - Running main action method: populate_webroot 16:38:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:38:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:38:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:38:02 INFO - '--tppagecycles', 16:38:02 INFO - '7'], 16:38:02 INFO - 'tests': ['tsvgm']}}, 16:38:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:38:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:38:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:38:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:38:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:38:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:38:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['damp', 'tps']}, 16:38:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'tests': ['damp', 'tps']}, 16:38:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['dromaeo_dom']}, 16:38:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:38:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['basic_compositor_video']}, 16:38:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:38:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'other-e10s': {'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['a11yr', 16:38:02 INFO - 'ts_paint', 16:38:02 INFO - 'tpaint', 16:38:02 INFO - 'sessionrestore', 16:38:02 INFO - 'sessionrestore_no_auto_restore', 16:38:02 INFO - 'tabpaint']}, 16:38:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['tsvgx', 16:38:02 INFO - 'tsvgr_opacity', 16:38:02 INFO - 'tart', 16:38:02 INFO - 'tscrollx', 16:38:02 INFO - 'cart']}, 16:38:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:38:02 INFO - 'tsvgr_opacity', 16:38:02 INFO - 'tart', 16:38:02 INFO - 'tscrollx', 16:38:02 INFO - 'cart']}, 16:38:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'talos_options': ['--disable-e10s'], 16:38:02 INFO - 'tests': ['tp5o']}, 16:38:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'tests': ['tp5o']}, 16:38:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'talos_options': ['--disable-e10s', 16:38:02 INFO - '--xperf_path', 16:38:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:38:02 INFO - 'tests': ['tp5n']}, 16:38:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:38:02 INFO - 'talos_options': ['--xperf_path', 16:38:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:38:02 INFO - 'tests': ['tp5n']}}, 16:38:02 INFO - 'talos.zip': {'path': '', 16:38:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:38:02 INFO - Running post-action listener: _resource_record_post_action 16:38:02 INFO - [mozharness: 2016-10-06 23:38:02.868338Z] Finished populate-webroot step (success) 16:38:02 INFO - [mozharness: 2016-10-06 23:38:02.868456Z] Running create-virtualenv step. 16:38:02 INFO - Running pre-action listener: _resource_record_pre_action 16:38:02 INFO - Running main action method: create_virtualenv 16:38:02 INFO - Creating virtualenv /builds/slave/test/build/venv 16:38:02 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 16:38:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:38:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:38:02 INFO - Using real prefix '/tools/python27' 16:38:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:38:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:38:06 INFO - Installing pip.................done. 16:38:06 INFO - Return code: 0 16:38:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:38:06 INFO - Reading from file tmpfile_stdout 16:38:06 INFO - Output received: 16:38:06 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) 16:38:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:38:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:06 INFO - 'HOME': '/Users/cltbld', 16:38:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:06 INFO - 'LOGNAME': 'cltbld', 16:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:06 INFO - 'MOZ_NO_REMOTE': '1', 16:38:06 INFO - 'NO_EM_RESTART': '1', 16:38:06 INFO - 'PAGER': '/bin/cat', 16:38:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:06 INFO - 'PWD': '/builds/slave/test', 16:38:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:06 INFO - 'SHELL': '/bin/bash', 16:38:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:06 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:06 INFO - 'USER': 'cltbld', 16:38:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:06 INFO - 'XPC_FLAGS': '0x0', 16:38:06 INFO - 'XPC_SERVICE_NAME': '0', 16:38:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:07 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 16:38:07 INFO - Cleaning up... 16:38:07 INFO - Return code: 0 16:38:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:07 INFO - 'HOME': '/Users/cltbld', 16:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:07 INFO - 'LOGNAME': 'cltbld', 16:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:07 INFO - 'MOZ_NO_REMOTE': '1', 16:38:07 INFO - 'NO_EM_RESTART': '1', 16:38:07 INFO - 'PAGER': '/bin/cat', 16:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:07 INFO - 'PWD': '/builds/slave/test', 16:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:07 INFO - 'SHELL': '/bin/bash', 16:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:07 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:07 INFO - 'USER': 'cltbld', 16:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:07 INFO - 'XPC_FLAGS': '0x0', 16:38:07 INFO - 'XPC_SERVICE_NAME': '0', 16:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:07 INFO - Downloading/unpacking psutil>=3.1.1 16:38:07 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 16:38:07 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 16:38:07 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 16:38:07 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 16:38:07 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 16:38:07 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 16:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:38:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:38:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:38:11 INFO - Installing collected packages: psutil 16:38:11 INFO - Running setup.py install for psutil 16:38:11 INFO - building 'psutil._psutil_osx' extension 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:12 INFO - building 'psutil._psutil_posix' extension 16:38:12 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 16:38:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:38:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:38:12 INFO - ^ 16:38:12 INFO - 1 warning generated. 16:38:12 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 16:38:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:38:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:38:12 INFO - Successfully installed psutil 16:38:12 INFO - Cleaning up... 16:38:12 INFO - Return code: 0 16:38:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:38:12 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')]} 16:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:12 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')]} 16:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:12 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:12 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 16:38:12 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 16:38:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:12 INFO - 'HOME': '/Users/cltbld', 16:38:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:12 INFO - 'LOGNAME': 'cltbld', 16:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:12 INFO - 'MOZ_NO_REMOTE': '1', 16:38:12 INFO - 'NO_EM_RESTART': '1', 16:38:12 INFO - 'PAGER': '/bin/cat', 16:38:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:12 INFO - 'PWD': '/builds/slave/test', 16:38:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:12 INFO - 'SHELL': '/bin/bash', 16:38:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:12 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:12 INFO - 'USER': 'cltbld', 16:38:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:12 INFO - 'XPC_FLAGS': '0x0', 16:38:12 INFO - 'XPC_SERVICE_NAME': '0', 16:38:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:38:15 INFO - Installing collected packages: mozsystemmonitor 16:38:15 INFO - Running setup.py install for mozsystemmonitor 16:38:15 INFO - Successfully installed mozsystemmonitor 16:38:15 INFO - Cleaning up... 16:38:15 INFO - Return code: 0 16:38:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:38:15 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')]} 16:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:15 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')]} 16:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:15 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:15 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 16:38:15 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 16:38:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:15 INFO - 'HOME': '/Users/cltbld', 16:38:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:15 INFO - 'LOGNAME': 'cltbld', 16:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:15 INFO - 'MOZ_NO_REMOTE': '1', 16:38:15 INFO - 'NO_EM_RESTART': '1', 16:38:15 INFO - 'PAGER': '/bin/cat', 16:38:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:15 INFO - 'PWD': '/builds/slave/test', 16:38:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:15 INFO - 'SHELL': '/bin/bash', 16:38:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:15 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:15 INFO - 'USER': 'cltbld', 16:38:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:15 INFO - 'XPC_FLAGS': '0x0', 16:38:15 INFO - 'XPC_SERVICE_NAME': '0', 16:38:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:38:15 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 16:38:15 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 16:38:15 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 16:38:15 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 16:38:15 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 16:38:15 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 16:38:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:38:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:38: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 16:38: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 16:38: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 16:38: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 16:38: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 16:38: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 16:38:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:38:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:38:18 INFO - warning: no files found matching '*.txt' 16:38:18 INFO - no previously-included directories found matching 'build' 16:38:18 INFO - no previously-included directories found matching 'dist' 16:38:18 INFO - no previously-included directories found matching '.git*' 16:38:18 INFO - Installing collected packages: jsonschema, functools32 16:38:18 INFO - Running setup.py install for functools32 16:38:18 INFO - warning: no files found matching '*.txt' 16:38:18 INFO - no previously-included directories found matching 'build' 16:38:18 INFO - no previously-included directories found matching 'dist' 16:38:18 INFO - no previously-included directories found matching '.git*' 16:38:18 INFO - Successfully installed jsonschema functools32 16:38:18 INFO - Cleaning up... 16:38:18 INFO - Return code: 0 16:38:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:18 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:18 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 16:38:18 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 16:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:18 INFO - 'HOME': '/Users/cltbld', 16:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:18 INFO - 'LOGNAME': 'cltbld', 16:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:18 INFO - 'MOZ_NO_REMOTE': '1', 16:38:18 INFO - 'NO_EM_RESTART': '1', 16:38:18 INFO - 'PAGER': '/bin/cat', 16:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:18 INFO - 'PWD': '/builds/slave/test', 16:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:18 INFO - 'SHELL': '/bin/bash', 16:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:18 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:18 INFO - 'USER': 'cltbld', 16:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:18 INFO - 'XPC_FLAGS': '0x0', 16:38:18 INFO - 'XPC_SERVICE_NAME': '0', 16:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:38:19 INFO - Cleaning up... 16:38:19 INFO - Return code: 0 16:38:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:38: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')]} 16:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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')]} 16:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:19 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:19 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 16:38:19 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 16:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:19 INFO - 'HOME': '/Users/cltbld', 16:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:19 INFO - 'LOGNAME': 'cltbld', 16:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:19 INFO - 'MOZ_NO_REMOTE': '1', 16:38:19 INFO - 'NO_EM_RESTART': '1', 16:38:19 INFO - 'PAGER': '/bin/cat', 16:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:19 INFO - 'PWD': '/builds/slave/test', 16:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:19 INFO - 'SHELL': '/bin/bash', 16:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:19 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:19 INFO - 'USER': 'cltbld', 16:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:19 INFO - 'XPC_FLAGS': '0x0', 16:38:19 INFO - 'XPC_SERVICE_NAME': '0', 16:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:19 INFO - Downloading/unpacking blobuploader==1.2.4 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:21 INFO - Downloading blobuploader-1.2.4.tar.gz 16:38:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:38:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:38:21 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 16:38:21 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 16:38:21 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 16:38:21 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 16:38:21 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 16:38:21 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 16:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:38:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:38:22 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 16:38:22 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 16:38:22 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 16:38:22 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 16:38:22 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 16:38:22 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 16:38:22 INFO - Downloading docopt-0.6.1.tar.gz 16:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:38:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:38:22 INFO - Running setup.py install for blobuploader 16:38:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:38:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:38:23 INFO - Running setup.py install for requests 16:38:23 INFO - Running setup.py install for docopt 16:38:23 INFO - Successfully installed blobuploader requests docopt 16:38:23 INFO - Cleaning up... 16:38:23 INFO - Return code: 0 16:38:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:23 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:23 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 16:38:23 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 16:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:23 INFO - 'HOME': '/Users/cltbld', 16:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:23 INFO - 'LOGNAME': 'cltbld', 16:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:23 INFO - 'MOZ_NO_REMOTE': '1', 16:38:23 INFO - 'NO_EM_RESTART': '1', 16:38:23 INFO - 'PAGER': '/bin/cat', 16:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:23 INFO - 'PWD': '/builds/slave/test', 16:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:23 INFO - 'SHELL': '/bin/bash', 16:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:23 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:23 INFO - 'USER': 'cltbld', 16:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:23 INFO - 'XPC_FLAGS': '0x0', 16:38:23 INFO - 'XPC_SERVICE_NAME': '0', 16:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-gdeh8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-loAHPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-aidJee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UxSKv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-heHBE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UvV4TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6SO5oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:38:24 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SVfoUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jEls26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-RDHwx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-aoazBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YhGhzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ABGYyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_Qgdex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vTe42w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-l_YoQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_QBSKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:38:25 INFO - Running setup.py install for manifestparser 16:38:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:38:26 INFO - Running setup.py install for mozcrash 16:38:26 INFO - Running setup.py install for mozdebug 16:38:26 INFO - Running setup.py install for mozdevice 16:38:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:38:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:38:26 INFO - Running setup.py install for mozfile 16:38:26 INFO - Running setup.py install for mozhttpd 16:38:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:38:26 INFO - Running setup.py install for mozinfo 16:38:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:38:26 INFO - Running setup.py install for mozInstall 16:38:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Running setup.py install for mozleak 16:38:27 INFO - Running setup.py install for mozlog 16:38:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Running setup.py install for moznetwork 16:38:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Running setup.py install for mozprocess 16:38:27 INFO - Running setup.py install for mozprofile 16:38:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:38:27 INFO - Running setup.py install for mozrunner 16:38:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:38:28 INFO - Running setup.py install for mozscreenshot 16:38:28 INFO - Running setup.py install for moztest 16:38:28 INFO - Running setup.py install for mozversion 16:38:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:38:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:38:28 INFO - Cleaning up... 16:38:28 INFO - Return code: 0 16:38:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:28 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:28 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 16:38:28 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 16:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:28 INFO - 'HOME': '/Users/cltbld', 16:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:28 INFO - 'LOGNAME': 'cltbld', 16:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:28 INFO - 'MOZ_NO_REMOTE': '1', 16:38:28 INFO - 'NO_EM_RESTART': '1', 16:38:28 INFO - 'PAGER': '/bin/cat', 16:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:28 INFO - 'PWD': '/builds/slave/test', 16:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:28 INFO - 'SHELL': '/bin/bash', 16:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:28 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:28 INFO - 'USER': 'cltbld', 16:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:28 INFO - 'XPC_FLAGS': '0x0', 16:38:28 INFO - 'XPC_SERVICE_NAME': '0', 16:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:38:28 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ZmysAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:38:28 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)) 16:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:38:28 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-m7cilP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-F89f7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-27kEX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-xqrkm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-rg5f4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-y5IEPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-3ZjpOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_06rVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-f_ox6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-j_xiv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:38:29 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)) 16:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:38:29 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ZYfvb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:38:30 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)) 16:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:38:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-sDApgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:38:30 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)) 16:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:38:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wCbgY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:38:30 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)) 16:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-gA9AmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:30 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)) 16:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cmC1XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:30 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)) 16:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:30 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-1KR7sV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:30 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)) 16:38:30 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)) 16:38:30 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)) 16:38:30 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)) 16:38:30 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)) 16:38:30 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)) 16:38:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:38:30 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 16:38:30 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 16:38:30 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 16:38:30 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 16:38:30 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 16:38:30 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 16:38:33 INFO - Downloading blessings-1.6.tar.gz 16:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:38:33 INFO - Installing collected packages: blessings 16:38:33 INFO - Running setup.py install for blessings 16:38:33 INFO - Successfully installed blessings 16:38:33 INFO - Cleaning up... 16:38:33 INFO - Return code: 0 16:38:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:38:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:33 INFO - Reading from file tmpfile_stdout 16:38:33 INFO - Current package versions: 16:38:33 INFO - blessings == 1.6 16:38:33 INFO - blobuploader == 1.2.4 16:38:33 INFO - docopt == 0.6.1 16:38:33 INFO - functools32 == 3.2.3-2 16:38:33 INFO - jsonschema == 2.5.1 16:38:33 INFO - manifestparser == 1.1 16:38:33 INFO - mozInstall == 1.12 16:38:33 INFO - mozcrash == 0.17 16:38:33 INFO - mozdebug == 0.1 16:38:33 INFO - mozdevice == 0.48 16:38:33 INFO - mozfile == 1.2 16:38:33 INFO - mozhttpd == 0.7 16:38:33 INFO - mozinfo == 0.9 16:38:33 INFO - mozleak == 0.1 16:38:33 INFO - mozlog == 3.4 16:38:33 INFO - moznetwork == 0.27 16:38:33 INFO - mozprocess == 0.23 16:38:33 INFO - mozprofile == 0.28 16:38:33 INFO - mozrunner == 6.12 16:38:33 INFO - mozscreenshot == 0.1 16:38:33 INFO - mozsystemmonitor == 0.3 16:38:33 INFO - moztest == 0.7 16:38:33 INFO - mozversion == 1.4 16:38:33 INFO - psutil == 3.1.1 16:38:33 INFO - requests == 1.2.3 16:38:33 INFO - wsgiref == 0.1.2 16:38:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:33 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')]} 16:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:33 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')]} 16:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:33 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:33 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 16:38:33 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 16:38:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:33 INFO - 'HOME': '/Users/cltbld', 16:38:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:33 INFO - 'LOGNAME': 'cltbld', 16:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:33 INFO - 'MOZ_NO_REMOTE': '1', 16:38:33 INFO - 'NO_EM_RESTART': '1', 16:38:33 INFO - 'PAGER': '/bin/cat', 16:38:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:33 INFO - 'PWD': '/builds/slave/test', 16:38:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:33 INFO - 'SHELL': '/bin/bash', 16:38:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:33 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:33 INFO - 'USER': 'cltbld', 16:38:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:33 INFO - 'XPC_FLAGS': '0x0', 16:38:33 INFO - 'XPC_SERVICE_NAME': '0', 16:38:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 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)) 16:38:33 INFO - Cleaning up... 16:38:33 INFO - Return code: 0 16:38:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:38:33 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')]} 16:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:33 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')]} 16:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:33 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 0x10c2ae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c224570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd962d676a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, '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.DfoPsxJ7WF/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.OWohocax4h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 16:38:33 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 16:38:33 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 16:38:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:38:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:33 INFO - 'HOME': '/Users/cltbld', 16:38:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:33 INFO - 'LOGNAME': 'cltbld', 16:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:33 INFO - 'MOZ_NO_REMOTE': '1', 16:38:33 INFO - 'NO_EM_RESTART': '1', 16:38:33 INFO - 'PAGER': '/bin/cat', 16:38:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:33 INFO - 'PWD': '/builds/slave/test', 16:38:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:33 INFO - 'SHELL': '/bin/bash', 16:38:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:38:33 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:38:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:33 INFO - 'USER': 'cltbld', 16:38:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:33 INFO - 'XPC_FLAGS': '0x0', 16:38:33 INFO - 'XPC_SERVICE_NAME': '0', 16:38:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:38:34 INFO - Cleaning up... 16:38:34 INFO - Return code: 0 16:38:34 INFO - Running post-action listener: _resource_record_post_action 16:38:34 INFO - Running post-action listener: _start_resource_monitoring 16:38:34 INFO - Starting resource monitoring. 16:38:34 INFO - [mozharness: 2016-10-06 23:38:34.269616Z] Finished create-virtualenv step (success) 16:38:34 INFO - [mozharness: 2016-10-06 23:38:34.270590Z] Running install step. 16:38:34 INFO - Running pre-action listener: _resource_record_pre_action 16:38:34 INFO - Running main action method: install 16:38:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:34 INFO - Reading from file tmpfile_stdout 16:38:34 INFO - Detecting whether we're running mozinstall >=1.0... 16:38:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:38:34 INFO - Reading from file tmpfile_stdout 16:38:34 INFO - Output received: 16:38:34 INFO - Usage: mozinstall [options] installer 16:38:34 INFO - Options: 16:38:34 INFO - -h, --help show this help message and exit 16:38:34 INFO - -d DEST, --destination=DEST 16:38:34 INFO - Directory to install application into. [default: 16:38:34 INFO - "/builds/slave/test"] 16:38:34 INFO - --app=APP Application being installed. [default: firefox] 16:38:34 INFO - mkdir: /builds/slave/test/build/application 16:38:34 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'] 16:38:34 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 16:39:08 INFO - Reading from file tmpfile_stdout 16:39:08 INFO - Output received: 16:39:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:39:08 INFO - Running post-action listener: _resource_record_post_action 16:39:08 INFO - [mozharness: 2016-10-06 23:39:08.745543Z] Finished install step (success) 16:39:08 INFO - [mozharness: 2016-10-06 23:39:08.745679Z] Running run-tests step. 16:39:08 INFO - Running pre-action listener: _resource_record_pre_action 16:39:08 INFO - Running main action method: run_tests 16:39:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:39:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:39:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:39:08 INFO - 2016-10-06 16:39:08.760 screenresolution[1648:7844] starting screenresolution argv=screenresolution get 16:39:08 INFO - 2016-10-06 16:39:08.762 screenresolution[1648:7844] Display 0: 1600x1200x32@60 16:39:08 INFO - 2016-10-06 16:39:08.768 screenresolution[1649:7846] starting screenresolution argv=screenresolution list 16:39:08 INFO - Available Modes on Display 0 16:39:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:39:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:39:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:39:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:39:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:39:08 INFO - Intel Iris: 16:39:08 INFO - Chipset Model: Intel Iris 16:39:08 INFO - Type: GPU 16:39:08 INFO - Bus: Built-In 16:39:08 INFO - VRAM (Dynamic, Max): 1536 MB 16:39:08 INFO - Vendor: Intel (0x8086) 16:39:08 INFO - Device ID: 0x0a2e 16:39:08 INFO - Revision ID: 0x0009 16:39:08 INFO - Displays: 16:39:08 INFO - Display: 16:39:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:39:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:39:08 INFO - Display Serial Number: 1600x1200 60 16:39:08 INFO - Main Display: Yes 16:39:08 INFO - Mirror: Off 16:39:08 INFO - Online: Yes 16:39:08 INFO - Rotation: Supported 16:39:09 INFO - Return code: 0 16:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:39:09 INFO - Python 2.7.3 16:39:09 INFO - Return code: 0 16:39:09 INFO - grabbing minidump binary from tooltool 16:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:09 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 0x10c0c2d30>, '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 0x10bee8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c0bab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:39:09 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 16:39:09 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 16:39:09 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 16:39:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:39:09 INFO - Return code: 0 16:39:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:39:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:39:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:39:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:39:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:39:09 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/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 16:39:09 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/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 16:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfoPsxJ7WF/Render', 16:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:39:09 INFO - 'HOME': '/Users/cltbld', 16:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:39:09 INFO - 'LOGNAME': 'cltbld', 16:39:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:39:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:39:09 INFO - 'MOZ_NO_REMOTE': '1', 16:39:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:39:09 INFO - 'NO_EM_RESTART': '1', 16:39:09 INFO - 'PAGER': '/bin/cat', 16:39:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:39:09 INFO - 'PWD': '/builds/slave/test', 16:39:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:39:09 INFO - 'SHELL': '/bin/bash', 16:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OWohocax4h/Listeners', 16:39:09 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 16:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:39:09 INFO - 'USER': 'cltbld', 16:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:39:09 INFO - 'XPC_FLAGS': '0x0', 16:39:09 INFO - 'XPC_SERVICE_NAME': '0', 16:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:39:09 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/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 16:39:09 INFO - mozversion application_buildid: 20161006150000 16:39:09 INFO - mozversion application_changeset: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 16:39:09 INFO - mozversion application_display_name: Nightly 16:39:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:39:09 INFO - mozversion application_name: Firefox 16:39:09 INFO - mozversion application_remotingname: firefox 16:39:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:39:09 INFO - mozversion application_vendor: Mozilla 16:39:09 INFO - mozversion application_version: 52.0a1 16:39:09 INFO - mozversion platform_buildid: 20161006150000 16:39:09 INFO - mozversion platform_changeset: d8e1f5cf0a70a53e8a5532809096a0a5bf729196 16:39:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:39:09 INFO - mozversion platform_version: 52.0a1 16:39:09 INFO - using testdate: 1475797149 16:39:09 INFO - actual date: 1475797149 16:39:09 INFO - starting webserver on 'localhost:49267' 16:39:09 INFO - SUITE-START | Running 2 tests 16:39:09 INFO - TEST-START | dromaeo_css 16:39:09 INFO - operating with platform_type : mac_ 16:39:09 INFO - Initialising browser for dromaeo_css test... 16:39:09 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpnD6dQG/profile http://localhost:49267/getInfo.html) 16:39:11 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 16:39:11 INFO - PROCESS | 1659 | colorDepth:24 16:39:11 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 16:39:11 INFO - PROCESS | 1659 | __metrics 16:39:11 INFO - PROCESS | 1659 | 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" 16:39:11 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:39:12 INFO - TEST-INFO | 1659: exit 0 16:39:12 INFO - Browser initialized. 16:39:12 INFO - Running cycle 1/1 for dromaeo_css test... 16:39:12 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpnD6dQG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:39:24 INFO - PROCESS | 1662 | RSS: Main: 235401216 16:39:24 INFO - PROCESS | 1662 | 16:40:15 INFO - PROCESS | 1662 | 1475797215606 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] 16:42:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 16:42:30 INFO - PROCESS | 1662 | RSS: Main: 421502976 16:42:30 INFO - PROCESS | 1662 | 16:45:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 16:45:34 INFO - PROCESS | 1662 | RSS: Main: 372613120 16:45:34 INFO - PROCESS | 1662 | 16:48:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 16:48:37 INFO - PROCESS | 1662 | RSS: Main: 340844544 16:48:37 INFO - PROCESS | 1662 | 16:51:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 16:51:40 INFO - PROCESS | 1662 | RSS: Main: 349601792 16:51:40 INFO - PROCESS | 1662 | 16:54:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 16:54:44 INFO - PROCESS | 1662 | RSS: Main: 336723968 16:54:44 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 16:57:48 INFO - PROCESS | 1662 | RSS: Main: 381407232 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | __start_tp_report 16:57:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 16:57:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 16:57:48 INFO - PROCESS | 1662 | |i|pagename|runs| 16:57:48 INFO - PROCESS | 1662 | |0;dojo.html;3325.1188435064696;3326.933461330773;3332.700056994871;3371.612264589573;3383.543221665076;5563.998480273549;5656.547476201904;5678.687672178034;5697.088465845465;5712.0575105107655;5633.915491267634;5678.716064196784;5687.744051517681;5748.683822389767;5762.481376676098;5142.717150556718;5166.896662066756;5200.349956255468;5210.4008039075425;5228.372595288552;5292.814751483699;5319.760610772524;5329.920051199235;5334.51989320961;5388.030154572176;5146.253793200002;5171.043356978971;5239.607029472767;5269.736513174326;5297.761600727977;5226.895462090775;5230.92153617696;5231.581473482151;5238.266642670029;5241.240020197089;5143.7685304161405;5180.4042535108565;5197.948020519784;5209.583233341362;5211.817586384458;5627.634203776783;5702.914456283159;5703.857403564903;5712.571557133231;5734.569907256972;6226.2839773426185;6231.127642130105;6232.439080482778;6237.688115594247;6256.749730010794;6232.34560371159;6234.906476402857;6311.747530098791;6313.337099604521;6315.400036996478;5641.971790141023;5642.322921249394;5707.857303567444;5763;5763.682997435139;6239.438450539473;6261.9373806262265;6285.214348206474;6288.088227207029;6307.463865571478;4961.5286547777905;6178.165947597017;6181.938180618226;6203.720832562545;6204.7828326008375;6121.148138557471;6179.969100154471;6288.685565721696;6289.371062893765;6289.528285378615;6139.6454669416935;6165.84585385369;6202.81391558254;6229.875402491924;6267.341929097471;6254.8123556293385;6255.687215639291;6260.46786023184;6263.530235232284;6284.340144284875;4875.463698993108;4879.365682461257;4917.311576379309;4918.975405123023;4922.064807686528;4319.913601727948;4381.9123617527475;4386.473623165177;4388.539203383599;4426.933595996063;5772.960867043888;5779.624324418899;5788.27646544182;5791.276090488689;5837.591368604157;6315.494760419156;6320.905186422207;6330.968345158245;6338.080978258126;6347.96826015867;5567.415421380778;5674.9785038693435;5693.886122277531;5744.942550574524;5755.050416681201;5321.521063104262;5357.705326207096;5375.489328513785;5376.112941364631;5380.7309634518115;6277.246730392382;6279.403456671609;6288.496920246369;6295.055741638791;6302.65335406557;5313.123334649894;6193.256809182747;6203.503719702323;6232.470240029648;6272.40412160844;6310.148130002391;6318.905216421849;6326.746930122928;6331.778387756591;6344.777932772331;6204.72078756448;6210.5031597473;6225.439710426083;6228.252609686867;6238.469730072995;6141.025769127022;6145;6285.151504546828;6300.369963003846;6316.62100273985;6085.4523092921845;6118.388161183846;6132.908006379818;6139.185504090546;6149.815505534841;6118.230673832282;6124.167343420869;6128.105930682507;6131.254333328352;6139.308154111861;5771.595988280948;5964;5965.373635768356;5978.880422391615;6004.039353703386;5003.899922001467;5973.402659733992;6067.332593414632;6072.544559158169;6074;5868.67722275279;5874.530037596896;5874.676892770939;5889.911651325319;5893.086571581412 16:57:48 INFO - PROCESS | 1662 | |1;ext.html;476.58298988385144;506.1041955738341;520.2274622186054;528.1575886461096;540.049512857371;28289.292767680818;39497;41096.58903410965;41121.383179252334;41131.766047018566;34146.48780268292;41562.7921860391;42096.36855447171;42104.15791684164;42174.57825421745;8772.517511536864;9708.368956017854;9937.552810123525;9946.20430365569;9947.950260248688;8776.956115219447;11987.64037078889;12287.815682764722;12294.323812190327;12495.375231238402;12196.49971755788;12290.938545307261;12305.384730763471;12308.44611992458;12310.569130080448;8851.911480885174;12235.510579576807;12309.753804923852;12339.938300308531;12345.876541234653;8811.603477843513;10027.649032283834;11766.176367654267;12079.154459187861;12324;40499.39250911239;40821.59178408208;40830.79584602058;40910.59089409097;40958.9760255993;3305.2397948471985;3350.5811773528308;3425.1094715374184;3581.552305961755;3585.0678823506078;25363.858626361856;30888;30972.225694357596;31103.37793244145;32215.19462013445;3237.8057316560844;3238.012406216103;3255.3326568053494;3367.6463971282906;3375.611804642473;462992;464200;464384;464489;467683;56878;57158;57278.7136064317;57403;57476.425235747105;34308.65691343105;34875.476867847;35233.47149792755;36267.81866090652;36284.27431451356;15896;16055.518334449986;16058.919705401631;16084.034957902564;16091.67816643684;11157;11383.089352851917;11615.825762613567;12385.752284954251;12401.069919756055;15441.613959651098;16533.58666033335;18494.907525462288;18790.966496842804;19242.71135932962;6586.8353291168105;7758.728444504415;7898.565578893101;7951.602419878983;7955.721549745731;871.7689812199773;936.3024546712743;948.6869333120054;1006.8842083160383;1035.0942924940678;26912;28114.43771124566;29277.437286915418;29280.560791588145;30111.849440753092;985.7486340983004;996.5266498413196;1051.4427353502656;1061.1616822710128;1170.4674373160192;12313.93843030797;12843.935780321039;14634.780478292834;15110.773338399933;15639.3744250231;596793;610277;610413;611916;613268;55669;56140;56230.71884640632;56288;56419.43580564224;1239.646700690317;1288.9033322500663;1363.849976502584;1387.9028468007346;1401.2153194211273;29403.41193176167;30308.242293941945;30864.382712345177;30924.381512370077;31183.53224701586;47748.761256194884;48149.75925120352;48263.27605085856;48283.75858120687;48552.75723621359;527321.3633931805;529042.354788239;529934;530890;643720;38975.415368768925;39133;40917.59082409257;41108.58891411167;41207.58792412157;43996.56003439925;44654.3301850466;45378.319325209486;45714.54285457103;45758;30778.692213077582;37418.625813742605;37420.6257937428;37526.81236593799;37965.62034379621;36802.63197367992;37248.81375593105;38120.61879381171;38159.04602384962;38215.42676859905;36182.09544761402;36355;36728.449073263386;37165.62834371622;37210.06974825651;31712.682873170972;32412.627675007185;32952.67047329496;33668.32663346768;33671.494927575615;20397;24419.511609768062;24554.01783928623;24818.875905620356;25337.619935701347 16:57:48 INFO - PROCESS | 1662 | |2;jquery.html;349.6101846441219;364.38055305979833;368.2414226693014;376.69299520890473;398.2811026097894;15134.394624215032;15211.695766084671;16317.510474685763;20722.896385518052;21341.57316853662;19974.600507989868;20424.387268381935;20490.18039278427;21073.47316317093;21197.894010529963;4721.6458765592715;5938.64368137911;6224.875502489947;6230.657313847737;6234.345393733661;6016.639001659914;6179.412955769195;6189.721462534174;6201.627902325875;6205.658688772116;5937.1984782054515;6033.818985430444;6071.210742603477;6157.353477884825;6184.319724830265;4968.180250258739;5474.589405794542;6081.753240585669;6082.878342433149;6144.815655530341;4503.347014682885;5017.147084995523;5227.242049902781;5277.76250068744;5295.841124766263;25994.480110397875;26057.60913586298;26125.346866328302;26243.63807257356;26378.208653740418;2026.2401599400225;2259.5932732108213;2419.685440892673;2602.583586626131;2739.9726002739917;10423.583056677724;10486.213533984905;11345.489452974636;15481.071135731892;16022.919885400615;1832.6334733053309;1874.6250749850085;1936.1093896807486;1945.4163750874823;1945.659509585831;79692.60153699195;118090.40954795257;127659.36170319183;127982;131392;11270.492827822767;11462.108752055818;11636.825447618292;11658.475368608433;11825.994790442737;14543.490977815725;14566.490172844111;14581.416743330361;14582.343794529272;14586.927065364605;6739.898901516482;8632.35045833289;8659.610317535726;8827.514486703165;8840.381173317935;6892.414144797748;8610.6986255482;8659.567021648892;8815.118488151134;8831.984321802945;4442.622377097919;4540.8637740867825;4550.112728018004;4569.977150114295;4641.396618439649;1564.7965764450548;1783.1440908363797;1941.6019715958196;1970.3399361214153;1979.4259664697076;637.3912913167914;724.8260417499762;742.5099434373287;812.3704129299722;934.8317302885546;19756.407307780788;21042.053107610194;21437.571248574943;23030.42423939415;23047.654285185734;736.7384578474672;916.679162293192;946.8674385586023;954.4941181173989;991.8214721350226;10848.870388230782;11319.528461299979;11350.318980861175;11436.656900293005;11438;598193;605643;611357;611473;621477;99677;130732;140605;144495;144534;928.6146249306462;994.2841154368842;1020.3418794877412;1022.3150489172273;1024.938503689781;18666.626667466302;19617.411477655693;19631.018449077204;19699.11353989045;19705.113269903177;55445.722771387496;56481.71759141178;57041.71479142578;57920.131198031224;57959.84080318455;82992;83136.33727325298;83619.5819020901;84894;85577.87487626569;7121.252268511732;8029.839403211786;8059.476134051268;8404.537750423786;8451.83096338082;11243.931826476472;12304.138710290194;12705.983521318458;13217.603471895858;13230.206187628773;10936.945315273373;11194.66416007521;11223.66329010131;11345.092392608736;11495.712607184887;8779.20987111163;10565.154787616819;10966.890331096587;11056.668299951014;11589.188756786943;11083.279586826831;11196.38419886914;11417.0866330695;11489.425528723697;11769.293842369143;8073.6770529178175;8116.594170291343;8152.592370381576;8527.573621319034;8530.78673033179;10177.491125443847;11009.174311926798;11413.029892459233;11459.770804584028;11518.308901465938 16:57:48 INFO - PROCESS | 1662 | |3;mootools.html;1551.2864082522035;1564.0615630621626;1733.9479815605528;1874.5501079740866;1888.2730148892672;8521.275691566216;9002.279817614592;9002.819943601124;9012.639494420224;9025.232855207307;7230.674619642115;7923.613367660665;8175.632096555654;8289.171082891708;8828.558572071403;2121.09853312342;2258.3563684349965;2341.438054866828;2345.9765402346015;2348.7064116985375;2335.9182428615004;2336.4626135988733;2337.31049340445;2341.94145146371;2344.23812261015;2076.9896150519303;2293.3463962770616;2352.764723527642;2355.105060077177;2357;1957.9510512237166;2102.2431924507164;2248.62897621893;2317.687112239843;2320.280712978982;2288.9771102289096;2312.9653055204185;2315.2938353802083;2326.9883650581637;2327.94180145496;8879;8933.06202848698;8943.195112439847;8950.149735775156;8950.820983580292;5136.8202112925865;5393.622446428752;5395.163749618776;5397.595180361489;5409.810656627039;3447.0692912913373;3570.9107272318142;3650.34293827111;3660.5790334111384;3661.0847288177956;1043.7390652336917;1072.6781965410423;1080.5407701726813;1096.780643871221;1097.5280629329386;11810.763784724344;11836.05311575072;11886.049116070693;11892.167548271624;11909.344986025852;4393.165298593257;4541.886452838639;4724.055188962221;4753.2668715488735;4754;8441.915580844236;8513.787155321043;10042.19662427004;10054.195664346835;10054.899451005396;5356.276902618097;5356.8660783479945;5359.196120581866;5361.812336568201;5384.192371144281;4770.95229047712;4854.17479028566;4866.221404575251;4909.0672772173175;4917.041176970456;3842.769433833979;3875.0312421894528;3897.4348719435525;3930.21395720857;3957.22834047358;1244.7261602447447;1255.234307072685;1263.1410640764368;1272.5673271087874;1285.5243559882715;810.3922058456158;879.1691851200554;913.4291068082448;913.9451632902047;962.4225464721251;5783.392743761929;5796.797112101056;5958.523318134538;5983;6136.969315153485;439.2423070203873;442.3232454344857;446.2703479810468;951.5004622573066;995.2684776689127;3412.2151905061974;3880.20455806559;4049.8987525312104;4051.434737431042;4074.714769966074;10515.001074897873;10808.51361688726;10825.188110891715;11009.2843965142;11010;10779.67660970172;10822;10876.673699789018;10925.235233533735;10988.670339889815;8088;8097.35221182304;8181.140980197112;8319.459235149696;8329.416940814206;5887.0580707086665;5894.734736936934;5918.7928422505;5984.042553191469;6010.098485227251;7911.604419778873;9986.400815950776;10022.498875056071;10098.242631802497;10191.745206369604;10097.495125243562;10155.89844101579;10273.075423211947;10304.742381440525;10314.484275786332;7464.365528929885;7494.587797671181;7634.274743899321;7640.121386040676;7667.424943129176;8176.46852954556;8198.590070496331;8364.205400486944;8372.706955256537;8374.204450577185;6069.787557435468;6224.470919971853;7501.512401693873;7534.020577324913;7724.729634462767;6515.446187074152;7273.163586187656;7459.738909138154;7550.509216900884;7595.202503737249;6819.5226334158115;6909.481788865754;7012.684429200596;7023.754168604074;7023.964880175567;4986.850394488171;5028.798848046037;5074.720890351066;5083.262926875508;5171.146760784429;4915.3855768029;4935.161022626234;5104.106781313181;5240.34495688039;5241.737913104405 16:57:48 INFO - PROCESS | 1662 | |4;prototype.html;300.46667165780735;308.7529976019184;309.5063373918599;315.51883377848793;315.78999965023246;5273.156294992802;5273.499017593332;5288.709120998344;5306.840794776158;5317.627766056368;4175.812088456019;4280.143971205755;5261.658277139324;5272.051030814452;5274.472552744734;3406.7104296134858;3479.8168622668286;3829;3918.746001279592;3919.1965647042357;3295.5715756951563;3822.541295044585;4037.5356833964174;4054.3310353791735;4092.242935057009;3621.746477746559;3963.702722295825;3991.6207960243732;4006.198760247962;4009.7393669411395;3874.8062596870186;3992.7803970781597;4012.3379642358977;4026.5973402659793;4072.226277007389;2773.4869049225754;3509.7016753575977;3517.4899639552123;3525.506429099928;3527.4885141654318;4239.51245606753;4548.885523046842;5103.489651034867;5156.613254005927;5157.30376399185;3603.6126091454717;4295.542088618692;4413.558644135561;4636.0727854428915;4662.930056049162;5448.346198456171;5515.420880807497;5517.503424691757;5573.944260557383;5598.636088654224;3798.240351929625;3801.6925275010917;3801.7909015004166;3812.913319703871;3813.7521061130938;5480.835574932758;5856.736446859902;5880.235569375954;5889.411058894119;5903.26209223847;3673.2102597941034;3793.203427280247;3864.768113913174;3869.5356557213313;3879.0302424393903;3930.4844899264494;3940.3498422760495;3942.8619998300496;3980.840766369313;3980.8407663693706;4555.180067587866;4580.358749775034;4580.404547408815;4609.446866376056;4621.630269578425;4059.715819892579;5602.467765562265;5617.634853734494;5637.520810731134;5672.192264828854;1105.4030823355458;1333.3200067965213;1343.328335832084;1345.8586848380976;1346.5556366399064;773.5822655765824;804.4007214625025;820.9368867316839;821.765796747924;829.5686243153526;5041.59667226621;5074.416442109205;5110.386753589519;5284.630075894651;5297.655652382583;5171.50870667286;5432.429594892558;5486.149646804752;5621.915671264935;5745.368009518949;4922.630802689741;4924.852254432372;5212.765425555859;5248.475152484721;5251.52736253739;2671.559192733216;2998.7750918681186;3016.3364059906785;3038.9392212155635;3041.4373340931998;5872.412758724094;5943.286805583358;5945.108233764883;5982.551308651869;5992.34084250732;5471.616986810965;5897.49871260939;5913.733881975236;5981.401859814071;5994.7602095916545;5799.739011744482;6015.368386319461;6036.563126102311;6050.667213303311;6065.787697430569;4188.85339013133;5381.192821076713;5490.368607610176;5831.650100993954;5862.237909071964;4981.28145789702;5019.268352418409;5441.884413695104;5710.600257981901;5715.542536653127;5003.673291919275;5942.762289508544;5975.790847320323;6053.545984051126;6057.969710151421;3318.838406557782;3348.547946027255;3678.135638124985;3708.276886007203;3829.425586162098;5769.307683078057;5786.363500014911;5790.9286781945475;5797.565182611405;5839.562032847638;5615.483819368829;5645.1532270159805;5647.971760141173;5686.317641883001;5695.209347419652;4869.659123861437;5364.731763411735;5596.244506991504;5628.662280263197;5686.374498805046;5492.340919089735;5501.771782428123;5510.201020851874;5511.448855114617;5525.557955363481;4502.919299368194;5401.4868587484125;5534.315856809364;5555.194561767523;5601.719914004202;4802.615790736662;4992.975035124801;5121.590272778094;5496.839317392595;5500.614956952975 16:57:48 INFO - PROCESS | 1662 | |5;yui.html;580.6777238632559;650.0854982077782;695.4297476069625;714.125196634123;820.1839170025826;15281.388744450223;15781.684366312667;15871.523854284376;15955.042697438163;16054.839451605496;14334.569962901103;15220.847791522081;15889.682206355867;16097;16547.586310342216;691.5988726538595;854.09465966076;886.020946853727;886.1360173830528;890.9821803563957;707.2220557386885;842.8862103616025;874.903760586335;875.9794839012575;879.8636211387245;917.4128557723062;917.8164367126568;918.7335672654922;919.4804935211585;920.2729843423721;916.3219217778862;917.9947956987088;918.2699753695828;920.0569416348296;920.0707285641483;536.6484952356213;550.7191332420455;550.7769353411875;551.9530839878615;554.2572952243972;15373.769393459108;15894.284757185955;15958.202089895574;15960.361585536564;15968.600784980352;13906.652333691638;14245.358958846773;14358.784618230735;14497.565073047825;14531.418743250257;11674.68124588467;11857.169998100137;11977.880221197764;12052.096092793076;12235.632931012084;3765.35988881888;3802.3454739295476;3830.751001184914;3856.942145867786;3963.5243770747406;113606;115829.99999999916;116512;117573.41213293964;119240;10767.89232107669;10868.728281793019;10978.945105274423;11112.55549778016;11310.264832785842;20305.593888122155;20934.58130837375;21240.575188496143;21262.468438288855;21266.680999785014;7886.64510097047;8081.232282933112;8095.716649917343;8103.959480202561;8164.469309494863;4464.776761161994;4485.596296333346;4498.752568608693;4580.862574122782;4643.721376717408;3616.9276614467562;3735.589085200626;3771.4719939208535;3773.3585290500682;3773.8301776420126;688.8863337549349;700.2997002997002;704.1550139832221;705.0376236437329;713.3651050565002;535.8338914936382;564.9152627105885;628.977985770505;658.5818005566404;664.8371149068413;8729.825403491895;9412.952935235417;9542.570584323723;9700.563474643657;9758.12176904068;543.2937181663822;549.373713966079;564.2551831582272;633.7306644675995;673.8382788130813;4614.238650622677;4617.12274667812;4678.8596342109795;4688.2733176357715;4753.33453316536;149144;159266;162120;163184;164153.3584664138;140977;142271;142977;145274;146278.26860865628;115507;119413;122977;123855;124430.377848112;9020.233280170996;9044.276457883485;9080.954595226982;9134.173165366821;9186.954065229898;59210.81578368495;59628;62675.6866215666;65617.67191164014;70714.64642676753;91536.54231728798;95729;96013;99105;99670;10876.836847447137;11534.653960381202;12756.87243127594;13093.279869607262;13186.538471153464;14733.336999834819;14733.778993314894;14790.260486975823;15120.243987800346;15575.454859080332;13635.727285454434;13676.93161534186;13699.863001369858;13839.930800345934;13911.860881391462;13815.516456923959;13850.09974351664;14253.35859886287;14436.494722684656;14444.711105777615;13215.207087574776;13549.932250338685;13799.931000345336;13956.651083722581;13960.930195348958;11383.487743051748;11418.543258269576;11540.769184616092;11694.824577631172;11902.761944761229;11358.421294171678;11429.199956003;11483.770324593293;11606.535738570363;11641.59254426091 16:57:48 INFO - PROCESS | 1662 | __end_tp_report 16:57:48 INFO - PROCESS | 1662 | __start_cc_report 16:57:48 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2021 16:57:48 INFO - PROCESS | 1662 | __end_cc_report 16:57:48 INFO - PROCESS | 1662 | __startTimestamp1475798268457__endTimestamp 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | ------- Summary: start ------- 16:57:48 INFO - PROCESS | 1662 | Number of tests: 6 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5733.48 Median:5889.91 stddev:649.90 (11.0%) stddev-sans-first:623.82 16:57:48 INFO - PROCESS | 1662 | Values: 3325.1 3326.9 3332.7 3371.6 3383.5 5564.0 5656.5 5678.7 5697.1 5712.1 5633.9 5678.7 5687.7 5748.7 5762.5 5142.7 5166.9 5200.3 5210.4 5228.4 5292.8 5319.8 5329.9 5334.5 5388.0 5146.3 5171.0 5239.6 5269.7 5297.8 5226.9 5230.9 5231.6 5238.3 5241.2 5143.8 5180.4 5197.9 5209.6 5211.8 5627.6 5702.9 5703.9 5712.6 5734.6 6226.3 6231.1 6232.4 6237.7 6256.7 6232.3 6234.9 6311.7 6313.3 6315.4 5642.0 5642.3 5707.9 5763.0 5763.7 6239.4 6261.9 6285.2 6288.1 6307.5 4961.5 6178.2 6181.9 6203.7 6204.8 6121.1 6180.0 6288.7 6289.4 6289.5 6139.6 6165.8 6202.8 6229.9 6267.3 6254.8 6255.7 6260.5 6263.5 6284.3 4875.5 4879.4 4917.3 4919.0 4922.1 4319.9 4381.9 4386.5 4388.5 4426.9 5773.0 5779.6 5788.3 5791.3 5837.6 6315.5 6320.9 6331.0 6338.1 6348.0 5567.4 5675.0 5693.9 5744.9 5755.1 5321.5 5357.7 5375.5 5376.1 5380.7 6277.2 6279.4 6288.5 6295.1 6302.7 5313.1 6193.3 6203.5 6232.5 6272.4 6310.1 6318.9 6326.7 6331.8 6344.8 6204.7 6210.5 6225.4 6228.3 6238.5 6141.0 6145.0 6285.2 6300.4 6316.6 6085.5 6118.4 6132.9 6139.2 6149.8 6118.2 6124.2 6128.1 6131.3 6139.3 5771.6 5964.0 5965.4 5978.9 6004.0 5003.9 5973.4 6067.3 6072.5 6074.0 5868.7 5874.5 5874.7 5889.9 5893.1 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:67169.49 Median:36319.64 stddev:145647.92 (401.0%) stddev-sans-first:145970.43 16:57:48 INFO - PROCESS | 1662 | Values: 476.6 506.1 520.2 528.2 540.0 28289.3 39497.0 41096.6 41121.4 41131.8 34146.5 41562.8 42096.4 42104.2 42174.6 8772.5 9708.4 9937.6 9946.2 9948.0 8777.0 11987.6 12287.8 12294.3 12495.4 12196.5 12290.9 12305.4 12308.4 12310.6 8851.9 12235.5 12309.8 12339.9 12345.9 8811.6 10027.6 11766.2 12079.2 12324.0 40499.4 40821.6 40830.8 40910.6 40959.0 3305.2 3350.6 3425.1 3581.6 3585.1 25363.9 30888.0 30972.2 31103.4 32215.2 3237.8 3238.0 3255.3 3367.6 3375.6 462992.0 464200.0 464384.0 464489.0 467683.0 56878.0 57158.0 57278.7 57403.0 57476.4 34308.7 34875.5 35233.5 36267.8 36284.3 15896.0 16055.5 16058.9 16084.0 16091.7 11157.0 11383.1 11615.8 12385.8 12401.1 15441.6 16533.6 18494.9 18791.0 19242.7 6586.8 7758.7 7898.6 7951.6 7955.7 871.8 936.3 948.7 1006.9 1035.1 26912.0 28114.4 29277.4 29280.6 30111.8 985.7 996.5 1051.4 1061.2 1170.5 12313.9 12843.9 14634.8 15110.8 15639.4 596793.0 610277.0 610413.0 611916.0 613268.0 55669.0 56140.0 56230.7 56288.0 56419.4 1239.6 1288.9 1363.8 1387.9 1401.2 29403.4 30308.2 30864.4 30924.4 31183.5 47748.8 48149.8 48263.3 48283.8 48552.8 527321.4 529042.4 529934.0 530890.0 643720.0 38975.4 39133.0 40917.6 41108.6 41207.6 43996.6 44654.3 45378.3 45714.5 45758.0 30778.7 37418.6 37420.6 37526.8 37965.6 36802.6 37248.8 38120.6 38159.0 38215.4 36182.1 36355.0 36728.4 37165.6 37210.1 31712.7 32412.6 32952.7 33668.3 33671.5 20397.0 24419.5 24554.0 24818.9 25337.6 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35778.00 Median:26091.48 stddev:101902.12 (390.6%) stddev-sans-first:102153.26 16:57:48 INFO - PROCESS | 1662 | Values: 349.6 364.4 368.2 376.7 398.3 15134.4 15211.7 16317.5 20722.9 21341.6 19974.6 20424.4 20490.2 21073.5 21197.9 4721.6 5938.6 6224.9 6230.7 6234.3 6016.6 6179.4 6189.7 6201.6 6205.7 5937.2 6033.8 6071.2 6157.4 6184.3 4968.2 5474.6 6081.8 6082.9 6144.8 4503.3 5017.1 5227.2 5277.8 5295.8 25994.5 26057.6 26125.3 26243.6 26378.2 2026.2 2259.6 2419.7 2602.6 2740.0 10423.6 10486.2 11345.5 15481.1 16022.9 1832.6 1874.6 1936.1 1945.4 1945.7 79692.6 118090.4 127659.4 127982.0 131392.0 11270.5 11462.1 11636.8 11658.5 11826.0 14543.5 14566.5 14581.4 14582.3 14586.9 6739.9 8632.4 8659.6 8827.5 8840.4 6892.4 8610.7 8659.6 8815.1 8832.0 4442.6 4540.9 4550.1 4570.0 4641.4 1564.8 1783.1 1941.6 1970.3 1979.4 637.4 724.8 742.5 812.4 934.8 19756.4 21042.1 21437.6 23030.4 23047.7 736.7 916.7 946.9 954.5 991.8 10848.9 11319.5 11350.3 11436.7 11438.0 598193.0 605643.0 611357.0 611473.0 621477.0 99677.0 130732.0 140605.0 144495.0 144534.0 928.6 994.3 1020.3 1022.3 1024.9 18666.6 19617.4 19631.0 19699.1 19705.1 55445.7 56481.7 57041.7 57920.1 57959.8 82992.0 83136.3 83619.6 84894.0 85577.9 7121.3 8029.8 8059.5 8404.5 8451.8 11243.9 12304.1 12706.0 13217.6 13230.2 10936.9 11194.7 11223.7 11345.1 11495.7 8779.2 10565.2 10966.9 11056.7 11589.2 11083.3 11196.4 11417.1 11489.4 11769.3 8073.7 8116.6 8152.6 8527.6 8530.8 10177.5 11009.2 11413.0 11459.8 11518.3 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5688.56 Median:4860.20 stddev:3216.00 (66.2%) stddev-sans-first:3209.99 16:57:48 INFO - PROCESS | 1662 | Values: 1551.3 1564.1 1733.9 1874.6 1888.3 8521.3 9002.3 9002.8 9012.6 9025.2 7230.7 7923.6 8175.6 8289.2 8828.6 2121.1 2258.4 2341.4 2346.0 2348.7 2335.9 2336.5 2337.3 2341.9 2344.2 2077.0 2293.3 2352.8 2355.1 2357.0 1958.0 2102.2 2248.6 2317.7 2320.3 2289.0 2313.0 2315.3 2327.0 2327.9 8879.0 8933.1 8943.2 8950.1 8950.8 5136.8 5393.6 5395.2 5397.6 5409.8 3447.1 3570.9 3650.3 3660.6 3661.1 1043.7 1072.7 1080.5 1096.8 1097.5 11810.8 11836.1 11886.0 11892.2 11909.3 4393.2 4541.9 4724.1 4753.3 4754.0 8441.9 8513.8 10042.2 10054.2 10054.9 5356.3 5356.9 5359.2 5361.8 5384.2 4771.0 4854.2 4866.2 4909.1 4917.0 3842.8 3875.0 3897.4 3930.2 3957.2 1244.7 1255.2 1263.1 1272.6 1285.5 810.4 879.2 913.4 913.9 962.4 5783.4 5796.8 5958.5 5983.0 6137.0 439.2 442.3 446.3 951.5 995.3 3412.2 3880.2 4049.9 4051.4 4074.7 10515.0 10808.5 10825.2 11009.3 11010.0 10779.7 10822.0 10876.7 10925.2 10988.7 8088.0 8097.4 8181.1 8319.5 8329.4 5887.1 5894.7 5918.8 5984.0 6010.1 7911.6 9986.4 10022.5 10098.2 10191.7 10097.5 10155.9 10273.1 10304.7 10314.5 7464.4 7494.6 7634.3 7640.1 7667.4 8176.5 8198.6 8364.2 8372.7 8374.2 6069.8 6224.5 7501.5 7534.0 7724.7 6515.4 7273.2 7459.7 7550.5 7595.2 6819.5 6909.5 7012.7 7023.8 7024.0 4986.9 5028.8 5074.7 5083.3 5171.1 4915.4 4935.2 5104.1 5240.3 5241.7 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4525.27 Median:5156.96 stddev:1425.43 (27.6%) stddev-sans-first:1393.71 16:57:48 INFO - PROCESS | 1662 | Values: 300.5 308.8 309.5 315.5 315.8 5273.2 5273.5 5288.7 5306.8 5317.6 4175.8 4280.1 5261.7 5272.1 5274.5 3406.7 3479.8 3829.0 3918.7 3919.2 3295.6 3822.5 4037.5 4054.3 4092.2 3621.7 3963.7 3991.6 4006.2 4009.7 3874.8 3992.8 4012.3 4026.6 4072.2 2773.5 3509.7 3517.5 3525.5 3527.5 4239.5 4548.9 5103.5 5156.6 5157.3 3603.6 4295.5 4413.6 4636.1 4662.9 5448.3 5515.4 5517.5 5573.9 5598.6 3798.2 3801.7 3801.8 3812.9 3813.8 5480.8 5856.7 5880.2 5889.4 5903.3 3673.2 3793.2 3864.8 3869.5 3879.0 3930.5 3940.3 3942.9 3980.8 3980.8 4555.2 4580.4 4580.4 4609.4 4621.6 4059.7 5602.5 5617.6 5637.5 5672.2 1105.4 1333.3 1343.3 1345.9 1346.6 773.6 804.4 820.9 821.8 829.6 5041.6 5074.4 5110.4 5284.6 5297.7 5171.5 5432.4 5486.1 5621.9 5745.4 4922.6 4924.9 5212.8 5248.5 5251.5 2671.6 2998.8 3016.3 3038.9 3041.4 5872.4 5943.3 5945.1 5982.6 5992.3 5471.6 5897.5 5913.7 5981.4 5994.8 5799.7 6015.4 6036.6 6050.7 6065.8 4188.9 5381.2 5490.4 5831.7 5862.2 4981.3 5019.3 5441.9 5710.6 5715.5 5003.7 5942.8 5975.8 6053.5 6058.0 3318.8 3348.5 3678.1 3708.3 3829.4 5769.3 5786.4 5790.9 5797.6 5839.6 5615.5 5645.2 5648.0 5686.3 5695.2 4869.7 5364.7 5596.2 5628.7 5686.4 5492.3 5501.8 5510.2 5511.4 5525.6 4502.9 5401.5 5534.3 5555.2 5601.7 4802.6 4993.0 5121.6 5496.8 5500.6 16:57:48 INFO - PROCESS | 1662 | 16:57:48 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:26345.23 Median:3676.26 stddev:42926.38 (1167.7%) stddev-sans-first:43003.21 16:57:48 INFO - PROCESS | 1662 | Values: 580.7 650.1 695.4 714.1 820.2 15281.4 15781.7 15871.5 15955.0 16054.8 14334.6 15220.8 15889.7 16097.0 16547.6 691.6 854.1 886.0 886.1 891.0 707.2 842.9 874.9 876.0 879.9 917.4 917.8 918.7 919.5 920.3 916.3 918.0 918.3 920.1 920.1 536.6 550.7 550.8 552.0 554.3 15373.8 15894.3 15958.2 15960.4 15968.6 13906.7 14245.4 14358.8 14497.6 14531.4 11674.7 11857.2 11977.9 12052.1 12235.6 3765.4 3802.3 3830.8 3856.9 3963.5 113606.0 115830.0 116512.0 117573.4 119240.0 10767.9 10868.7 10978.9 11112.6 11310.3 20305.6 20934.6 21240.6 21262.5 21266.7 7886.6 8081.2 8095.7 8104.0 8164.5 4464.8 4485.6 4498.8 4580.9 4643.7 3616.9 3735.6 3771.5 3773.4 3773.8 688.9 700.3 704.2 705.0 713.4 535.8 564.9 629.0 658.6 664.8 8729.8 9413.0 9542.6 9700.6 9758.1 543.3 549.4 564.3 633.7 673.8 4614.2 4617.1 4678.9 4688.3 4753.3 149144.0 159266.0 162120.0 163184.0 164153.4 140977.0 142271.0 142977.0 145274.0 146278.3 115507.0 119413.0 122977.0 123855.0 124430.4 9020.2 9044.3 9081.0 9134.2 9187.0 59210.8 59628.0 62675.7 65617.7 70714.6 91536.5 95729.0 96013.0 99105.0 99670.0 10876.8 11534.7 12756.9 13093.3 13186.5 14733.3 14733.8 14790.3 15120.2 15575.5 13635.7 13676.9 13699.9 13839.9 13911.9 13815.5 13850.1 14253.4 14436.5 14444.7 13215.2 13549.9 13799.9 13956.7 13960.9 11383.5 11418.5 11540.8 11694.8 11902.8 11358.4 11429.2 11483.8 11606.5 11641.6 16:57:48 INFO - PROCESS | 1662 | -------- Summary: end -------- 16:57:48 INFO - PROCESS | 1662 | 16:57:49 INFO - TEST-INFO | 1662: exit 0 16:57:49 INFO - TEST-OK | dromaeo_css | took 1119905ms 16:57:49 INFO - TEST-START | kraken 16:57:49 INFO - operating with platform_type : mac_ 16:57:49 INFO - Initialising browser for kraken test... 16:57:49 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp0PuRqI/profile http://localhost:49267/getInfo.html) 16:57:51 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 16:57:51 INFO - PROCESS | 1667 | colorDepth:24 16:57:51 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 16:57:51 INFO - PROCESS | 1667 | __metrics 16:57:51 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" 16:57:51 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] 16:57:52 INFO - TEST-INFO | 1667: exit 0 16:57:52 INFO - Browser initialized. 16:57:52 INFO - Running cycle 1/1 for kraken test... 16:57:52 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp0PuRqI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:58:04 INFO - PROCESS | 1668 | RSS: Main: 243822592 16:58:04 INFO - PROCESS | 1668 | 16:58:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 16:58:08 INFO - PROCESS | 1668 | RSS: Main: 294117376 16:58:08 INFO - PROCESS | 1668 | 16:58:10 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 16:58:11 INFO - PROCESS | 1668 | RSS: Main: 293519360 16:58:11 INFO - PROCESS | 1668 | 16:58:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 16:58:15 INFO - PROCESS | 1668 | RSS: Main: 385589248 16:58:15 INFO - PROCESS | 1668 | 16:58:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 16:58:17 INFO - PROCESS | 1668 | RSS: Main: 284684288 16:58:17 INFO - PROCESS | 1668 | 16:58:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:58:20 INFO - PROCESS | 1668 | RSS: Main: 290033664 16:58:20 INFO - PROCESS | 1668 | 16:58:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 16:58:24 INFO - PROCESS | 1668 | RSS: Main: 327725056 16:58:24 INFO - PROCESS | 1668 | 16:58:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 16:58:27 INFO - PROCESS | 1668 | RSS: Main: 328159232 16:58:27 INFO - PROCESS | 1668 | 16:58:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 16:58:31 INFO - PROCESS | 1668 | RSS: Main: 326746112 16:58:31 INFO - PROCESS | 1668 | 16:58:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:58:33 INFO - PROCESS | 1668 | RSS: Main: 302518272 16:58:33 INFO - PROCESS | 1668 | 16:58:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:58:35 INFO - PROCESS | 1668 | RSS: Main: 304078848 16:58:35 INFO - PROCESS | 1668 | 16:58:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:58:38 INFO - PROCESS | 1668 | RSS: Main: 309497856 16:58:38 INFO - PROCESS | 1668 | 16:58:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:58:42 INFO - PROCESS | 1668 | RSS: Main: 326565888 16:58:42 INFO - PROCESS | 1668 | 16:58:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:58:45 INFO - PROCESS | 1668 | RSS: Main: 319905792 16:58:45 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 16:58:47 INFO - PROCESS | 1668 | RSS: Main: 364343296 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | __start_tp_report 16:58:47 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 16:58:47 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 16:58:47 INFO - PROCESS | 1668 | |i|pagename|runs| 16:58:47 INFO - PROCESS | 1668 | |0;ai-astar;114;114;113;113;114;114;113;112;113;114 16:58:47 INFO - PROCESS | 1668 | |1;audio-beat-detection;147;115;112;148;113;113;116;111;141;111 16:58:47 INFO - PROCESS | 1668 | |2;audio-dft;182;183;184;183;183;184;181;178;184;247 16:58:47 INFO - PROCESS | 1668 | |3;audio-fft;79;58;58;86;59;59;59;58;80;58 16:58:47 INFO - PROCESS | 1668 | |4;audio-oscillator;82;71;91;71;67;70;91;94;67;90 16:58:47 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;87;84;88;85;89;127;88;84;87;83 16:58:47 INFO - PROCESS | 1668 | |6;imaging-darkroom;92;93;92;93;92;102;151;129;92;94 16:58:47 INFO - PROCESS | 1668 | |7;imaging-desaturate;84;107;131;137;83;83;86;85;88;83 16:58:47 INFO - PROCESS | 1668 | |8;json-parse-financial;55;55;55;53;53;54;52;53;55;54 16:58:47 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;65;77;79;75;71;68;70;70;70;70 16:58:47 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;85;86;85;85;90;89;85;102;99 16:58:47 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;158;161;143;148;155;153;144;159;195;155 16:58:47 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;233;186;174;172;171;171;170;172;170;172 16:58:47 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;68;72;66;89;68;69;68;68;68 16:58:47 INFO - PROCESS | 1668 | __end_tp_report 16:58:47 INFO - PROCESS | 1668 | __start_cc_report 16:58:47 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1957 16:58:47 INFO - PROCESS | 1668 | __end_cc_report 16:58:47 INFO - PROCESS | 1668 | __startTimestamp1475798327967__endTimestamp 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | ------- Summary: start ------- 16:58:47 INFO - PROCESS | 1668 | Number of tests: 14 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 16:58:47 INFO - PROCESS | 1668 | Values: 114.0 114.0 113.0 113.0 114.0 114.0 113.0 112.0 113.0 114.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:115.50 stddev:15.80 (13.7%) stddev-sans-first:14.10 16:58:47 INFO - PROCESS | 1668 | Values: 147.0 115.0 112.0 148.0 113.0 113.0 116.0 111.0 141.0 111.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:188.90 Median:183.50 stddev:20.50 (11.2%) stddev-sans-first:21.59 16:58:47 INFO - PROCESS | 1668 | Values: 182.0 183.0 184.0 183.0 183.0 184.0 181.0 178.0 184.0 247.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:65.40 Median:59.00 stddev:11.37 (19.3%) stddev-sans-first:10.95 16:58:47 INFO - PROCESS | 1668 | Values: 79.0 58.0 58.0 86.0 59.0 59.0 59.0 58.0 80.0 58.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:86.00 stddev:11.25 (13.1%) stddev-sans-first:11.89 16:58:47 INFO - PROCESS | 1668 | Values: 82.0 71.0 91.0 71.0 67.0 70.0 91.0 94.0 67.0 90.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:87.00 stddev:13.09 (15.0%) stddev-sans-first:13.83 16:58:47 INFO - PROCESS | 1668 | Values: 87.0 84.0 88.0 85.0 89.0 127.0 88.0 84.0 87.0 83.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:92.00 stddev:20.40 (22.2%) stddev-sans-first:21.25 16:58:47 INFO - PROCESS | 1668 | Values: 92.0 93.0 92.0 93.0 92.0 102.0 151.0 129.0 92.0 94.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:83.50 stddev:20.97 (25.1%) stddev-sans-first:21.73 16:58:47 INFO - PROCESS | 1668 | Values: 84.0 107.0 131.0 137.0 83.0 83.0 86.0 85.0 88.0 83.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.50 stddev:1.10 (2.0%) stddev-sans-first:1.09 16:58:47 INFO - PROCESS | 1668 | Values: 55.0 55.0 55.0 53.0 53.0 54.0 52.0 53.0 55.0 54.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:70.50 stddev:4.25 (6.0%) stddev-sans-first:3.80 16:58:47 INFO - PROCESS | 1668 | Values: 65.0 77.0 79.0 75.0 71.0 68.0 70.0 70.0 70.0 70.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:86.00 stddev:6.25 (7.3%) stddev-sans-first:6.52 16:58:47 INFO - PROCESS | 1668 | Values: 86.0 85.0 86.0 85.0 85.0 90.0 89.0 85.0 102.0 99.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:156.50 stddev:14.66 (9.4%) stddev-sans-first:15.55 16:58:47 INFO - PROCESS | 1668 | Values: 158.0 161.0 143.0 148.0 155.0 153.0 144.0 159.0 195.0 155.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:172.00 stddev:19.51 (11.3%) stddev-sans-first:4.99 16:58:47 INFO - PROCESS | 1668 | Values: 233.0 186.0 174.0 172.0 171.0 171.0 170.0 172.0 170.0 172.0 16:58:47 INFO - PROCESS | 1668 | 16:58:47 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:68.00 stddev:6.70 (9.9%) stddev-sans-first:7.05 16:58:47 INFO - PROCESS | 1668 | Values: 68.0 68.0 72.0 66.0 89.0 68.0 69.0 68.0 68.0 68.0 16:58:47 INFO - PROCESS | 1668 | -------- Summary: end -------- 16:58:47 INFO - PROCESS | 1668 | 16:58:48 INFO - TEST-INFO | 1668: exit 0 16:58:48 INFO - TEST-OK | kraken | took 59556ms 16:58:48 INFO - SUITE-END | took 1179s 16:58:49 INFO - Completed test suite (00:19:39) 16:58:49 INFO - javascript benchmark 16:58:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.1188435064696, 3326.933461330773, 3332.700056994871, 3371.612264589573, 3383.543221665076, 5563.998480273549, 5656.547476201904, 5678.687672178034, 5697.088465845465, 5712.0575105107655, 5633.915491267634, 5678.716064196784, 5687.744051517681, 5748.683822389767, 5762.481376676098, 5142.717150556718, 5166.896662066756, 5200.349956255468, 5210.4008039075425, 5228.372595288552, 5292.814751483699, 5319.760610772524, 5329.920051199235, 5334.51989320961, 5388.030154572176, 5146.253793200002, 5171.043356978971, 5239.607029472767, 5269.736513174326, 5297.761600727977, 5226.895462090775, 5230.92153617696, 5231.581473482151, 5238.266642670029, 5241.240020197089, 5143.7685304161405, 5180.4042535108565, 5197.948020519784, 5209.583233341362, 5211.817586384458, 5627.634203776783, 5702.914456283159, 5703.857403564903, 5712.571557133231, 5734.569907256972, 6226.2839773426185, 6231.127642130105, 6232.439080482778, 6237.688115594247, 6256.749730010794, 6232.34560371159, 6234.906476402857, 6311.747530098791, 6313.337099604521, 6315.400036996478, 5641.971790141023, 5642.322921249394, 5707.857303567444, 5763.0, 5763.682997435139, 6239.438450539473, 6261.9373806262265, 6285.214348206474, 6288.088227207029, 6307.463865571478, 4961.5286547777905, 6178.165947597017, 6181.938180618226, 6203.720832562545, 6204.7828326008375, 6121.148138557471, 6179.969100154471, 6288.685565721696, 6289.371062893765, 6289.528285378615, 6139.6454669416935, 6165.84585385369, 6202.81391558254, 6229.875402491924, 6267.341929097471, 6254.8123556293385, 6255.687215639291, 6260.46786023184, 6263.530235232284, 6284.340144284875, 4875.463698993108, 4879.365682461257, 4917.311576379309, 4918.975405123023, 4922.064807686528, 4319.913601727948, 4381.9123617527475, 4386.473623165177, 4388.539203383599, 4426.933595996063, 5772.960867043888, 5779.624324418899, 5788.27646544182, 5791.276090488689, 5837.591368604157, 6315.494760419156, 6320.905186422207, 6330.968345158245, 6338.080978258126, 6347.96826015867, 5567.415421380778, 5674.9785038693435, 5693.886122277531, 5744.942550574524, 5755.050416681201, 5321.521063104262, 5357.705326207096, 5375.489328513785, 5376.112941364631, 5380.7309634518115, 6277.246730392382, 6279.403456671609, 6288.496920246369, 6295.055741638791, 6302.65335406557, 5313.123334649894, 6193.256809182747, 6203.503719702323, 6232.470240029648, 6272.40412160844, 6310.148130002391, 6318.905216421849, 6326.746930122928, 6331.778387756591, 6344.777932772331, 6204.72078756448, 6210.5031597473, 6225.439710426083, 6228.252609686867, 6238.469730072995, 6141.025769127022, 6145.0, 6285.151504546828, 6300.369963003846, 6316.62100273985, 6085.4523092921845, 6118.388161183846, 6132.908006379818, 6139.185504090546, 6149.815505534841, 6118.230673832282, 6124.167343420869, 6128.105930682507, 6131.254333328352, 6139.308154111861, 5771.595988280948, 5964.0, 5965.373635768356, 5978.880422391615, 6004.039353703386, 5003.899922001467, 5973.402659733992, 6067.332593414632, 6072.544559158169, 6074.0, 5868.67722275279, 5874.530037596896, 5874.676892770939, 5889.911651325319, 5893.086571581412], "value": 5691.649677406635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.58298988385144, 506.1041955738341, 520.2274622186054, 528.1575886461096, 540.049512857371, 28289.292767680818, 39497.0, 41096.58903410965, 41121.383179252334, 41131.766047018566, 34146.48780268292, 41562.7921860391, 42096.36855447171, 42104.15791684164, 42174.57825421745, 8772.517511536864, 9708.368956017854, 9937.552810123525, 9946.20430365569, 9947.950260248688, 8776.956115219447, 11987.64037078889, 12287.815682764722, 12294.323812190327, 12495.375231238402, 12196.49971755788, 12290.938545307261, 12305.384730763471, 12308.44611992458, 12310.569130080448, 8851.911480885174, 12235.510579576807, 12309.753804923852, 12339.938300308531, 12345.876541234653, 8811.603477843513, 10027.649032283834, 11766.176367654267, 12079.154459187861, 12324.0, 40499.39250911239, 40821.59178408208, 40830.79584602058, 40910.59089409097, 40958.9760255993, 3305.2397948471985, 3350.5811773528308, 3425.1094715374184, 3581.552305961755, 3585.0678823506078, 25363.858626361856, 30888.0, 30972.225694357596, 31103.37793244145, 32215.19462013445, 3237.8057316560844, 3238.012406216103, 3255.3326568053494, 3367.6463971282906, 3375.611804642473, 462992.0, 464200.0, 464384.0, 464489.0, 467683.0, 56878.0, 57158.0, 57278.7136064317, 57403.0, 57476.425235747105, 34308.65691343105, 34875.476867847, 35233.47149792755, 36267.81866090652, 36284.27431451356, 15896.0, 16055.518334449986, 16058.919705401631, 16084.034957902564, 16091.67816643684, 11157.0, 11383.089352851917, 11615.825762613567, 12385.752284954251, 12401.069919756055, 15441.613959651098, 16533.58666033335, 18494.907525462288, 18790.966496842804, 19242.71135932962, 6586.8353291168105, 7758.728444504415, 7898.565578893101, 7951.602419878983, 7955.721549745731, 871.7689812199773, 936.3024546712743, 948.6869333120054, 1006.8842083160383, 1035.0942924940678, 26912.0, 28114.43771124566, 29277.437286915418, 29280.560791588145, 30111.849440753092, 985.7486340983004, 996.5266498413196, 1051.4427353502656, 1061.1616822710128, 1170.4674373160192, 12313.93843030797, 12843.935780321039, 14634.780478292834, 15110.773338399933, 15639.3744250231, 596793.0, 610277.0, 610413.0, 611916.0, 613268.0, 55669.0, 56140.0, 56230.71884640632, 56288.0, 56419.43580564224, 1239.646700690317, 1288.9033322500663, 1363.849976502584, 1387.9028468007346, 1401.2153194211273, 29403.41193176167, 30308.242293941945, 30864.382712345177, 30924.381512370077, 31183.53224701586, 47748.761256194884, 48149.75925120352, 48263.27605085856, 48283.75858120687, 48552.75723621359, 527321.3633931805, 529042.354788239, 529934.0, 530890.0, 643720.0, 38975.415368768925, 39133.0, 40917.59082409257, 41108.58891411167, 41207.58792412157, 43996.56003439925, 44654.3301850466, 45378.319325209486, 45714.54285457103, 45758.0, 30778.692213077582, 37418.625813742605, 37420.6257937428, 37526.81236593799, 37965.62034379621, 36802.63197367992, 37248.81375593105, 38120.61879381171, 38159.04602384962, 38215.42676859905, 36182.09544761402, 36355.0, 36728.449073263386, 37165.62834371622, 37210.06974825651, 31712.682873170972, 32412.627675007185, 32952.67047329496, 33668.32663346768, 33671.494927575615, 20397.0, 24419.511609768062, 24554.01783928623, 24818.875905620356, 25337.619935701347], "value": 19837.33768815304, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.6101846441219, 364.38055305979833, 368.2414226693014, 376.69299520890473, 398.2811026097894, 15134.394624215032, 15211.695766084671, 16317.510474685763, 20722.896385518052, 21341.57316853662, 19974.600507989868, 20424.387268381935, 20490.18039278427, 21073.47316317093, 21197.894010529963, 4721.6458765592715, 5938.64368137911, 6224.875502489947, 6230.657313847737, 6234.345393733661, 6016.639001659914, 6179.412955769195, 6189.721462534174, 6201.627902325875, 6205.658688772116, 5937.1984782054515, 6033.818985430444, 6071.210742603477, 6157.353477884825, 6184.319724830265, 4968.180250258739, 5474.589405794542, 6081.753240585669, 6082.878342433149, 6144.815655530341, 4503.347014682885, 5017.147084995523, 5227.242049902781, 5277.76250068744, 5295.841124766263, 25994.480110397875, 26057.60913586298, 26125.346866328302, 26243.63807257356, 26378.208653740418, 2026.2401599400225, 2259.5932732108213, 2419.685440892673, 2602.583586626131, 2739.9726002739917, 10423.583056677724, 10486.213533984905, 11345.489452974636, 15481.071135731892, 16022.919885400615, 1832.6334733053309, 1874.6250749850085, 1936.1093896807486, 1945.4163750874823, 1945.659509585831, 79692.60153699195, 118090.40954795257, 127659.36170319183, 127982.0, 131392.0, 11270.492827822767, 11462.108752055818, 11636.825447618292, 11658.475368608433, 11825.994790442737, 14543.490977815725, 14566.490172844111, 14581.416743330361, 14582.343794529272, 14586.927065364605, 6739.898901516482, 8632.35045833289, 8659.610317535726, 8827.514486703165, 8840.381173317935, 6892.414144797748, 8610.6986255482, 8659.567021648892, 8815.118488151134, 8831.984321802945, 4442.622377097919, 4540.8637740867825, 4550.112728018004, 4569.977150114295, 4641.396618439649, 1564.7965764450548, 1783.1440908363797, 1941.6019715958196, 1970.3399361214153, 1979.4259664697076, 637.3912913167914, 724.8260417499762, 742.5099434373287, 812.3704129299722, 934.8317302885546, 19756.407307780788, 21042.053107610194, 21437.571248574943, 23030.42423939415, 23047.654285185734, 736.7384578474672, 916.679162293192, 946.8674385586023, 954.4941181173989, 991.8214721350226, 10848.870388230782, 11319.528461299979, 11350.318980861175, 11436.656900293005, 11438.0, 598193.0, 605643.0, 611357.0, 611473.0, 621477.0, 99677.0, 130732.0, 140605.0, 144495.0, 144534.0, 928.6146249306462, 994.2841154368842, 1020.3418794877412, 1022.3150489172273, 1024.938503689781, 18666.626667466302, 19617.411477655693, 19631.018449077204, 19699.11353989045, 19705.113269903177, 55445.722771387496, 56481.71759141178, 57041.71479142578, 57920.131198031224, 57959.84080318455, 82992.0, 83136.33727325298, 83619.5819020901, 84894.0, 85577.87487626569, 7121.252268511732, 8029.839403211786, 8059.476134051268, 8404.537750423786, 8451.83096338082, 11243.931826476472, 12304.138710290194, 12705.983521318458, 13217.603471895858, 13230.206187628773, 10936.945315273373, 11194.66416007521, 11223.66329010131, 11345.092392608736, 11495.712607184887, 8779.20987111163, 10565.154787616819, 10966.890331096587, 11056.668299951014, 11589.188756786943, 11083.279586826831, 11196.38419886914, 11417.0866330695, 11489.425528723697, 11769.293842369143, 8073.6770529178175, 8116.594170291343, 8152.592370381576, 8527.573621319034, 8530.78673033179, 10177.491125443847, 11009.174311926798, 11413.029892459233, 11459.770804584028, 11518.308901465938], "value": 9621.530757599685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.2864082522035, 1564.0615630621626, 1733.9479815605528, 1874.5501079740866, 1888.2730148892672, 8521.275691566216, 9002.279817614592, 9002.819943601124, 9012.639494420224, 9025.232855207307, 7230.674619642115, 7923.613367660665, 8175.632096555654, 8289.171082891708, 8828.558572071403, 2121.09853312342, 2258.3563684349965, 2341.438054866828, 2345.9765402346015, 2348.7064116985375, 2335.9182428615004, 2336.4626135988733, 2337.31049340445, 2341.94145146371, 2344.23812261015, 2076.9896150519303, 2293.3463962770616, 2352.764723527642, 2355.105060077177, 2357.0, 1957.9510512237166, 2102.2431924507164, 2248.62897621893, 2317.687112239843, 2320.280712978982, 2288.9771102289096, 2312.9653055204185, 2315.2938353802083, 2326.9883650581637, 2327.94180145496, 8879.0, 8933.06202848698, 8943.195112439847, 8950.149735775156, 8950.820983580292, 5136.8202112925865, 5393.622446428752, 5395.163749618776, 5397.595180361489, 5409.810656627039, 3447.0692912913373, 3570.9107272318142, 3650.34293827111, 3660.5790334111384, 3661.0847288177956, 1043.7390652336917, 1072.6781965410423, 1080.5407701726813, 1096.780643871221, 1097.5280629329386, 11810.763784724344, 11836.05311575072, 11886.049116070693, 11892.167548271624, 11909.344986025852, 4393.165298593257, 4541.886452838639, 4724.055188962221, 4753.2668715488735, 4754.0, 8441.915580844236, 8513.787155321043, 10042.19662427004, 10054.195664346835, 10054.899451005396, 5356.276902618097, 5356.8660783479945, 5359.196120581866, 5361.812336568201, 5384.192371144281, 4770.95229047712, 4854.17479028566, 4866.221404575251, 4909.0672772173175, 4917.041176970456, 3842.769433833979, 3875.0312421894528, 3897.4348719435525, 3930.21395720857, 3957.22834047358, 1244.7261602447447, 1255.234307072685, 1263.1410640764368, 1272.5673271087874, 1285.5243559882715, 810.3922058456158, 879.1691851200554, 913.4291068082448, 913.9451632902047, 962.4225464721251, 5783.392743761929, 5796.797112101056, 5958.523318134538, 5983.0, 6136.969315153485, 439.2423070203873, 442.3232454344857, 446.2703479810468, 951.5004622573066, 995.2684776689127, 3412.2151905061974, 3880.20455806559, 4049.8987525312104, 4051.434737431042, 4074.714769966074, 10515.001074897873, 10808.51361688726, 10825.188110891715, 11009.2843965142, 11010.0, 10779.67660970172, 10822.0, 10876.673699789018, 10925.235233533735, 10988.670339889815, 8088.0, 8097.35221182304, 8181.140980197112, 8319.459235149696, 8329.416940814206, 5887.0580707086665, 5894.734736936934, 5918.7928422505, 5984.042553191469, 6010.098485227251, 7911.604419778873, 9986.400815950776, 10022.498875056071, 10098.242631802497, 10191.745206369604, 10097.495125243562, 10155.89844101579, 10273.075423211947, 10304.742381440525, 10314.484275786332, 7464.365528929885, 7494.587797671181, 7634.274743899321, 7640.121386040676, 7667.424943129176, 8176.46852954556, 8198.590070496331, 8364.205400486944, 8372.706955256537, 8374.204450577185, 6069.787557435468, 6224.470919971853, 7501.512401693873, 7534.020577324913, 7724.729634462767, 6515.446187074152, 7273.163586187656, 7459.738909138154, 7550.509216900884, 7595.202503737249, 6819.5226334158115, 6909.481788865754, 7012.684429200596, 7023.754168604074, 7023.964880175567, 4986.850394488171, 5028.798848046037, 5074.720890351066, 5083.262926875508, 5171.146760784429, 4915.3855768029, 4935.161022626234, 5104.106781313181, 5240.34495688039, 5241.737913104405], "value": 4531.273174121906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.46667165780735, 308.7529976019184, 309.5063373918599, 315.51883377848793, 315.78999965023246, 5273.156294992802, 5273.499017593332, 5288.709120998344, 5306.840794776158, 5317.627766056368, 4175.812088456019, 4280.143971205755, 5261.658277139324, 5272.051030814452, 5274.472552744734, 3406.7104296134858, 3479.8168622668286, 3829.0, 3918.746001279592, 3919.1965647042357, 3295.5715756951563, 3822.541295044585, 4037.5356833964174, 4054.3310353791735, 4092.242935057009, 3621.746477746559, 3963.702722295825, 3991.6207960243732, 4006.198760247962, 4009.7393669411395, 3874.8062596870186, 3992.7803970781597, 4012.3379642358977, 4026.5973402659793, 4072.226277007389, 2773.4869049225754, 3509.7016753575977, 3517.4899639552123, 3525.506429099928, 3527.4885141654318, 4239.51245606753, 4548.885523046842, 5103.489651034867, 5156.613254005927, 5157.30376399185, 3603.6126091454717, 4295.542088618692, 4413.558644135561, 4636.0727854428915, 4662.930056049162, 5448.346198456171, 5515.420880807497, 5517.503424691757, 5573.944260557383, 5598.636088654224, 3798.240351929625, 3801.6925275010917, 3801.7909015004166, 3812.913319703871, 3813.7521061130938, 5480.835574932758, 5856.736446859902, 5880.235569375954, 5889.411058894119, 5903.26209223847, 3673.2102597941034, 3793.203427280247, 3864.768113913174, 3869.5356557213313, 3879.0302424393903, 3930.4844899264494, 3940.3498422760495, 3942.8619998300496, 3980.840766369313, 3980.8407663693706, 4555.180067587866, 4580.358749775034, 4580.404547408815, 4609.446866376056, 4621.630269578425, 4059.715819892579, 5602.467765562265, 5617.634853734494, 5637.520810731134, 5672.192264828854, 1105.4030823355458, 1333.3200067965213, 1343.328335832084, 1345.8586848380976, 1346.5556366399064, 773.5822655765824, 804.4007214625025, 820.9368867316839, 821.765796747924, 829.5686243153526, 5041.59667226621, 5074.416442109205, 5110.386753589519, 5284.630075894651, 5297.655652382583, 5171.50870667286, 5432.429594892558, 5486.149646804752, 5621.915671264935, 5745.368009518949, 4922.630802689741, 4924.852254432372, 5212.765425555859, 5248.475152484721, 5251.52736253739, 2671.559192733216, 2998.7750918681186, 3016.3364059906785, 3038.9392212155635, 3041.4373340931998, 5872.412758724094, 5943.286805583358, 5945.108233764883, 5982.551308651869, 5992.34084250732, 5471.616986810965, 5897.49871260939, 5913.733881975236, 5981.401859814071, 5994.7602095916545, 5799.739011744482, 6015.368386319461, 6036.563126102311, 6050.667213303311, 6065.787697430569, 4188.85339013133, 5381.192821076713, 5490.368607610176, 5831.650100993954, 5862.237909071964, 4981.28145789702, 5019.268352418409, 5441.884413695104, 5710.600257981901, 5715.542536653127, 5003.673291919275, 5942.762289508544, 5975.790847320323, 6053.545984051126, 6057.969710151421, 3318.838406557782, 3348.547946027255, 3678.135638124985, 3708.276886007203, 3829.425586162098, 5769.307683078057, 5786.363500014911, 5790.9286781945475, 5797.565182611405, 5839.562032847638, 5615.483819368829, 5645.1532270159805, 5647.971760141173, 5686.317641883001, 5695.209347419652, 4869.659123861437, 5364.731763411735, 5596.244506991504, 5628.662280263197, 5686.374498805046, 5492.340919089735, 5501.771782428123, 5510.201020851874, 5511.448855114617, 5525.557955363481, 4502.919299368194, 5401.4868587484125, 5534.315856809364, 5555.194561767523, 5601.719914004202, 4802.615790736662, 4992.975035124801, 5121.590272778094, 5496.839317392595, 5500.614956952975], "value": 4067.109669888054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.6777238632559, 650.0854982077782, 695.4297476069625, 714.125196634123, 820.1839170025826, 15281.388744450223, 15781.684366312667, 15871.523854284376, 15955.042697438163, 16054.839451605496, 14334.569962901103, 15220.847791522081, 15889.682206355867, 16097.0, 16547.586310342216, 691.5988726538595, 854.09465966076, 886.020946853727, 886.1360173830528, 890.9821803563957, 707.2220557386885, 842.8862103616025, 874.903760586335, 875.9794839012575, 879.8636211387245, 917.4128557723062, 917.8164367126568, 918.7335672654922, 919.4804935211585, 920.2729843423721, 916.3219217778862, 917.9947956987088, 918.2699753695828, 920.0569416348296, 920.0707285641483, 536.6484952356213, 550.7191332420455, 550.7769353411875, 551.9530839878615, 554.2572952243972, 15373.769393459108, 15894.284757185955, 15958.202089895574, 15960.361585536564, 15968.600784980352, 13906.652333691638, 14245.358958846773, 14358.784618230735, 14497.565073047825, 14531.418743250257, 11674.68124588467, 11857.169998100137, 11977.880221197764, 12052.096092793076, 12235.632931012084, 3765.35988881888, 3802.3454739295476, 3830.751001184914, 3856.942145867786, 3963.5243770747406, 113606.0, 115829.99999999916, 116512.0, 117573.41213293964, 119240.0, 10767.89232107669, 10868.728281793019, 10978.945105274423, 11112.55549778016, 11310.264832785842, 20305.593888122155, 20934.58130837375, 21240.575188496143, 21262.468438288855, 21266.680999785014, 7886.64510097047, 8081.232282933112, 8095.716649917343, 8103.959480202561, 8164.469309494863, 4464.776761161994, 4485.596296333346, 4498.752568608693, 4580.862574122782, 4643.721376717408, 3616.9276614467562, 3735.589085200626, 3771.4719939208535, 3773.3585290500682, 3773.8301776420126, 688.8863337549349, 700.2997002997002, 704.1550139832221, 705.0376236437329, 713.3651050565002, 535.8338914936382, 564.9152627105885, 628.977985770505, 658.5818005566404, 664.8371149068413, 8729.825403491895, 9412.952935235417, 9542.570584323723, 9700.563474643657, 9758.12176904068, 543.2937181663822, 549.373713966079, 564.2551831582272, 633.7306644675995, 673.8382788130813, 4614.238650622677, 4617.12274667812, 4678.8596342109795, 4688.2733176357715, 4753.33453316536, 149144.0, 159266.0, 162120.0, 163184.0, 164153.3584664138, 140977.0, 142271.0, 142977.0, 145274.0, 146278.26860865628, 115507.0, 119413.0, 122977.0, 123855.0, 124430.377848112, 9020.233280170996, 9044.276457883485, 9080.954595226982, 9134.173165366821, 9186.954065229898, 59210.81578368495, 59628.0, 62675.6866215666, 65617.67191164014, 70714.64642676753, 91536.54231728798, 95729.0, 96013.0, 99105.0, 99670.0, 10876.836847447137, 11534.653960381202, 12756.87243127594, 13093.279869607262, 13186.538471153464, 14733.336999834819, 14733.778993314894, 14790.260486975823, 15120.243987800346, 15575.454859080332, 13635.727285454434, 13676.93161534186, 13699.863001369858, 13839.930800345934, 13911.860881391462, 13815.516456923959, 13850.09974351664, 14253.35859886287, 14436.494722684656, 14444.711105777615, 13215.207087574776, 13549.932250338685, 13799.931000345336, 13956.651083722581, 13960.930195348958, 11383.487743051748, 11418.543258269576, 11540.769184616092, 11694.824577631172, 11902.761944761229, 11358.421294171678, 11429.199956003, 11483.770324593293, 11606.535738570363, 11641.59254426091], "value": 7937.440246011069, "unit": "score"}], "name": "dromaeo_css", "value": 7359.808049046607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 112.0, 113.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 112.0, 148.0, 113.0, 113.0, 116.0, 111.0, 141.0, 111.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 184.0, 183.0, 183.0, 184.0, 181.0, 178.0, 184.0, 247.0], "value": 188.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 58.0, 86.0, 59.0, 59.0, 59.0, 58.0, 80.0, 58.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 91.0, 71.0, 67.0, 70.0, 91.0, 94.0, 67.0, 90.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 89.0, 127.0, 88.0, 84.0, 87.0, 83.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 92.0, 102.0, 151.0, 129.0, 92.0, 94.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 107.0, 131.0, 137.0, 83.0, 83.0, 86.0, 85.0, 88.0, 83.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 53.0, 53.0, 54.0, 52.0, 53.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 79.0, 75.0, 71.0, 68.0, 70.0, 70.0, 70.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 85.0, 90.0, 89.0, 85.0, 102.0, 99.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 143.0, 148.0, 155.0, 153.0, 144.0, 159.0, 195.0, 155.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 186.0, 174.0, 172.0, 171.0, 171.0, 170.0, 172.0, 170.0, 172.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 72.0, 66.0, 89.0, 68.0, 69.0, 68.0, 68.0, 68.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1480.8999999999999, "alertThreshold": 2.0}]} 16:58:49 INFO - Return code: 0 16:58:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:58:49 INFO - # TBPL SUCCESS # 16:58:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:58:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:58:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:58:49 INFO - 2016-10-06 16:58:49.306 screenresolution[1672:10172] starting screenresolution argv=screenresolution get 16:58:49 INFO - 2016-10-06 16:58:49.308 screenresolution[1672:10172] Display 0: 1600x1200x32@60 16:58:49 INFO - 2016-10-06 16:58:49.314 screenresolution[1673:10175] starting screenresolution argv=screenresolution list 16:58:49 INFO - Available Modes on Display 0 16:58:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:58:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:58:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:58:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:58:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:58:49 INFO - Intel Iris: 16:58:49 INFO - Chipset Model: Intel Iris 16:58:49 INFO - Type: GPU 16:58:49 INFO - Bus: Built-In 16:58:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:58:49 INFO - Vendor: Intel (0x8086) 16:58:49 INFO - Device ID: 0x0a2e 16:58:49 INFO - Revision ID: 0x0009 16:58:49 INFO - Displays: 16:58:49 INFO - Display: 16:58:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:58:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:58:49 INFO - Display Serial Number: 1600x1200 60 16:58:49 INFO - Main Display: Yes 16:58:49 INFO - Mirror: Off 16:58:49 INFO - Online: Yes 16:58:49 INFO - Rotation: Supported 16:58:49 INFO - Return code: 0 16:58:49 INFO - Running post-action listener: _resource_record_post_action 16:58:49 INFO - [mozharness: 2016-10-06 23:58:49.547961Z] Finished run-tests step (success) 16:58:49 INFO - Running post-run listener: _resource_record_post_run 16:58:49 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 31525888; Write bytes: 710774272; Read time: 324; Write time: 2206 16:58:49 INFO - TinderboxPrint: CPU usage
53.6% 16:58:49 INFO - TinderboxPrint: I/O read bytes / time
31,525,888 / 324 16:58:49 INFO - TinderboxPrint: I/O write bytes / time
710,774,272 / 2,206 16:58:49 INFO - TinderboxPrint: CPU idle
2,254.8 (46.4%) 16:58:49 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 16:58:49 INFO - TinderboxPrint: CPU user
1,627.4 (33.5%) 16:58:49 INFO - TinderboxPrint: Swap in / out
435,134,464 / 0 16:58:49 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400359936; Write bytes: 397199360; Read time: 29560; Write time: 746 16:58:49 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 29502464; Write bytes: 296687104; Read time: 285; Write time: 1422 16:58:50 INFO - Running post-run listener: _upload_blobber_files 16:58:50 INFO - Blob upload gear active. 16:58:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:58:50 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 16:58:50 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'] 16:58:50 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 16:58:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:58:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:58:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:58:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:51 INFO - (blobuploader) - INFO - Done attempting. 16:58:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:58:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:58:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:52 INFO - (blobuploader) - INFO - Done attempting. 16:58:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:58:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:58:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:54 INFO - (blobuploader) - INFO - Done attempting. 16:58:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:58:54 INFO - Return code: 0 16:58:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:58:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:58:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c1ed901e30d040d3d42d2b02b8195ef9bcf099ce3de13ecd1cb8efde0c75a2a9f509e13a4fbf4c4cf7bac531f6a4e2bf526584721b730ff75470e31146f1c0b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ceb99b9bd119089e9258dada9b2c14217d70b1a17f942c24941d9262c07679b3f0becc1a7f3044b8f6c71deba8134879eedf5d443a068aef8c14615f8f43ab44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c9d4615e062850b4824dd8e6d97fca941eb71e00c81013ce4c24bfd0f53018128a77f8830bdd856813b6aa32cc91191286f5db5767bdb683ced987b979974d"} 16:58:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:58:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:58:54 INFO - Contents: 16:58:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c1ed901e30d040d3d42d2b02b8195ef9bcf099ce3de13ecd1cb8efde0c75a2a9f509e13a4fbf4c4cf7bac531f6a4e2bf526584721b730ff75470e31146f1c0b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ceb99b9bd119089e9258dada9b2c14217d70b1a17f942c24941d9262c07679b3f0becc1a7f3044b8f6c71deba8134879eedf5d443a068aef8c14615f8f43ab44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c9d4615e062850b4824dd8e6d97fca941eb71e00c81013ce4c24bfd0f53018128a77f8830bdd856813b6aa32cc91191286f5db5767bdb683ced987b979974d"} 16:58:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:58:54 INFO - Copying logs to upload dir... 16:58:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:58:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.656409 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-06 16:58:54.904586) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 16:58:54.907660) ========= 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.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/c1ed901e30d040d3d42d2b02b8195ef9bcf099ce3de13ecd1cb8efde0c75a2a9f509e13a4fbf4c4cf7bac531f6a4e2bf526584721b730ff75470e31146f1c0b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ceb99b9bd119089e9258dada9b2c14217d70b1a17f942c24941d9262c07679b3f0becc1a7f3044b8f6c71deba8134879eedf5d443a068aef8c14615f8f43ab44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c9d4615e062850b4824dd8e6d97fca941eb71e00c81013ce4c24bfd0f53018128a77f8830bdd856813b6aa32cc91191286f5db5767bdb683ced987b979974d"} build_url:https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/b2ZabXiQTzidRS3DsdWhNw/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/c1ed901e30d040d3d42d2b02b8195ef9bcf099ce3de13ecd1cb8efde0c75a2a9f509e13a4fbf4c4cf7bac531f6a4e2bf526584721b730ff75470e31146f1c0b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ceb99b9bd119089e9258dada9b2c14217d70b1a17f942c24941d9262c07679b3f0becc1a7f3044b8f6c71deba8134879eedf5d443a068aef8c14615f8f43ab44", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/53c9d4615e062850b4824dd8e6d97fca941eb71e00c81013ce4c24bfd0f53018128a77f8830bdd856813b6aa32cc91191286f5db5767bdb683ced987b979974d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 16:58:54.941150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:58:54.941505) ========= 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.DfoPsxJ7WF/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.OWohocax4h/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 16:58:54.990176) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 16:58:54.990504) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 16:58:54.990836) ========= ========= Total master_lag: 0.17 =========