builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0177 starttime: 1481902310.44 results: success (0) buildid: 20161216062349 builduid: e22b0ae9dc934263bbd4e58670e869d7 revision: ae98ffdc57822f068c011e305ea8a9662dd11e01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.440335) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.440785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.441074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.467476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.467838) ========= 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.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-16 07:31:50-- 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% 80.7M=0s 2016-12-16 07:31:50 (80.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.669063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.669440) ========= 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.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.026905 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.723930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:50.724412) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ae98ffdc57822f068c011e305ea8a9662dd11e01 --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 ae98ffdc57822f068c011e305ea8a9662dd11e01 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-16 07:31:50,870 truncating revision to first 12 chars 2016-12-16 07:31:50,870 Setting DEBUG logging. 2016-12-16 07:31:50,870 attempt 1/10 2016-12-16 07:31:50,870 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ae98ffdc5782?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 07:31:51,525 unpacking tar archive at: mozilla-aurora-ae98ffdc5782/testing/mozharness/ program finished with exit code 0 elapsedTime=0.903124 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:51.651614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:51.651945) ========= script_repo_revision: ae98ffdc57822f068c011e305ea8a9662dd11e01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:51.652340) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:51.652635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 07:31:51.672611) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-16 07:31:51.673006) ========= /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.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:31:51 INFO - MultiFileLogger online at 20161216 07:31:51 in /builds/slave/test 07:31:51 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 07:31:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:51 INFO - {'append_to_log': False, 07:31:51 INFO - 'base_work_dir': '/builds/slave/test', 07:31:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:31:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:51 INFO - 'branch': 'Mozilla-Aurora', 07:31:51 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:51 INFO - 'config_files': ('talos/mac_config.py',), 07:31:51 INFO - 'default_actions': ('clobber', 07:31:51 INFO - 'read-buildbot-config', 07:31:51 INFO - 'download-and-extract', 07:31:51 INFO - 'populate-webroot', 07:31:51 INFO - 'create-virtualenv', 07:31:51 INFO - 'install', 07:31:51 INFO - 'run-tests'), 07:31:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:51 INFO - 'download_minidump_stackwalk': True, 07:31:51 INFO - 'download_symbols': 'ondemand', 07:31:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:51 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:51 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:51 INFO - 'installer_path': 'installer.exe', 07:31:51 INFO - 'log_level': 'info', 07:31:51 INFO - 'log_name': 'talos', 07:31:51 INFO - 'log_to_console': True, 07:31:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:51 INFO - 'opt_config_files': (), 07:31:51 INFO - 'pip_index': False, 07:31:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:51 INFO - 'cmd': ('bash', 07:31:51 INFO - '-c', 07:31:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:51 INFO - 'enabled': True, 07:31:51 INFO - 'halt_on_failure': False, 07:31:51 INFO - 'name': 'check_screen_resolution'},), 07:31:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:51 INFO - 'cmd': ('bash', 07:31:51 INFO - '-c', 07:31:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:51 INFO - 'enabled': True, 07:31:51 INFO - 'halt_on_failure': False, 07:31:51 INFO - 'name': 'check_screen_resolution'},), 07:31:51 INFO - 'run_cmd_checks_enabled': True, 07:31:51 INFO - 'sps_profile': False, 07:31:51 INFO - 'sps_profile_interval': 0, 07:31:51 INFO - 'suite': 'dromaeojs', 07:31:51 INFO - 'system_bits': '32', 07:31:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:51 INFO - 'title': 't-yosemite-r7-0177', 07:31:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:51 INFO - 'use_talos_json': True, 07:31:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:51 INFO - 'work_dir': 'build'} 07:31:51 INFO - [mozharness: 2016-12-16 15:31:51.932014Z] Running clobber step. 07:31:51 INFO - Running pre-action listener: _resource_record_pre_action 07:31:51 INFO - Running main action method: clobber 07:31:51 INFO - rmtree: /builds/slave/test/build 07:31:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:54 INFO - Running post-action listener: _resource_record_post_action 07:31:54 INFO - [mozharness: 2016-12-16 15:31:54.471378Z] Finished clobber step (success) 07:31:54 INFO - [mozharness: 2016-12-16 15:31:54.471498Z] Running read-buildbot-config step. 07:31:54 INFO - Running pre-action listener: _resource_record_pre_action 07:31:54 INFO - Running main action method: read_buildbot_config 07:31:54 INFO - Using buildbot properties: 07:31:54 INFO - { 07:31:54 INFO - "project": "", 07:31:54 INFO - "product": "firefox", 07:31:54 INFO - "script_repo_revision": "production", 07:31:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:31:54 INFO - "repository": "", 07:31:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:31:54 INFO - "buildid": "20161216062349", 07:31:54 INFO - "pgo_build": "False", 07:31:54 INFO - "basedir": "/builds/slave/test", 07:31:54 INFO - "buildnumber": 64, 07:31:54 INFO - "slavename": "t-yosemite-r7-0177", 07:31:54 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:31:54 INFO - "platform": "yosemite_r7", 07:31:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:31:54 INFO - "branch": "mozilla-aurora", 07:31:54 INFO - "repo_path": "releases/mozilla-aurora", 07:31:54 INFO - "revision": "ae98ffdc57822f068c011e305ea8a9662dd11e01", 07:31:54 INFO - "stage_platform": "macosx64", 07:31:54 INFO - "builduid": "e22b0ae9dc934263bbd4e58670e869d7", 07:31:54 INFO - "slavebuilddir": "test" 07:31:54 INFO - } 07:31:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:31:54 INFO - Running post-action listener: _resource_record_post_action 07:31:54 INFO - [mozharness: 2016-12-16 15:31:54.474921Z] Finished read-buildbot-config step (success) 07:31:54 INFO - [mozharness: 2016-12-16 15:31:54.475038Z] Running download-and-extract step. 07:31:54 INFO - Running pre-action listener: _resource_record_pre_action 07:31:54 INFO - Running main action method: download_and_extract 07:31:54 INFO - mkdir: /builds/slave/test/build/tests 07:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:54 INFO - trying https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:31:54 INFO - Downloading https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/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 07:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/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 07:31:55 INFO - Downloaded 1199 bytes. 07:31:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:31:55 INFO - Using the following test package requirements: 07:31:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:31:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:31:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:31:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'jsshell-mac.zip'], 07:31:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:31:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:31:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:31:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:31:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:31:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:31:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:31:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:31:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:31:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:31:55 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:31:56 INFO - Http code: 200 07:31:56 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 07:31:56 INFO - x-amz-cf-id: qP0E2zBYqFcamE0EfbgfljOxX4pnZ8aA1c2Zz2cMlOg1MPiUVJV5Qw== 07:31:56 INFO - x-amz-version-id: 3Fib7RddHUXyr37F5ydr9j64vvpdssn4 07:31:56 INFO - Expected file size: 16280346 07:31:56 INFO - Obtained file size: 16280346 07:32:03 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 07:32:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:32:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:32:03 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:32:03 INFO - Http code: 200 07:32:03 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 07:32:03 INFO - x-amz-cf-id: hkl5ZK620Yo1PuWxbk_vghdqJMombmb7g8CC18uYKrZmjuWVYNCsRg== 07:32:03 INFO - x-amz-version-id: 3Fib7RddHUXyr37F5ydr9j64vvpdssn4 07:32:03 INFO - Expected file size: 16280346 07:32:03 INFO - Obtained file size: 16280346 07:32:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:32:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:32:10 INFO - Fetch https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:32:11 INFO - Http code: 200 07:32:11 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 07:32:11 INFO - x-amz-cf-id: Yuafx5qD-IANQLzJmEDoQKoDhOFxfPqNzjQThuAiv6X6WMXj9xWThg== 07:32:11 INFO - x-amz-version-id: JCy634WTF8RNu7QXTMwNQhhMeqE2UPH_ 07:32:11 INFO - Expected file size: 14409038 07:32:11 INFO - Obtained file size: 14409038 07:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:11 INFO - trying https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:11 INFO - Downloading https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:32:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/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 07:32:14 INFO - Downloaded 109725504 bytes. 07:32:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:14 INFO - mkdir: /builds/slave/test/properties 07:32:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:32:14 INFO - Writing to file /builds/slave/test/properties/build_url 07:32:14 INFO - Contents: 07:32:14 INFO - build_url:https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:32:15 INFO - Running post-action listener: _resource_record_post_action 07:32:15 INFO - Running post-action listener: set_extra_try_arguments 07:32:15 INFO - [mozharness: 2016-12-16 15:32:15.971946Z] Finished download-and-extract step (success) 07:32:15 INFO - [mozharness: 2016-12-16 15:32:15.972069Z] Running populate-webroot step. 07:32:15 INFO - Running pre-action listener: _resource_record_pre_action 07:32:15 INFO - Running main action method: populate_webroot 07:32:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:15 INFO - '--tppagecycles', 07:32:15 INFO - '7'], 07:32:15 INFO - 'tests': ['tsvgm']}}, 07:32:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['damp', 'tps']}, 07:32:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'tests': ['damp', 'tps']}, 07:32:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['dromaeo_dom']}, 07:32:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['basic_compositor_video']}, 07:32:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['a11yr', 07:32:15 INFO - 'ts_paint', 07:32:15 INFO - 'tpaint', 07:32:15 INFO - 'sessionrestore', 07:32:15 INFO - 'sessionrestore_no_auto_restore', 07:32:15 INFO - 'tabpaint']}, 07:32:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['tsvgx', 07:32:15 INFO - 'tsvgr_opacity', 07:32:15 INFO - 'tart', 07:32:15 INFO - 'tscrollx', 07:32:15 INFO - 'cart']}, 07:32:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:15 INFO - 'tsvgr_opacity', 07:32:15 INFO - 'tart', 07:32:15 INFO - 'tscrollx', 07:32:15 INFO - 'cart']}, 07:32:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'talos_options': ['--disable-e10s'], 07:32:15 INFO - 'tests': ['tp5o']}, 07:32:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'tests': ['tp5o']}, 07:32:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'talos_options': ['--disable-e10s', 07:32:15 INFO - '--xperf_path', 07:32:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:15 INFO - 'tests': ['tp5n']}, 07:32:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:15 INFO - 'talos_options': ['--xperf_path', 07:32:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:15 INFO - 'tests': ['tp5n']}}, 07:32:15 INFO - 'talos.zip': {'path': '', 07:32:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:15 INFO - Running post-action listener: _resource_record_post_action 07:32:15 INFO - [mozharness: 2016-12-16 15:32:15.990847Z] Finished populate-webroot step (success) 07:32:15 INFO - [mozharness: 2016-12-16 15:32:15.990979Z] Running create-virtualenv step. 07:32:15 INFO - Running pre-action listener: _resource_record_pre_action 07:32:15 INFO - Running main action method: create_virtualenv 07:32:15 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:15 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 07:32:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:16 INFO - Using real prefix '/tools/python27' 07:32:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:16 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:19 INFO - Installing pip.................done. 07:32:19 INFO - Return code: 0 07:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:32:19 INFO - Reading from file tmpfile_stdout 07:32:19 INFO - Output received: 07:32:19 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) 07:32:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:19 INFO - 'HOME': '/Users/cltbld', 07:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:19 INFO - 'LOGNAME': 'cltbld', 07:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:19 INFO - 'MOZ_NO_REMOTE': '1', 07:32:19 INFO - 'NO_EM_RESTART': '1', 07:32:19 INFO - 'PAGER': '/bin/cat', 07:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:19 INFO - 'PWD': '/builds/slave/test', 07:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:19 INFO - 'SHELL': '/bin/bash', 07:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:19 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:19 INFO - 'USER': 'cltbld', 07:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:19 INFO - 'XPC_FLAGS': '0x0', 07:32:19 INFO - 'XPC_SERVICE_NAME': '0', 07:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:20 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 07:32:20 INFO - Cleaning up... 07:32:20 INFO - Return code: 0 07:32:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32:20 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')]} 07:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:20 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')]} 07:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:20 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:20 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 07:32:20 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 07:32:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:20 INFO - 'HOME': '/Users/cltbld', 07:32:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:20 INFO - 'LOGNAME': 'cltbld', 07:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:20 INFO - 'MOZ_NO_REMOTE': '1', 07:32:20 INFO - 'NO_EM_RESTART': '1', 07:32:20 INFO - 'PAGER': '/bin/cat', 07:32:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:20 INFO - 'PWD': '/builds/slave/test', 07:32:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:20 INFO - 'SHELL': '/bin/bash', 07:32:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:20 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:20 INFO - 'USER': 'cltbld', 07:32:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:20 INFO - 'XPC_FLAGS': '0x0', 07:32:20 INFO - 'XPC_SERVICE_NAME': '0', 07:32:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:20 INFO - Downloading/unpacking psutil>=3.1.1 07:32:20 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 07:32:20 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 07:32:20 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 07:32:20 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 07:32:20 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 07:32:20 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 07:32:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:23 INFO - Installing collected packages: psutil 07:32:23 INFO - Running setup.py install for psutil 07:32:23 INFO - building 'psutil._psutil_osx' extension 07:32:23 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 07:32:24 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 07:32:24 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 07:32:24 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 07:32:24 INFO - building 'psutil._psutil_posix' extension 07:32:24 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 07:32:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:24 INFO - ^ 07:32:24 INFO - 1 warning generated. 07:32:24 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 07:32:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:24 INFO - Successfully installed psutil 07:32:24 INFO - Cleaning up... 07:32:25 INFO - Return code: 0 07:32:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:25 INFO - 'HOME': '/Users/cltbld', 07:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:25 INFO - 'LOGNAME': 'cltbld', 07:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:25 INFO - 'MOZ_NO_REMOTE': '1', 07:32:25 INFO - 'NO_EM_RESTART': '1', 07:32:25 INFO - 'PAGER': '/bin/cat', 07:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:25 INFO - 'PWD': '/builds/slave/test', 07:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:25 INFO - 'SHELL': '/bin/bash', 07:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:25 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:25 INFO - 'USER': 'cltbld', 07:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:25 INFO - 'XPC_FLAGS': '0x0', 07:32:25 INFO - 'XPC_SERVICE_NAME': '0', 07:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:25 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 07:32:25 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 07:32:25 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 07:32:25 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 07:32:25 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 07:32:25 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 07:32:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:28 INFO - Installing collected packages: mozsystemmonitor 07:32:28 INFO - Running setup.py install for mozsystemmonitor 07:32:28 INFO - Successfully installed mozsystemmonitor 07:32:28 INFO - Cleaning up... 07:32:28 INFO - Return code: 0 07:32:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', '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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:32: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 jsonschema==2.5.1 07:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:28 INFO - 'HOME': '/Users/cltbld', 07:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:28 INFO - 'LOGNAME': 'cltbld', 07:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:28 INFO - 'MOZ_NO_REMOTE': '1', 07:32:28 INFO - 'NO_EM_RESTART': '1', 07:32:28 INFO - 'PAGER': '/bin/cat', 07:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:28 INFO - 'PWD': '/builds/slave/test', 07:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:28 INFO - 'SHELL': '/bin/bash', 07:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:28 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:28 INFO - 'USER': 'cltbld', 07:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:28 INFO - 'XPC_FLAGS': '0x0', 07:32:28 INFO - 'XPC_SERVICE_NAME': '0', 07:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:31 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:31 INFO - warning: no files found matching '*.txt' 07:32:31 INFO - no previously-included directories found matching 'build' 07:32:31 INFO - no previously-included directories found matching 'dist' 07:32:31 INFO - no previously-included directories found matching '.git*' 07:32:31 INFO - Installing collected packages: jsonschema, functools32 07:32:31 INFO - Running setup.py install for functools32 07:32:31 INFO - warning: no files found matching '*.txt' 07:32:31 INFO - no previously-included directories found matching 'build' 07:32:31 INFO - no previously-included directories found matching 'dist' 07:32:31 INFO - no previously-included directories found matching '.git*' 07:32:31 INFO - Successfully installed jsonschema functools32 07:32:31 INFO - Cleaning up... 07:32:31 INFO - Return code: 0 07:32:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:31 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:31 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 07:32:31 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 07:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:31 INFO - 'HOME': '/Users/cltbld', 07:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:31 INFO - 'LOGNAME': 'cltbld', 07:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:31 INFO - 'MOZ_NO_REMOTE': '1', 07:32:31 INFO - 'NO_EM_RESTART': '1', 07:32:31 INFO - 'PAGER': '/bin/cat', 07:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:31 INFO - 'PWD': '/builds/slave/test', 07:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:31 INFO - 'SHELL': '/bin/bash', 07:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:31 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:31 INFO - 'USER': 'cltbld', 07:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:31 INFO - 'XPC_FLAGS': '0x0', 07:32:31 INFO - 'XPC_SERVICE_NAME': '0', 07:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:32 INFO - Cleaning up... 07:32:32 INFO - Return code: 0 07:32:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32:32 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')]} 07:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:32 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')]} 07:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:32 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:32 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 07:32:32 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 07:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:32 INFO - 'HOME': '/Users/cltbld', 07:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:32 INFO - 'LOGNAME': 'cltbld', 07:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:32 INFO - 'MOZ_NO_REMOTE': '1', 07:32:32 INFO - 'NO_EM_RESTART': '1', 07:32:32 INFO - 'PAGER': '/bin/cat', 07:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:32 INFO - 'PWD': '/builds/slave/test', 07:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:32 INFO - 'SHELL': '/bin/bash', 07:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:32 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:32 INFO - 'USER': 'cltbld', 07:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:32 INFO - 'XPC_FLAGS': '0x0', 07:32:32 INFO - 'XPC_SERVICE_NAME': '0', 07:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:32 INFO - Downloading/unpacking blobuploader==1.2.4 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:34 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:35 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 07:32:36 INFO - Downloading docopt-0.6.1.tar.gz 07:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:36 INFO - Running setup.py install for blobuploader 07:32:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:36 INFO - Running setup.py install for requests 07:32:36 INFO - Running setup.py install for docopt 07:32:37 INFO - Successfully installed blobuploader requests docopt 07:32:37 INFO - Cleaning up... 07:32:37 INFO - Return code: 0 07:32:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:37 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')]} 07:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:37 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')]} 07:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:37 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:37 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 07:32:37 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 07:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:37 INFO - 'HOME': '/Users/cltbld', 07:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:37 INFO - 'LOGNAME': 'cltbld', 07:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:37 INFO - 'MOZ_NO_REMOTE': '1', 07:32:37 INFO - 'NO_EM_RESTART': '1', 07:32:37 INFO - 'PAGER': '/bin/cat', 07:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:37 INFO - 'PWD': '/builds/slave/test', 07:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:37 INFO - 'SHELL': '/bin/bash', 07:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:37 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:37 INFO - 'USER': 'cltbld', 07:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:37 INFO - 'XPC_FLAGS': '0x0', 07:32:37 INFO - 'XPC_SERVICE_NAME': '0', 07:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-xQ0OxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-RFFuVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-OUSGov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-vcjAuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-IrqO1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-p_1isK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:37 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-KeQMcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-7OgTRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-yA5aNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-TzPKOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-T81jLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-o5XUVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-hgyY2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-xBsJCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-FCwCKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:38 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-6u_APL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:39 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-BhfU5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:39 INFO - Running setup.py install for manifestparser 07:32:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:39 INFO - Running setup.py install for mozcrash 07:32:39 INFO - Running setup.py install for mozdebug 07:32:39 INFO - Running setup.py install for mozdevice 07:32:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:39 INFO - Running setup.py install for mozfile 07:32:39 INFO - Running setup.py install for mozhttpd 07:32:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Running setup.py install for mozinfo 07:32:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Running setup.py install for mozInstall 07:32:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Running setup.py install for mozleak 07:32:40 INFO - Running setup.py install for mozlog 07:32:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Running setup.py install for moznetwork 07:32:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:40 INFO - Running setup.py install for mozprocess 07:32:40 INFO - Running setup.py install for mozprofile 07:32:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:41 INFO - Running setup.py install for mozrunner 07:32:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:41 INFO - Running setup.py install for mozscreenshot 07:32:41 INFO - Running setup.py install for moztest 07:32:41 INFO - Running setup.py install for mozversion 07:32:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:41 INFO - Cleaning up... 07:32:41 INFO - Return code: 0 07:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:41 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:41 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 07:32:41 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 07:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:41 INFO - 'HOME': '/Users/cltbld', 07:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:41 INFO - 'LOGNAME': 'cltbld', 07:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:41 INFO - 'MOZ_NO_REMOTE': '1', 07:32:41 INFO - 'NO_EM_RESTART': '1', 07:32:41 INFO - 'PAGER': '/bin/cat', 07:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:41 INFO - 'PWD': '/builds/slave/test', 07:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:41 INFO - 'SHELL': '/bin/bash', 07:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:41 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:41 INFO - 'USER': 'cltbld', 07:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:41 INFO - 'XPC_FLAGS': '0x0', 07:32:41 INFO - 'XPC_SERVICE_NAME': '0', 07:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-EhAO3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-Q9n8cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-ZDGXCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-Qi73vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-nvgFoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-NGdko4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-DeO0v5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-wsMGGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-jlKXBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:42 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)) 07:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:42 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-XKxJqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-VAL7bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-dtWkbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-e4ewc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-SMMD2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-K5rVk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-xP94Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:43 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)) 07:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:43 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-Y3Fee4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:43 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)) 07:32:43 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)) 07:32:43 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)) 07:32:43 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)) 07:32:43 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)) 07:32:43 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)) 07:32:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:46 INFO - Downloading blessings-1.6.tar.gz 07:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:46 INFO - Installing collected packages: blessings 07:32:46 INFO - Running setup.py install for blessings 07:32:46 INFO - Successfully installed blessings 07:32:46 INFO - Cleaning up... 07:32:46 INFO - Return code: 0 07:32:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:47 INFO - Reading from file tmpfile_stdout 07:32:47 INFO - Current package versions: 07:32:47 INFO - blessings == 1.6 07:32:47 INFO - blobuploader == 1.2.4 07:32:47 INFO - docopt == 0.6.1 07:32:47 INFO - functools32 == 3.2.3-2 07:32:47 INFO - jsonschema == 2.5.1 07:32:47 INFO - manifestparser == 1.1 07:32:47 INFO - mozInstall == 1.12 07:32:47 INFO - mozcrash == 1.0 07:32:47 INFO - mozdebug == 0.1 07:32:47 INFO - mozdevice == 0.48 07:32:47 INFO - mozfile == 1.2 07:32:47 INFO - mozhttpd == 0.7 07:32:47 INFO - mozinfo == 0.9 07:32:47 INFO - mozleak == 0.1 07:32:47 INFO - mozlog == 3.4 07:32:47 INFO - moznetwork == 0.27 07:32:47 INFO - mozprocess == 0.23 07:32:47 INFO - mozprofile == 0.28 07:32:47 INFO - mozrunner == 6.13 07:32:47 INFO - mozscreenshot == 0.1 07:32:47 INFO - mozsystemmonitor == 0.3 07:32:47 INFO - moztest == 0.7 07:32:47 INFO - mozversion == 1.4 07:32:47 INFO - psutil == 3.1.1 07:32:47 INFO - requests == 1.2.3 07:32:47 INFO - wsgiref == 0.1.2 07:32:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:47 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 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:47 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 07:32:47 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 07:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:47 INFO - 'HOME': '/Users/cltbld', 07:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:47 INFO - 'LOGNAME': 'cltbld', 07:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:47 INFO - 'MOZ_NO_REMOTE': '1', 07:32:47 INFO - 'NO_EM_RESTART': '1', 07:32:47 INFO - 'PAGER': '/bin/cat', 07:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:47 INFO - 'PWD': '/builds/slave/test', 07:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:47 INFO - 'SHELL': '/bin/bash', 07:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:47 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:47 INFO - 'USER': 'cltbld', 07:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:47 INFO - 'XPC_FLAGS': '0x0', 07:32:47 INFO - 'XPC_SERVICE_NAME': '0', 07:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 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)) 07:32:48 INFO - Cleaning up... 07:32:48 INFO - Return code: 0 07:32:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c45490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bbb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc992d6ed90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, '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.YGxgDupm6C/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.8AHalqbFYD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 07:32:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:48 INFO - 'HOME': '/Users/cltbld', 07:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:48 INFO - 'LOGNAME': 'cltbld', 07:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:48 INFO - 'MOZ_NO_REMOTE': '1', 07:32:48 INFO - 'NO_EM_RESTART': '1', 07:32:48 INFO - 'PAGER': '/bin/cat', 07:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:48 INFO - 'PWD': '/builds/slave/test', 07:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:48 INFO - 'SHELL': '/bin/bash', 07:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:32:48 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:48 INFO - 'USER': 'cltbld', 07:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:48 INFO - 'XPC_FLAGS': '0x0', 07:32:48 INFO - 'XPC_SERVICE_NAME': '0', 07:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:32:48 INFO - Cleaning up... 07:32:48 INFO - Return code: 0 07:32:48 INFO - Running post-action listener: _resource_record_post_action 07:32:48 INFO - Running post-action listener: _start_resource_monitoring 07:32:48 INFO - Starting resource monitoring. 07:32:48 INFO - [mozharness: 2016-12-16 15:32:48.801699Z] Finished create-virtualenv step (success) 07:32:48 INFO - [mozharness: 2016-12-16 15:32:48.802676Z] Running install step. 07:32:48 INFO - Running pre-action listener: _resource_record_pre_action 07:32:48 INFO - Running main action method: install 07:32:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:49 INFO - Reading from file tmpfile_stdout 07:32:49 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:49 INFO - Reading from file tmpfile_stdout 07:32:49 INFO - Output received: 07:32:49 INFO - Usage: mozinstall [options] installer 07:32:49 INFO - Options: 07:32:49 INFO - -h, --help show this help message and exit 07:32:49 INFO - -d DEST, --destination=DEST 07:32:49 INFO - Directory to install application into. [default: 07:32:49 INFO - "/builds/slave/test"] 07:32:49 INFO - --app=APP Application being installed. [default: firefox] 07:32:49 INFO - mkdir: /builds/slave/test/build/application 07:32:49 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'] 07:32:49 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 07:33:19 INFO - Reading from file tmpfile_stdout 07:33:19 INFO - Output received: 07:33:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:33:19 INFO - Running post-action listener: _resource_record_post_action 07:33:19 INFO - [mozharness: 2016-12-16 15:33:19.673782Z] Finished install step (success) 07:33:19 INFO - [mozharness: 2016-12-16 15:33:19.673916Z] Running run-tests step. 07:33:19 INFO - Running pre-action listener: _resource_record_pre_action 07:33:19 INFO - Running main action method: run_tests 07:33:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:19 INFO - 2016-12-16 07:33:19.688 screenresolution[1621:7265] starting screenresolution argv=screenresolution get 07:33:19 INFO - 2016-12-16 07:33:19.691 screenresolution[1621:7265] Display 0: 1600x1200x32@60 07:33:19 INFO - 2016-12-16 07:33:19.696 screenresolution[1622:7268] starting screenresolution argv=screenresolution list 07:33:19 INFO - Available Modes on Display 0 07:33:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:19 INFO - Intel Iris: 07:33:19 INFO - Chipset Model: Intel Iris 07:33:19 INFO - Type: GPU 07:33:19 INFO - Bus: Built-In 07:33:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:19 INFO - Vendor: Intel (0x8086) 07:33:19 INFO - Device ID: 0x0a2e 07:33:19 INFO - Revision ID: 0x0009 07:33:19 INFO - Displays: 07:33:19 INFO - Display: 07:33:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:19 INFO - Display Serial Number: 1600x1200 60 07:33:19 INFO - Main Display: Yes 07:33:19 INFO - Mirror: Off 07:33:19 INFO - Online: Yes 07:33:19 INFO - Rotation: Supported 07:33:19 INFO - Return code: 0 07:33:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:33:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:33:19 INFO - Python 2.7.3 07:33:19 INFO - Return code: 0 07:33:19 INFO - grabbing minidump binary from tooltool 07:33:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:33:19 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a59d30>, '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 0x1088bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a51b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:33:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:33:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:33:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:33:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:33:20 INFO - Return code: 0 07:33:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:33:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:33:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:33:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:33:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:33:20 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/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 07:33:20 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/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0177 --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 07:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YGxgDupm6C/Render', 07:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:20 INFO - 'HOME': '/Users/cltbld', 07:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:20 INFO - 'LOGNAME': 'cltbld', 07:33:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:20 INFO - 'MOZ_NO_REMOTE': '1', 07:33:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:20 INFO - 'NO_EM_RESTART': '1', 07:33:20 INFO - 'PAGER': '/bin/cat', 07:33:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:20 INFO - 'PWD': '/builds/slave/test', 07:33:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:20 INFO - 'SHELL': '/bin/bash', 07:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AHalqbFYD/Listeners', 07:33:20 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 07:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:20 INFO - 'USER': 'cltbld', 07:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:20 INFO - 'XPC_FLAGS': '0x0', 07:33:20 INFO - 'XPC_SERVICE_NAME': '0', 07:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:20 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/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 07:33:20 INFO - mozversion application_buildid: 20161216062349 07:33:20 INFO - mozversion application_changeset: ae98ffdc57822f068c011e305ea8a9662dd11e01 07:33:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:33:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:33:20 INFO - mozversion application_name: Firefox 07:33:20 INFO - mozversion application_remotingname: firefox-dev 07:33:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:20 INFO - mozversion application_vendor: Mozilla 07:33:20 INFO - mozversion application_version: 52.0a2 07:33:20 INFO - mozversion platform_buildid: 20161216062349 07:33:20 INFO - mozversion platform_changeset: ae98ffdc57822f068c011e305ea8a9662dd11e01 07:33:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:33:20 INFO - mozversion platform_version: 52.0a2 07:33:20 INFO - using testdate: 1481902400 07:33:20 INFO - actual date: 1481902400 07:33:20 INFO - starting webserver on 'localhost:49245' 07:33:20 INFO - SUITE-START | Running 2 tests 07:33:20 INFO - TEST-START | dromaeo_css 07:33:20 INFO - operating with platform_type : mac_ 07:33:20 INFO - Initialising browser for dromaeo_css test... 07:33:20 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpN0GYG9/profile http://localhost:49245/getInfo.html) 07:33:23 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 07:33:23 INFO - PROCESS | 1632 | colorDepth:24 07:33:23 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 07:33:23 INFO - PROCESS | 1632 | __metrics 07:33:23 INFO - PROCESS | 1632 | 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" 07:33:23 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:33:24 INFO - TEST-INFO | 1632: exit 0 07:33:24 INFO - Browser initialized. 07:33:24 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:24 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpN0GYG9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:36 INFO - PROCESS | 1634 | RSS: Main: 222654464 07:33:36 INFO - PROCESS | 1634 | 07:34:27 INFO - PROCESS | 1634 | 1481902467298 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] 07:36:40 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 07:36:43 INFO - PROCESS | 1634 | RSS: Main: 478326784 07:36:43 INFO - PROCESS | 1634 | 07:39:46 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 07:39:47 INFO - PROCESS | 1634 | RSS: Main: 348692480 07:39:47 INFO - PROCESS | 1634 | 07:42:49 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 07:42:50 INFO - PROCESS | 1634 | RSS: Main: 326103040 07:42:50 INFO - PROCESS | 1634 | 07:45:52 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 07:45:53 INFO - PROCESS | 1634 | RSS: Main: 322265088 07:45:53 INFO - PROCESS | 1634 | 07:48:56 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 07:48:57 INFO - PROCESS | 1634 | RSS: Main: 311156736 07:48:57 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 07:52:00 INFO - PROCESS | 1634 | RSS: Main: 463450112 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | __start_tp_report 07:52:00 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 07:52:00 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 07:52:00 INFO - PROCESS | 1634 | |i|pagename|runs| 07:52:00 INFO - PROCESS | 1634 | |0;dojo.html;2850.4156647887185;3007.1429642551875;3195.8721651133956;3213.971529110684;3248.058063161683;5472;5511.72441377931;5543.029969755296;5573.359063707675;5588.273524441822;5553.666779993203;5555.388907220203;5613.550915926727;5614.045612245918;5640.858978525539;5081.593472522208;5101.234814777795;5125.769340379692;5198.818041368553;5233.3981592116825;5067.543921047105;5073.086844368012;5165.793368265265;5198.09033419152;5236.345456817898;5127.051495473331;5197.896042079156;5200.869978250536;5206.5834733221445;5209.21861720743;5193.169092945149;5202;5203.505666961632;5204.557612602934;5229;5047.798088076473;5138.665986710852;5145.614078944057;5148.227765835118;5163.40620828602;5656.462636049569;5677.914831277534;5683.062294721366;5687.061634830248;5692.089265717506;5528.419515950807;5684.550439637907;6067.1202675611785;6090.756369745205;6095.268567771852;6093.086037094471;6118.223266370672;6121.117656725397;6125.540694081788;6131.449496612492;5659.51894089003;5667.886642267132;5682.431756824326;5702.287214098238;5714.914276285859;6256.7810126645345;6274.1843560336865;6278.717457714414;6282.246130464282;6288.056791481315;5868.972929737279;5904.321003084614;5904.996150654399;5982.46157845796;6017.398260174035;6111.174991376108;6114.471014204479;6154.907676384858;6166.938330616636;6166.969165154145;6124.693765311717;6147.44672979434;6151.784687535915;6153.876922461525;6180.3201647818705;6002.789902353484;6052.788152414644;6069.969650151809;6086.634801911811;6159.96920015406;4774.092922344744;4830.541098595698;4893.412790465096;4898.24077268024;4904.901901962012;4337.891552711208;4351.97824010878;4356.346548017759;4361.258586040381;4391.736495810262;5521.365043020024;5714.485696287271;5735.741891614887;5736.827895163152;5759.65442073477;6124.930239974474;6133.325334213233;6162.198914141133;6168.568200225941;6186.690665466709;5695.373508914016;5717.685527296038;5720.68536230503;5729.88540229193;5745.87955348703;5326.840194794162;5350.5184533392185;5361.303030605996;5378.9462105379225;5380.811671591476;6134.191681014119;6186.876262474635;6216.689165541795;6292.307846137013;6294.055891616111;6074.483668888194;6119.68535298207;6165.938340616536;6167.6916154193;6225.408586184305;6270.561060725706;6280.717367718555;6289.811305660837;6294.8111556653375;6296.087067375113;5798.5651076168615;6108.786192483241;6112.908306375319;6127.540434467343;6133.907991380043;5690.260266165372;5931.555133365101;5939.2575928009;5995.490383317467;6015.187949626745;4963.659811850852;5491.560675145899;5778.595498315078;5804.158397032322;5820.272465941757;5711.428857114422;5789.2763404574425;5800.1589769484035;5801.216835727291;5802.4197580241635;5351.357837059578;5460.672359658433;5636.4927156556105;5699.686517241592;5705.286839145107;4864.513548645249;5812.21535092757;5816.069428891357;5816.098504731774;5823.883522329615;4869.31829543857;5773.134029895549;5803.7968671096305;5812.796552120655;5819.476247137778 07:52:00 INFO - PROCESS | 1634 | |1;ext.html;443.552012467408;458.9885252868677;482.1201307613604;506.2077848167618;572.6621293436872;29368.853155734218;38199.42700859486;38843.61156388439;39387.60612393875;40551.39172912409;40429.393559096636;40743;40866;40868.79565602168;40962;8358.414910956235;9132.726018219431;9329.86005209919;9344.159025687686;9345.205657519118;8427.83144337113;10934.890651093467;11234.3259404436;11247.83128253077;11359.886401135966;11230.101591872672;11280.943595282011;11288.943555282212;11293.604723834667;11311.208215424924;8394.874076888851;8593.828123437495;11125.388103654295;11211.439428028607;11212.383318917457;9588.95205523975;10789.298695584841;11126.38804865732;11166.665000050012;11262.605808796652;38185.61814381876;38429.23141537182;38432.80783596092;38467.84596462083;38502.422463662784;3195.2491164576304;3213.939399997995;3306.3717893600137;3522.154682876117;3541.8760343388117;27092;28692.856535717397;28868.855655721585;29105.272368190752;29562.556561651592;2886.451574200916;3100.9689903100907;3217.324361883984;3237.43344914639;3275.983620081908;461383.6930815358;464656;466998;469596;470349;32168.83915580407;32820.179495512326;33284.1678958028;55581;56106.43893561094;27647.861760691067;32033.67966320307;33325.83337083299;33436.33127337488;33578.328433431685;13535.984801139954;13636.65908352299;13639.181649100887;13646.84001859853;13649.385777640031;10596.682099537025;10790.51442685081;10806.945965270123;11544;11627.941860290644;7480.139783924809;7598.506097103671;7694.999650045458;7704.8844267336035;7746.147923728386;3169.462810536888;3221.855016524262;3228.7094161525574;3241.108695108864;3246.610406751158;867.8481265778463;891.8751374807532;932.8274269260208;946.4936259101348;956.636478138303;29139;29427.705722942927;29632.555511667342;29802.403951921275;29973.70026299753;998.1765043838863;1046.3094357723867;1059.655611926127;1076.3488089705615;1082.6938620737778;14835.703285934436;15212.847871521364;15327.770083448757;15630.609234769221;15642.68714625701;189475;189876;191163;191370;635077;54251.72874135604;54629.1805622916;54734.72632636891;54765.452345476835;54858;1191.7973944429468;1236.0852968802842;1301.5054279373778;1338.7188690374935;1348.5010546098006;28351.43297134087;29929.551056734603;29932.401351973273;30080.54879176858;30504.694953051072;44866;45225.773871130434;45256.54743452524;45463.54536454725;45465.31802022906;406303;406412;406738.9663051666;408959;602286;39519.2096158081;39913.802585922465;40069.79965100156;40199.79900100481;40286.194276113725;43688;43795.12409751851;43890.341644874716;44510;44520.554794451644;36273.81863090667;36406.27187456183;37186.25627487381;38212.617873820906;38421.6157838418;36403.386454183266;36771;36781.08047298839;37494.250114998096;37537;35908.28183436264;36547.451788222665;36579.817100914326;36684.81657591695;36738.26523469569;31604.683953160173;33050.33899322048;33146.17134571655;33418.66581334253;33548;24332.756672433756;24414.145504907243;24620.876895616122;25098.498030038932;25196.874015629804 07:52:00 INFO - PROCESS | 1634 | |2;jquery.html;352.97176225901933;367.5827935293442;370.87204914304573;386;406.64418633695516;14409.63975900603;14822;15109.471168509104;19190.040497975115;19593.902030489826;19455.221791128337;19863.30478433255;19884.00579971003;20005.699914501292;20345.28791492298;5580.972095139528;5878.470937615613;5879.294484661847;5884.558658100638;5902.61632993854;5462.153366228241;5706.828795136153;5752.482276595106;5773.971130144343;5779;5493.917591236134;5727.742251598667;5740.827775166731;5741.827745167651;5753.510951569122;5587.580931430159;5715.828525144231;5720.370759216483;5721.227634269362;5730.19777231188;4772.141014617368;4974.3533340665845;4974.825881094181;4992.301077849104;5070.847874563769;24701.876490617433;24885.377865553324;24970.37574060663;25066.122685705912;25295.62056569153;2249.696291000727;2336.719593648756;2439.9878000609883;2636.9208923732317;2708.3635345693747;14172.929135354258;14519.927400363036;14894;15473.916825822196;15502;1726.378503738653;1780.6883795335893;1802.78366596008;1825.6074943886995;1865.8880467172014;117586;118726.40636796848;119365;120134;120228;11513.942430287963;11573.537058517564;11601.781812909692;11690.590829321103;11865;14514.637134071732;14658.706825863635;14660.780088298685;14693;14721.116732996055;6823.454123670094;8199.672013119409;8351.415400921998;8438.198371154731;8451.366147538836;7389.704411823574;7506.699732010658;8078.919210807934;8105.75682729519;8220.301274391744;3644.9453258201147;4509.210888094571;4542.3413605027745;4580.404547408815;4588.426446694163;1614.6840325135213;1830.9359172429167;1893.2616279650947;1918.4915997260737;1935.419374187738;702.707019084876;808.0651114003261;818.6111596991382;856.1652388920752;957.3824882950597;20009.09959051846;21103.366898993056;21413;21528.892355538123;21730.78269217319;750.7860259825924;858.9484630922165;868.2489646455897;917.4174399256386;931.0875342164717;10764.730881727863;11346.602868899548;11365.147613928988;11375.544978200945;11467;577832;579031;581833;589623;590946;117052;120790;123220.38389807993;123266;123851;912.1562554636935;923.5566927874657;947.5593848860314;948.4214629076258;950.5912457643142;18410.447686569423;18542.907285464025;18611.06944652789;18622.441326759676;18698.43904682862;31900.521492177173;42382.78808605937;50220;51116.48883511117;52980.41058768243;71568.64215678888;74309.25690743023;74924.62537687494;75102.62448687722;76504;7162.64186790648;7275.163356213891;7835.817170920652;8027.759167224993;8111.878321825059;10544.420056896945;12237.04207326859;12329.69175770577;12377.814332785196;12493.562725304935;10412.271141020055;10588.682339529827;10698.786024279627;10702.518386672775;11051.83422248683;8245.299149572353;9018.098190181192;10317.793644127225;10566.577336906437;10763.838542422027;10625.574977000833;11118.49966751482;11313.981741643292;11331.603393881174;11363.261388009752;6562.376574225441;7823.882641760264;7902.4863383879865;8024.117347091698;8046.396520260887;9879.357841740264;10505.842412363974;10838.945805270923;10893.727656808644;10935.726606834893 07:52:00 INFO - PROCESS | 1634 | |3;mootools.html;1366.576361327988;1428.1431141315209;1562.6171587960944;1770.9114544272784;1790.0781097734662;7987.5606841623685;8397.958010209955;8399.202075802788;8414.705485308015;8431.957840210805;7407.518511296776;7674.769756907288;7693.7307194248215;8000.839983200332;8232.629531671075;2331.638596017616;2363.054778088761;2364.078009576258;2367.4081479630095;2370.075670488502;2348.3542025942834;2374.0622454130607;2394.5570069537193;2401.5677178107935;2403.9399015024587;2346.8826558672085;2351.1065794997876;2370.5851475991717;2373.359193017884;2374.3351861478727;2248.6739422783685;2253.740819805727;2272.704548408696;2275.761045090275;2307.157887371114;2350.494643651606;2373.382920440698;2373.6914201153913;2374.1571742031674;2374.5607062693457;8294.668213271461;8304.70933517324;8363.372747043995;8407.369447291478;8418.158184181593;4338.58783415575;4781.737004464753;5085.186370180791;5091.949080509185;5093.770780314895;3277.4919887417486;3387.4072037393357;3566.6433356664384;3570.357335679603;3581.997040828572;1045.4563626913962;1072.7264547540328;1093.578972095749;1106.651404807483;1108.8724796648407;10837.83743243852;10895.727606809814;10939.726506837313;10966.287191332538;10982.890171098266;4466.821327146878;4550.974816206823;4747.738874361874;4753.83361582343;4754.405699287589;9389.76525586852;9396.389234699722;9396.812063758687;9402.623895044258;9402.858957115648;5150.871228219249;5166.509181627739;5167.560757335668;5192.5586325162785;5213.556847667915;4000.7199496035582;4250;4351.564843515623;4760.547747964007;4763.857084287477;3126.437241296543;3144.8584813683437;3498.1429549759964;3724.4413337999517;3896.629820167136;1266.7276535544718;1266.7585765949236;1271.0235583667302;1283.4288741509938;1286.5689993852163;731.6597782031381;847.3221422861686;866.7356456280769;869.4435561240684;871.7166920750782;5507.284053073075;5569.972150139223;5570.164475328652;5600.6919619420505;5643.604947653707;430.92674245377736;431.6654592690627;438.3184148648818;878.6573236437795;957.818014577228;3668.4680721295263;3690.538682664667;3710.128119891823;3780.281746468162;3790.29879472293;9789.804203915883;9930.900690993141;10144;10276.794464110675;10334.483275836179;10297.588096476207;10302;10302.587896484207;10304.587816487407;10313.639022634172;7563.924360756432;7864.370850331959;7976.401769867167;7990.3208227300165;8013.839723205503;5425.348958125051;5443.346798384219;5501.972490137524;5626.887462250814;5650.569421848294;9374.296927730311;9449.669261575811;9476.57355418994;9486.95643479203;9599.904000959901;8008.719694810654;9543.33196676226;9576.616935322787;9664.9033509664;9674.56464459087;5484.369297530676;6410.0705397718;6417.294097649162;6609.074729537772;6787.253402125861;7705.422093343133;7896.131425543301;8026.3980201483955;8067.959660201662;8080.83838323242;7111.964440177766;7238.6380680966795;7244.818879528054;7296.343329100407;7354.558726476428;6974.372306492237;7030.894536582057;7056.435485161277;7155.391791697764;7160.176579693193;6031.944409728368;6466.321036291121;6602.0096985452565;6617.239017513048;6748.358905903931;4758.547937945889;4778.713277203379;4784.114938736345;4846.5638092571835;4860.927086093782;4704.553067458586;4724.881877953078;4862.3922009748785;4885.1450996076255;4895.216765317532 07:52:00 INFO - PROCESS | 1634 | |4;prototype.html;303.6341208843344;308.5033096714291;311.67897066021993;318.83898631191244;320.7754571799738;4471.150481408538;4988.201887697969;5290.1006828839145;5291.973540132299;5345.305110335661;4855.660103792736;4950.851474455763;5113.821016264432;5128.615353848458;5187.066328060957;3735.477033215352;4020.954551816527;4038;4107.301758701028;4133.256013917494;3469.3061387722432;3758.32350176968;4079.3473044312914;4102.610252026053;4112.794360281974;3708.814559272039;3975.284448799215;4013.5585085640555;4025.9597404025876;4039.757614543122;3969.6030396960364;4008.478897743304;4035.616616421435;4061.4110953911663;4071.2671719090554;2952.35048289376;3187.569678093451;3434.5191673165778;3450.9827450862836;3455.7580969332157;5048;5149.2791009258735;5159.432462429129;5179.715115668637;5221.608379371524;4471.552844715502;4534.8186072557055;4535.047639995571;4609.700369476007;4618.607418369443;5565.5825813063775;5582.274304340451;5607.158926161109;5623.453550273666;5640.7743690252755;3348.2299071213515;3587.8206089695673;3772.6043962084273;3782.7730336179916;3787.299349620329;4754.027078762344;5217.060929032773;5635.746391412315;5752.913706294409;5784.421557844266;3128.3586864692684;3417.2823707021307;3694.2796154750104;3826;3863.3239183142837;3923.3330333842746;3941.2117576484816;3951.7431366961055;3956.940645890314;3965.8017099144927;4048.352263637804;4317.740935543878;4475.70907890986;4535.886602834956;4631.490536041033;4496.527864574173;5419.864503387447;5557.249771280906;5614.045612245928;5622.100463925753;1065.5418170186895;1197.2696655040418;1216.3249396584804;1220.365409986802;1224.063591352616;729.6643543969727;743.3124359967009;780.9223271884877;819.7540737778762;842.4793248186959;4222.852200173041;4279.651909648513;4406.669499787528;4851.296561998491;4884.5603895649565;5022.573081288131;5318.361796584435;5378.381486129145;5383.273258110105;5483.026110600181;4424.933625995613;4644.2801365788355;4871.707697538159;5279.815206467755;5325.4674532547215;2403.5433267679437;2547.5414425403605;2742.698303186648;2901.419716056797;2929.091981485746;5353.2237825515085;5492.286002819608;5642.965279824944;5650.858728531737;5665.592123347561;4772.665913386099;5180.352455943007;5518.392976772552;5542;5542.362628297717;4872.366592342973;5017.971315880236;5187.896242075138;5592.7762889484775;5617.410171931889;4346.413234213467;4921.335619691439;5087.720175390389;5112.667676601009;5408.810691625774;4641.466231383434;5148.69107853515;5306.569014872624;5316.521513063843;5339.839804805862;5389.7035663038905;5444.401115877171;5461.088618983324;5588.51311166527;5660.433956604306;3365.5961284646;3564.9287014259053;3584.247308065231;3600.3339382214376;3846.519185101862;5729.799457019151;5760.135979603093;5798.652080875161;5839.678817665069;5848.035074212878;5582;5624.100143976944;5628.493435590816;5658.5473162147755;5695.772169113355;4857.616423036893;4975.850724478271;5193.350831146106;5302.814401495929;5641.323041235078;5008;5185.392528316234;5392.676439413648;5449.929026969885;5461.781528738965;5450.174191645843;5528.364238112668;5594.636348637326;5595.860103497445;5667.744951477276;5472.425395333433;5543.833684989457;5557.888842223052;5560.138178582326;5616.135770756861 07:52:00 INFO - PROCESS | 1634 | |5;yui.html;661.851083506211;667.4426853577265;692.6259819697364;737.697544006957;756.4514287699723;13564;13592.660183495405;14054;14304.998650094498;14327.426902923871;12725.745485090292;12816.93591532041;12835.550755723552;12849.935750321236;13300.534481293156;794.5272562825124;826.5330088500008;830.2278880641002;831.5551180118638;832.6086739232551;655.5869802024718;672.6670298202397;680.877442060429;801.9318357939553;814.2590242878981;866.3588944181292;874.7026011156206;876.7325965580495;878.4290211362602;879.5118709116438;867.9392442529025;870.4124715816862;871.5729292646589;871.6992637540038;874.553977471491;514.1080225808257;525.1649876696033;623.2054130982988;655.9540832141752;658.032691942848;13379.130356526892;13834.515791947335;13852.445902163901;13861.23763193024;13868.167909925436;12319.753604927851;12520.499180032788;12653.74692506154;12739.554115606003;13005.609831705064;10999.34003959757;11133.610323638633;11233.269837460539;11291.887081129165;11661.825072623833;3583.068402215417;3610.981945090284;3637.5453068366455;3639.5632524097277;3653.4154535274492;87200;87202.56398717966;87481.12518874857;87546.68679969806;88314.55842720809;9978;10025.448600327052;10165.390076595428;10207.591696332063;10258;18682.439526814218;18742.437726868215;18788.342408015655;18842.325746479306;18884.244630214638;6231.2522497300615;7318.414526837841;7443.851122977619;7648.273414025659;7664.118626357928;4079.1841631673783;4207.558206388285;4282.336237883133;4305.978470107692;4402.889927751768;3149.984250078735;3230.6607806180014;3250.9837450812593;3254.625718042408;3361.9495707564406;657.7336178847575;663.5885750834514;667.5699464955115;669.6773871603544;674.0293976673672;555.9277293951841;631.3434028610194;639.3766078073934;640.8910485217524;651.3388910256094;8939.240164585954;8994.550272486349;9002.774930626787;9093.035640519089;9100.772480688036;604.1873679900526;623.5697368815594;629.8803227386782;634.7524465458475;635.6694518850172;3921.9803900980314;3950.4469374287623;3994.021464741157;4010.6590939770444;4065.5121385433936;67641;68049.97925031128;91464.3414263422;106859.93140068655;114646.42676786729;59042;80039.799403009;80384.59807700924;81947;98137.50931245298;56922.146167807514;69669.47148435336;70800;77191;80490.54849240862;8340.541270230196;9318;9356.859647105435;9537.570809313736;9551.06689043415;45589.54410455853;48880.75559622179;51764.70588235324;52015.73992130166;53310.46689533055;50729.746351268004;51471.48528514667;53637.19544206762;65701;73501;10038.598456061676;11562.595309164139;11585.304881707125;11844.407779611158;12203.511859525519;13189.010824188415;13230.603081907559;14354.856451435351;14702.779458308349;14813.25933703289;12792.103343890272;12957.546485873327;13068.738625227632;13298.601041968757;13687.178769273876;10900.563977440814;11197.440127993405;11640.417979101181;11724.941375293069;11854.585089521825;12077.39613019363;12500.499980001063;12674.429650665927;12991.895688866553;13314.93342533281;10381.688549343531;10485.318454300446;11137.276077054965;11506.712332191762;11640.068794496588;8625.654973800978;9703.902960970583;10549.208809339178;10606.151507879506;10606.893931060591 07:52:00 INFO - PROCESS | 1634 | __end_tp_report 07:52:00 INFO - PROCESS | 1634 | __start_cc_report 07:52:00 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,3483 07:52:00 INFO - PROCESS | 1634 | __end_cc_report 07:52:00 INFO - PROCESS | 1634 | __startTimestamp1481903520792__endTimestamp 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | ------- Summary: start ------- 07:52:00 INFO - PROCESS | 1634 | Number of tests: 6 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5604.88 Median:5720.69 stddev:641.52 (11.2%) stddev-sans-first:606.00 07:52:00 INFO - PROCESS | 1634 | Values: 2850.4 3007.1 3195.9 3214.0 3248.1 5472.0 5511.7 5543.0 5573.4 5588.3 5553.7 5555.4 5613.6 5614.0 5640.9 5081.6 5101.2 5125.8 5198.8 5233.4 5067.5 5073.1 5165.8 5198.1 5236.3 5127.1 5197.9 5200.9 5206.6 5209.2 5193.2 5202.0 5203.5 5204.6 5229.0 5047.8 5138.7 5145.6 5148.2 5163.4 5656.5 5677.9 5683.1 5687.1 5692.1 5528.4 5684.6 6067.1 6090.8 6095.3 6093.1 6118.2 6121.1 6125.5 6131.4 5659.5 5667.9 5682.4 5702.3 5714.9 6256.8 6274.2 6278.7 6282.2 6288.1 5869.0 5904.3 5905.0 5982.5 6017.4 6111.2 6114.5 6154.9 6166.9 6167.0 6124.7 6147.4 6151.8 6153.9 6180.3 6002.8 6052.8 6070.0 6086.6 6160.0 4774.1 4830.5 4893.4 4898.2 4904.9 4337.9 4352.0 4356.3 4361.3 4391.7 5521.4 5714.5 5735.7 5736.8 5759.7 6124.9 6133.3 6162.2 6168.6 6186.7 5695.4 5717.7 5720.7 5729.9 5745.9 5326.8 5350.5 5361.3 5378.9 5380.8 6134.2 6186.9 6216.7 6292.3 6294.1 6074.5 6119.7 6165.9 6167.7 6225.4 6270.6 6280.7 6289.8 6294.8 6296.1 5798.6 6108.8 6112.9 6127.5 6133.9 5690.3 5931.6 5939.3 5995.5 6015.2 4963.7 5491.6 5778.6 5804.2 5820.3 5711.4 5789.3 5800.2 5801.2 5802.4 5351.4 5460.7 5636.5 5699.7 5705.3 4864.5 5812.2 5816.1 5816.1 5823.9 4869.3 5773.1 5803.8 5812.8 5819.5 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:53780.72 Median:18550.24 stddev:112615.28 (607.1%) stddev-sans-first:112859.99 07:52:00 INFO - PROCESS | 1634 | Values: 443.6 459.0 482.1 506.2 572.7 29368.9 38199.4 38843.6 39387.6 40551.4 40429.4 40743.0 40866.0 40868.8 40962.0 8358.4 9132.7 9329.9 9344.2 9345.2 8427.8 10934.9 11234.3 11247.8 11359.9 11230.1 11280.9 11288.9 11293.6 11311.2 8394.9 8593.8 11125.4 11211.4 11212.4 9589.0 10789.3 11126.4 11166.7 11262.6 38185.6 38429.2 38432.8 38467.8 38502.4 3195.2 3213.9 3306.4 3522.2 3541.9 27092.0 28692.9 28868.9 29105.3 29562.6 2886.5 3101.0 3217.3 3237.4 3276.0 461383.7 464656.0 466998.0 469596.0 470349.0 32168.8 32820.2 33284.2 55581.0 56106.4 27647.9 32033.7 33325.8 33436.3 33578.3 13536.0 13636.7 13639.2 13646.8 13649.4 10596.7 10790.5 10806.9 11544.0 11627.9 7480.1 7598.5 7695.0 7704.9 7746.1 3169.5 3221.9 3228.7 3241.1 3246.6 867.8 891.9 932.8 946.5 956.6 29139.0 29427.7 29632.6 29802.4 29973.7 998.2 1046.3 1059.7 1076.3 1082.7 14835.7 15212.8 15327.8 15630.6 15642.7 189475.0 189876.0 191163.0 191370.0 635077.0 54251.7 54629.2 54734.7 54765.5 54858.0 1191.8 1236.1 1301.5 1338.7 1348.5 28351.4 29929.6 29932.4 30080.5 30504.7 44866.0 45225.8 45256.5 45463.5 45465.3 406303.0 406412.0 406739.0 408959.0 602286.0 39519.2 39913.8 40069.8 40199.8 40286.2 43688.0 43795.1 43890.3 44510.0 44520.6 36273.8 36406.3 37186.3 38212.6 38421.6 36403.4 36771.0 36781.1 37494.3 37537.0 35908.3 36547.5 36579.8 36684.8 36738.3 31604.7 33050.3 33146.2 33418.7 33548.0 24332.8 24414.1 24620.9 25098.5 25196.9 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:34041.19 Median:2672.64 stddev:97415.64 (3644.9%) stddev-sans-first:97656.08 07:52:00 INFO - PROCESS | 1634 | Values: 353.0 367.6 370.9 386.0 406.6 14409.6 14822.0 15109.5 19190.0 19593.9 19455.2 19863.3 19884.0 20005.7 20345.3 5581.0 5878.5 5879.3 5884.6 5902.6 5462.2 5706.8 5752.5 5774.0 5779.0 5493.9 5727.7 5740.8 5741.8 5753.5 5587.6 5715.8 5720.4 5721.2 5730.2 4772.1 4974.4 4974.8 4992.3 5070.8 24701.9 24885.4 24970.4 25066.1 25295.6 2249.7 2336.7 2440.0 2636.9 2708.4 14172.9 14519.9 14894.0 15473.9 15502.0 1726.4 1780.7 1802.8 1825.6 1865.9 117586.0 118726.4 119365.0 120134.0 120228.0 11513.9 11573.5 11601.8 11690.6 11865.0 14514.6 14658.7 14660.8 14693.0 14721.1 6823.5 8199.7 8351.4 8438.2 8451.4 7389.7 7506.7 8078.9 8105.8 8220.3 3644.9 4509.2 4542.3 4580.4 4588.4 1614.7 1830.9 1893.3 1918.5 1935.4 702.7 808.1 818.6 856.2 957.4 20009.1 21103.4 21413.0 21528.9 21730.8 750.8 858.9 868.2 917.4 931.1 10764.7 11346.6 11365.1 11375.5 11467.0 577832.0 579031.0 581833.0 589623.0 590946.0 117052.0 120790.0 123220.4 123266.0 123851.0 912.2 923.6 947.6 948.4 950.6 18410.4 18542.9 18611.1 18622.4 18698.4 31900.5 42382.8 50220.0 51116.5 52980.4 71568.6 74309.3 74924.6 75102.6 76504.0 7162.6 7275.2 7835.8 8027.8 8111.9 10544.4 12237.0 12329.7 12377.8 12493.6 10412.3 10588.7 10698.8 10702.5 11051.8 8245.3 9018.1 10317.8 10566.6 10763.8 10625.6 11118.5 11314.0 11331.6 11363.3 6562.4 7823.9 7902.5 8024.1 8046.4 9879.4 10505.8 10838.9 10893.7 10935.7 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5407.76 Median:4873.77 stddev:3003.06 (61.6%) stddev-sans-first:2996.13 07:52:00 INFO - PROCESS | 1634 | Values: 1366.6 1428.1 1562.6 1770.9 1790.1 7987.6 8398.0 8399.2 8414.7 8432.0 7407.5 7674.8 7693.7 8000.8 8232.6 2331.6 2363.1 2364.1 2367.4 2370.1 2348.4 2374.1 2394.6 2401.6 2403.9 2346.9 2351.1 2370.6 2373.4 2374.3 2248.7 2253.7 2272.7 2275.8 2307.2 2350.5 2373.4 2373.7 2374.2 2374.6 8294.7 8304.7 8363.4 8407.4 8418.2 4338.6 4781.7 5085.2 5091.9 5093.8 3277.5 3387.4 3566.6 3570.4 3582.0 1045.5 1072.7 1093.6 1106.7 1108.9 10837.8 10895.7 10939.7 10966.3 10982.9 4466.8 4551.0 4747.7 4753.8 4754.4 9389.8 9396.4 9396.8 9402.6 9402.9 5150.9 5166.5 5167.6 5192.6 5213.6 4000.7 4250.0 4351.6 4760.5 4763.9 3126.4 3144.9 3498.1 3724.4 3896.6 1266.7 1266.8 1271.0 1283.4 1286.6 731.7 847.3 866.7 869.4 871.7 5507.3 5570.0 5570.2 5600.7 5643.6 430.9 431.7 438.3 878.7 957.8 3668.5 3690.5 3710.1 3780.3 3790.3 9789.8 9930.9 10144.0 10276.8 10334.5 10297.6 10302.0 10302.6 10304.6 10313.6 7563.9 7864.4 7976.4 7990.3 8013.8 5425.3 5443.3 5502.0 5626.9 5650.6 9374.3 9449.7 9476.6 9487.0 9599.9 8008.7 9543.3 9576.6 9664.9 9674.6 5484.4 6410.1 6417.3 6609.1 6787.3 7705.4 7896.1 8026.4 8068.0 8080.8 7112.0 7238.6 7244.8 7296.3 7354.6 6974.4 7030.9 7056.4 7155.4 7160.2 6031.9 6466.3 6602.0 6617.2 6748.4 4758.5 4778.7 4784.1 4846.6 4860.9 4704.6 4724.9 4862.4 4885.1 4895.2 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4420.21 Median:4998.10 stddev:1370.14 (27.4%) stddev-sans-first:1338.69 07:52:00 INFO - PROCESS | 1634 | Values: 303.6 308.5 311.7 318.8 320.8 4471.2 4988.2 5290.1 5292.0 5345.3 4855.7 4950.9 5113.8 5128.6 5187.1 3735.5 4021.0 4038.0 4107.3 4133.3 3469.3 3758.3 4079.3 4102.6 4112.8 3708.8 3975.3 4013.6 4026.0 4039.8 3969.6 4008.5 4035.6 4061.4 4071.3 2952.4 3187.6 3434.5 3451.0 3455.8 5048.0 5149.3 5159.4 5179.7 5221.6 4471.6 4534.8 4535.0 4609.7 4618.6 5565.6 5582.3 5607.2 5623.5 5640.8 3348.2 3587.8 3772.6 3782.8 3787.3 4754.0 5217.1 5635.7 5752.9 5784.4 3128.4 3417.3 3694.3 3826.0 3863.3 3923.3 3941.2 3951.7 3956.9 3965.8 4048.4 4317.7 4475.7 4535.9 4631.5 4496.5 5419.9 5557.2 5614.0 5622.1 1065.5 1197.3 1216.3 1220.4 1224.1 729.7 743.3 780.9 819.8 842.5 4222.9 4279.7 4406.7 4851.3 4884.6 5022.6 5318.4 5378.4 5383.3 5483.0 4424.9 4644.3 4871.7 5279.8 5325.5 2403.5 2547.5 2742.7 2901.4 2929.1 5353.2 5492.3 5643.0 5650.9 5665.6 4772.7 5180.4 5518.4 5542.0 5542.4 4872.4 5018.0 5187.9 5592.8 5617.4 4346.4 4921.3 5087.7 5112.7 5408.8 4641.5 5148.7 5306.6 5316.5 5339.8 5389.7 5444.4 5461.1 5588.5 5660.4 3365.6 3564.9 3584.2 3600.3 3846.5 5729.8 5760.1 5798.7 5839.7 5848.0 5582.0 5624.1 5628.5 5658.5 5695.8 4857.6 4975.9 5193.4 5302.8 5641.3 5008.0 5185.4 5392.7 5449.9 5461.8 5450.2 5528.4 5594.6 5595.9 5667.7 5472.4 5543.8 5557.9 5560.1 5616.1 07:52:00 INFO - PROCESS | 1634 | 07:52:00 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:18389.22 Median:49805.25 stddev:26034.34 (52.3%) stddev-sans-first:26073.35 07:52:00 INFO - PROCESS | 1634 | Values: 661.9 667.4 692.6 737.7 756.5 13564.0 13592.7 14054.0 14305.0 14327.4 12725.7 12816.9 12835.6 12849.9 13300.5 794.5 826.5 830.2 831.6 832.6 655.6 672.7 680.9 801.9 814.3 866.4 874.7 876.7 878.4 879.5 867.9 870.4 871.6 871.7 874.6 514.1 525.2 623.2 656.0 658.0 13379.1 13834.5 13852.4 13861.2 13868.2 12319.8 12520.5 12653.7 12739.6 13005.6 10999.3 11133.6 11233.3 11291.9 11661.8 3583.1 3611.0 3637.5 3639.6 3653.4 87200.0 87202.6 87481.1 87546.7 88314.6 9978.0 10025.4 10165.4 10207.6 10258.0 18682.4 18742.4 18788.3 18842.3 18884.2 6231.3 7318.4 7443.9 7648.3 7664.1 4079.2 4207.6 4282.3 4306.0 4402.9 3150.0 3230.7 3251.0 3254.6 3361.9 657.7 663.6 667.6 669.7 674.0 555.9 631.3 639.4 640.9 651.3 8939.2 8994.6 9002.8 9093.0 9100.8 604.2 623.6 629.9 634.8 635.7 3922.0 3950.4 3994.0 4010.7 4065.5 67641.0 68050.0 91464.3 106859.9 114646.4 59042.0 80039.8 80384.6 81947.0 98137.5 56922.1 69669.5 70800.0 77191.0 80490.5 8340.5 9318.0 9356.9 9537.6 9551.1 45589.5 48880.8 51764.7 52015.7 53310.5 50729.7 51471.5 53637.2 65701.0 73501.0 10038.6 11562.6 11585.3 11844.4 12203.5 13189.0 13230.6 14354.9 14702.8 14813.3 12792.1 12957.5 13068.7 13298.6 13687.2 10900.6 11197.4 11640.4 11724.9 11854.6 12077.4 12500.5 12674.4 12991.9 13314.9 10381.7 10485.3 11137.3 11506.7 11640.1 8625.7 9703.9 10549.2 10606.2 10606.9 07:52:00 INFO - PROCESS | 1634 | -------- Summary: end -------- 07:52:00 INFO - PROCESS | 1634 | 07:52:01 INFO - TEST-INFO | 1634: exit 0 07:52:01 INFO - TEST-OK | dromaeo_css | took 1121171ms 07:52:01 INFO - TEST-START | kraken 07:52:01 INFO - operating with platform_type : mac_ 07:52:01 INFO - Initialising browser for kraken test... 07:52:01 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpTJi8t4/profile http://localhost:49245/getInfo.html) 07:52:04 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 07:52:04 INFO - PROCESS | 1644 | colorDepth:24 07:52:04 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 07:52:04 INFO - PROCESS | 1644 | __metrics 07:52:04 INFO - PROCESS | 1644 | 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" 07:52:04 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:52:05 INFO - TEST-INFO | 1644: exit 0 07:52:05 INFO - Browser initialized. 07:52:05 INFO - Running cycle 1/1 for kraken test... 07:52:05 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpTJi8t4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:17 INFO - PROCESS | 1645 | RSS: Main: 221786112 07:52:17 INFO - PROCESS | 1645 | 07:52:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:20 INFO - PROCESS | 1645 | RSS: Main: 271929344 07:52:20 INFO - PROCESS | 1645 | 07:52:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 07:52:24 INFO - PROCESS | 1645 | RSS: Main: 282517504 07:52:24 INFO - PROCESS | 1645 | 07:52:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 07:52:27 INFO - PROCESS | 1645 | RSS: Main: 380497920 07:52:27 INFO - PROCESS | 1645 | 07:52:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 07:52:30 INFO - PROCESS | 1645 | RSS: Main: 282206208 07:52:30 INFO - PROCESS | 1645 | 07:52:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:33 INFO - PROCESS | 1645 | RSS: Main: 283443200 07:52:33 INFO - PROCESS | 1645 | 07:52:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:36 INFO - PROCESS | 1645 | RSS: Main: 326234112 07:52:36 INFO - PROCESS | 1645 | 07:52:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:39 INFO - PROCESS | 1645 | RSS: Main: 326377472 07:52:39 INFO - PROCESS | 1645 | 07:52:42 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 07:52:43 INFO - PROCESS | 1645 | RSS: Main: 325296128 07:52:43 INFO - PROCESS | 1645 | 07:52:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:45 INFO - PROCESS | 1645 | RSS: Main: 299741184 07:52:45 INFO - PROCESS | 1645 | 07:52:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:47 INFO - PROCESS | 1645 | RSS: Main: 298950656 07:52:47 INFO - PROCESS | 1645 | 07:52:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:52:50 INFO - PROCESS | 1645 | RSS: Main: 300777472 07:52:50 INFO - PROCESS | 1645 | 07:52:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:52:54 INFO - PROCESS | 1645 | RSS: Main: 313294848 07:52:54 INFO - PROCESS | 1645 | 07:52:57 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:52:57 INFO - PROCESS | 1645 | RSS: Main: 313667584 07:52:57 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 07:52:59 INFO - PROCESS | 1645 | RSS: Main: 356716544 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | __start_tp_report 07:52:59 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 07:52:59 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 07:52:59 INFO - PROCESS | 1645 | |i|pagename|runs| 07:52:59 INFO - PROCESS | 1645 | |0;ai-astar;114;114;114;113;113;114;114;113;114;114 07:52:59 INFO - PROCESS | 1645 | |1;audio-beat-detection;143;110;111;149;109;110;110;108;138;108 07:52:59 INFO - PROCESS | 1645 | |2;audio-dft;182;185;181;186;185;183;182;184;183;185 07:52:59 INFO - PROCESS | 1645 | |3;audio-fft;84;60;59;87;61;58;59;59;81;58 07:52:59 INFO - PROCESS | 1645 | |4;audio-oscillator;81;69;94;69;68;69;90;69;69;92 07:52:59 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;86;86;87;84;86;84;88;84;86;84 07:52:59 INFO - PROCESS | 1645 | |6;imaging-darkroom;93;93;92;92;92;98;93;87;149;93 07:52:59 INFO - PROCESS | 1645 | |7;imaging-desaturate;80;81;82;82;81;93;129;86;80;82 07:52:59 INFO - PROCESS | 1645 | |8;json-parse-financial;54;51;56;51;57;58;57;57;56;56 07:52:59 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;64;69;78;74;73;70;70;70;71;72 07:52:59 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;84;83;84;83;84;81;99;83;81;85 07:52:59 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;145;144;149;141;142;143;141;151;221;174 07:52:59 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;162;162;165;165;166;166;163;163;165;161 07:52:59 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;66;69;68;68;67;68;67;67;68;65 07:52:59 INFO - PROCESS | 1645 | __end_tp_report 07:52:59 INFO - PROCESS | 1645 | __start_cc_report 07:52:59 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2020 07:52:59 INFO - PROCESS | 1645 | __end_cc_report 07:52:59 INFO - PROCESS | 1645 | __startTimestamp1481903579839__endTimestamp 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | ------- Summary: start ------- 07:52:59 INFO - PROCESS | 1645 | Number of tests: 14 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:52:59 INFO - PROCESS | 1645 | Values: 114.0 114.0 114.0 113.0 113.0 114.0 114.0 113.0 114.0 114.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:110.50 stddev:16.61 (15.0%) stddev-sans-first:15.31 07:52:59 INFO - PROCESS | 1645 | Values: 143.0 110.0 111.0 149.0 109.0 110.0 110.0 108.0 138.0 108.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:1.65 (0.9%) stddev-sans-first:1.64 07:52:59 INFO - PROCESS | 1645 | Values: 182.0 185.0 181.0 186.0 185.0 183.0 182.0 184.0 183.0 185.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.12 (20.0%) stddev-sans-first:11.10 07:52:59 INFO - PROCESS | 1645 | Values: 84.0 60.0 59.0 87.0 61.0 58.0 59.0 59.0 81.0 58.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:75.00 stddev:11.06 (14.7%) stddev-sans-first:11.63 07:52:59 INFO - PROCESS | 1645 | Values: 81.0 69.0 94.0 69.0 68.0 69.0 90.0 69.0 69.0 92.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 07:52:59 INFO - PROCESS | 1645 | Values: 86.0 86.0 87.0 84.0 86.0 84.0 88.0 84.0 86.0 84.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:93.00 stddev:18.04 (19.4%) stddev-sans-first:19.04 07:52:59 INFO - PROCESS | 1645 | Values: 93.0 93.0 92.0 92.0 92.0 98.0 93.0 87.0 149.0 93.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:87.60 Median:82.00 stddev:15.06 (18.4%) stddev-sans-first:15.73 07:52:59 INFO - PROCESS | 1645 | Values: 80.0 81.0 82.0 82.0 81.0 93.0 129.0 86.0 80.0 82.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.50 stddev:2.50 (4.4%) stddev-sans-first:2.60 07:52:59 INFO - PROCESS | 1645 | Values: 54.0 51.0 56.0 51.0 57.0 58.0 57.0 57.0 56.0 56.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.63 (5.1%) stddev-sans-first:2.80 07:52:59 INFO - PROCESS | 1645 | Values: 64.0 69.0 78.0 74.0 73.0 70.0 70.0 70.0 71.0 72.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:5.19 (6.2%) stddev-sans-first:5.49 07:52:59 INFO - PROCESS | 1645 | Values: 84.0 83.0 84.0 83.0 84.0 81.0 99.0 83.0 81.0 85.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:147.00 stddev:25.15 (17.1%) stddev-sans-first:26.41 07:52:59 INFO - PROCESS | 1645 | Values: 145.0 144.0 149.0 141.0 142.0 143.0 141.0 151.0 221.0 174.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:165.00 stddev:1.81 (1.1%) stddev-sans-first:1.80 07:52:59 INFO - PROCESS | 1645 | Values: 162.0 162.0 165.0 165.0 166.0 166.0 163.0 163.0 165.0 161.0 07:52:59 INFO - PROCESS | 1645 | 07:52:59 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.13 07:52:59 INFO - PROCESS | 1645 | Values: 66.0 69.0 68.0 68.0 67.0 68.0 67.0 67.0 68.0 65.0 07:52:59 INFO - PROCESS | 1645 | -------- Summary: end -------- 07:52:59 INFO - PROCESS | 1645 | 07:53:01 INFO - TEST-INFO | 1645: exit 0 07:53:01 INFO - TEST-OK | kraken | took 59607ms 07:53:01 INFO - SUITE-END | took 1180s 07:53:01 INFO - Completed test suite (00:19:41) 07:53:01 INFO - javascript benchmark 07:53:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2850.4156647887185, 3007.1429642551875, 3195.8721651133956, 3213.971529110684, 3248.058063161683, 5472.0, 5511.72441377931, 5543.029969755296, 5573.359063707675, 5588.273524441822, 5553.666779993203, 5555.388907220203, 5613.550915926727, 5614.045612245918, 5640.858978525539, 5081.593472522208, 5101.234814777795, 5125.769340379692, 5198.818041368553, 5233.3981592116825, 5067.543921047105, 5073.086844368012, 5165.793368265265, 5198.09033419152, 5236.345456817898, 5127.051495473331, 5197.896042079156, 5200.869978250536, 5206.5834733221445, 5209.21861720743, 5193.169092945149, 5202.0, 5203.505666961632, 5204.557612602934, 5229.0, 5047.798088076473, 5138.665986710852, 5145.614078944057, 5148.227765835118, 5163.40620828602, 5656.462636049569, 5677.914831277534, 5683.062294721366, 5687.061634830248, 5692.089265717506, 5528.419515950807, 5684.550439637907, 6067.1202675611785, 6090.756369745205, 6095.268567771852, 6093.086037094471, 6118.223266370672, 6121.117656725397, 6125.540694081788, 6131.449496612492, 5659.51894089003, 5667.886642267132, 5682.431756824326, 5702.287214098238, 5714.914276285859, 6256.7810126645345, 6274.1843560336865, 6278.717457714414, 6282.246130464282, 6288.056791481315, 5868.972929737279, 5904.321003084614, 5904.996150654399, 5982.46157845796, 6017.398260174035, 6111.174991376108, 6114.471014204479, 6154.907676384858, 6166.938330616636, 6166.969165154145, 6124.693765311717, 6147.44672979434, 6151.784687535915, 6153.876922461525, 6180.3201647818705, 6002.789902353484, 6052.788152414644, 6069.969650151809, 6086.634801911811, 6159.96920015406, 4774.092922344744, 4830.541098595698, 4893.412790465096, 4898.24077268024, 4904.901901962012, 4337.891552711208, 4351.97824010878, 4356.346548017759, 4361.258586040381, 4391.736495810262, 5521.365043020024, 5714.485696287271, 5735.741891614887, 5736.827895163152, 5759.65442073477, 6124.930239974474, 6133.325334213233, 6162.198914141133, 6168.568200225941, 6186.690665466709, 5695.373508914016, 5717.685527296038, 5720.68536230503, 5729.88540229193, 5745.87955348703, 5326.840194794162, 5350.5184533392185, 5361.303030605996, 5378.9462105379225, 5380.811671591476, 6134.191681014119, 6186.876262474635, 6216.689165541795, 6292.307846137013, 6294.055891616111, 6074.483668888194, 6119.68535298207, 6165.938340616536, 6167.6916154193, 6225.408586184305, 6270.561060725706, 6280.717367718555, 6289.811305660837, 6294.8111556653375, 6296.087067375113, 5798.5651076168615, 6108.786192483241, 6112.908306375319, 6127.540434467343, 6133.907991380043, 5690.260266165372, 5931.555133365101, 5939.2575928009, 5995.490383317467, 6015.187949626745, 4963.659811850852, 5491.560675145899, 5778.595498315078, 5804.158397032322, 5820.272465941757, 5711.428857114422, 5789.2763404574425, 5800.1589769484035, 5801.216835727291, 5802.4197580241635, 5351.357837059578, 5460.672359658433, 5636.4927156556105, 5699.686517241592, 5705.286839145107, 4864.513548645249, 5812.21535092757, 5816.069428891357, 5816.098504731774, 5823.883522329615, 4869.31829543857, 5773.134029895549, 5803.7968671096305, 5812.796552120655, 5819.476247137778], "value": 5561.9283442002115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.552012467408, 458.9885252868677, 482.1201307613604, 506.2077848167618, 572.6621293436872, 29368.853155734218, 38199.42700859486, 38843.61156388439, 39387.60612393875, 40551.39172912409, 40429.393559096636, 40743.0, 40866.0, 40868.79565602168, 40962.0, 8358.414910956235, 9132.726018219431, 9329.86005209919, 9344.159025687686, 9345.205657519118, 8427.83144337113, 10934.890651093467, 11234.3259404436, 11247.83128253077, 11359.886401135966, 11230.101591872672, 11280.943595282011, 11288.943555282212, 11293.604723834667, 11311.208215424924, 8394.874076888851, 8593.828123437495, 11125.388103654295, 11211.439428028607, 11212.383318917457, 9588.95205523975, 10789.298695584841, 11126.38804865732, 11166.665000050012, 11262.605808796652, 38185.61814381876, 38429.23141537182, 38432.80783596092, 38467.84596462083, 38502.422463662784, 3195.2491164576304, 3213.939399997995, 3306.3717893600137, 3522.154682876117, 3541.8760343388117, 27092.0, 28692.856535717397, 28868.855655721585, 29105.272368190752, 29562.556561651592, 2886.451574200916, 3100.9689903100907, 3217.324361883984, 3237.43344914639, 3275.983620081908, 461383.6930815358, 464656.0, 466998.0, 469596.0, 470349.0, 32168.83915580407, 32820.179495512326, 33284.1678958028, 55581.0, 56106.43893561094, 27647.861760691067, 32033.67966320307, 33325.83337083299, 33436.33127337488, 33578.328433431685, 13535.984801139954, 13636.65908352299, 13639.181649100887, 13646.84001859853, 13649.385777640031, 10596.682099537025, 10790.51442685081, 10806.945965270123, 11544.0, 11627.941860290644, 7480.139783924809, 7598.506097103671, 7694.999650045458, 7704.8844267336035, 7746.147923728386, 3169.462810536888, 3221.855016524262, 3228.7094161525574, 3241.108695108864, 3246.610406751158, 867.8481265778463, 891.8751374807532, 932.8274269260208, 946.4936259101348, 956.636478138303, 29139.0, 29427.705722942927, 29632.555511667342, 29802.403951921275, 29973.70026299753, 998.1765043838863, 1046.3094357723867, 1059.655611926127, 1076.3488089705615, 1082.6938620737778, 14835.703285934436, 15212.847871521364, 15327.770083448757, 15630.609234769221, 15642.68714625701, 189475.0, 189876.0, 191163.0, 191370.0, 635077.0, 54251.72874135604, 54629.1805622916, 54734.72632636891, 54765.452345476835, 54858.0, 1191.7973944429468, 1236.0852968802842, 1301.5054279373778, 1338.7188690374935, 1348.5010546098006, 28351.43297134087, 29929.551056734603, 29932.401351973273, 30080.54879176858, 30504.694953051072, 44866.0, 45225.773871130434, 45256.54743452524, 45463.54536454725, 45465.31802022906, 406303.0, 406412.0, 406738.9663051666, 408959.0, 602286.0, 39519.2096158081, 39913.802585922465, 40069.79965100156, 40199.79900100481, 40286.194276113725, 43688.0, 43795.12409751851, 43890.341644874716, 44510.0, 44520.554794451644, 36273.81863090667, 36406.27187456183, 37186.25627487381, 38212.617873820906, 38421.6157838418, 36403.386454183266, 36771.0, 36781.08047298839, 37494.250114998096, 37537.0, 35908.28183436264, 36547.451788222665, 36579.817100914326, 36684.81657591695, 36738.26523469569, 31604.683953160173, 33050.33899322048, 33146.17134571655, 33418.66581334253, 33548.0, 24332.756672433756, 24414.145504907243, 24620.876895616122, 25098.498030038932, 25196.874015629804], "value": 17734.631495743557, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.97176225901933, 367.5827935293442, 370.87204914304573, 386.0, 406.64418633695516, 14409.63975900603, 14822.0, 15109.471168509104, 19190.040497975115, 19593.902030489826, 19455.221791128337, 19863.30478433255, 19884.00579971003, 20005.699914501292, 20345.28791492298, 5580.972095139528, 5878.470937615613, 5879.294484661847, 5884.558658100638, 5902.61632993854, 5462.153366228241, 5706.828795136153, 5752.482276595106, 5773.971130144343, 5779.0, 5493.917591236134, 5727.742251598667, 5740.827775166731, 5741.827745167651, 5753.510951569122, 5587.580931430159, 5715.828525144231, 5720.370759216483, 5721.227634269362, 5730.19777231188, 4772.141014617368, 4974.3533340665845, 4974.825881094181, 4992.301077849104, 5070.847874563769, 24701.876490617433, 24885.377865553324, 24970.37574060663, 25066.122685705912, 25295.62056569153, 2249.696291000727, 2336.719593648756, 2439.9878000609883, 2636.9208923732317, 2708.3635345693747, 14172.929135354258, 14519.927400363036, 14894.0, 15473.916825822196, 15502.0, 1726.378503738653, 1780.6883795335893, 1802.78366596008, 1825.6074943886995, 1865.8880467172014, 117586.0, 118726.40636796848, 119365.0, 120134.0, 120228.0, 11513.942430287963, 11573.537058517564, 11601.781812909692, 11690.590829321103, 11865.0, 14514.637134071732, 14658.706825863635, 14660.780088298685, 14693.0, 14721.116732996055, 6823.454123670094, 8199.672013119409, 8351.415400921998, 8438.198371154731, 8451.366147538836, 7389.704411823574, 7506.699732010658, 8078.919210807934, 8105.75682729519, 8220.301274391744, 3644.9453258201147, 4509.210888094571, 4542.3413605027745, 4580.404547408815, 4588.426446694163, 1614.6840325135213, 1830.9359172429167, 1893.2616279650947, 1918.4915997260737, 1935.419374187738, 702.707019084876, 808.0651114003261, 818.6111596991382, 856.1652388920752, 957.3824882950597, 20009.09959051846, 21103.366898993056, 21413.0, 21528.892355538123, 21730.78269217319, 750.7860259825924, 858.9484630922165, 868.2489646455897, 917.4174399256386, 931.0875342164717, 10764.730881727863, 11346.602868899548, 11365.147613928988, 11375.544978200945, 11467.0, 577832.0, 579031.0, 581833.0, 589623.0, 590946.0, 117052.0, 120790.0, 123220.38389807993, 123266.0, 123851.0, 912.1562554636935, 923.5566927874657, 947.5593848860314, 948.4214629076258, 950.5912457643142, 18410.447686569423, 18542.907285464025, 18611.06944652789, 18622.441326759676, 18698.43904682862, 31900.521492177173, 42382.78808605937, 50220.0, 51116.48883511117, 52980.41058768243, 71568.64215678888, 74309.25690743023, 74924.62537687494, 75102.62448687722, 76504.0, 7162.64186790648, 7275.163356213891, 7835.817170920652, 8027.759167224993, 8111.878321825059, 10544.420056896945, 12237.04207326859, 12329.69175770577, 12377.814332785196, 12493.562725304935, 10412.271141020055, 10588.682339529827, 10698.786024279627, 10702.518386672775, 11051.83422248683, 8245.299149572353, 9018.098190181192, 10317.793644127225, 10566.577336906437, 10763.838542422027, 10625.574977000833, 11118.49966751482, 11313.981741643292, 11331.603393881174, 11363.261388009752, 6562.376574225441, 7823.882641760264, 7902.4863383879865, 8024.117347091698, 8046.396520260887, 9879.357841740264, 10505.842412363974, 10838.945805270923, 10893.727656808644, 10935.726606834893], "value": 9296.30273517766, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.576361327988, 1428.1431141315209, 1562.6171587960944, 1770.9114544272784, 1790.0781097734662, 7987.5606841623685, 8397.958010209955, 8399.202075802788, 8414.705485308015, 8431.957840210805, 7407.518511296776, 7674.769756907288, 7693.7307194248215, 8000.839983200332, 8232.629531671075, 2331.638596017616, 2363.054778088761, 2364.078009576258, 2367.4081479630095, 2370.075670488502, 2348.3542025942834, 2374.0622454130607, 2394.5570069537193, 2401.5677178107935, 2403.9399015024587, 2346.8826558672085, 2351.1065794997876, 2370.5851475991717, 2373.359193017884, 2374.3351861478727, 2248.6739422783685, 2253.740819805727, 2272.704548408696, 2275.761045090275, 2307.157887371114, 2350.494643651606, 2373.382920440698, 2373.6914201153913, 2374.1571742031674, 2374.5607062693457, 8294.668213271461, 8304.70933517324, 8363.372747043995, 8407.369447291478, 8418.158184181593, 4338.58783415575, 4781.737004464753, 5085.186370180791, 5091.949080509185, 5093.770780314895, 3277.4919887417486, 3387.4072037393357, 3566.6433356664384, 3570.357335679603, 3581.997040828572, 1045.4563626913962, 1072.7264547540328, 1093.578972095749, 1106.651404807483, 1108.8724796648407, 10837.83743243852, 10895.727606809814, 10939.726506837313, 10966.287191332538, 10982.890171098266, 4466.821327146878, 4550.974816206823, 4747.738874361874, 4753.83361582343, 4754.405699287589, 9389.76525586852, 9396.389234699722, 9396.812063758687, 9402.623895044258, 9402.858957115648, 5150.871228219249, 5166.509181627739, 5167.560757335668, 5192.5586325162785, 5213.556847667915, 4000.7199496035582, 4250.0, 4351.564843515623, 4760.547747964007, 4763.857084287477, 3126.437241296543, 3144.8584813683437, 3498.1429549759964, 3724.4413337999517, 3896.629820167136, 1266.7276535544718, 1266.7585765949236, 1271.0235583667302, 1283.4288741509938, 1286.5689993852163, 731.6597782031381, 847.3221422861686, 866.7356456280769, 869.4435561240684, 871.7166920750782, 5507.284053073075, 5569.972150139223, 5570.164475328652, 5600.6919619420505, 5643.604947653707, 430.92674245377736, 431.6654592690627, 438.3184148648818, 878.6573236437795, 957.818014577228, 3668.4680721295263, 3690.538682664667, 3710.128119891823, 3780.281746468162, 3790.29879472293, 9789.804203915883, 9930.900690993141, 10144.0, 10276.794464110675, 10334.483275836179, 10297.588096476207, 10302.0, 10302.587896484207, 10304.587816487407, 10313.639022634172, 7563.924360756432, 7864.370850331959, 7976.401769867167, 7990.3208227300165, 8013.839723205503, 5425.348958125051, 5443.346798384219, 5501.972490137524, 5626.887462250814, 5650.569421848294, 9374.296927730311, 9449.669261575811, 9476.57355418994, 9486.95643479203, 9599.904000959901, 8008.719694810654, 9543.33196676226, 9576.616935322787, 9664.9033509664, 9674.56464459087, 5484.369297530676, 6410.0705397718, 6417.294097649162, 6609.074729537772, 6787.253402125861, 7705.422093343133, 7896.131425543301, 8026.3980201483955, 8067.959660201662, 8080.83838323242, 7111.964440177766, 7238.6380680966795, 7244.818879528054, 7296.343329100407, 7354.558726476428, 6974.372306492237, 7030.894536582057, 7056.435485161277, 7155.391791697764, 7160.176579693193, 6031.944409728368, 6466.321036291121, 6602.0096985452565, 6617.239017513048, 6748.358905903931, 4758.547937945889, 4778.713277203379, 4784.114938736345, 4846.5638092571835, 4860.927086093782, 4704.553067458586, 4724.881877953078, 4862.3922009748785, 4885.1450996076255, 4895.216765317532], "value": 4346.0028344537795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.6341208843344, 308.5033096714291, 311.67897066021993, 318.83898631191244, 320.7754571799738, 4471.150481408538, 4988.201887697969, 5290.1006828839145, 5291.973540132299, 5345.305110335661, 4855.660103792736, 4950.851474455763, 5113.821016264432, 5128.615353848458, 5187.066328060957, 3735.477033215352, 4020.954551816527, 4038.0, 4107.301758701028, 4133.256013917494, 3469.3061387722432, 3758.32350176968, 4079.3473044312914, 4102.610252026053, 4112.794360281974, 3708.814559272039, 3975.284448799215, 4013.5585085640555, 4025.9597404025876, 4039.757614543122, 3969.6030396960364, 4008.478897743304, 4035.616616421435, 4061.4110953911663, 4071.2671719090554, 2952.35048289376, 3187.569678093451, 3434.5191673165778, 3450.9827450862836, 3455.7580969332157, 5048.0, 5149.2791009258735, 5159.432462429129, 5179.715115668637, 5221.608379371524, 4471.552844715502, 4534.8186072557055, 4535.047639995571, 4609.700369476007, 4618.607418369443, 5565.5825813063775, 5582.274304340451, 5607.158926161109, 5623.453550273666, 5640.7743690252755, 3348.2299071213515, 3587.8206089695673, 3772.6043962084273, 3782.7730336179916, 3787.299349620329, 4754.027078762344, 5217.060929032773, 5635.746391412315, 5752.913706294409, 5784.421557844266, 3128.3586864692684, 3417.2823707021307, 3694.2796154750104, 3826.0, 3863.3239183142837, 3923.3330333842746, 3941.2117576484816, 3951.7431366961055, 3956.940645890314, 3965.8017099144927, 4048.352263637804, 4317.740935543878, 4475.70907890986, 4535.886602834956, 4631.490536041033, 4496.527864574173, 5419.864503387447, 5557.249771280906, 5614.045612245928, 5622.100463925753, 1065.5418170186895, 1197.2696655040418, 1216.3249396584804, 1220.365409986802, 1224.063591352616, 729.6643543969727, 743.3124359967009, 780.9223271884877, 819.7540737778762, 842.4793248186959, 4222.852200173041, 4279.651909648513, 4406.669499787528, 4851.296561998491, 4884.5603895649565, 5022.573081288131, 5318.361796584435, 5378.381486129145, 5383.273258110105, 5483.026110600181, 4424.933625995613, 4644.2801365788355, 4871.707697538159, 5279.815206467755, 5325.4674532547215, 2403.5433267679437, 2547.5414425403605, 2742.698303186648, 2901.419716056797, 2929.091981485746, 5353.2237825515085, 5492.286002819608, 5642.965279824944, 5650.858728531737, 5665.592123347561, 4772.665913386099, 5180.352455943007, 5518.392976772552, 5542.0, 5542.362628297717, 4872.366592342973, 5017.971315880236, 5187.896242075138, 5592.7762889484775, 5617.410171931889, 4346.413234213467, 4921.335619691439, 5087.720175390389, 5112.667676601009, 5408.810691625774, 4641.466231383434, 5148.69107853515, 5306.569014872624, 5316.521513063843, 5339.839804805862, 5389.7035663038905, 5444.401115877171, 5461.088618983324, 5588.51311166527, 5660.433956604306, 3365.5961284646, 3564.9287014259053, 3584.247308065231, 3600.3339382214376, 3846.519185101862, 5729.799457019151, 5760.135979603093, 5798.652080875161, 5839.678817665069, 5848.035074212878, 5582.0, 5624.100143976944, 5628.493435590816, 5658.5473162147755, 5695.772169113355, 4857.616423036893, 4975.850724478271, 5193.350831146106, 5302.814401495929, 5641.323041235078, 5008.0, 5185.392528316234, 5392.676439413648, 5449.929026969885, 5461.781528738965, 5450.174191645843, 5528.364238112668, 5594.636348637326, 5595.860103497445, 5667.744951477276, 5472.425395333433, 5543.833684989457, 5557.888842223052, 5560.138178582326, 5616.135770756861], "value": 3973.7129193176916, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.851083506211, 667.4426853577265, 692.6259819697364, 737.697544006957, 756.4514287699723, 13564.0, 13592.660183495405, 14054.0, 14304.998650094498, 14327.426902923871, 12725.745485090292, 12816.93591532041, 12835.550755723552, 12849.935750321236, 13300.534481293156, 794.5272562825124, 826.5330088500008, 830.2278880641002, 831.5551180118638, 832.6086739232551, 655.5869802024718, 672.6670298202397, 680.877442060429, 801.9318357939553, 814.2590242878981, 866.3588944181292, 874.7026011156206, 876.7325965580495, 878.4290211362602, 879.5118709116438, 867.9392442529025, 870.4124715816862, 871.5729292646589, 871.6992637540038, 874.553977471491, 514.1080225808257, 525.1649876696033, 623.2054130982988, 655.9540832141752, 658.032691942848, 13379.130356526892, 13834.515791947335, 13852.445902163901, 13861.23763193024, 13868.167909925436, 12319.753604927851, 12520.499180032788, 12653.74692506154, 12739.554115606003, 13005.609831705064, 10999.34003959757, 11133.610323638633, 11233.269837460539, 11291.887081129165, 11661.825072623833, 3583.068402215417, 3610.981945090284, 3637.5453068366455, 3639.5632524097277, 3653.4154535274492, 87200.0, 87202.56398717966, 87481.12518874857, 87546.68679969806, 88314.55842720809, 9978.0, 10025.448600327052, 10165.390076595428, 10207.591696332063, 10258.0, 18682.439526814218, 18742.437726868215, 18788.342408015655, 18842.325746479306, 18884.244630214638, 6231.2522497300615, 7318.414526837841, 7443.851122977619, 7648.273414025659, 7664.118626357928, 4079.1841631673783, 4207.558206388285, 4282.336237883133, 4305.978470107692, 4402.889927751768, 3149.984250078735, 3230.6607806180014, 3250.9837450812593, 3254.625718042408, 3361.9495707564406, 657.7336178847575, 663.5885750834514, 667.5699464955115, 669.6773871603544, 674.0293976673672, 555.9277293951841, 631.3434028610194, 639.3766078073934, 640.8910485217524, 651.3388910256094, 8939.240164585954, 8994.550272486349, 9002.774930626787, 9093.035640519089, 9100.772480688036, 604.1873679900526, 623.5697368815594, 629.8803227386782, 634.7524465458475, 635.6694518850172, 3921.9803900980314, 3950.4469374287623, 3994.021464741157, 4010.6590939770444, 4065.5121385433936, 67641.0, 68049.97925031128, 91464.3414263422, 106859.93140068655, 114646.42676786729, 59042.0, 80039.799403009, 80384.59807700924, 81947.0, 98137.50931245298, 56922.146167807514, 69669.47148435336, 70800.0, 77191.0, 80490.54849240862, 8340.541270230196, 9318.0, 9356.859647105435, 9537.570809313736, 9551.06689043415, 45589.54410455853, 48880.75559622179, 51764.70588235324, 52015.73992130166, 53310.46689533055, 50729.746351268004, 51471.48528514667, 53637.19544206762, 65701.0, 73501.0, 10038.598456061676, 11562.595309164139, 11585.304881707125, 11844.407779611158, 12203.511859525519, 13189.010824188415, 13230.603081907559, 14354.856451435351, 14702.779458308349, 14813.25933703289, 12792.103343890272, 12957.546485873327, 13068.738625227632, 13298.601041968757, 13687.178769273876, 10900.563977440814, 11197.440127993405, 11640.417979101181, 11724.941375293069, 11854.585089521825, 12077.39613019363, 12500.499980001063, 12674.429650665927, 12991.895688866553, 13314.93342533281, 10381.688549343531, 10485.318454300446, 11137.276077054965, 11506.712332191762, 11640.068794496588, 8625.654973800978, 9703.902960970583, 10549.208809339178, 10606.151507879506, 10606.893931060591], "value": 6928.812168531396, "unit": "score"}], "name": "dromaeo_css", "value": 6919.232854648995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 111.0, 149.0, 109.0, 110.0, 110.0, 108.0, 138.0, 108.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 181.0, 186.0, 185.0, 183.0, 182.0, 184.0, 183.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 59.0, 87.0, 61.0, 58.0, 59.0, 59.0, 81.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 94.0, 69.0, 68.0, 69.0, 90.0, 69.0, 69.0, 92.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 86.0, 84.0, 88.0, 84.0, 86.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 92.0, 92.0, 98.0, 93.0, 87.0, 149.0, 93.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 82.0, 82.0, 81.0, 93.0, 129.0, 86.0, 80.0, 82.0], "value": 87.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 56.0, 51.0, 57.0, 58.0, 57.0, 57.0, 56.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 78.0, 74.0, 73.0, 70.0, 70.0, 70.0, 71.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 83.0, 84.0, 81.0, 99.0, 83.0, 81.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 149.0, 141.0, 142.0, 143.0, 141.0, 151.0, 221.0, 174.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 165.0, 166.0, 166.0, 163.0, 163.0, 165.0, 161.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 65.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1429.1, "alertThreshold": 2.0}]} 07:53:01 INFO - Return code: 0 07:53:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:01 INFO - # TBPL SUCCESS # 07:53:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:01 INFO - 2016-12-16 07:53:01.651 screenresolution[1649:9762] starting screenresolution argv=screenresolution get 07:53:01 INFO - 2016-12-16 07:53:01.653 screenresolution[1649:9762] Display 0: 1600x1200x32@60 07:53:01 INFO - 2016-12-16 07:53:01.659 screenresolution[1650:9765] starting screenresolution argv=screenresolution list 07:53:01 INFO - Available Modes on Display 0 07:53:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:01 INFO - Intel Iris: 07:53:01 INFO - Chipset Model: Intel Iris 07:53:01 INFO - Type: GPU 07:53:01 INFO - Bus: Built-In 07:53:01 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:01 INFO - Vendor: Intel (0x8086) 07:53:01 INFO - Device ID: 0x0a2e 07:53:01 INFO - Revision ID: 0x0009 07:53:01 INFO - Displays: 07:53:01 INFO - Display: 07:53:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:01 INFO - Display Serial Number: 1600x1200 60 07:53:01 INFO - Main Display: Yes 07:53:01 INFO - Mirror: Off 07:53:01 INFO - Online: Yes 07:53:01 INFO - Rotation: Supported 07:53:01 INFO - Return code: 0 07:53:01 INFO - Running post-action listener: _resource_record_post_action 07:53:01 INFO - [mozharness: 2016-12-16 15:53:01.893691Z] Finished run-tests step (success) 07:53:01 INFO - Running post-run listener: _resource_record_post_run 07:53:01 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 50038784; Write bytes: 666046464; Read time: 598; Write time: 1993 07:53:01 INFO - TinderboxPrint: CPU usage
53.6% 07:53:01 INFO - TinderboxPrint: I/O read bytes / time
50,038,784 / 598 07:53:01 INFO - TinderboxPrint: I/O write bytes / time
666,046,464 / 1,993 07:53:01 INFO - TinderboxPrint: CPU idle
2,249.1 (46.4%) 07:53:01 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 07:53:01 INFO - TinderboxPrint: CPU user
1,631.5 (33.6%) 07:53:01 INFO - TinderboxPrint: Swap in / out
391,479,296 / 0 07:53:01 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 336290304; Write bytes: 333190656; Read time: 25841; Write time: 646 07:53:01 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 49084416; Write bytes: 315161088; Read time: 581; Write time: 1293 07:53:02 INFO - Running post-run listener: _upload_blobber_files 07:53:02 INFO - Blob upload gear active. 07:53:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:02 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 07:53:02 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'] 07:53:02 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 07:53:02 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:53:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:53:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:03 INFO - (blobuploader) - INFO - Done attempting. 07:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:53:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:04 INFO - (blobuploader) - INFO - Done attempting. 07:53:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:07 INFO - (blobuploader) - INFO - Done attempting. 07:53:07 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:07 INFO - Return code: 0 07:53:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecb18c7e81a01ee0f45fd84d4832474a2100aec1450241600361ce9fb3d5342e817c625a2fbc734b36148a7bf6e902c7032bfeb67a365911e98a7f510b5c9d5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff982c037b01dc92fc3ac3783ea79e7645765cf9025298dba8916244dd839198ddad71ced2d242d853992df097a9d06b1bc5515dd8ec17d7005e038fd736c08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e63ddb8b2f3f0e6b7593d89ecf06c8c6c017e4bff52ae114483c33557fc3756ad36fc9b2557e19c24d6dd456068851572c41c9f5aa95bbec25b1a28a3e50686"} 07:53:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:07 INFO - Contents: 07:53:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecb18c7e81a01ee0f45fd84d4832474a2100aec1450241600361ce9fb3d5342e817c625a2fbc734b36148a7bf6e902c7032bfeb67a365911e98a7f510b5c9d5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff982c037b01dc92fc3ac3783ea79e7645765cf9025298dba8916244dd839198ddad71ced2d242d853992df097a9d06b1bc5515dd8ec17d7005e038fd736c08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e63ddb8b2f3f0e6b7593d89ecf06c8c6c017e4bff52ae114483c33557fc3756ad36fc9b2557e19c24d6dd456068851572c41c9f5aa95bbec25b1a28a3e50686"} 07:53:07 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:07 INFO - Copying logs to upload dir... 07:53:07 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.830981 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-16 07:53:07.573759) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 07:53:07.576996) ========= 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.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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/ecb18c7e81a01ee0f45fd84d4832474a2100aec1450241600361ce9fb3d5342e817c625a2fbc734b36148a7bf6e902c7032bfeb67a365911e98a7f510b5c9d5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff982c037b01dc92fc3ac3783ea79e7645765cf9025298dba8916244dd839198ddad71ced2d242d853992df097a9d06b1bc5515dd8ec17d7005e038fd736c08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e63ddb8b2f3f0e6b7593d89ecf06c8c6c017e4bff52ae114483c33557fc3756ad36fc9b2557e19c24d6dd456068851572c41c9f5aa95bbec25b1a28a3e50686"} build_url:https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010959 build_url: 'https://queue.taskcluster.net/v1/task/dP5RjLbPReO2SCiNN0UX5A/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/ecb18c7e81a01ee0f45fd84d4832474a2100aec1450241600361ce9fb3d5342e817c625a2fbc734b36148a7bf6e902c7032bfeb67a365911e98a7f510b5c9d5f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ff982c037b01dc92fc3ac3783ea79e7645765cf9025298dba8916244dd839198ddad71ced2d242d853992df097a9d06b1bc5515dd8ec17d7005e038fd736c08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e63ddb8b2f3f0e6b7593d89ecf06c8c6c017e4bff52ae114483c33557fc3756ad36fc9b2557e19c24d6dd456068851572c41c9f5aa95bbec25b1a28a3e50686"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 07:53:07.610871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:53:07.611206) ========= 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.YGxgDupm6C/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.8AHalqbFYD/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.004986 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 07:53:07.659779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 07:53:07.660181) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 07:53:07.660525) ========= ========= Total master_lag: 0.21 =========