builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0230 starttime: 1483295265.98 results: success (0) buildid: 20170101004005 builduid: 65927fe2962d47a982e109b87e72b7c6 revision: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:45.984557) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:45.984951) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:45.985223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:46.154175) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:46.154558) ========= 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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 --2017-01-01 10:27:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 204M=0s 2017-01-01 10:27:46 (204 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144249 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:46.335941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:46.336232) ========= 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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.024508 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:46.385984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-01 10:27:46.386394) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 623f7b10ae96b129068d1ceb85d0e83ef5b19502 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 623f7b10ae96b129068d1ceb85d0e83ef5b19502 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 2017-01-01 10:27:46,487 truncating revision to first 12 chars 2017-01-01 10:27:46,488 Setting DEBUG logging. 2017-01-01 10:27:46,488 attempt 1/10 2017-01-01 10:27:46,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/623f7b10ae96?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-01 10:27:47,512 unpacking tar archive at: mozilla-aurora-623f7b10ae96/testing/mozharness/ program finished with exit code 0 elapsedTime=1.287886 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-01 10:27:47.699188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:47.699510) ========= script_repo_revision: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:47.699927) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:47.700198) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 10:27:47.720562) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-01 10:27:47.720873) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:27:47 INFO - MultiFileLogger online at 20170101 10:27:47 in /builds/slave/test 10:27:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:27:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:27:47 INFO - {'append_to_log': False, 10:27:47 INFO - 'base_work_dir': '/builds/slave/test', 10:27:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:27:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:27:47 INFO - 'branch': 'Mozilla-Aurora', 10:27:47 INFO - 'buildbot_json_path': 'buildprops.json', 10:27:47 INFO - 'config_files': ('talos/mac_config.py',), 10:27:47 INFO - 'default_actions': ('clobber', 10:27:47 INFO - 'read-buildbot-config', 10:27:47 INFO - 'download-and-extract', 10:27:47 INFO - 'populate-webroot', 10:27:47 INFO - 'create-virtualenv', 10:27:47 INFO - 'install', 10:27:47 INFO - 'run-tests'), 10:27:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:27:47 INFO - 'download_minidump_stackwalk': True, 10:27:47 INFO - 'download_symbols': 'ondemand', 10:27:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:27:47 INFO - 'tooltool.py': '/tools/tooltool.py', 10:27:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:27:47 INFO - '/tools/misc-python/virtualenv.py')}, 10:27:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:27:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:27:47 INFO - 'installer_path': 'installer.exe', 10:27:47 INFO - 'log_level': 'info', 10:27:47 INFO - 'log_name': 'talos', 10:27:47 INFO - 'log_to_console': True, 10:27:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:27:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:27:47 INFO - 'opt_config_files': (), 10:27:47 INFO - 'pip_index': False, 10:27:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:27:47 INFO - 'cmd': ('bash', 10:27:47 INFO - '-c', 10:27:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:27:47 INFO - 'enabled': True, 10:27:47 INFO - 'halt_on_failure': False, 10:27:47 INFO - 'name': 'check_screen_resolution'},), 10:27:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:27:47 INFO - 'cmd': ('bash', 10:27:47 INFO - '-c', 10:27:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:27:47 INFO - 'enabled': True, 10:27:47 INFO - 'halt_on_failure': False, 10:27:47 INFO - 'name': 'check_screen_resolution'},), 10:27:47 INFO - 'run_cmd_checks_enabled': True, 10:27:47 INFO - 'sps_profile': False, 10:27:47 INFO - 'sps_profile_interval': 0, 10:27:47 INFO - 'suite': 'dromaeojs', 10:27:47 INFO - 'system_bits': '32', 10:27:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:27:47 INFO - 'title': 't-yosemite-r7-0230', 10:27:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:27:47 INFO - 'use_talos_json': True, 10:27:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:27:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:27:47 INFO - 'work_dir': 'build'} 10:27:47 INFO - [mozharness: 2017-01-01 18:27:47.926709Z] Running clobber step. 10:27:47 INFO - Running pre-action listener: _resource_record_pre_action 10:27:47 INFO - Running main action method: clobber 10:27:47 INFO - rmtree: /builds/slave/test/build 10:27:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:27:50 INFO - Running post-action listener: _resource_record_post_action 10:27:50 INFO - [mozharness: 2017-01-01 18:27:50.418988Z] Finished clobber step (success) 10:27:50 INFO - [mozharness: 2017-01-01 18:27:50.419126Z] Running read-buildbot-config step. 10:27:50 INFO - Running pre-action listener: _resource_record_pre_action 10:27:50 INFO - Running main action method: read_buildbot_config 10:27:50 INFO - Using buildbot properties: 10:27:50 INFO - { 10:27:50 INFO - "project": "", 10:27:50 INFO - "product": "firefox", 10:27:50 INFO - "script_repo_revision": "production", 10:27:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:27:50 INFO - "repository": "", 10:27:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:27:50 INFO - "buildid": "20170101004005", 10:27:50 INFO - "stage_platform": "macosx64", 10:27:50 INFO - "basedir": "/builds/slave/test", 10:27:50 INFO - "nightly_build": "True", 10:27:50 INFO - "buildnumber": 5, 10:27:50 INFO - "slavename": "t-yosemite-r7-0230", 10:27:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:27:50 INFO - "platform": "yosemite_r7", 10:27:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:27:50 INFO - "branch": "mozilla-aurora", 10:27:50 INFO - "repo_path": "releases/mozilla-aurora", 10:27:50 INFO - "revision": "623f7b10ae96b129068d1ceb85d0e83ef5b19502", 10:27:50 INFO - "pgo_build": "False", 10:27:50 INFO - "builduid": "65927fe2962d47a982e109b87e72b7c6", 10:27:50 INFO - "slavebuilddir": "test" 10:27:50 INFO - } 10:27:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:27:50 INFO - Running post-action listener: _resource_record_post_action 10:27:50 INFO - [mozharness: 2017-01-01 18:27:50.422632Z] Finished read-buildbot-config step (success) 10:27:50 INFO - [mozharness: 2017-01-01 18:27:50.422736Z] Running download-and-extract step. 10:27:50 INFO - Running pre-action listener: _resource_record_pre_action 10:27:50 INFO - Running main action method: download_and_extract 10:27:50 INFO - mkdir: /builds/slave/test/build/tests 10:27:50 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')]} 10:27:50 INFO - trying https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 10:27:51 INFO - Downloaded 1199 bytes. 10:27:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:27:51 INFO - Using the following test package requirements: 10:27:51 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:27:51 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:27:51 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:27:51 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'jsshell-mac.zip'], 10:27:51 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:27:51 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:27:51 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:27:51 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:27:51 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:27:51 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:27:51 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:27:51 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:27:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:27:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:27:51 INFO - Fetch https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:27:52 INFO - Http code: 200 10:27:52 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 10:27:52 INFO - x-amz-cf-id: Cfi6LgclH4ln_eVK2gnJjl70pqv-YbJFJcJamTEujtzs4kb6YWW_kQ== 10:27:52 INFO - x-amz-version-id: IAY2uSPHpeAVO5i9yUZCqTIMdSOy3v8B 10:27:53 INFO - Expected file size: 16268570 10:27:53 INFO - Obtained file size: 16268570 10:27:59 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:27:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:27:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:27:59 INFO - Fetch https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:28:00 INFO - Http code: 200 10:28:00 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 10:28:00 INFO - x-amz-cf-id: d9BKvr4Js6h44vqIDnCGwrYLXbXf3onWSub46YzHQtHjT3SdE5hVgQ== 10:28:00 INFO - x-amz-version-id: IAY2uSPHpeAVO5i9yUZCqTIMdSOy3v8B 10:28:00 INFO - Expected file size: 16268570 10:28:00 INFO - Obtained file size: 16268570 10:28:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:28:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:28:07 INFO - Fetch https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:28:07 INFO - Http code: 200 10:28:07 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 10:28:07 INFO - x-amz-cf-id: ucaajQeIC08CUmxQfK3x766A5ptjCMpZyHsByCWSw42R6Nn73oy92g== 10:28:07 INFO - x-amz-version-id: FHDl0LGHoblHURDFUAphrq13KPIeMrmn 10:28:07 INFO - Expected file size: 14409038 10:28:07 INFO - Obtained file size: 14409038 10:28:08 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')]} 10:28:08 INFO - trying https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:28:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:28:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 10:28:11 INFO - Downloaded 109956907 bytes. 10:28:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:28:11 INFO - mkdir: /builds/slave/test/properties 10:28:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:28:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:28:11 INFO - Contents: 10:28:11 INFO - build_url:https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:28:11 INFO - Running post-action listener: _resource_record_post_action 10:28:11 INFO - Running post-action listener: set_extra_try_arguments 10:28:11 INFO - [mozharness: 2017-01-01 18:28:11.590584Z] Finished download-and-extract step (success) 10:28:11 INFO - [mozharness: 2017-01-01 18:28:11.590716Z] Running populate-webroot step. 10:28:11 INFO - Running pre-action listener: _resource_record_pre_action 10:28:11 INFO - Running main action method: populate_webroot 10:28:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:28:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:28:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:28:11 INFO - '--tppagecycles', 10:28:11 INFO - '7'], 10:28:11 INFO - 'tests': ['tsvgm']}}, 10:28:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:28:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:28:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:28:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:28:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['damp', 'tps']}, 10:28:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'tests': ['damp', 'tps']}, 10:28:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['dromaeo_dom']}, 10:28:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:28:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['basic_compositor_video']}, 10:28:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:28:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'other-e10s': {'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['a11yr', 10:28:11 INFO - 'ts_paint', 10:28:11 INFO - 'tpaint', 10:28:11 INFO - 'sessionrestore', 10:28:11 INFO - 'sessionrestore_no_auto_restore', 10:28:11 INFO - 'tabpaint']}, 10:28:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['tsvgx', 10:28:11 INFO - 'tsvgr_opacity', 10:28:11 INFO - 'tart', 10:28:11 INFO - 'tscrollx', 10:28:11 INFO - 'cart']}, 10:28:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:28:11 INFO - 'tsvgr_opacity', 10:28:11 INFO - 'tart', 10:28:11 INFO - 'tscrollx', 10:28:11 INFO - 'cart']}, 10:28:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'talos_options': ['--disable-e10s'], 10:28:11 INFO - 'tests': ['tp5o']}, 10:28:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'tests': ['tp5o']}, 10:28:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'talos_options': ['--disable-e10s', 10:28:11 INFO - '--xperf_path', 10:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:11 INFO - 'tests': ['tp5n']}, 10:28:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:11 INFO - 'talos_options': ['--xperf_path', 10:28:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:11 INFO - 'tests': ['tp5n']}}, 10:28:11 INFO - 'talos.zip': {'path': '', 10:28:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:28:11 INFO - Running post-action listener: _resource_record_post_action 10:28:11 INFO - [mozharness: 2017-01-01 18:28:11.609742Z] Finished populate-webroot step (success) 10:28:11 INFO - [mozharness: 2017-01-01 18:28:11.609883Z] Running create-virtualenv step. 10:28:11 INFO - Running pre-action listener: _resource_record_pre_action 10:28:11 INFO - Running main action method: create_virtualenv 10:28:11 INFO - Creating virtualenv /builds/slave/test/build/venv 10:28:11 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 10:28:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:28:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:28:11 INFO - Using real prefix '/tools/python27' 10:28:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:28:12 INFO - Installing distribute.............................................................................................................................................................................................done. 10:28:15 INFO - Installing pip.................done. 10:28:15 INFO - Return code: 0 10:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:28:15 INFO - Reading from file tmpfile_stdout 10:28:15 INFO - Output received: 10:28:15 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) 10:28:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28: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', 'pip>=1.5'] in /builds/slave/test/build 10:28: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 pip>=1.5 10:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:15 INFO - 'HOME': '/Users/cltbld', 10:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:15 INFO - 'LOGNAME': 'cltbld', 10:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:15 INFO - 'MOZ_NO_REMOTE': '1', 10:28:15 INFO - 'NO_EM_RESTART': '1', 10:28:15 INFO - 'PAGER': '/bin/cat', 10:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:15 INFO - 'PWD': '/builds/slave/test', 10:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:15 INFO - 'SHELL': '/bin/bash', 10:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:15 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:15 INFO - 'USER': 'cltbld', 10:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:15 INFO - 'XPC_FLAGS': '0x0', 10:28:15 INFO - 'XPC_SERVICE_NAME': '0', 10:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:15 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 10:28:15 INFO - Cleaning up... 10:28:15 INFO - Return code: 0 10:28:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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', '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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:28: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 psutil>=3.1.1 10:28:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:15 INFO - 'HOME': '/Users/cltbld', 10:28:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:15 INFO - 'LOGNAME': 'cltbld', 10:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:15 INFO - 'MOZ_NO_REMOTE': '1', 10:28:15 INFO - 'NO_EM_RESTART': '1', 10:28:15 INFO - 'PAGER': '/bin/cat', 10:28:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:15 INFO - 'PWD': '/builds/slave/test', 10:28:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:15 INFO - 'SHELL': '/bin/bash', 10:28:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:15 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:15 INFO - 'USER': 'cltbld', 10:28:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:15 INFO - 'XPC_FLAGS': '0x0', 10:28:15 INFO - 'XPC_SERVICE_NAME': '0', 10:28:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:16 INFO - Downloading/unpacking psutil>=3.1.1 10:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:28:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:20 INFO - Installing collected packages: psutil 10:28:20 INFO - Running setup.py install for psutil 10:28:20 INFO - building 'psutil._psutil_osx' extension 10:28:20 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 10:28:20 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 10:28:20 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 10:28:21 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 10:28:21 INFO - building 'psutil._psutil_posix' extension 10:28:21 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 10:28:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:28:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:28:21 INFO - ^ 10:28:21 INFO - 1 warning generated. 10:28:21 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 10:28:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:21 INFO - Successfully installed psutil 10:28:21 INFO - Cleaning up... 10:28:21 INFO - Return code: 0 10:28:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:28:21 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')]} 10:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:21 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')]} 10:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:21 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:21 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 10:28:21 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 10:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:21 INFO - 'HOME': '/Users/cltbld', 10:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:21 INFO - 'LOGNAME': 'cltbld', 10:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:21 INFO - 'MOZ_NO_REMOTE': '1', 10:28:21 INFO - 'NO_EM_RESTART': '1', 10:28:21 INFO - 'PAGER': '/bin/cat', 10:28:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:21 INFO - 'PWD': '/builds/slave/test', 10:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:21 INFO - 'SHELL': '/bin/bash', 10:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:21 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:21 INFO - 'USER': 'cltbld', 10:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:21 INFO - 'XPC_FLAGS': '0x0', 10:28:21 INFO - 'XPC_SERVICE_NAME': '0', 10:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:28:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:28:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:28:24 INFO - Installing collected packages: mozsystemmonitor 10:28:24 INFO - Running setup.py install for mozsystemmonitor 10:28:24 INFO - Successfully installed mozsystemmonitor 10:28:24 INFO - Cleaning up... 10:28:24 INFO - Return code: 0 10:28:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:28:24 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')]} 10:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:24 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')]} 10:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:24 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:24 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 10:28:24 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 10:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:24 INFO - 'HOME': '/Users/cltbld', 10:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:24 INFO - 'LOGNAME': 'cltbld', 10:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:24 INFO - 'MOZ_NO_REMOTE': '1', 10:28:24 INFO - 'NO_EM_RESTART': '1', 10:28:24 INFO - 'PAGER': '/bin/cat', 10:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:24 INFO - 'PWD': '/builds/slave/test', 10:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:24 INFO - 'SHELL': '/bin/bash', 10:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:24 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:24 INFO - 'USER': 'cltbld', 10:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:24 INFO - 'XPC_FLAGS': '0x0', 10:28:24 INFO - 'XPC_SERVICE_NAME': '0', 10:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:24 INFO - Downloading/unpacking jsonschema==2.5.1 10:28:24 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 10:28:24 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 10:28:24 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 10:28:24 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 10:28:24 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 10:28:24 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 10:28:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:28:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:28:27 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 10:28:27 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 10:28:27 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 10:28:27 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 10:28:27 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 10:28:27 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 10:28:27 INFO - Downloading functools32-3.2.3-2.tar.gz 10:28:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:28:28 INFO - warning: no files found matching '*.txt' 10:28:28 INFO - no previously-included directories found matching 'build' 10:28:28 INFO - no previously-included directories found matching 'dist' 10:28:28 INFO - no previously-included directories found matching '.git*' 10:28:28 INFO - Installing collected packages: jsonschema, functools32 10:28:28 INFO - Running setup.py install for functools32 10:28:28 INFO - warning: no files found matching '*.txt' 10:28:28 INFO - no previously-included directories found matching 'build' 10:28:28 INFO - no previously-included directories found matching 'dist' 10:28:28 INFO - no previously-included directories found matching '.git*' 10:28:28 INFO - Successfully installed jsonschema functools32 10:28:28 INFO - Cleaning up... 10:28:28 INFO - Return code: 0 10:28:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:28 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 10:28:28 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 10:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:28 INFO - 'HOME': '/Users/cltbld', 10:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:28 INFO - 'LOGNAME': 'cltbld', 10:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:28 INFO - 'MOZ_NO_REMOTE': '1', 10:28:28 INFO - 'NO_EM_RESTART': '1', 10:28:28 INFO - 'PAGER': '/bin/cat', 10:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:28 INFO - 'PWD': '/builds/slave/test', 10:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:28 INFO - 'SHELL': '/bin/bash', 10:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:28 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:28 INFO - 'USER': 'cltbld', 10:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:28 INFO - 'XPC_FLAGS': '0x0', 10:28:28 INFO - 'XPC_SERVICE_NAME': '0', 10:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:28:28 INFO - Cleaning up... 10:28:28 INFO - Return code: 0 10:28:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:28 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 10:28:28 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 10:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:28 INFO - 'HOME': '/Users/cltbld', 10:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:28 INFO - 'LOGNAME': 'cltbld', 10:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:28 INFO - 'MOZ_NO_REMOTE': '1', 10:28:28 INFO - 'NO_EM_RESTART': '1', 10:28:28 INFO - 'PAGER': '/bin/cat', 10:28:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:28 INFO - 'PWD': '/builds/slave/test', 10:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:28 INFO - 'SHELL': '/bin/bash', 10:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:28 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:28 INFO - 'USER': 'cltbld', 10:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:28 INFO - 'XPC_FLAGS': '0x0', 10:28:28 INFO - 'XPC_SERVICE_NAME': '0', 10:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:28 INFO - Downloading/unpacking blobuploader==1.2.4 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:31 INFO - Downloading blobuploader-1.2.4.tar.gz 10:28:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:28:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:31 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 10:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:28:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:32 INFO - Downloading docopt-0.6.1.tar.gz 10:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:28:32 INFO - Installing collected packages: blobuploader, requests, docopt 10:28:32 INFO - Running setup.py install for blobuploader 10:28:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:28:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:28:33 INFO - Running setup.py install for requests 10:28:33 INFO - Running setup.py install for docopt 10:28:33 INFO - Successfully installed blobuploader requests docopt 10:28:33 INFO - Cleaning up... 10:28:33 INFO - Return code: 0 10:28:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28: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')]} 10:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:33 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:33 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 10:28:33 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 10:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:33 INFO - 'HOME': '/Users/cltbld', 10:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:33 INFO - 'LOGNAME': 'cltbld', 10:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:33 INFO - 'MOZ_NO_REMOTE': '1', 10:28:33 INFO - 'NO_EM_RESTART': '1', 10:28:33 INFO - 'PAGER': '/bin/cat', 10:28:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:33 INFO - 'PWD': '/builds/slave/test', 10:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:33 INFO - 'SHELL': '/bin/bash', 10:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:33 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:33 INFO - 'USER': 'cltbld', 10:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:33 INFO - 'XPC_FLAGS': '0x0', 10:28:33 INFO - 'XPC_SERVICE_NAME': '0', 10:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-oCkiyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-De4ykV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-lOlzjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-XFqNnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-mIXaBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5c0pXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-IczMMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-2pTU7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-VS4Irn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-cTEHiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-fCc8jM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-48Uc6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Ru8SYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-CERL4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-WCoNBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-fCGWHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-a_QJxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:28:35 INFO - Running setup.py install for manifestparser 10:28:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:28:35 INFO - Running setup.py install for mozcrash 10:28:36 INFO - Running setup.py install for mozdebug 10:28:36 INFO - Running setup.py install for mozdevice 10:28:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Running setup.py install for mozfile 10:28:36 INFO - Running setup.py install for mozhttpd 10:28:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Running setup.py install for mozinfo 10:28:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Running setup.py install for mozInstall 10:28:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:28:36 INFO - Running setup.py install for mozleak 10:28:37 INFO - Running setup.py install for mozlog 10:28:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Running setup.py install for moznetwork 10:28:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Running setup.py install for mozprocess 10:28:37 INFO - Running setup.py install for mozprofile 10:28:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Running setup.py install for mozrunner 10:28:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:28:37 INFO - Running setup.py install for mozscreenshot 10:28:38 INFO - Running setup.py install for moztest 10:28:38 INFO - Running setup.py install for mozversion 10:28:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:28:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:28:38 INFO - Cleaning up... 10:28:38 INFO - Return code: 0 10:28:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:38 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')]} 10:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:38 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')]} 10:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:38 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:38 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 10:28:38 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 10:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:38 INFO - 'HOME': '/Users/cltbld', 10:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:38 INFO - 'LOGNAME': 'cltbld', 10:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:38 INFO - 'MOZ_NO_REMOTE': '1', 10:28:38 INFO - 'NO_EM_RESTART': '1', 10:28:38 INFO - 'PAGER': '/bin/cat', 10:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:38 INFO - 'PWD': '/builds/slave/test', 10:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:38 INFO - 'SHELL': '/bin/bash', 10:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:38 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:38 INFO - 'USER': 'cltbld', 10:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:38 INFO - 'XPC_FLAGS': '0x0', 10:28:38 INFO - 'XPC_SERVICE_NAME': '0', 10:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5Ic2S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:38 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)) 10:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-o9X4HS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:28:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-vatHrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-1_cA2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-3KQn7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Fox_ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-W8bFyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_6bfMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-0SlWAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-OeF8M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-QnnIw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wFJTZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:39 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)) 10:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-3smXm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-32cUad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-QfZ7wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-6HwzCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:40 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)) 10:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-eDDhC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:40 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)) 10:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:28:40 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)) 10:28:40 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)) 10:28:40 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)) 10:28:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:28:40 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 10:28:40 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 10:28:40 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 10:28:40 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 10:28:40 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 10:28:40 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 10:28:43 INFO - Downloading blessings-1.6.tar.gz 10:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:28:43 INFO - Installing collected packages: blessings 10:28:43 INFO - Running setup.py install for blessings 10:28:43 INFO - Successfully installed blessings 10:28:43 INFO - Cleaning up... 10:28:43 INFO - Return code: 0 10:28:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:28:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:43 INFO - Reading from file tmpfile_stdout 10:28:43 INFO - Current package versions: 10:28:43 INFO - blessings == 1.6 10:28:43 INFO - blobuploader == 1.2.4 10:28:43 INFO - docopt == 0.6.1 10:28:43 INFO - functools32 == 3.2.3-2 10:28:43 INFO - jsonschema == 2.5.1 10:28:43 INFO - manifestparser == 1.1 10:28:43 INFO - mozInstall == 1.12 10:28:43 INFO - mozcrash == 1.0 10:28:43 INFO - mozdebug == 0.1 10:28:43 INFO - mozdevice == 0.48 10:28:43 INFO - mozfile == 1.2 10:28:43 INFO - mozhttpd == 0.7 10:28:43 INFO - mozinfo == 0.9 10:28:43 INFO - mozleak == 0.1 10:28:43 INFO - mozlog == 3.4 10:28:43 INFO - moznetwork == 0.27 10:28:43 INFO - mozprocess == 0.23 10:28:43 INFO - mozprofile == 0.28 10:28:43 INFO - mozrunner == 6.13 10:28:43 INFO - mozscreenshot == 0.1 10:28:43 INFO - mozsystemmonitor == 0.3 10:28:43 INFO - moztest == 0.7 10:28:43 INFO - mozversion == 1.4 10:28:43 INFO - psutil == 3.1.1 10:28:43 INFO - requests == 1.2.3 10:28:43 INFO - wsgiref == 0.1.2 10:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:43 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 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:43 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 10:28:43 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 10:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:43 INFO - 'HOME': '/Users/cltbld', 10:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:43 INFO - 'LOGNAME': 'cltbld', 10:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:43 INFO - 'MOZ_NO_REMOTE': '1', 10:28:43 INFO - 'NO_EM_RESTART': '1', 10:28:43 INFO - 'PAGER': '/bin/cat', 10:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:43 INFO - 'PWD': '/builds/slave/test', 10:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:43 INFO - 'SHELL': '/bin/bash', 10:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:43 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:43 INFO - 'USER': 'cltbld', 10:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:43 INFO - 'XPC_FLAGS': '0x0', 10:28:43 INFO - 'XPC_SERVICE_NAME': '0', 10:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:28:44 INFO - Cleaning up... 10:28:44 INFO - Return code: 0 10:28:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1065eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106561570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa80b5c9f30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, '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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 10:28:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:28:44 INFO - 'HOME': '/Users/cltbld', 10:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:28:44 INFO - 'LOGNAME': 'cltbld', 10:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:44 INFO - 'MOZ_NO_REMOTE': '1', 10:28:44 INFO - 'NO_EM_RESTART': '1', 10:28:44 INFO - 'PAGER': '/bin/cat', 10:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:44 INFO - 'PWD': '/builds/slave/test', 10:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:28:44 INFO - 'SHELL': '/bin/bash', 10:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:28:44 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:28:44 INFO - 'USER': 'cltbld', 10:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:44 INFO - 'XPC_FLAGS': '0x0', 10:28:44 INFO - 'XPC_SERVICE_NAME': '0', 10:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:28:44 INFO - Cleaning up... 10:28:44 INFO - Return code: 0 10:28:44 INFO - Running post-action listener: _resource_record_post_action 10:28:44 INFO - Running post-action listener: _start_resource_monitoring 10:28:44 INFO - Starting resource monitoring. 10:28:44 INFO - [mozharness: 2017-01-01 18:28:44.464467Z] Finished create-virtualenv step (success) 10:28:44 INFO - [mozharness: 2017-01-01 18:28:44.465487Z] Running install step. 10:28:44 INFO - Running pre-action listener: _resource_record_pre_action 10:28:44 INFO - Running main action method: install 10:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:44 INFO - Reading from file tmpfile_stdout 10:28:44 INFO - Detecting whether we're running mozinstall >=1.0... 10:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:28:44 INFO - Reading from file tmpfile_stdout 10:28:44 INFO - Output received: 10:28:44 INFO - Usage: mozinstall [options] installer 10:28:44 INFO - Options: 10:28:44 INFO - -h, --help show this help message and exit 10:28:44 INFO - -d DEST, --destination=DEST 10:28:44 INFO - Directory to install application into. [default: 10:28:44 INFO - "/builds/slave/test"] 10:28:44 INFO - --app=APP Application being installed. [default: firefox] 10:28:44 INFO - mkdir: /builds/slave/test/build/application 10:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:29:16 INFO - Reading from file tmpfile_stdout 10:29:16 INFO - Output received: 10:29:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:29:16 INFO - Running post-action listener: _resource_record_post_action 10:29:16 INFO - [mozharness: 2017-01-01 18:29:16.310346Z] Finished install step (success) 10:29:16 INFO - [mozharness: 2017-01-01 18:29:16.310461Z] Running run-tests step. 10:29:16 INFO - Running pre-action listener: _resource_record_pre_action 10:29:16 INFO - Running main action method: run_tests 10:29:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:29:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:29:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:29:16 INFO - 2017-01-01 10:29:16.324 screenresolution[1637:8927] starting screenresolution argv=screenresolution get 10:29:16 INFO - 2017-01-01 10:29:16.327 screenresolution[1637:8927] Display 0: 1600x1200x32@60 10:29:16 INFO - 2017-01-01 10:29:16.333 screenresolution[1638:8930] starting screenresolution argv=screenresolution list 10:29:16 INFO - Available Modes on Display 0 10:29:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:29:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:29:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:29:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:29:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:29:16 INFO - Intel Iris: 10:29:16 INFO - Chipset Model: Intel Iris 10:29:16 INFO - Type: GPU 10:29:16 INFO - Bus: Built-In 10:29:16 INFO - VRAM (Dynamic, Max): 1536 MB 10:29:16 INFO - Vendor: Intel (0x8086) 10:29:16 INFO - Device ID: 0x0a2e 10:29:16 INFO - Revision ID: 0x0009 10:29:16 INFO - Displays: 10:29:16 INFO - Display: 10:29:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:29:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:29:16 INFO - Display Serial Number: 1600x1200 60 10:29:16 INFO - Main Display: Yes 10:29:16 INFO - Mirror: Off 10:29:16 INFO - Online: Yes 10:29:16 INFO - Rotation: Supported 10:29:16 INFO - Return code: 0 10:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:29:16 INFO - Python 2.7.3 10:29:16 INFO - Return code: 0 10:29:16 INFO - grabbing minidump binary from tooltool 10:29:16 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')]} 10:29:16 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 0x1063ffd30>, '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 0x106265db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063f7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:29:16 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 10:29:16 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 10:29:16 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 10:29:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:29:16 INFO - Return code: 0 10:29:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:29:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:29:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:29:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:29:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 10:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 10:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lD4xzTNeZ5/Render', 10:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:29:16 INFO - 'HOME': '/Users/cltbld', 10:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:29:16 INFO - 'LOGNAME': 'cltbld', 10:29:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:29:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:16 INFO - 'MOZ_NO_REMOTE': '1', 10:29:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:29:16 INFO - 'NO_EM_RESTART': '1', 10:29:16 INFO - 'PAGER': '/bin/cat', 10:29:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:29:16 INFO - 'PWD': '/builds/slave/test', 10:29:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:29:16 INFO - 'SHELL': '/bin/bash', 10:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PShkOWu2fn/Listeners', 10:29:16 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 10:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:29:16 INFO - 'USER': 'cltbld', 10:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:16 INFO - 'XPC_FLAGS': '0x0', 10:29:16 INFO - 'XPC_SERVICE_NAME': '0', 10:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:29:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 10:29:16 INFO - mozversion application_buildid: 20170101004005 10:29:16 INFO - mozversion application_changeset: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 10:29:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:29:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:29:16 INFO - mozversion application_name: Firefox 10:29:16 INFO - mozversion application_remotingname: firefox-dev 10:29:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:29:16 INFO - mozversion application_vendor: Mozilla 10:29:16 INFO - mozversion application_version: 52.0a2 10:29:16 INFO - mozversion platform_buildid: 20170101004005 10:29:16 INFO - mozversion platform_changeset: 623f7b10ae96b129068d1ceb85d0e83ef5b19502 10:29:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:29:16 INFO - mozversion platform_version: 52.0a2 10:29:16 INFO - using testdate: 1483295356 10:29:16 INFO - actual date: 1483295356 10:29:16 INFO - starting webserver on 'localhost:49244' 10:29:16 INFO - SUITE-START | Running 2 tests 10:29:16 INFO - TEST-START | dromaeo_css 10:29:16 INFO - operating with platform_type : mac_ 10:29:16 INFO - Initialising browser for dromaeo_css test... 10:29:16 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7fzX4x/profile http://localhost:49244/getInfo.html) 10:29:19 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:29:19 INFO - PROCESS | 1648 | colorDepth:24 10:29:19 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:29:19 INFO - PROCESS | 1648 | __metrics 10:29:19 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:29:19 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:20 INFO - TEST-INFO | 1648: exit 0 10:29:20 INFO - Browser initialized. 10:29:20 INFO - Running cycle 1/1 for dromaeo_css test... 10:29:20 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7fzX4x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:29:32 INFO - PROCESS | 1652 | RSS: Main: 222736384 10:29:32 INFO - PROCESS | 1652 | 10:30:24 INFO - PROCESS | 1652 | 1483295424435 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] 10:32:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 10:32:39 INFO - PROCESS | 1652 | RSS: Main: 351100928 10:32:39 INFO - PROCESS | 1652 | 10:35:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 10:35:42 INFO - PROCESS | 1652 | RSS: Main: 350085120 10:35:42 INFO - PROCESS | 1652 | 10:38:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 10:38:45 INFO - PROCESS | 1652 | RSS: Main: 319377408 10:38:45 INFO - PROCESS | 1652 | 10:41:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 10:41:48 INFO - PROCESS | 1652 | RSS: Main: 323960832 10:41:48 INFO - PROCESS | 1652 | 10:44:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 10:44:52 INFO - PROCESS | 1652 | RSS: Main: 311189504 10:44:52 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 10:47:55 INFO - PROCESS | 1652 | RSS: Main: 382124032 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | __start_tp_report 10:47:55 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 10:47:55 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 10:47:55 INFO - PROCESS | 1652 | |i|pagename|runs| 10:47:55 INFO - PROCESS | 1652 | |0;dojo.html;3277.2953814929783;3286.3427314537093;3304.0748590394696;3329.9167520811993;3334.3164651246498;5603.80386686466;5658.462446067627;5662.037453632887;5670.262865827447;5684.602077854552;5628.971855140729;5674.290713660793;5687.8578035549135;5704.572157088214;5741.19623252745;5143.4599367066485;5159.432462429129;5160.974195129019;5176.715280659562;5209.400918894337;5135.6148288878485;5142.485751424846;5147.7426128693605;5256.92114618281;5286.973565132169;5175.948240517584;5224.947750522484;5246.816361427351;5249.737513124348;5260.473952604747;5170.715610641394;5230.8169214077325;5237.240600113;5243.082460569385;5296.152615581488;5110.718910459923;5232.555232805216;5237.4500677428705;5279.815206467793;5290.39160496544;5509.47659972302;5553.555715542747;5572.442755724395;5692.6299790513485;5707.486326230659;5160.354955630546;6103.87792244157;6114.4190257321325;6122.1033335326;6164.6917654117115;6298.149749783766;6311.526635502356;6318.147050148217;6320.968395158041;6332.081848132041;5603.103503439512;5658.773649054074;5757.740901659436;5769.451902069339;5777.913331300034;6235.563510554218;6284.685765711696;6285.9057114143325;6293.590916590499;6303.086052522451;6105.145279660851;6105.542084343692;6145.8156255312415;6155.353687862799;6156.5998210117095;6206.72069756862;6211.9689401552705;6266.154069200599;6272.466840318532;6280.5289603279025;5984.162217289583;6080.209572755513;6213.316535181127;6221.9377806222265;6228.968855155786;4991.625628077909;6155.569110162246;6156.846078848064;6199.070139479114;6212.316645169028;4899.289603007615;4901.632377571696;4910.386201724785;4914.778834952435;4935.555799978019;4361.149575832682;4385.758783266951;4387.056782791651;4393.494748103945;4440;5519.503244707996;5565.2486914266865;5580.5535557156145;5714.457126572969;5764.221830052973;6256.906146407808;6282.811515654537;6283.340249273851;6308.116863639094;6310.8422289442215;5589.74846131925;5667.404922483162;5671.291088613923;5721.141828725725;5743.827685169368;5216.191490319006;5225.425203227642;5291.629585929025;5378.569714422837;5386.730663466811;6198.318184999646;6240.563160578716;6250.562460627894;6260.593061450991;6297.086922396135;6270.216222972129;6280.560360774702;6280.905786413116;6283.403076707706;6284.057391391328;6079.756809727562;6227.159333489921;6282.089097080806;6282.340354262919;6296.905546416899;6008.369121242206;6076.33160352352;6108.493847774579;6136.631802091889;6207;6070.484008859296;6071.908921366271;6082.36135205797;6280.277768056732;6292.1505596743855;5919.733611987557;5943;6046.84882877914;6112.480439162721;6114;5860.941390586039;5916.852078698067;5983.850403739942;6006.429389208019;6009.429104235091;4469.508354081114;5893.793717219876;5919;5919.763209471573;5948.226730525004;5909.497692695997;5952.851178720567;5969.701514924323;6044.697765111639;6051.7579296827635;5834.299884013945;5896.968030594543;5916.940830591639;5944.167816505606;5994.190784244071 10:47:55 INFO - PROCESS | 1652 | |1;ext.html;499.3757802746567;511.23825500004995;514.6834696661555;516.3597139546963;550.0127271547572;30355;31076.844615776918;39134.804325978366;40722.79638601803;40725.79637101818;36547.817260913725;40169.39745903814;41690;41917.371239431435;41941.58058419415;9249.6300147994;9378.577963991602;9392.765180870465;9397.95301023494;9420.528973551294;9278.443293402417;9371.765705857373;9372.062793720608;9377.343585948985;9386.577604007836;9160.633574657038;9318;9387.530623468834;9394.718158455258;9406;9318.906810931905;9364.25085993119;9370.67202647904;9371.437713737198;9380.90619093807;9207.217386522154;9263.907360926372;9328.300377471716;9340.906590934072;9349;38694.419583706265;38925.221495570215;39033.41449878254;39083.41374879379;39157.21685566273;3123.7969531980348;3248.3990461764647;3258.006308076036;3273.508973653947;3311.850966706504;28457.430851382855;28727.28181795451;28981.85509072462;30249;30390.84804575963;2800.453911487261;3062.096681478969;3097.132802815215;3140.8586613602442;3319.3195394943978;456637;456898.7155064237;458299;461562;462475;59228.40771592315;59509.40490595039;59629.40370596239;59650;59673.40326596765;62751.058734119026;62842.37157628457;62857;62866.37133628605;62902.056469153;15987.520374388787;16250.75623865643;16265.756013659804;16282.43011494592;16299.511014669579;12131.5147394105;12620.432080556397;12685;12746.10777245584;12753.362331883369;15435.922820385826;17201.311947521957;17205.483835484956;17212.913935430493;17255.3960611378;5317.521423071942;6205.162303089025;6341.778037768656;6387;6584.572002819801;759.9202083781123;793.6825269892089;826.5288785392389;836.4897412578322;879.560219890055;27712.722872771417;28821.56767648487;29009.7099029007;29195.124146275222;29205.41589168247;995.5470261031213;1027.6865556005341;1060.3849767135043;1071.4321409652896;1089.6349722842936;14804.333804978802;15193.848061519244;15213.847861521464;15359.232038398259;15560.066396016275;190329;191155.044224778;191225;191539;192393.03803480894;56412.153817692764;56646;56782.71608642013;56884.86230275371;56993.86012279731;1151.3264740126951;1308.1954597922208;1352.600982710103;1359.320339830085;1359.7348517039081;28603.570946435404;28663.713362866103;29083.272918176368;29700.55449168221;29949.251268718457;49931.75034124806;50405.49594504008;50431.24353134633;50503.48489545172;50533.49466505435;586828;589322.0533897303;590115;590593;592195;39629.40555891606;40438;40447;40531.98670033273;40702.9824254396;45508.31737523874;46103.76948115372;46175.76912115418;46186.769066154455;46628.53371466242;29576.55635165431;30688.232794180323;31010.379792404477;33958.83020584881;39423.408648869714;37550.24899502049;37942.24115517619;38477.42283865688;38508.614913850506;38964;32104.876329328363;36297.637023629424;36491.270174595826;37492.87521374363;37543.62456375401;32319.19202019874;33796.662033379354;34115.176696600574;34551.96344109681;34597.78907738217;25084.49831003406;25240;25317.240482785546;25437.10970116037;25481.617775733008 10:47:55 INFO - PROCESS | 1652 | |2;jquery.html;322.3182968022632;344.7982929985958;374.16001077580825;374.60479194449846;387.49625486867063;19212.615747685057;19897.602047959033;20131.899340503278;20183.39449816506;20619.07214175362;19391;20068.197272109133;20133.798662013374;20676.793232067674;20705.585888282225;5319.281896943921;5474.671519708831;5830.6501609903435;6120.816375508742;6125.050617154348;5939.2575928009;5991.041433370661;6024.1566180734735;6029.879402411949;6076.908846367308;5626.465485778865;5919;5954.702264886739;5981.730822112993;5983.521318294526;5909.290885093795;5954.374790646963;5956.344802071769;5959.165716799652;5959.791407300745;5000.89998200034;5123.564497017758;5210.114280572312;5218.9739051304505;5224.843254702365;24907.750922490723;24969;25124.24627261167;25160.874195629087;25311.240662780147;2377.7265614454386;2418.3019465105217;2442.1452491627965;2523.697156341251;3025.0394429706744;15331.846681533154;15421.460248891348;15471.916965812397;15687.215639217993;15790.526284211492;1841.7237414387814;1865.1327132883275;1901.8193271639311;1905.295040834886;1948.1428171604448;104873.47563262211;106449;106940.46529767379;109153.90846091516;112357.87642123556;10222.846657300146;10312.175025997902;10482.947585262025;10814.837777433344;11243;13801.240931748851;13820.585382438543;13840.446382144803;13851.099678520864;13903.165810051429;8293.543855088028;8515.318774498026;8556.61495232716;8659.740207793777;8693;8006.599670016476;8224.327093694574;8457.661693532313;8566.100559441293;8739.825203495895;5548.639338443069;5994.670293133832;6665.366790154928;6666.866662666719;6803.7618683345845;1777.2624360890086;1791.8924864508172;1814.9818501814814;1818.1272988965106;1818.4635532517939;749.2844333661341;898.1153563739712;952.3714348529937;965.5558443116105;1042.671558459083;15637.921810391103;19711.310104146287;20017.799822001594;21083.68374474384;21209.469763255733;946.2099147212017;970.9123793812754;974.8635191073255;989.9604015839286;1059.2849826367326;10868.61959831402;11290.7177320566;11342;11469.713257168638;11482.311061336346;586975;617733;624543;626403;630183;82369.76445353325;92795;102138.48930755298;104050;116494;935.6678379175431;938.7700013496874;986.3342243985425;989.9703008909601;994.3884511025667;18451.354202603383;19359.51601209981;19486.512837179183;20153.535515083993;20276.493087672927;46795.76602117104;53061.87752489857;55373.72313138408;56395.43604564066;57113.14330285132;74920;76429.2357076422;79848.60075699585;81228;83493;7184.6048467334795;7525.096988361432;7992.960035199787;8106.8783968241705;8281.751547453587;12047.879521204675;12301.630951071482;12369.381530923598;13044.739105218032;13358.933205333911;10723.94638026836;11040.834387484354;11277;11541.538338466367;11651.067914566984;10915.290506117077;11242.494087766234;11325.377104259345;11502.194846360675;11537.961583457529;10408.947955260175;11459.140564457866;11503.942480287546;11559.364234967157;11956.820647690118;7340.816479588053;7420.591867447342;7635.350995165473;7666.9616651916385;7775.961120194589;10721.571137154426;11036.889631103586;11286.60496882638;11370.715732106764;11522.596709115141 10:47:55 INFO - PROCESS | 1652 | |3;mootools.html;1434.7489189391854;1479.297333766461;1611.7179493588626;1780.6082661814396;1793.4529968359652;8090.757277281684;8433.367497437686;8438.451500652462;8447.91552084479;8464.534450605213;7518.7368442104535;7731.188225236354;7770.494917830351;8075.676972921076;8219.178082191778;2319.976800231997;2354.846934949223;2357.71707395112;2363.243761996162;2364.2198074635417;2349.48311371498;2374.608189648706;2385.0698227691214;2386.0932845518764;2395.053953688292;2068.503559145802;2153.3324669352473;2154.202944910385;2273.4316420894775;2347.1784875293597;1954.2378472395776;2342.367560758594;2344.8944797484155;2349.1308215960203;2351.4356554426986;2328.4062564046058;2338.2400719765974;2367.230650038727;2369.6800931874322;2369.7393286738443;8323.250907418302;8406;8413.663453461853;8422;8424.620892059873;5100.132977393852;5251.186066159751;5263.289455923478;5271.156614941629;5276.076686579833;3345.4647256438852;3452.102453362144;3598.946015809765;3603.333383324067;3616.4575313703153;1020.7601213714771;1071.9946400268027;1102.9889701102966;1106.6956586938575;1113.5712750591;10759.30064545802;10865.5110520026;10947.288426252348;10952.397618130999;10968.506417211245;4431.711938724037;4504.166729155117;4559.635229181657;4566.109608626286;4567.520410356931;8767.868481972775;8780.034196238408;8790.340724445692;8793.20861122502;8801.339899507562;5144.279800827887;5148.021875843578;5152.587792976553;5152.948470515322;5164.638475306768;4672.322513235562;4819.95180048202;4839.104765618372;4869.853904382874;4889.975550122298;3656.8537258509364;3718.386466233041;3761.8307176177136;3803.581606023335;3811.256804923069;1133.8582677165355;1140.1961617059953;1148.133159464599;1148.4315263944402;1163.988360116388;877.5612193903048;885.1856292211181;898.3711402018613;900.6127365233012;903.6430609909049;5421.430749771217;5502.724863756796;5595.21666966625;5620.4098569649595;5779.711014449261;436.18868903838836;441.1860118082133;446.9731816091045;453.3766071651479;458.282787437659;3540.309639620301;3709.8145092745253;3777.036855601804;3825.2349530094093;3880.767153970771;10191.184705223564;10276.383416995004;10415.114715249138;10433.373997560018;10457.477126143815;10385.63650272237;10398.324108932895;10402.843957340647;10405.167586593054;10416.583336666448;7565.546067235873;7765.339946104645;8013.11855695873;8031.1567285434185;8031.237032481905;5194;5224.52979231871;5306.336707911511;5363.061464243664;5480.260164877691;8879.866801997847;9283.953580232055;9354;9467.715968520955;9635.903640963501;9370.625174992923;9555.140037396668;9648.903510964801;9692.41845489273;9822.901770982484;7207.819804504929;7246.1666908306215;7250.9637451814515;7283.817904552428;7304.9634751828025;7910.604469776603;8002.679892804223;8008.919910801051;8012.959935200287;8024.919750802417;6456.418922297016;7109.786706398816;7192.957021231997;7254.347108760237;7353.852922941618;7003.229644739176;7030.086088808423;7059.470539709439;7093.82265443368;7125.821854453473;6235.314115447207;6380.138681277968;6487.3836985486305;6508.707108180049;6517.837054073497;4849;4850.83022094225;4865.537773911473;4929.97535012337;4937.950620493749;4796.496367881322;4819.807207711652;4848.5636292733825;4982.10322142018;4987.202047672355 10:47:55 INFO - PROCESS | 1652 | |4;prototype.html;247.85005071931482;284.81091443224557;301.5612284126596;307.5801530910307;311.2887053083704;4505.752183629903;4936.346323981466;4949.554540091391;4980.128477516434;5211.853392253707;4049.898752531188;4104.753714777108;4928.888292772589;5080.441151473335;5120.8975820483665;3299.6306532788863;3937.4093885917055;3988.820503077361;3996.300647386696;4027.536833264183;3456.723462123024;3784.451254568086;3850.0182453474326;3937.8031098444962;3975.483187185662;3393.457046872501;3704.7033538261526;3847.134139942126;3899.2201559688033;3909.3158697227873;3679.767277961886;3892.8637497687587;3897.4933258676333;3914.608539146091;3925.371940489508;3078.830664313462;3397.1676939150066;3418.1454636340914;3436.604790449105;3447.172678557153;4113.244126435296;4822;4877.3903262092235;4940.357753492023;4971.577415919651;4423.447069116361;4444.177827101997;4445.399226626443;4447.421835161408;4451.087527056946;5316.840494785162;5355.3398446481715;5365.812196573102;5367.302250707422;5386.245925570423;3567.500549923013;3677.5403074615674;3706.870259540903;3722.933771756674;3730.8134593270497;4867.34290870735;5101.821436249714;5423.566114710814;5634.267545219136;5635.71821408932;3192.345569158317;3734.4128745283147;3753.4932784074344;3755.4602612929116;3758.9436158457647;3421.4525675891737;3818.980905095457;3824.8087595620664;3824.8661296854475;3838.6353296436796;3628.4738712886483;3775.2067767810618;4172.310214363188;4251.744895306229;4294.26997410441;5281.049411106038;5369.6509726867625;5405;5409;5427.45725427462;1063.3991794636006;1215.6263422332706;1229.874664681806;1239.964629534331;1263.9747205055846;708.3907839258234;801.3268854162532;805.2873207211543;807.23716088296;838.6079108679563;4328;4442.622377097919;4524.977375113169;4839.95160048395;4852.8301509447;4728.834490792806;5267.367915850123;5301.469853014668;5326;5329.413764485904;4050.675945924319;4559.658025648089;4776.952230477651;4823.541763532458;4832.420109586802;2214.844960852725;2295.5294164696197;2354.4231663242617;2768.7923405744245;2781.8887244510397;5465.508104270635;5491.615586908958;5509;5509.669419834822;5511.84251307222;4035.20433407131;5078.466760990117;5337.973310133424;5390.8921821563345;5421.349438067457;4849.878753031202;5389.254468144759;5448.030823201365;5477.287952566141;5503.449655034465;4496.842610508616;4649.907001859876;5134.717590532557;5249.2126181073145;5272.789088436573;4467;4988.480817187442;4996.775145118406;5177.8187763428095;5256.976064215228;4475.26158183896;5383.703896285742;5405.351357837085;5440.782368705208;5452.454754524516;2971.509700899414;3183.80897146172;3591.51514545533;3596.9640303597675;3615.9096022599647;4442.977785111054;4601.37881386022;5287.920681189708;5303.602229832855;5312.45002398843;4700.694454860423;4746.908211111395;4887.682300650446;5077.695338279715;5101.6173786966865;4908.637583255481;5034.395872495177;5260.370344462309;5260.89478210426;5422.783088676567;5290.312259406406;5299.4700529946695;5333.5199832015305;5346.652467589594;5399.811006614749;4862.905846184722;5017.37282839645;5376.919346209888;5430.972845135907;5437.238786569805;4250.961586492906;4911.435184953633;5153.273653326178;5372.919406208832;5384.353877534721 10:47:55 INFO - PROCESS | 1652 | |5;yui.html;671.271670237792;685.7736946807554;705.6401235369963;751.7293774241272;768.6848392159214;13532.323383830806;14254.714905701892;14276.500322488715;14279.50021749239;14297.857021429769;13144.408501617427;13173.275469849155;13190.736185276313;13228;13242.933785331084;774.8566515194678;802.1016461563057;807.7011505742884;812.3298278919885;813.5444151275304;815.1522416686669;816.6529225079347;819.4223072733708;820.63481750621;820.7332616899531;831.193742070191;833.9833203335929;834.3742193354984;834.8246868157694;835.8202986357932;825.6986200037003;826.2233500509502;826.6858593734372;827.5283088639447;827.664795757713;613.1201725523888;613.1844646619985;613.3467856732566;613.3774219167549;613.7851751886849;14067.859321406757;14358.425662973468;14493.78259326111;14501.492447764384;14521.709565808625;11921.225120367148;12432.937835310857;13134.864238162876;13415.329233538381;13551.503848900857;10813.83779243312;11107.222494425394;11172;11186.832197517044;11623;3520.841562129685;3589.335972845032;3590.784552926833;3608.170120872188;3688.3360995020885;60745;65763.67118164379;67916.64166716687;79820.80268795915;86171.56914215452;10132.746681333025;10338.741531461774;10492.21308401873;10568;10840.566377344976;19423.44612431002;19814.306499272458;20216.191352346035;20266.69599956002;20411.693824592643;7607.201243869314;7625.847483050308;7668.463207575528;7722.498037627536;7751.612419379008;4083.51976137211;4113.4446849675505;4269.829206831692;4273.87178384649;4339.284018136972;3403.7447191460737;3430.7255419566873;3472.5312082868995;3529.294141171776;3531.6644918732677;557.0863783395235;614.9538784591084;622.6108682073703;628.9182406287154;630.1021045010841;520.2300595119244;545.2965674280214;649.3798422506495;657.4922786931772;662.8011596521121;8289.8342033159;8746.650133994695;8922.241609463268;8942.329325300629;9351.438913665203;559.3232189051294;600.342624825815;617.5893031134335;634.7810005548079;665.7969319357545;4424.977875110604;4428.247197976351;4440.578145076212;4447.221736196153;4475.216837053503;103987.4800625992;107873.46063269634;111751.32373014411;111800;112281;62992.370076299565;77294;87801.56099219463;96811;108825.36761948577;57248.42751572431;58549.24352269524;66489.33510664832;75656.62171689107;79790;9345.392549484262;9450.952745236229;9525.095115963972;9550.474723889974;9564.13922746956;39983;43544;50085.74957125191;52979.470205298996;55587.72206138943;64626.353736462035;72665.27334726584;76767;79101.60449197948;79308.81036784568;11678.88321116778;11826.82259766087;11915.82126268124;12216.862049141188;12242.816357754462;13494.932525337703;13604.387802548712;13619;13772;14153.716925661634;12288.38558072075;12528.624141275777;12713.36433178356;12724.427400766803;12766.87233127694;12037.38689470007;12109.636710898687;12282.692932676755;12692.873071269169;12761.489540418279;10591.788164236827;11798.41007949582;11880.405979701154;12446.81329780036;12573.182743121668;10216.846747298649;10435.530401132119;10603;10828.945855270673;11295.81393953623;9325.253979681473;10193.337433066827;10822.296550724177;10957.945210273898;10980.1215902726 10:47:55 INFO - PROCESS | 1652 | __end_tp_report 10:47:55 INFO - PROCESS | 1652 | __start_cc_report 10:47:55 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1518 10:47:55 INFO - PROCESS | 1652 | __end_cc_report 10:47:55 INFO - PROCESS | 1652 | __startTimestamp1483296475788__endTimestamp 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | ------- Summary: start ------- 10:47:55 INFO - PROCESS | 1652 | Number of tests: 6 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5690.25 Median:5909.50 stddev:646.26 (10.9%) stddev-sans-first:619.90 10:47:55 INFO - PROCESS | 1652 | Values: 3277.3 3286.3 3304.1 3329.9 3334.3 5603.8 5658.5 5662.0 5670.3 5684.6 5629.0 5674.3 5687.9 5704.6 5741.2 5143.5 5159.4 5161.0 5176.7 5209.4 5135.6 5142.5 5147.7 5256.9 5287.0 5175.9 5224.9 5246.8 5249.7 5260.5 5170.7 5230.8 5237.2 5243.1 5296.2 5110.7 5232.6 5237.5 5279.8 5290.4 5509.5 5553.6 5572.4 5692.6 5707.5 5160.4 6103.9 6114.4 6122.1 6164.7 6298.1 6311.5 6318.1 6321.0 6332.1 5603.1 5658.8 5757.7 5769.5 5777.9 6235.6 6284.7 6285.9 6293.6 6303.1 6105.1 6105.5 6145.8 6155.4 6156.6 6206.7 6212.0 6266.2 6272.5 6280.5 5984.2 6080.2 6213.3 6221.9 6229.0 4991.6 6155.6 6156.8 6199.1 6212.3 4899.3 4901.6 4910.4 4914.8 4935.6 4361.1 4385.8 4387.1 4393.5 4440.0 5519.5 5565.2 5580.6 5714.5 5764.2 6256.9 6282.8 6283.3 6308.1 6310.8 5589.7 5667.4 5671.3 5721.1 5743.8 5216.2 5225.4 5291.6 5378.6 5386.7 6198.3 6240.6 6250.6 6260.6 6297.1 6270.2 6280.6 6280.9 6283.4 6284.1 6079.8 6227.2 6282.1 6282.3 6296.9 6008.4 6076.3 6108.5 6136.6 6207.0 6070.5 6071.9 6082.4 6280.3 6292.2 5919.7 5943.0 6046.8 6112.5 6114.0 5860.9 5916.9 5983.9 6006.4 6009.4 4469.5 5893.8 5919.0 5919.8 5948.2 5909.5 5952.9 5969.7 6044.7 6051.8 5834.3 5897.0 5916.9 5944.2 5994.2 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:56934.66 Median:39146.01 stddev:119330.48 (304.8%) stddev-sans-first:119590.00 10:47:55 INFO - PROCESS | 1652 | Values: 499.4 511.2 514.7 516.4 550.0 30355.0 31076.8 39134.8 40722.8 40725.8 36547.8 40169.4 41690.0 41917.4 41941.6 9249.6 9378.6 9392.8 9398.0 9420.5 9278.4 9371.8 9372.1 9377.3 9386.6 9160.6 9318.0 9387.5 9394.7 9406.0 9318.9 9364.3 9370.7 9371.4 9380.9 9207.2 9263.9 9328.3 9340.9 9349.0 38694.4 38925.2 39033.4 39083.4 39157.2 3123.8 3248.4 3258.0 3273.5 3311.9 28457.4 28727.3 28981.9 30249.0 30390.8 2800.5 3062.1 3097.1 3140.9 3319.3 456637.0 456898.7 458299.0 461562.0 462475.0 59228.4 59509.4 59629.4 59650.0 59673.4 62751.1 62842.4 62857.0 62866.4 62902.1 15987.5 16250.8 16265.8 16282.4 16299.5 12131.5 12620.4 12685.0 12746.1 12753.4 15435.9 17201.3 17205.5 17212.9 17255.4 5317.5 6205.2 6341.8 6387.0 6584.6 759.9 793.7 826.5 836.5 879.6 27712.7 28821.6 29009.7 29195.1 29205.4 995.5 1027.7 1060.4 1071.4 1089.6 14804.3 15193.8 15213.8 15359.2 15560.1 190329.0 191155.0 191225.0 191539.0 192393.0 56412.2 56646.0 56782.7 56884.9 56993.9 1151.3 1308.2 1352.6 1359.3 1359.7 28603.6 28663.7 29083.3 29700.6 29949.3 49931.8 50405.5 50431.2 50503.5 50533.5 586828.0 589322.1 590115.0 590593.0 592195.0 39629.4 40438.0 40447.0 40532.0 40703.0 45508.3 46103.8 46175.8 46186.8 46628.5 29576.6 30688.2 31010.4 33958.8 39423.4 37550.2 37942.2 38477.4 38508.6 38964.0 32104.9 36297.6 36491.3 37492.9 37543.6 32319.2 33796.7 34115.2 34552.0 34597.8 25084.5 25240.0 25317.2 25437.1 25481.6 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:34665.88 Median:25142.56 stddev:102046.21 (405.9%) stddev-sans-first:102299.89 10:47:55 INFO - PROCESS | 1652 | Values: 322.3 344.8 374.2 374.6 387.5 19212.6 19897.6 20131.9 20183.4 20619.1 19391.0 20068.2 20133.8 20676.8 20705.6 5319.3 5474.7 5830.7 6120.8 6125.1 5939.3 5991.0 6024.2 6029.9 6076.9 5626.5 5919.0 5954.7 5981.7 5983.5 5909.3 5954.4 5956.3 5959.2 5959.8 5000.9 5123.6 5210.1 5219.0 5224.8 24907.8 24969.0 25124.2 25160.9 25311.2 2377.7 2418.3 2442.1 2523.7 3025.0 15331.8 15421.5 15471.9 15687.2 15790.5 1841.7 1865.1 1901.8 1905.3 1948.1 104873.5 106449.0 106940.5 109153.9 112357.9 10222.8 10312.2 10482.9 10814.8 11243.0 13801.2 13820.6 13840.4 13851.1 13903.2 8293.5 8515.3 8556.6 8659.7 8693.0 8006.6 8224.3 8457.7 8566.1 8739.8 5548.6 5994.7 6665.4 6666.9 6803.8 1777.3 1791.9 1815.0 1818.1 1818.5 749.3 898.1 952.4 965.6 1042.7 15637.9 19711.3 20017.8 21083.7 21209.5 946.2 970.9 974.9 990.0 1059.3 10868.6 11290.7 11342.0 11469.7 11482.3 586975.0 617733.0 624543.0 626403.0 630183.0 82369.8 92795.0 102138.5 104050.0 116494.0 935.7 938.8 986.3 990.0 994.4 18451.4 19359.5 19486.5 20153.5 20276.5 46795.8 53061.9 55373.7 56395.4 57113.1 74920.0 76429.2 79848.6 81228.0 83493.0 7184.6 7525.1 7993.0 8106.9 8281.8 12047.9 12301.6 12369.4 13044.7 13358.9 10723.9 11040.8 11277.0 11541.5 11651.1 10915.3 11242.5 11325.4 11502.2 11538.0 10408.9 11459.1 11503.9 11559.4 11956.8 7340.8 7420.6 7635.4 7667.0 7776.0 10721.6 11036.9 11286.6 11370.7 11522.6 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5441.02 Median:4909.98 stddev:3017.95 (61.5%) stddev-sans-first:3011.40 10:47:55 INFO - PROCESS | 1652 | Values: 1434.7 1479.3 1611.7 1780.6 1793.5 8090.8 8433.4 8438.5 8447.9 8464.5 7518.7 7731.2 7770.5 8075.7 8219.2 2320.0 2354.8 2357.7 2363.2 2364.2 2349.5 2374.6 2385.1 2386.1 2395.1 2068.5 2153.3 2154.2 2273.4 2347.2 1954.2 2342.4 2344.9 2349.1 2351.4 2328.4 2338.2 2367.2 2369.7 2369.7 8323.3 8406.0 8413.7 8422.0 8424.6 5100.1 5251.2 5263.3 5271.2 5276.1 3345.5 3452.1 3598.9 3603.3 3616.5 1020.8 1072.0 1103.0 1106.7 1113.6 10759.3 10865.5 10947.3 10952.4 10968.5 4431.7 4504.2 4559.6 4566.1 4567.5 8767.9 8780.0 8790.3 8793.2 8801.3 5144.3 5148.0 5152.6 5152.9 5164.6 4672.3 4820.0 4839.1 4869.9 4890.0 3656.9 3718.4 3761.8 3803.6 3811.3 1133.9 1140.2 1148.1 1148.4 1164.0 877.6 885.2 898.4 900.6 903.6 5421.4 5502.7 5595.2 5620.4 5779.7 436.2 441.2 447.0 453.4 458.3 3540.3 3709.8 3777.0 3825.2 3880.8 10191.2 10276.4 10415.1 10433.4 10457.5 10385.6 10398.3 10402.8 10405.2 10416.6 7565.5 7765.3 8013.1 8031.2 8031.2 5194.0 5224.5 5306.3 5363.1 5480.3 8879.9 9284.0 9354.0 9467.7 9635.9 9370.6 9555.1 9648.9 9692.4 9822.9 7207.8 7246.2 7251.0 7283.8 7305.0 7910.6 8002.7 8008.9 8013.0 8024.9 6456.4 7109.8 7193.0 7254.3 7353.9 7003.2 7030.1 7059.5 7093.8 7125.8 6235.3 6380.1 6487.4 6508.7 6517.8 4849.0 4850.8 4865.5 4930.0 4938.0 4796.5 4819.8 4848.6 4982.1 4987.2 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4244.28 Median:4799.48 stddev:1307.35 (27.2%) stddev-sans-first:1276.15 10:47:55 INFO - PROCESS | 1652 | Values: 247.9 284.8 301.6 307.6 311.3 4505.8 4936.3 4949.6 4980.1 5211.9 4049.9 4104.8 4928.9 5080.4 5120.9 3299.6 3937.4 3988.8 3996.3 4027.5 3456.7 3784.5 3850.0 3937.8 3975.5 3393.5 3704.7 3847.1 3899.2 3909.3 3679.8 3892.9 3897.5 3914.6 3925.4 3078.8 3397.2 3418.1 3436.6 3447.2 4113.2 4822.0 4877.4 4940.4 4971.6 4423.4 4444.2 4445.4 4447.4 4451.1 5316.8 5355.3 5365.8 5367.3 5386.2 3567.5 3677.5 3706.9 3722.9 3730.8 4867.3 5101.8 5423.6 5634.3 5635.7 3192.3 3734.4 3753.5 3755.5 3758.9 3421.5 3819.0 3824.8 3824.9 3838.6 3628.5 3775.2 4172.3 4251.7 4294.3 5281.0 5369.7 5405.0 5409.0 5427.5 1063.4 1215.6 1229.9 1240.0 1264.0 708.4 801.3 805.3 807.2 838.6 4328.0 4442.6 4525.0 4840.0 4852.8 4728.8 5267.4 5301.5 5326.0 5329.4 4050.7 4559.7 4777.0 4823.5 4832.4 2214.8 2295.5 2354.4 2768.8 2781.9 5465.5 5491.6 5509.0 5509.7 5511.8 4035.2 5078.5 5338.0 5390.9 5421.3 4849.9 5389.3 5448.0 5477.3 5503.4 4496.8 4649.9 5134.7 5249.2 5272.8 4467.0 4988.5 4996.8 5177.8 5257.0 4475.3 5383.7 5405.4 5440.8 5452.5 2971.5 3183.8 3591.5 3597.0 3615.9 4443.0 4601.4 5287.9 5303.6 5312.5 4700.7 4746.9 4887.7 5077.7 5101.6 4908.6 5034.4 5260.4 5260.9 5422.8 5290.3 5299.5 5333.5 5346.7 5399.8 4862.9 5017.4 5376.9 5431.0 5437.2 4251.0 4911.4 5153.3 5372.9 5384.4 10:47:55 INFO - PROCESS | 1652 | 10:47:55 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:19083.19 Median:23941.64 stddev:27587.91 (115.2%) stddev-sans-first:27630.66 10:47:55 INFO - PROCESS | 1652 | Values: 671.3 685.8 705.6 751.7 768.7 13532.3 14254.7 14276.5 14279.5 14297.9 13144.4 13173.3 13190.7 13228.0 13242.9 774.9 802.1 807.7 812.3 813.5 815.2 816.7 819.4 820.6 820.7 831.2 834.0 834.4 834.8 835.8 825.7 826.2 826.7 827.5 827.7 613.1 613.2 613.3 613.4 613.8 14067.9 14358.4 14493.8 14501.5 14521.7 11921.2 12432.9 13134.9 13415.3 13551.5 10813.8 11107.2 11172.0 11186.8 11623.0 3520.8 3589.3 3590.8 3608.2 3688.3 60745.0 65763.7 67916.6 79820.8 86171.6 10132.7 10338.7 10492.2 10568.0 10840.6 19423.4 19814.3 20216.2 20266.7 20411.7 7607.2 7625.8 7668.5 7722.5 7751.6 4083.5 4113.4 4269.8 4273.9 4339.3 3403.7 3430.7 3472.5 3529.3 3531.7 557.1 615.0 622.6 628.9 630.1 520.2 545.3 649.4 657.5 662.8 8289.8 8746.7 8922.2 8942.3 9351.4 559.3 600.3 617.6 634.8 665.8 4425.0 4428.2 4440.6 4447.2 4475.2 103987.5 107873.5 111751.3 111800.0 112281.0 62992.4 77294.0 87801.6 96811.0 108825.4 57248.4 58549.2 66489.3 75656.6 79790.0 9345.4 9451.0 9525.1 9550.5 9564.1 39983.0 43544.0 50085.7 52979.5 55587.7 64626.4 72665.3 76767.0 79101.6 79308.8 11678.9 11826.8 11915.8 12216.9 12242.8 13494.9 13604.4 13619.0 13772.0 14153.7 12288.4 12528.6 12713.4 12724.4 12766.9 12037.4 12109.6 12282.7 12692.9 12761.5 10591.8 11798.4 11880.4 12446.8 12573.2 10216.8 10435.5 10603.0 10828.9 11295.8 9325.3 10193.3 10822.3 10957.9 10980.1 10:47:55 INFO - PROCESS | 1652 | -------- Summary: end -------- 10:47:55 INFO - PROCESS | 1652 | 10:47:56 INFO - TEST-INFO | 1652: exit 0 10:47:56 INFO - TEST-OK | dromaeo_css | took 1119642ms 10:47:56 INFO - TEST-START | kraken 10:47:56 INFO - operating with platform_type : mac_ 10:47:56 INFO - Initialising browser for kraken test... 10:47:56 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpLflr1h/profile http://localhost:49244/getInfo.html) 10:47:59 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 10:47:59 INFO - PROCESS | 1660 | colorDepth:24 10:47:59 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 10:47:59 INFO - PROCESS | 1660 | __metrics 10:47:59 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:47:59 INFO - PROCESS | 1660 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:00 INFO - TEST-INFO | 1660: exit 0 10:48:00 INFO - Browser initialized. 10:48:00 INFO - Running cycle 1/1 for kraken test... 10:48:00 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpLflr1h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:12 INFO - PROCESS | 1662 | RSS: Main: 222658560 10:48:12 INFO - PROCESS | 1662 | 10:48:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 10:48:15 INFO - PROCESS | 1662 | RSS: Main: 272633856 10:48:15 INFO - PROCESS | 1662 | 10:48:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 10:48:19 INFO - PROCESS | 1662 | RSS: Main: 284909568 10:48:19 INFO - PROCESS | 1662 | 10:48:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 10:48:23 INFO - PROCESS | 1662 | RSS: Main: 383471616 10:48:23 INFO - PROCESS | 1662 | 10:48:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 10:48:25 INFO - PROCESS | 1662 | RSS: Main: 283475968 10:48:25 INFO - PROCESS | 1662 | 10:48:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:48:28 INFO - PROCESS | 1662 | RSS: Main: 284221440 10:48:28 INFO - PROCESS | 1662 | 10:48:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 10:48:31 INFO - PROCESS | 1662 | RSS: Main: 325173248 10:48:31 INFO - PROCESS | 1662 | 10:48:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 10:48:35 INFO - PROCESS | 1662 | RSS: Main: 326631424 10:48:35 INFO - PROCESS | 1662 | 10:48:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 10:48:38 INFO - PROCESS | 1662 | RSS: Main: 323981312 10:48:38 INFO - PROCESS | 1662 | 10:48:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:48:40 INFO - PROCESS | 1662 | RSS: Main: 299114496 10:48:40 INFO - PROCESS | 1662 | 10:48:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:48:43 INFO - PROCESS | 1662 | RSS: Main: 299233280 10:48:43 INFO - PROCESS | 1662 | 10:48:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:48:46 INFO - PROCESS | 1662 | RSS: Main: 302387200 10:48:46 INFO - PROCESS | 1662 | 10:48:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:48:50 INFO - PROCESS | 1662 | RSS: Main: 321179648 10:48:50 INFO - PROCESS | 1662 | 10:48:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:48:53 INFO - PROCESS | 1662 | RSS: Main: 315064320 10:48:53 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 10:48:55 INFO - PROCESS | 1662 | RSS: Main: 359395328 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | __start_tp_report 10:48:55 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:48:55 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:48:55 INFO - PROCESS | 1662 | |i|pagename|runs| 10:48:55 INFO - PROCESS | 1662 | |0;ai-astar;116;115;116;117;115;115;114;115;115;114 10:48:55 INFO - PROCESS | 1662 | |1;audio-beat-detection;143;110;107;142;114;108;109;108;138;108 10:48:55 INFO - PROCESS | 1662 | |2;audio-dft;194;221;184;185;184;184;252;226;212;258 10:48:55 INFO - PROCESS | 1662 | |3;audio-fft;81;59;60;88;60;59;59;58;82;57 10:48:55 INFO - PROCESS | 1662 | |4;audio-oscillator;84;71;93;71;68;69;88;68;68;92 10:48:55 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;89;87;135;130;90;87;91;87;122;87 10:48:55 INFO - PROCESS | 1662 | |6;imaging-darkroom;95;96;95;96;96;96;96;96;95;95 10:48:55 INFO - PROCESS | 1662 | |7;imaging-desaturate;85;109;123;122;83;84;85;83;77;82 10:48:55 INFO - PROCESS | 1662 | |8;json-parse-financial;57;57;55;56;58;58;58;58;57;57 10:48:55 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;75;66;70;87;108;96;82;70;69;70 10:48:55 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;82;83;83;91;102;75;82;82;83;83 10:48:55 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;141;139;140;139;141;144;145;158;218;151 10:48:55 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;165;166;165;164;171;225;184;162;165;164 10:48:55 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;68;68;67;67;65;64;66;66;66 10:48:55 INFO - PROCESS | 1662 | __end_tp_report 10:48:55 INFO - PROCESS | 1662 | __start_cc_report 10:48:55 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2007 10:48:55 INFO - PROCESS | 1662 | __end_cc_report 10:48:55 INFO - PROCESS | 1662 | __startTimestamp1483296535531__endTimestamp 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:48:55 INFO - PROCESS | 1662 | Number of tests: 14 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.00 stddev:0.92 (0.8%) stddev-sans-first:0.93 10:48:55 INFO - PROCESS | 1662 | Values: 116.0 115.0 116.0 117.0 115.0 115.0 114.0 115.0 115.0 114.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:112.00 stddev:15.56 (13.9%) stddev-sans-first:13.79 10:48:55 INFO - PROCESS | 1662 | Values: 143.0 110.0 107.0 142.0 114.0 108.0 109.0 108.0 138.0 108.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:210.00 Median:216.50 stddev:28.59 (13.2%) stddev-sans-first:29.74 10:48:55 INFO - PROCESS | 1662 | Values: 194.0 221.0 184.0 185.0 184.0 184.0 252.0 226.0 212.0 258.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.00 stddev:12.15 (20.2%) stddev-sans-first:11.66 10:48:55 INFO - PROCESS | 1662 | Values: 81.0 59.0 60.0 88.0 60.0 59.0 59.0 58.0 82.0 57.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.50 stddev:10.70 (13.8%) stddev-sans-first:11.06 10:48:55 INFO - PROCESS | 1662 | Values: 84.0 71.0 93.0 71.0 68.0 69.0 88.0 68.0 68.0 92.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:100.50 Median:87.00 stddev:19.96 (22.9%) stddev-sans-first:20.73 10:48:55 INFO - PROCESS | 1662 | Values: 89.0 87.0 135.0 130.0 90.0 87.0 91.0 87.0 122.0 87.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:48:55 INFO - PROCESS | 1662 | Values: 95.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:83.00 stddev:17.58 (21.2%) stddev-sans-first:18.39 10:48:55 INFO - PROCESS | 1662 | Values: 85.0 109.0 123.0 122.0 83.0 84.0 85.0 83.0 77.0 82.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:0.99 (1.7%) stddev-sans-first:1.05 10:48:55 INFO - PROCESS | 1662 | Values: 57.0 57.0 55.0 56.0 58.0 58.0 58.0 58.0 57.0 57.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:72.50 stddev:13.86 (19.1%) stddev-sans-first:14.62 10:48:55 INFO - PROCESS | 1662 | Values: 75.0 66.0 70.0 87.0 108.0 96.0 82.0 70.0 69.0 70.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:83.00 stddev:7.20 (8.7%) stddev-sans-first:7.57 10:48:55 INFO - PROCESS | 1662 | Values: 82.0 83.0 83.0 91.0 102.0 75.0 82.0 82.0 83.0 83.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:144.50 stddev:24.10 (16.7%) stddev-sans-first:25.26 10:48:55 INFO - PROCESS | 1662 | Values: 141.0 139.0 140.0 139.0 141.0 144.0 145.0 158.0 218.0 151.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:165.50 stddev:19.30 (11.7%) stddev-sans-first:20.25 10:48:55 INFO - PROCESS | 1662 | Values: 165.0 166.0 165.0 164.0 171.0 225.0 184.0 162.0 165.0 164.0 10:48:55 INFO - PROCESS | 1662 | 10:48:55 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 10:48:55 INFO - PROCESS | 1662 | Values: 67.0 68.0 68.0 67.0 67.0 65.0 64.0 66.0 66.0 66.0 10:48:55 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:48:55 INFO - PROCESS | 1662 | 10:48:56 INFO - TEST-INFO | 1662: exit 0 10:48:56 INFO - TEST-OK | kraken | took 60169ms 10:48:56 INFO - SUITE-END | took 1179s 10:48:57 INFO - Completed test suite (00:19:40) 10:48:57 INFO - javascript benchmark 10:48:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.2953814929783, 3286.3427314537093, 3304.0748590394696, 3329.9167520811993, 3334.3164651246498, 5603.80386686466, 5658.462446067627, 5662.037453632887, 5670.262865827447, 5684.602077854552, 5628.971855140729, 5674.290713660793, 5687.8578035549135, 5704.572157088214, 5741.19623252745, 5143.4599367066485, 5159.432462429129, 5160.974195129019, 5176.715280659562, 5209.400918894337, 5135.6148288878485, 5142.485751424846, 5147.7426128693605, 5256.92114618281, 5286.973565132169, 5175.948240517584, 5224.947750522484, 5246.816361427351, 5249.737513124348, 5260.473952604747, 5170.715610641394, 5230.8169214077325, 5237.240600113, 5243.082460569385, 5296.152615581488, 5110.718910459923, 5232.555232805216, 5237.4500677428705, 5279.815206467793, 5290.39160496544, 5509.47659972302, 5553.555715542747, 5572.442755724395, 5692.6299790513485, 5707.486326230659, 5160.354955630546, 6103.87792244157, 6114.4190257321325, 6122.1033335326, 6164.6917654117115, 6298.149749783766, 6311.526635502356, 6318.147050148217, 6320.968395158041, 6332.081848132041, 5603.103503439512, 5658.773649054074, 5757.740901659436, 5769.451902069339, 5777.913331300034, 6235.563510554218, 6284.685765711696, 6285.9057114143325, 6293.590916590499, 6303.086052522451, 6105.145279660851, 6105.542084343692, 6145.8156255312415, 6155.353687862799, 6156.5998210117095, 6206.72069756862, 6211.9689401552705, 6266.154069200599, 6272.466840318532, 6280.5289603279025, 5984.162217289583, 6080.209572755513, 6213.316535181127, 6221.9377806222265, 6228.968855155786, 4991.625628077909, 6155.569110162246, 6156.846078848064, 6199.070139479114, 6212.316645169028, 4899.289603007615, 4901.632377571696, 4910.386201724785, 4914.778834952435, 4935.555799978019, 4361.149575832682, 4385.758783266951, 4387.056782791651, 4393.494748103945, 4440.0, 5519.503244707996, 5565.2486914266865, 5580.5535557156145, 5714.457126572969, 5764.221830052973, 6256.906146407808, 6282.811515654537, 6283.340249273851, 6308.116863639094, 6310.8422289442215, 5589.74846131925, 5667.404922483162, 5671.291088613923, 5721.141828725725, 5743.827685169368, 5216.191490319006, 5225.425203227642, 5291.629585929025, 5378.569714422837, 5386.730663466811, 6198.318184999646, 6240.563160578716, 6250.562460627894, 6260.593061450991, 6297.086922396135, 6270.216222972129, 6280.560360774702, 6280.905786413116, 6283.403076707706, 6284.057391391328, 6079.756809727562, 6227.159333489921, 6282.089097080806, 6282.340354262919, 6296.905546416899, 6008.369121242206, 6076.33160352352, 6108.493847774579, 6136.631802091889, 6207.0, 6070.484008859296, 6071.908921366271, 6082.36135205797, 6280.277768056732, 6292.1505596743855, 5919.733611987557, 5943.0, 6046.84882877914, 6112.480439162721, 6114.0, 5860.941390586039, 5916.852078698067, 5983.850403739942, 6006.429389208019, 6009.429104235091, 4469.508354081114, 5893.793717219876, 5919.0, 5919.763209471573, 5948.226730525004, 5909.497692695997, 5952.851178720567, 5969.701514924323, 6044.697765111639, 6051.7579296827635, 5834.299884013945, 5896.968030594543, 5916.940830591639, 5944.167816505606, 5994.190784244071], "value": 5649.188830798911, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.3757802746567, 511.23825500004995, 514.6834696661555, 516.3597139546963, 550.0127271547572, 30355.0, 31076.844615776918, 39134.804325978366, 40722.79638601803, 40725.79637101818, 36547.817260913725, 40169.39745903814, 41690.0, 41917.371239431435, 41941.58058419415, 9249.6300147994, 9378.577963991602, 9392.765180870465, 9397.95301023494, 9420.528973551294, 9278.443293402417, 9371.765705857373, 9372.062793720608, 9377.343585948985, 9386.577604007836, 9160.633574657038, 9318.0, 9387.530623468834, 9394.718158455258, 9406.0, 9318.906810931905, 9364.25085993119, 9370.67202647904, 9371.437713737198, 9380.90619093807, 9207.217386522154, 9263.907360926372, 9328.300377471716, 9340.906590934072, 9349.0, 38694.419583706265, 38925.221495570215, 39033.41449878254, 39083.41374879379, 39157.21685566273, 3123.7969531980348, 3248.3990461764647, 3258.006308076036, 3273.508973653947, 3311.850966706504, 28457.430851382855, 28727.28181795451, 28981.85509072462, 30249.0, 30390.84804575963, 2800.453911487261, 3062.096681478969, 3097.132802815215, 3140.8586613602442, 3319.3195394943978, 456637.0, 456898.7155064237, 458299.0, 461562.0, 462475.0, 59228.40771592315, 59509.40490595039, 59629.40370596239, 59650.0, 59673.40326596765, 62751.058734119026, 62842.37157628457, 62857.0, 62866.37133628605, 62902.056469153, 15987.520374388787, 16250.75623865643, 16265.756013659804, 16282.43011494592, 16299.511014669579, 12131.5147394105, 12620.432080556397, 12685.0, 12746.10777245584, 12753.362331883369, 15435.922820385826, 17201.311947521957, 17205.483835484956, 17212.913935430493, 17255.3960611378, 5317.521423071942, 6205.162303089025, 6341.778037768656, 6387.0, 6584.572002819801, 759.9202083781123, 793.6825269892089, 826.5288785392389, 836.4897412578322, 879.560219890055, 27712.722872771417, 28821.56767648487, 29009.7099029007, 29195.124146275222, 29205.41589168247, 995.5470261031213, 1027.6865556005341, 1060.3849767135043, 1071.4321409652896, 1089.6349722842936, 14804.333804978802, 15193.848061519244, 15213.847861521464, 15359.232038398259, 15560.066396016275, 190329.0, 191155.044224778, 191225.0, 191539.0, 192393.03803480894, 56412.153817692764, 56646.0, 56782.71608642013, 56884.86230275371, 56993.86012279731, 1151.3264740126951, 1308.1954597922208, 1352.600982710103, 1359.320339830085, 1359.7348517039081, 28603.570946435404, 28663.713362866103, 29083.272918176368, 29700.55449168221, 29949.251268718457, 49931.75034124806, 50405.49594504008, 50431.24353134633, 50503.48489545172, 50533.49466505435, 586828.0, 589322.0533897303, 590115.0, 590593.0, 592195.0, 39629.40555891606, 40438.0, 40447.0, 40531.98670033273, 40702.9824254396, 45508.31737523874, 46103.76948115372, 46175.76912115418, 46186.769066154455, 46628.53371466242, 29576.55635165431, 30688.232794180323, 31010.379792404477, 33958.83020584881, 39423.408648869714, 37550.24899502049, 37942.24115517619, 38477.42283865688, 38508.614913850506, 38964.0, 32104.876329328363, 36297.637023629424, 36491.270174595826, 37492.87521374363, 37543.62456375401, 32319.19202019874, 33796.662033379354, 34115.176696600574, 34551.96344109681, 34597.78907738217, 25084.49831003406, 25240.0, 25317.240482785546, 25437.10970116037, 25481.617775733008], "value": 18816.86741387259, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3182968022632, 344.7982929985958, 374.16001077580825, 374.60479194449846, 387.49625486867063, 19212.615747685057, 19897.602047959033, 20131.899340503278, 20183.39449816506, 20619.07214175362, 19391.0, 20068.197272109133, 20133.798662013374, 20676.793232067674, 20705.585888282225, 5319.281896943921, 5474.671519708831, 5830.6501609903435, 6120.816375508742, 6125.050617154348, 5939.2575928009, 5991.041433370661, 6024.1566180734735, 6029.879402411949, 6076.908846367308, 5626.465485778865, 5919.0, 5954.702264886739, 5981.730822112993, 5983.521318294526, 5909.290885093795, 5954.374790646963, 5956.344802071769, 5959.165716799652, 5959.791407300745, 5000.89998200034, 5123.564497017758, 5210.114280572312, 5218.9739051304505, 5224.843254702365, 24907.750922490723, 24969.0, 25124.24627261167, 25160.874195629087, 25311.240662780147, 2377.7265614454386, 2418.3019465105217, 2442.1452491627965, 2523.697156341251, 3025.0394429706744, 15331.846681533154, 15421.460248891348, 15471.916965812397, 15687.215639217993, 15790.526284211492, 1841.7237414387814, 1865.1327132883275, 1901.8193271639311, 1905.295040834886, 1948.1428171604448, 104873.47563262211, 106449.0, 106940.46529767379, 109153.90846091516, 112357.87642123556, 10222.846657300146, 10312.175025997902, 10482.947585262025, 10814.837777433344, 11243.0, 13801.240931748851, 13820.585382438543, 13840.446382144803, 13851.099678520864, 13903.165810051429, 8293.543855088028, 8515.318774498026, 8556.61495232716, 8659.740207793777, 8693.0, 8006.599670016476, 8224.327093694574, 8457.661693532313, 8566.100559441293, 8739.825203495895, 5548.639338443069, 5994.670293133832, 6665.366790154928, 6666.866662666719, 6803.7618683345845, 1777.2624360890086, 1791.8924864508172, 1814.9818501814814, 1818.1272988965106, 1818.4635532517939, 749.2844333661341, 898.1153563739712, 952.3714348529937, 965.5558443116105, 1042.671558459083, 15637.921810391103, 19711.310104146287, 20017.799822001594, 21083.68374474384, 21209.469763255733, 946.2099147212017, 970.9123793812754, 974.8635191073255, 989.9604015839286, 1059.2849826367326, 10868.61959831402, 11290.7177320566, 11342.0, 11469.713257168638, 11482.311061336346, 586975.0, 617733.0, 624543.0, 626403.0, 630183.0, 82369.76445353325, 92795.0, 102138.48930755298, 104050.0, 116494.0, 935.6678379175431, 938.7700013496874, 986.3342243985425, 989.9703008909601, 994.3884511025667, 18451.354202603383, 19359.51601209981, 19486.512837179183, 20153.535515083993, 20276.493087672927, 46795.76602117104, 53061.87752489857, 55373.72313138408, 56395.43604564066, 57113.14330285132, 74920.0, 76429.2357076422, 79848.60075699585, 81228.0, 83493.0, 7184.6048467334795, 7525.096988361432, 7992.960035199787, 8106.8783968241705, 8281.751547453587, 12047.879521204675, 12301.630951071482, 12369.381530923598, 13044.739105218032, 13358.933205333911, 10723.94638026836, 11040.834387484354, 11277.0, 11541.538338466367, 11651.067914566984, 10915.290506117077, 11242.494087766234, 11325.377104259345, 11502.194846360675, 11537.961583457529, 10408.947955260175, 11459.140564457866, 11503.942480287546, 11559.364234967157, 11956.820647690118, 7340.816479588053, 7420.591867447342, 7635.350995165473, 7666.9616651916385, 7775.961120194589, 10721.571137154426, 11036.889631103586, 11286.60496882638, 11370.715732106764, 11522.596709115141], "value": 9644.561519608038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.7489189391854, 1479.297333766461, 1611.7179493588626, 1780.6082661814396, 1793.4529968359652, 8090.757277281684, 8433.367497437686, 8438.451500652462, 8447.91552084479, 8464.534450605213, 7518.7368442104535, 7731.188225236354, 7770.494917830351, 8075.676972921076, 8219.178082191778, 2319.976800231997, 2354.846934949223, 2357.71707395112, 2363.243761996162, 2364.2198074635417, 2349.48311371498, 2374.608189648706, 2385.0698227691214, 2386.0932845518764, 2395.053953688292, 2068.503559145802, 2153.3324669352473, 2154.202944910385, 2273.4316420894775, 2347.1784875293597, 1954.2378472395776, 2342.367560758594, 2344.8944797484155, 2349.1308215960203, 2351.4356554426986, 2328.4062564046058, 2338.2400719765974, 2367.230650038727, 2369.6800931874322, 2369.7393286738443, 8323.250907418302, 8406.0, 8413.663453461853, 8422.0, 8424.620892059873, 5100.132977393852, 5251.186066159751, 5263.289455923478, 5271.156614941629, 5276.076686579833, 3345.4647256438852, 3452.102453362144, 3598.946015809765, 3603.333383324067, 3616.4575313703153, 1020.7601213714771, 1071.9946400268027, 1102.9889701102966, 1106.6956586938575, 1113.5712750591, 10759.30064545802, 10865.5110520026, 10947.288426252348, 10952.397618130999, 10968.506417211245, 4431.711938724037, 4504.166729155117, 4559.635229181657, 4566.109608626286, 4567.520410356931, 8767.868481972775, 8780.034196238408, 8790.340724445692, 8793.20861122502, 8801.339899507562, 5144.279800827887, 5148.021875843578, 5152.587792976553, 5152.948470515322, 5164.638475306768, 4672.322513235562, 4819.95180048202, 4839.104765618372, 4869.853904382874, 4889.975550122298, 3656.8537258509364, 3718.386466233041, 3761.8307176177136, 3803.581606023335, 3811.256804923069, 1133.8582677165355, 1140.1961617059953, 1148.133159464599, 1148.4315263944402, 1163.988360116388, 877.5612193903048, 885.1856292211181, 898.3711402018613, 900.6127365233012, 903.6430609909049, 5421.430749771217, 5502.724863756796, 5595.21666966625, 5620.4098569649595, 5779.711014449261, 436.18868903838836, 441.1860118082133, 446.9731816091045, 453.3766071651479, 458.282787437659, 3540.309639620301, 3709.8145092745253, 3777.036855601804, 3825.2349530094093, 3880.767153970771, 10191.184705223564, 10276.383416995004, 10415.114715249138, 10433.373997560018, 10457.477126143815, 10385.63650272237, 10398.324108932895, 10402.843957340647, 10405.167586593054, 10416.583336666448, 7565.546067235873, 7765.339946104645, 8013.11855695873, 8031.1567285434185, 8031.237032481905, 5194.0, 5224.52979231871, 5306.336707911511, 5363.061464243664, 5480.260164877691, 8879.866801997847, 9283.953580232055, 9354.0, 9467.715968520955, 9635.903640963501, 9370.625174992923, 9555.140037396668, 9648.903510964801, 9692.41845489273, 9822.901770982484, 7207.819804504929, 7246.1666908306215, 7250.9637451814515, 7283.817904552428, 7304.9634751828025, 7910.604469776603, 8002.679892804223, 8008.919910801051, 8012.959935200287, 8024.919750802417, 6456.418922297016, 7109.786706398816, 7192.957021231997, 7254.347108760237, 7353.852922941618, 7003.229644739176, 7030.086088808423, 7059.470539709439, 7093.82265443368, 7125.821854453473, 6235.314115447207, 6380.138681277968, 6487.3836985486305, 6508.707108180049, 6517.837054073497, 4849.0, 4850.83022094225, 4865.537773911473, 4929.97535012337, 4937.950620493749, 4796.496367881322, 4819.807207711652, 4848.5636292733825, 4982.10322142018, 4987.202047672355], "value": 4330.190973587471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [247.85005071931482, 284.81091443224557, 301.5612284126596, 307.5801530910307, 311.2887053083704, 4505.752183629903, 4936.346323981466, 4949.554540091391, 4980.128477516434, 5211.853392253707, 4049.898752531188, 4104.753714777108, 4928.888292772589, 5080.441151473335, 5120.8975820483665, 3299.6306532788863, 3937.4093885917055, 3988.820503077361, 3996.300647386696, 4027.536833264183, 3456.723462123024, 3784.451254568086, 3850.0182453474326, 3937.8031098444962, 3975.483187185662, 3393.457046872501, 3704.7033538261526, 3847.134139942126, 3899.2201559688033, 3909.3158697227873, 3679.767277961886, 3892.8637497687587, 3897.4933258676333, 3914.608539146091, 3925.371940489508, 3078.830664313462, 3397.1676939150066, 3418.1454636340914, 3436.604790449105, 3447.172678557153, 4113.244126435296, 4822.0, 4877.3903262092235, 4940.357753492023, 4971.577415919651, 4423.447069116361, 4444.177827101997, 4445.399226626443, 4447.421835161408, 4451.087527056946, 5316.840494785162, 5355.3398446481715, 5365.812196573102, 5367.302250707422, 5386.245925570423, 3567.500549923013, 3677.5403074615674, 3706.870259540903, 3722.933771756674, 3730.8134593270497, 4867.34290870735, 5101.821436249714, 5423.566114710814, 5634.267545219136, 5635.71821408932, 3192.345569158317, 3734.4128745283147, 3753.4932784074344, 3755.4602612929116, 3758.9436158457647, 3421.4525675891737, 3818.980905095457, 3824.8087595620664, 3824.8661296854475, 3838.6353296436796, 3628.4738712886483, 3775.2067767810618, 4172.310214363188, 4251.744895306229, 4294.26997410441, 5281.049411106038, 5369.6509726867625, 5405.0, 5409.0, 5427.45725427462, 1063.3991794636006, 1215.6263422332706, 1229.874664681806, 1239.964629534331, 1263.9747205055846, 708.3907839258234, 801.3268854162532, 805.2873207211543, 807.23716088296, 838.6079108679563, 4328.0, 4442.622377097919, 4524.977375113169, 4839.95160048395, 4852.8301509447, 4728.834490792806, 5267.367915850123, 5301.469853014668, 5326.0, 5329.413764485904, 4050.675945924319, 4559.658025648089, 4776.952230477651, 4823.541763532458, 4832.420109586802, 2214.844960852725, 2295.5294164696197, 2354.4231663242617, 2768.7923405744245, 2781.8887244510397, 5465.508104270635, 5491.615586908958, 5509.0, 5509.669419834822, 5511.84251307222, 4035.20433407131, 5078.466760990117, 5337.973310133424, 5390.8921821563345, 5421.349438067457, 4849.878753031202, 5389.254468144759, 5448.030823201365, 5477.287952566141, 5503.449655034465, 4496.842610508616, 4649.907001859876, 5134.717590532557, 5249.2126181073145, 5272.789088436573, 4467.0, 4988.480817187442, 4996.775145118406, 5177.8187763428095, 5256.976064215228, 4475.26158183896, 5383.703896285742, 5405.351357837085, 5440.782368705208, 5452.454754524516, 2971.509700899414, 3183.80897146172, 3591.51514545533, 3596.9640303597675, 3615.9096022599647, 4442.977785111054, 4601.37881386022, 5287.920681189708, 5303.602229832855, 5312.45002398843, 4700.694454860423, 4746.908211111395, 4887.682300650446, 5077.695338279715, 5101.6173786966865, 4908.637583255481, 5034.395872495177, 5260.370344462309, 5260.89478210426, 5422.783088676567, 5290.312259406406, 5299.4700529946695, 5333.5199832015305, 5346.652467589594, 5399.811006614749, 4862.905846184722, 5017.37282839645, 5376.919346209888, 5430.972845135907, 5437.238786569805, 4250.961586492906, 4911.435184953633, 5153.273653326178, 5372.919406208832, 5384.353877534721], "value": 3823.3446631165193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.271670237792, 685.7736946807554, 705.6401235369963, 751.7293774241272, 768.6848392159214, 13532.323383830806, 14254.714905701892, 14276.500322488715, 14279.50021749239, 14297.857021429769, 13144.408501617427, 13173.275469849155, 13190.736185276313, 13228.0, 13242.933785331084, 774.8566515194678, 802.1016461563057, 807.7011505742884, 812.3298278919885, 813.5444151275304, 815.1522416686669, 816.6529225079347, 819.4223072733708, 820.63481750621, 820.7332616899531, 831.193742070191, 833.9833203335929, 834.3742193354984, 834.8246868157694, 835.8202986357932, 825.6986200037003, 826.2233500509502, 826.6858593734372, 827.5283088639447, 827.664795757713, 613.1201725523888, 613.1844646619985, 613.3467856732566, 613.3774219167549, 613.7851751886849, 14067.859321406757, 14358.425662973468, 14493.78259326111, 14501.492447764384, 14521.709565808625, 11921.225120367148, 12432.937835310857, 13134.864238162876, 13415.329233538381, 13551.503848900857, 10813.83779243312, 11107.222494425394, 11172.0, 11186.832197517044, 11623.0, 3520.841562129685, 3589.335972845032, 3590.784552926833, 3608.170120872188, 3688.3360995020885, 60745.0, 65763.67118164379, 67916.64166716687, 79820.80268795915, 86171.56914215452, 10132.746681333025, 10338.741531461774, 10492.21308401873, 10568.0, 10840.566377344976, 19423.44612431002, 19814.306499272458, 20216.191352346035, 20266.69599956002, 20411.693824592643, 7607.201243869314, 7625.847483050308, 7668.463207575528, 7722.498037627536, 7751.612419379008, 4083.51976137211, 4113.4446849675505, 4269.829206831692, 4273.87178384649, 4339.284018136972, 3403.7447191460737, 3430.7255419566873, 3472.5312082868995, 3529.294141171776, 3531.6644918732677, 557.0863783395235, 614.9538784591084, 622.6108682073703, 628.9182406287154, 630.1021045010841, 520.2300595119244, 545.2965674280214, 649.3798422506495, 657.4922786931772, 662.8011596521121, 8289.8342033159, 8746.650133994695, 8922.241609463268, 8942.329325300629, 9351.438913665203, 559.3232189051294, 600.342624825815, 617.5893031134335, 634.7810005548079, 665.7969319357545, 4424.977875110604, 4428.247197976351, 4440.578145076212, 4447.221736196153, 4475.216837053503, 103987.4800625992, 107873.46063269634, 111751.32373014411, 111800.0, 112281.0, 62992.370076299565, 77294.0, 87801.56099219463, 96811.0, 108825.36761948577, 57248.42751572431, 58549.24352269524, 66489.33510664832, 75656.62171689107, 79790.0, 9345.392549484262, 9450.952745236229, 9525.095115963972, 9550.474723889974, 9564.13922746956, 39983.0, 43544.0, 50085.74957125191, 52979.470205298996, 55587.72206138943, 64626.353736462035, 72665.27334726584, 76767.0, 79101.60449197948, 79308.81036784568, 11678.88321116778, 11826.82259766087, 11915.82126268124, 12216.862049141188, 12242.816357754462, 13494.932525337703, 13604.387802548712, 13619.0, 13772.0, 14153.716925661634, 12288.38558072075, 12528.624141275777, 12713.36433178356, 12724.427400766803, 12766.87233127694, 12037.38689470007, 12109.636710898687, 12282.692932676755, 12692.873071269169, 12761.489540418279, 10591.788164236827, 11798.41007949582, 11880.405979701154, 12446.81329780036, 12573.182743121668, 10216.846747298649, 10435.530401132119, 10603.0, 10828.945855270673, 11295.81393953623, 9325.253979681473, 10193.337433066827, 10822.296550724177, 10957.945210273898, 10980.1215902726], "value": 7035.621719780833, "unit": "score"}], "name": "dromaeo_css", "value": 7017.563618827753, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 117.0, 115.0, 115.0, 114.0, 115.0, 115.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 107.0, 142.0, 114.0, 108.0, 109.0, 108.0, 138.0, 108.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 221.0, 184.0, 185.0, 184.0, 184.0, 252.0, 226.0, 212.0, 258.0], "value": 210.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 60.0, 59.0, 59.0, 58.0, 82.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 93.0, 71.0, 68.0, 69.0, 88.0, 68.0, 68.0, 92.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 135.0, 130.0, 90.0, 87.0, 91.0, 87.0, 122.0, 87.0], "value": 100.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 109.0, 123.0, 122.0, 83.0, 84.0, 85.0, 83.0, 77.0, 82.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 56.0, 58.0, 58.0, 58.0, 58.0, 57.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 66.0, 70.0, 87.0, 108.0, 96.0, 82.0, 70.0, 69.0, 70.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 91.0, 102.0, 75.0, 82.0, 82.0, 83.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 140.0, 139.0, 141.0, 144.0, 145.0, 158.0, 218.0, 151.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 164.0, 171.0, 225.0, 184.0, 162.0, 165.0, 164.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 67.0, 65.0, 64.0, 66.0, 66.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1488.8999999999999, "alertThreshold": 2.0}]} 10:48:57 INFO - Return code: 0 10:48:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:48:57 INFO - # TBPL SUCCESS # 10:48:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:48:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:48:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:48:57 INFO - 2017-01-01 10:48:57.365 screenresolution[1666:11247] starting screenresolution argv=screenresolution get 10:48:57 INFO - 2017-01-01 10:48:57.367 screenresolution[1666:11247] Display 0: 1600x1200x32@60 10:48:57 INFO - 2017-01-01 10:48:57.373 screenresolution[1667:11250] starting screenresolution argv=screenresolution list 10:48:57 INFO - Available Modes on Display 0 10:48:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:48:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:48:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:48:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:48:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:48:57 INFO - Intel Iris: 10:48:57 INFO - Chipset Model: Intel Iris 10:48:57 INFO - Type: GPU 10:48:57 INFO - Bus: Built-In 10:48:57 INFO - VRAM (Dynamic, Max): 1536 MB 10:48:57 INFO - Vendor: Intel (0x8086) 10:48:57 INFO - Device ID: 0x0a2e 10:48:57 INFO - Revision ID: 0x0009 10:48:57 INFO - Displays: 10:48:57 INFO - Display: 10:48:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:48:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:48:57 INFO - Display Serial Number: 1600x1200 60 10:48:57 INFO - Main Display: Yes 10:48:57 INFO - Mirror: Off 10:48:57 INFO - Online: Yes 10:48:57 INFO - Rotation: Supported 10:48:57 INFO - Return code: 0 10:48:57 INFO - Running post-action listener: _resource_record_post_action 10:48:57 INFO - [mozharness: 2017-01-01 18:48:57.609284Z] Finished run-tests step (success) 10:48:57 INFO - Running post-run listener: _resource_record_post_run 10:48:57 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32513024; Write bytes: 661100032; Read time: 204; Write time: 1926 10:48:57 INFO - TinderboxPrint: CPU usage
53.4% 10:48:57 INFO - TinderboxPrint: I/O read bytes / time
32,513,024 / 204 10:48:57 INFO - TinderboxPrint: I/O write bytes / time
661,100,032 / 1,926 10:48:57 INFO - TinderboxPrint: CPU idle
2,257.7 (46.6%) 10:48:57 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 10:48:57 INFO - TinderboxPrint: CPU user
1,621.5 (33.4%) 10:48:57 INFO - TinderboxPrint: Swap in / out
378,134,528 / 0 10:48:57 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 336577024; Write bytes: 332969472; Read time: 26751; Write time: 714 10:48:57 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 31587328; Write bytes: 310435840; Read time: 190; Write time: 1159 10:48:58 INFO - Running post-run listener: _upload_blobber_files 10:48:58 INFO - Blob upload gear active. 10:48:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:48:58 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 10:48:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:48:58 INFO - (blobuploader) - INFO - Open directory for files ... 10:48:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:48:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:48:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:59 INFO - (blobuploader) - INFO - Done attempting. 10:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:49:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:00 INFO - (blobuploader) - INFO - Done attempting. 10:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:49:02 INFO - (blobuploader) - INFO - Done attempting. 10:49:02 INFO - (blobuploader) - INFO - Iteration through files over. 10:49:02 INFO - Return code: 0 10:49:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:49:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:49:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/260c9809d17eaa767702d284f269b556e40f29f6abdeac7942fc31e9cdc352733698455df5505eca58390fe45bba00da1cecff9bf992d0593c951224b15140ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac56ce53669bf878b1813b2f1307f49475e9d91a3a2f138384b69dd841edf6f56c872f5dc4cbe5b47ec0c744b067c3902313184ce5efc90c218fcb775db7c959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/512f9625e13e524942a51957631308f7916c3b5cedb9145b4298931de9763b149c135a608024b272c3e3f8500ac27e68539a8e75b7ababfc728723d976938f7f"} 10:49:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:49:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:49:02 INFO - Contents: 10:49:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/260c9809d17eaa767702d284f269b556e40f29f6abdeac7942fc31e9cdc352733698455df5505eca58390fe45bba00da1cecff9bf992d0593c951224b15140ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac56ce53669bf878b1813b2f1307f49475e9d91a3a2f138384b69dd841edf6f56c872f5dc4cbe5b47ec0c744b067c3902313184ce5efc90c218fcb775db7c959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/512f9625e13e524942a51957631308f7916c3b5cedb9145b4298931de9763b149c135a608024b272c3e3f8500ac27e68539a8e75b7ababfc728723d976938f7f"} 10:49:02 INFO - Running post-run listener: copy_logs_to_upload_dir 10:49:02 INFO - Copying logs to upload dir... 10:49:02 INFO - mkdir: /builds/slave/test/build/upload/logs 10:49:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.048524 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-01 10:49:02.827515) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 10:49:02.831906) ========= 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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/260c9809d17eaa767702d284f269b556e40f29f6abdeac7942fc31e9cdc352733698455df5505eca58390fe45bba00da1cecff9bf992d0593c951224b15140ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac56ce53669bf878b1813b2f1307f49475e9d91a3a2f138384b69dd841edf6f56c872f5dc4cbe5b47ec0c744b067c3902313184ce5efc90c218fcb775db7c959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/512f9625e13e524942a51957631308f7916c3b5cedb9145b4298931de9763b149c135a608024b272c3e3f8500ac27e68539a8e75b7ababfc728723d976938f7f"} build_url:https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/QLVnWf0KSGy-4jOYr6VUhw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/260c9809d17eaa767702d284f269b556e40f29f6abdeac7942fc31e9cdc352733698455df5505eca58390fe45bba00da1cecff9bf992d0593c951224b15140ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac56ce53669bf878b1813b2f1307f49475e9d91a3a2f138384b69dd841edf6f56c872f5dc4cbe5b47ec0c744b067c3902313184ce5efc90c218fcb775db7c959", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/512f9625e13e524942a51957631308f7916c3b5cedb9145b4298931de9763b149c135a608024b272c3e3f8500ac27e68539a8e75b7ababfc728723d976938f7f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 10:49:02.869324) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:49:02.869667) ========= 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.lD4xzTNeZ5/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.PShkOWu2fn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005408 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 10:49:02.918246) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 10:49:02.921590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 10:49:02.921908) ========= ========= Total master_lag: 0.21 =========