builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1481547678.55 results: success (0) buildid: 20161212024950 builduid: 4e019df8898a4df2a802aa4223a9daac revision: b54a7014e03d92571b341b9ce82772305685a58d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:18.547572) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:18.547957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:18.548226) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:18.729655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:18.730238) ========= 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.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-12 05:01:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2016-12-12 05:01:19 (20.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.625423 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:19.387235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:19.387529) ========= 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.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.020096 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:19.457090) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 05:01:19.457402) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b54a7014e03d92571b341b9ce82772305685a58d --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 b54a7014e03d92571b341b9ce82772305685a58d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-12 05:01:19,535 truncating revision to first 12 chars 2016-12-12 05:01:19,535 Setting DEBUG logging. 2016-12-12 05:01:19,535 attempt 1/10 2016-12-12 05:01:19,535 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b54a7014e03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-12 05:01:20,770 unpacking tar archive at: mozilla-aurora-b54a7014e03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-12 05:01:20.975561) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:20.975830) ========= script_repo_revision: b54a7014e03d92571b341b9ce82772305685a58d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:20.976219) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:20.976543) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-12 05:01:21.002903) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-12 05:01:21.003169) ========= /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.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:01:21 INFO - MultiFileLogger online at 20161212 05:01:21 in /builds/slave/test 05:01:21 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 05:01:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:01:21 INFO - {'append_to_log': False, 05:01:21 INFO - 'base_work_dir': '/builds/slave/test', 05:01:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:01:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:01:21 INFO - 'branch': 'Mozilla-Aurora', 05:01:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:01:21 INFO - 'config_files': ('talos/mac_config.py',), 05:01:21 INFO - 'default_actions': ('clobber', 05:01:21 INFO - 'read-buildbot-config', 05:01:21 INFO - 'download-and-extract', 05:01:21 INFO - 'populate-webroot', 05:01:21 INFO - 'create-virtualenv', 05:01:21 INFO - 'install', 05:01:21 INFO - 'run-tests'), 05:01:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:01:21 INFO - 'download_minidump_stackwalk': True, 05:01:21 INFO - 'download_symbols': 'ondemand', 05:01:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:01:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:01:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:01:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:01:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:01:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:01:21 INFO - 'installer_path': 'installer.exe', 05:01:21 INFO - 'log_level': 'info', 05:01:21 INFO - 'log_name': 'talos', 05:01:21 INFO - 'log_to_console': True, 05:01:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:01:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:01:21 INFO - 'opt_config_files': (), 05:01:21 INFO - 'pip_index': False, 05:01:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:21 INFO - 'cmd': ('bash', 05:01:21 INFO - '-c', 05:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:21 INFO - 'enabled': True, 05:01:21 INFO - 'halt_on_failure': False, 05:01:21 INFO - 'name': 'check_screen_resolution'},), 05:01:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:21 INFO - 'cmd': ('bash', 05:01:21 INFO - '-c', 05:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:21 INFO - 'enabled': True, 05:01:21 INFO - 'halt_on_failure': False, 05:01:21 INFO - 'name': 'check_screen_resolution'},), 05:01:21 INFO - 'run_cmd_checks_enabled': True, 05:01:21 INFO - 'sps_profile': False, 05:01:21 INFO - 'sps_profile_interval': 0, 05:01:21 INFO - 'suite': 'dromaeojs', 05:01:21 INFO - 'system_bits': '32', 05:01:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:01:21 INFO - 'title': 't-yosemite-r7-0084', 05:01:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:01:21 INFO - 'use_talos_json': True, 05:01:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:01:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:01:21 INFO - 'work_dir': 'build'} 05:01:21 INFO - [mozharness: 2016-12-12 13:01:21.205802Z] Running clobber step. 05:01:21 INFO - Running pre-action listener: _resource_record_pre_action 05:01:21 INFO - Running main action method: clobber 05:01:21 INFO - rmtree: /builds/slave/test/build 05:01:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:01:22 INFO - Running post-action listener: _resource_record_post_action 05:01:22 INFO - [mozharness: 2016-12-12 13:01:22.714333Z] Finished clobber step (success) 05:01:22 INFO - [mozharness: 2016-12-12 13:01:22.714490Z] Running read-buildbot-config step. 05:01:22 INFO - Running pre-action listener: _resource_record_pre_action 05:01:22 INFO - Running main action method: read_buildbot_config 05:01:22 INFO - Using buildbot properties: 05:01:22 INFO - { 05:01:22 INFO - "project": "", 05:01:22 INFO - "product": "firefox", 05:01:22 INFO - "script_repo_revision": "production", 05:01:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:01:22 INFO - "repository": "", 05:01:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:01:22 INFO - "buildid": "20161212024950", 05:01:22 INFO - "pgo_build": "False", 05:01:22 INFO - "basedir": "/builds/slave/test", 05:01:22 INFO - "buildnumber": 6, 05:01:22 INFO - "slavename": "t-yosemite-r7-0084", 05:01:22 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:01:22 INFO - "platform": "yosemite_r7", 05:01:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:01:22 INFO - "branch": "mozilla-aurora", 05:01:22 INFO - "repo_path": "releases/mozilla-aurora", 05:01:22 INFO - "revision": "b54a7014e03d92571b341b9ce82772305685a58d", 05:01:22 INFO - "stage_platform": "macosx64", 05:01:22 INFO - "builduid": "4e019df8898a4df2a802aa4223a9daac", 05:01:22 INFO - "slavebuilddir": "test" 05:01:22 INFO - } 05:01:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 05:01:22 INFO - Running post-action listener: _resource_record_post_action 05:01:22 INFO - [mozharness: 2016-12-12 13:01:22.717904Z] Finished read-buildbot-config step (success) 05:01:22 INFO - [mozharness: 2016-12-12 13:01:22.718035Z] Running download-and-extract step. 05:01:22 INFO - Running pre-action listener: _resource_record_pre_action 05:01:22 INFO - Running main action method: download_and_extract 05:01:22 INFO - mkdir: /builds/slave/test/build/tests 05:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:22 INFO - trying https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 05:01:22 INFO - Downloading https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/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 05:01:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/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 05:01:23 INFO - Downloaded 1199 bytes. 05:01:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:01:23 INFO - Using the following test package requirements: 05:01:23 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:01:23 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 05:01:23 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 05:01:23 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'jsshell-mac.zip'], 05:01:23 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 05:01:23 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:01:23 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 05:01:23 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 05:01:23 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 05:01:23 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:01:23 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 05:01:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:01:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:01:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:01:23 INFO - Fetch https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:01:24 INFO - Http code: 200 05:01:24 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 05:01:24 INFO - x-amz-cf-id: ThzXA4Hs_ij6-7fa2NnA9SboQMoInaiC6Nry1E9EUQ3fdcPmrKqwMw== 05:01:24 INFO - x-amz-version-id: tJp1PFYCf6_HM6j8gEW8xDqA2Ek7SkO8 05:01:24 INFO - Expected file size: 16281032 05:01:24 INFO - Obtained file size: 16281032 05:01:31 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 05:01:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:01:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:01:31 INFO - Fetch https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:01:31 INFO - Http code: 200 05:01:31 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 05:01:31 INFO - x-amz-cf-id: 00UTFuKp1Ia-P1W4dMoJxee2ZhBD-y3wIOlQbVbbSQ8hLBmZPULccA== 05:01:31 INFO - x-amz-version-id: tJp1PFYCf6_HM6j8gEW8xDqA2Ek7SkO8 05:01:32 INFO - Expected file size: 16281032 05:01:32 INFO - Obtained file size: 16281032 05:01:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 05:01:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:01:38 INFO - Fetch https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 05:01:39 INFO - Http code: 200 05:01:39 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 05:01:39 INFO - x-amz-cf-id: sJ6FxJmnfJsCCXDDSHyjb9oogaSc0BSCM5HsouQtstgb1PFqoj4Iyg== 05:01:39 INFO - x-amz-version-id: jyTYkc2dMmU1M1Y5DSat0KbrjOwOng_D 05:01:40 INFO - Expected file size: 14409038 05:01:40 INFO - Obtained file size: 14409038 05:01:40 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')]} 05:01:40 INFO - trying https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:01:40 INFO - Downloading https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 05:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/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 05:01:44 INFO - Downloaded 109765446 bytes. 05:01:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:01:44 INFO - mkdir: /builds/slave/test/properties 05:01:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:01:44 INFO - Writing to file /builds/slave/test/properties/build_url 05:01:44 INFO - Contents: 05:01:44 INFO - build_url:https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:01:44 INFO - Running post-action listener: _resource_record_post_action 05:01:44 INFO - Running post-action listener: set_extra_try_arguments 05:01:44 INFO - [mozharness: 2016-12-12 13:01:44.896912Z] Finished download-and-extract step (success) 05:01:44 INFO - [mozharness: 2016-12-12 13:01:44.897036Z] Running populate-webroot step. 05:01:44 INFO - Running pre-action listener: _resource_record_pre_action 05:01:44 INFO - Running main action method: populate_webroot 05:01:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:01:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:01:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:01:44 INFO - '--tppagecycles', 05:01:44 INFO - '7'], 05:01:44 INFO - 'tests': ['tsvgm']}}, 05:01:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:01:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:01:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:01:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:01:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['damp', 'tps']}, 05:01:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'tests': ['damp', 'tps']}, 05:01:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['dromaeo_dom']}, 05:01:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:01:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['basic_compositor_video']}, 05:01:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:01:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'other-e10s': {'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['a11yr', 05:01:44 INFO - 'ts_paint', 05:01:44 INFO - 'tpaint', 05:01:44 INFO - 'sessionrestore', 05:01:44 INFO - 'sessionrestore_no_auto_restore', 05:01:44 INFO - 'tabpaint']}, 05:01:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['tsvgx', 05:01:44 INFO - 'tsvgr_opacity', 05:01:44 INFO - 'tart', 05:01:44 INFO - 'tscrollx', 05:01:44 INFO - 'cart']}, 05:01:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:01:44 INFO - 'tsvgr_opacity', 05:01:44 INFO - 'tart', 05:01:44 INFO - 'tscrollx', 05:01:44 INFO - 'cart']}, 05:01:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'talos_options': ['--disable-e10s'], 05:01:44 INFO - 'tests': ['tp5o']}, 05:01:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'tests': ['tp5o']}, 05:01:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'talos_options': ['--disable-e10s', 05:01:44 INFO - '--xperf_path', 05:01:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:44 INFO - 'tests': ['tp5n']}, 05:01:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:01:44 INFO - 'talos_options': ['--xperf_path', 05:01:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:44 INFO - 'tests': ['tp5n']}}, 05:01:44 INFO - 'talos.zip': {'path': '', 05:01:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:01:44 INFO - Running post-action listener: _resource_record_post_action 05:01:44 INFO - [mozharness: 2016-12-12 13:01:44.915793Z] Finished populate-webroot step (success) 05:01:44 INFO - [mozharness: 2016-12-12 13:01:44.915902Z] Running create-virtualenv step. 05:01:44 INFO - Running pre-action listener: _resource_record_pre_action 05:01:44 INFO - Running main action method: create_virtualenv 05:01:44 INFO - Creating virtualenv /builds/slave/test/build/venv 05:01:44 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 05:01:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:01:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:01:45 INFO - Using real prefix '/tools/python27' 05:01:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:01:45 INFO - Installing distribute.............................................................................................................................................................................................done. 05:01:48 INFO - Installing pip.................done. 05:01:48 INFO - Return code: 0 05:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:01:48 INFO - Reading from file tmpfile_stdout 05:01:48 INFO - Output received: 05:01:48 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) 05:01:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:01: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')]} 05:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01: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')]} 05:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:01: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', 'pip>=1.5'] in /builds/slave/test/build 05:01: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 pip>=1.5 05:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:48 INFO - 'HOME': '/Users/cltbld', 05:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:48 INFO - 'LOGNAME': 'cltbld', 05:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:48 INFO - 'MOZ_NO_REMOTE': '1', 05:01:48 INFO - 'NO_EM_RESTART': '1', 05:01:48 INFO - 'PAGER': '/bin/cat', 05:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:48 INFO - 'PWD': '/builds/slave/test', 05:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:48 INFO - 'SHELL': '/bin/bash', 05:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:01:48 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:48 INFO - 'USER': 'cltbld', 05:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:48 INFO - 'XPC_FLAGS': '0x0', 05:01:48 INFO - 'XPC_SERVICE_NAME': '0', 05:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:49 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 05:01:49 INFO - Cleaning up... 05:01:49 INFO - Return code: 0 05:01:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:01:49 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')]} 05:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:49 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')]} 05:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:49 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:01:49 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 05:01:49 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 05:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:49 INFO - 'HOME': '/Users/cltbld', 05:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:49 INFO - 'LOGNAME': 'cltbld', 05:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:49 INFO - 'MOZ_NO_REMOTE': '1', 05:01:49 INFO - 'NO_EM_RESTART': '1', 05:01:49 INFO - 'PAGER': '/bin/cat', 05:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:49 INFO - 'PWD': '/builds/slave/test', 05:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:49 INFO - 'SHELL': '/bin/bash', 05:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:01:49 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:49 INFO - 'USER': 'cltbld', 05:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:49 INFO - 'XPC_FLAGS': '0x0', 05:01:49 INFO - 'XPC_SERVICE_NAME': '0', 05:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:49 INFO - Downloading/unpacking psutil>=3.1.1 05:01:49 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 05:01:49 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 05:01:49 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 05:01:49 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 05:01:49 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 05:01:49 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 05:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:01:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:53 INFO - Installing collected packages: psutil 05:01:53 INFO - Running setup.py install for psutil 05:01:53 INFO - building 'psutil._psutil_osx' extension 05:01:53 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 05:01:53 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 05:01:53 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 05:01:53 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 05:01:53 INFO - building 'psutil._psutil_posix' extension 05:01:53 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 05:01:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:01:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:01:53 INFO - ^ 05:01:53 INFO - 1 warning generated. 05:01:53 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 05:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:54 INFO - Successfully installed psutil 05:01:54 INFO - Cleaning up... 05:01:54 INFO - Return code: 0 05:01:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:01: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')]} 05:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01: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')]} 05:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:01:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:54 INFO - 'HOME': '/Users/cltbld', 05:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:54 INFO - 'LOGNAME': 'cltbld', 05:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:54 INFO - 'MOZ_NO_REMOTE': '1', 05:01:54 INFO - 'NO_EM_RESTART': '1', 05:01:54 INFO - 'PAGER': '/bin/cat', 05:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:54 INFO - 'PWD': '/builds/slave/test', 05:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:54 INFO - 'SHELL': '/bin/bash', 05:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:01:54 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:54 INFO - 'USER': 'cltbld', 05:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:54 INFO - 'XPC_FLAGS': '0x0', 05:01:54 INFO - 'XPC_SERVICE_NAME': '0', 05:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:01:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:01:57 INFO - Installing collected packages: mozsystemmonitor 05:01:57 INFO - Running setup.py install for mozsystemmonitor 05:01:57 INFO - Successfully installed mozsystemmonitor 05:01:57 INFO - Cleaning up... 05:01:57 INFO - Return code: 0 05:01:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:01:57 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')]} 05:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:57 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')]} 05:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:01:57 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:01:57 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 05:01:57 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 05:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:01:57 INFO - 'HOME': '/Users/cltbld', 05:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:01:57 INFO - 'LOGNAME': 'cltbld', 05:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:57 INFO - 'MOZ_NO_REMOTE': '1', 05:01:57 INFO - 'NO_EM_RESTART': '1', 05:01:57 INFO - 'PAGER': '/bin/cat', 05:01:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:57 INFO - 'PWD': '/builds/slave/test', 05:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:01:57 INFO - 'SHELL': '/bin/bash', 05:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:01:57 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:01:57 INFO - 'USER': 'cltbld', 05:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:57 INFO - 'XPC_FLAGS': '0x0', 05:01:57 INFO - 'XPC_SERVICE_NAME': '0', 05:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:57 INFO - Downloading/unpacking jsonschema==2.5.1 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:01:57 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 05:02:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:02:00 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 05:02:00 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 05:02:00 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 05:02:00 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 05:02:00 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 05:02:00 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 05:02:00 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:00 INFO - warning: no files found matching '*.txt' 05:02:00 INFO - no previously-included directories found matching 'build' 05:02:00 INFO - no previously-included directories found matching 'dist' 05:02:00 INFO - no previously-included directories found matching '.git*' 05:02:00 INFO - Installing collected packages: jsonschema, functools32 05:02:00 INFO - Running setup.py install for functools32 05:02:00 INFO - warning: no files found matching '*.txt' 05:02:00 INFO - no previously-included directories found matching 'build' 05:02:00 INFO - no previously-included directories found matching 'dist' 05:02:00 INFO - no previously-included directories found matching '.git*' 05:02:00 INFO - Successfully installed jsonschema functools32 05:02:00 INFO - Cleaning up... 05:02:00 INFO - Return code: 0 05:02:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:00 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:00 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 05:02:00 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 05:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:00 INFO - 'HOME': '/Users/cltbld', 05:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:00 INFO - 'LOGNAME': 'cltbld', 05:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:00 INFO - 'MOZ_NO_REMOTE': '1', 05:02:00 INFO - 'NO_EM_RESTART': '1', 05:02:00 INFO - 'PAGER': '/bin/cat', 05:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:00 INFO - 'PWD': '/builds/slave/test', 05:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:00 INFO - 'SHELL': '/bin/bash', 05:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:00 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:00 INFO - 'USER': 'cltbld', 05:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:00 INFO - 'XPC_FLAGS': '0x0', 05:02:00 INFO - 'XPC_SERVICE_NAME': '0', 05:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:02:01 INFO - Cleaning up... 05:02:01 INFO - Return code: 0 05:02:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:01 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')]} 05:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:01 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')]} 05:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:01 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:01 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 05:02:01 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 05:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:01 INFO - 'HOME': '/Users/cltbld', 05:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:01 INFO - 'LOGNAME': 'cltbld', 05:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:01 INFO - 'MOZ_NO_REMOTE': '1', 05:02:01 INFO - 'NO_EM_RESTART': '1', 05:02:01 INFO - 'PAGER': '/bin/cat', 05:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:01 INFO - 'PWD': '/builds/slave/test', 05:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:01 INFO - 'SHELL': '/bin/bash', 05:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:01 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:01 INFO - 'USER': 'cltbld', 05:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:01 INFO - 'XPC_FLAGS': '0x0', 05:02:01 INFO - 'XPC_SERVICE_NAME': '0', 05:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:01 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 05:02:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:04 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 05:02:05 INFO - Downloading docopt-0.6.1.tar.gz 05:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:05 INFO - Running setup.py install for blobuploader 05:02:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:05 INFO - Running setup.py install for requests 05:02:06 INFO - Running setup.py install for docopt 05:02:06 INFO - Successfully installed blobuploader requests docopt 05:02:06 INFO - Cleaning up... 05:02:06 INFO - Return code: 0 05:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:06 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:06 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 05:02:06 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 05:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:06 INFO - 'HOME': '/Users/cltbld', 05:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:06 INFO - 'LOGNAME': 'cltbld', 05:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:06 INFO - 'MOZ_NO_REMOTE': '1', 05:02:06 INFO - 'NO_EM_RESTART': '1', 05:02:06 INFO - 'PAGER': '/bin/cat', 05:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:06 INFO - 'PWD': '/builds/slave/test', 05:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:06 INFO - 'SHELL': '/bin/bash', 05:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:06 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:06 INFO - 'USER': 'cltbld', 05:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:06 INFO - 'XPC_FLAGS': '0x0', 05:02:06 INFO - 'XPC_SERVICE_NAME': '0', 05:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-oTe5Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-aHEqLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-uPZV0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SpuMt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:06 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-rtEFhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-up6D0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-6tF96B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-t2jkvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-W_6VSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-0M_76I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-nquPDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7emKMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-qAI5d4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:07 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-sfAe1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:08 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-44cIju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:08 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jR3BFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:08 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-XkZKY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:08 INFO - Running setup.py install for manifestparser 05:02:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Running setup.py install for mozcrash 05:02:08 INFO - Running setup.py install for mozdebug 05:02:08 INFO - Running setup.py install for mozdevice 05:02:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:02:08 INFO - Running setup.py install for mozfile 05:02:09 INFO - Running setup.py install for mozhttpd 05:02:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozinfo 05:02:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozInstall 05:02:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozleak 05:02:09 INFO - Running setup.py install for mozlog 05:02:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for moznetwork 05:02:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:02:09 INFO - Running setup.py install for mozprocess 05:02:10 INFO - Running setup.py install for mozprofile 05:02:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Running setup.py install for mozrunner 05:02:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Running setup.py install for mozscreenshot 05:02:10 INFO - Running setup.py install for moztest 05:02:10 INFO - Running setup.py install for mozversion 05:02:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:02:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:02:10 INFO - Cleaning up... 05:02:10 INFO - Return code: 0 05:02:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:10 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:10 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 05:02:10 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 05:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:10 INFO - 'HOME': '/Users/cltbld', 05:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:10 INFO - 'LOGNAME': 'cltbld', 05:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:10 INFO - 'MOZ_NO_REMOTE': '1', 05:02:10 INFO - 'NO_EM_RESTART': '1', 05:02:10 INFO - 'PAGER': '/bin/cat', 05:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:10 INFO - 'PWD': '/builds/slave/test', 05:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:10 INFO - 'SHELL': '/bin/bash', 05:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:10 INFO - 'USER': 'cltbld', 05:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:10 INFO - 'XPC_FLAGS': '0x0', 05:02:10 INFO - 'XPC_SERVICE_NAME': '0', 05:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MAGOhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EHcrs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-RPahLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-L_JpND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-h8f4zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-UaKqah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-AYeUmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:11 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)) 05:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:11 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-fE0X4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-7hrP_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-WRDihY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4YsE2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-KYBdvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-znYURp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ffXbWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-CtuV3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-qkTXUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:12 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)) 05:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:12 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-RoHAhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:13 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)) 05:02:13 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)) 05:02:13 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)) 05:02:13 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)) 05:02:13 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)) 05:02:13 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)) 05:02:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02:13 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 05:02:13 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 05:02:13 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 05:02:13 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 05:02:13 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 05:02:13 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 05:02:15 INFO - Downloading blessings-1.6.tar.gz 05:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:02:15 INFO - Installing collected packages: blessings 05:02:15 INFO - Running setup.py install for blessings 05:02:15 INFO - Successfully installed blessings 05:02:15 INFO - Cleaning up... 05:02:15 INFO - Return code: 0 05:02:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:16 INFO - Reading from file tmpfile_stdout 05:02:16 INFO - Current package versions: 05:02:16 INFO - blessings == 1.6 05:02:16 INFO - blobuploader == 1.2.4 05:02:16 INFO - docopt == 0.6.1 05:02:16 INFO - functools32 == 3.2.3-2 05:02:16 INFO - jsonschema == 2.5.1 05:02:16 INFO - manifestparser == 1.1 05:02:16 INFO - mozInstall == 1.12 05:02:16 INFO - mozcrash == 1.0 05:02:16 INFO - mozdebug == 0.1 05:02:16 INFO - mozdevice == 0.48 05:02:16 INFO - mozfile == 1.2 05:02:16 INFO - mozhttpd == 0.7 05:02:16 INFO - mozinfo == 0.9 05:02:16 INFO - mozleak == 0.1 05:02:16 INFO - mozlog == 3.4 05:02:16 INFO - moznetwork == 0.27 05:02:16 INFO - mozprocess == 0.23 05:02:16 INFO - mozprofile == 0.28 05:02:16 INFO - mozrunner == 6.13 05:02:16 INFO - mozscreenshot == 0.1 05:02:16 INFO - mozsystemmonitor == 0.3 05:02:16 INFO - moztest == 0.7 05:02:16 INFO - mozversion == 1.4 05:02:16 INFO - psutil == 3.1.1 05:02:16 INFO - requests == 1.2.3 05:02:16 INFO - wsgiref == 0.1.2 05:02:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:16 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 05:02:16 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 05:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:16 INFO - 'HOME': '/Users/cltbld', 05:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:16 INFO - 'LOGNAME': 'cltbld', 05:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:16 INFO - 'MOZ_NO_REMOTE': '1', 05:02:16 INFO - 'NO_EM_RESTART': '1', 05:02:16 INFO - 'PAGER': '/bin/cat', 05:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:16 INFO - 'PWD': '/builds/slave/test', 05:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:16 INFO - 'SHELL': '/bin/bash', 05:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:16 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:16 INFO - 'USER': 'cltbld', 05:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:16 INFO - 'XPC_FLAGS': '0x0', 05:02:16 INFO - 'XPC_SERVICE_NAME': '0', 05:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 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)) 05:02:16 INFO - Cleaning up... 05:02:16 INFO - Return code: 0 05:02:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:16 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 0x103a52490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039c8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d0bd7b5f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, '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.StvSZRo9oV/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.FtTp0xbaOl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 05:02:16 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 05:02:16 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 05:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:16 INFO - 'HOME': '/Users/cltbld', 05:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:16 INFO - 'LOGNAME': 'cltbld', 05:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:16 INFO - 'MOZ_NO_REMOTE': '1', 05:02:16 INFO - 'NO_EM_RESTART': '1', 05:02:16 INFO - 'PAGER': '/bin/cat', 05:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:16 INFO - 'PWD': '/builds/slave/test', 05:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:16 INFO - 'SHELL': '/bin/bash', 05:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:16 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:16 INFO - 'USER': 'cltbld', 05:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:16 INFO - 'XPC_FLAGS': '0x0', 05:02:16 INFO - 'XPC_SERVICE_NAME': '0', 05:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:02:16 INFO - Cleaning up... 05:02:16 INFO - Return code: 0 05:02:16 INFO - Running post-action listener: _resource_record_post_action 05:02:16 INFO - Running post-action listener: _start_resource_monitoring 05:02:16 INFO - Starting resource monitoring. 05:02:16 INFO - [mozharness: 2016-12-12 13:02:16.863213Z] Finished create-virtualenv step (success) 05:02:16 INFO - [mozharness: 2016-12-12 13:02:16.864200Z] Running install step. 05:02:16 INFO - Running pre-action listener: _resource_record_pre_action 05:02:16 INFO - Running main action method: install 05:02:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:17 INFO - Reading from file tmpfile_stdout 05:02:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:02:17 INFO - Reading from file tmpfile_stdout 05:02:17 INFO - Output received: 05:02:17 INFO - Usage: mozinstall [options] installer 05:02:17 INFO - Options: 05:02:17 INFO - -h, --help show this help message and exit 05:02:17 INFO - -d DEST, --destination=DEST 05:02:17 INFO - Directory to install application into. [default: 05:02:17 INFO - "/builds/slave/test"] 05:02:17 INFO - --app=APP Application being installed. [default: firefox] 05:02:17 INFO - mkdir: /builds/slave/test/build/application 05:02:17 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'] 05:02:17 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 05:02:48 INFO - Reading from file tmpfile_stdout 05:02:48 INFO - Output received: 05:02:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:02:48 INFO - Running post-action listener: _resource_record_post_action 05:02:48 INFO - [mozharness: 2016-12-12 13:02:48.028819Z] Finished install step (success) 05:02:48 INFO - [mozharness: 2016-12-12 13:02:48.028987Z] Running run-tests step. 05:02:48 INFO - Running pre-action listener: _resource_record_pre_action 05:02:48 INFO - Running main action method: run_tests 05:02:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:48 INFO - 2016-12-12 05:02:48.043 screenresolution[1924:18406] starting screenresolution argv=screenresolution get 05:02:48 INFO - 2016-12-12 05:02:48.046 screenresolution[1924:18406] Display 0: 1600x1200x32@60 05:02:48 INFO - 2016-12-12 05:02:48.052 screenresolution[1925:18409] starting screenresolution argv=screenresolution list 05:02:48 INFO - Available Modes on Display 0 05:02:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:48 INFO - Intel Iris: 05:02:48 INFO - Chipset Model: Intel Iris 05:02:48 INFO - Type: GPU 05:02:48 INFO - Bus: Built-In 05:02:48 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:48 INFO - Vendor: Intel (0x8086) 05:02:48 INFO - Device ID: 0x0a2e 05:02:48 INFO - Revision ID: 0x0009 05:02:48 INFO - Displays: 05:02:48 INFO - Display: 05:02:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:48 INFO - Display Serial Number: 1600x1200 60 05:02:48 INFO - Main Display: Yes 05:02:48 INFO - Mirror: Off 05:02:48 INFO - Online: Yes 05:02:48 INFO - Rotation: Supported 05:02:48 INFO - Return code: 0 05:02:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:02:48 INFO - Python 2.7.3 05:02:48 INFO - Return code: 0 05:02:48 INFO - grabbing minidump binary from tooltool 05:02: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')]} 05:02:48 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 0x103866d30>, '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 0x1036ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:02:48 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 05:02:48 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 05:02:48 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 05:02:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:02:48 INFO - Return code: 0 05:02:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:02:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:02:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:02:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:02:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:02:48 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/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 05:02:48 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/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 05:02:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.StvSZRo9oV/Render', 05:02:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:48 INFO - 'HOME': '/Users/cltbld', 05:02:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:48 INFO - 'LOGNAME': 'cltbld', 05:02:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:02:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:48 INFO - 'MOZ_NO_REMOTE': '1', 05:02:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:02:48 INFO - 'NO_EM_RESTART': '1', 05:02:48 INFO - 'PAGER': '/bin/cat', 05:02:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:48 INFO - 'PWD': '/builds/slave/test', 05:02:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:02:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:48 INFO - 'SHELL': '/bin/bash', 05:02:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FtTp0xbaOl/Listeners', 05:02:48 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 05:02:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:48 INFO - 'USER': 'cltbld', 05:02:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:48 INFO - 'XPC_FLAGS': '0x0', 05:02:48 INFO - 'XPC_SERVICE_NAME': '0', 05:02:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:48 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/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 05:02:48 INFO - mozversion application_buildid: 20161212024950 05:02:48 INFO - mozversion application_changeset: b54a7014e03d92571b341b9ce82772305685a58d 05:02:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:02:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:02:48 INFO - mozversion application_name: Firefox 05:02:48 INFO - mozversion application_remotingname: firefox-dev 05:02:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:02:48 INFO - mozversion application_vendor: Mozilla 05:02:48 INFO - mozversion application_version: 52.0a2 05:02:48 INFO - mozversion platform_buildid: 20161212024950 05:02:48 INFO - mozversion platform_changeset: b54a7014e03d92571b341b9ce82772305685a58d 05:02:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:02:48 INFO - mozversion platform_version: 52.0a2 05:02:48 INFO - using testdate: 1481547768 05:02:48 INFO - actual date: 1481547768 05:02:48 INFO - starting webserver on 'localhost:52425' 05:02:48 INFO - SUITE-START | Running 2 tests 05:02:48 INFO - TEST-START | dromaeo_css 05:02:48 INFO - operating with platform_type : mac_ 05:02:48 INFO - Initialising browser for dromaeo_css test... 05:02:48 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpUwSgt_/profile http://localhost:52425/getInfo.html) 05:02:51 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 05:02:51 INFO - PROCESS | 1935 | colorDepth:24 05:02:51 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 05:02:51 INFO - PROCESS | 1935 | __metrics 05:02:51 INFO - PROCESS | 1935 | 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" 05:02:51 INFO - PROCESS | 1935 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:02:52 INFO - TEST-INFO | 1935: exit 0 05:02:52 INFO - Browser initialized. 05:02:52 INFO - Running cycle 1/1 for dromaeo_css test... 05:02:52 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpUwSgt_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:05 INFO - PROCESS | 1937 | RSS: Main: 222732288 05:03:05 INFO - PROCESS | 1937 | 05:03:59 INFO - PROCESS | 1937 | 1481547839926 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] 05:06:10 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52425/tests/dromaeo/cssquery-ext.html) 05:06:11 INFO - PROCESS | 1937 | RSS: Main: 351793152 05:06:11 INFO - PROCESS | 1937 | 05:09:14 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-ext.html (next: http://localhost:52425/tests/dromaeo/cssquery-jquery.html) 05:09:15 INFO - PROCESS | 1937 | RSS: Main: 346234880 05:09:15 INFO - PROCESS | 1937 | 05:12:17 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52425/tests/dromaeo/cssquery-mootools.html) 05:12:18 INFO - PROCESS | 1937 | RSS: Main: 343339008 05:12:18 INFO - PROCESS | 1937 | 05:15:20 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52425/tests/dromaeo/cssquery-prototype.html) 05:15:21 INFO - PROCESS | 1937 | RSS: Main: 319406080 05:15:21 INFO - PROCESS | 1937 | 05:18:24 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52425/tests/dromaeo/cssquery-yui.html) 05:18:25 INFO - PROCESS | 1937 | RSS: Main: 305684480 05:18:25 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52425/tests/dromaeo/cssquery-yui.html (next: http://localhost:52425/tests/dromaeo/cssquery-dojo.html) 05:21:28 INFO - PROCESS | 1937 | RSS: Main: 385617920 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | __start_tp_report 05:21:28 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 05:21:28 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 05:21:28 INFO - PROCESS | 1937 | |i|pagename|runs| 05:21:28 INFO - PROCESS | 1937 | |0;dojo.html;2736.6031925370817;2771.1963530576136;3296.9010929672118;3305.8016519008847;3323.451630480971;5714.657120572763;5767.682777447239;5818.1272809078655;5828.0675091985295;5851.970740146293;5760.135979603051;5790.449907258814;5859.882802343951;5880.470757631812;5882.6764527950945;5235.65968212065;5237.764300606482;5250.343707036611;5277.47225277473;5320.707361095138;5166.328377310944;5191.506806853343;5209.765560549766;5286.65636733613;5352.812651557197;5304.9655317213155;5307.893842123155;5324.733763311839;5326.174442961347;5335.866603334909;5226.346706661668;5289.233061206142;5289.994900968823;5295.073362161606;5335.439778823245;5236.266922630834;5244.79020839166;5258.07983602868;5301.761420736076;5359.38367087786;5892.823215303548;5928.555358348098;5957.255343082114;5986.371430999726;5988.31134419543;6121.785737499166;6124.594542075431;6146.524834039843;6163.537734669872;6220.315765265817;6105.793350785086;6159.414855588712;6165.7533698652;6198.876022479525;6208.348123447018;5913.319968203712;5915.08316210988;5929.318128415244;5932.139839723216;5943.881122377528;6151;6158.692065396713;6195.132681424604;6303.495720342362;6308.779192728232;5555.361133469622;5967.671778052249;6219.844503887439;6227.294246629974;6258.186435763413;6295.937040629627;6300.653464059521;6306.9684651576445;6312.810615681445;6316.557840951089;6092.81190167913;6113.993245360534;6155.922713525205;6261.812145635638;6261.843453913689;6157.768446310756;6249.500039996789;6275.058741188767;6290.087937249074;6315.021171718391;4953.90092198154;4958.206686930074;4965.428975667773;4984.800607975713;4995.925061124013;4395.53846846083;4443.533428989974;4453.19842428366;4474.105178964221;4490.887727806766;5753.3958934311295;5793.449622285876;5818.738156782955;5904.380040095728;5906.645601263852;6191.597546159485;6276;6291.779787707408;6297.96851015742;6309.400606942333;5742.712864356766;5882.411758824169;5883.970305196659;5899.144624029492;5908;5256.26412302278;5387.326584176978;5408.513233808976;5423.728813559306;5432.076546987022;6211.875762484816;6220.2535695715;6263.530235232284;6361.904571431525;6384.106225128578;6314.715837787218;6316.494680425647;6327.335629758809;6329.019002054689;6342.904856427065;6272.843178920563;6323.557350985387;6343.080253363144;6350.587211831216;6350.841228969312;5926.733297001731;6039.818805435844;6130.887362651087;6175.938240617536;6218.22272215973;5566.081596536526;6296.496280297657;6308.369163083655;6326.114343991753;6328.367163283635;4851.199552073868;5976.581639285208;6151.292601350902;6177.536684748572;6228.657423841732;5962;6032.366601506778;6040.214772079602;6113.51091912637;6228.096925945803;6007.189029481139;6046.697665116814;6050.818475445743;6051.243594550681;6115.724792384264;6151.72317245716;6172;6172.722227499863;6182.319944805978;6185.536084793568;5777.2200752897825;5876.236089308533;5880.441358070976;5978.312494063239;5990.520758339409 05:21:28 INFO - PROCESS | 1937 | |1;ext.html;455.79944824277317;501.2331133365951;510.2983397827987;513.2404042017813;521.7782442461954;29689.554656680168;31601.68398316019;38675.80662096693;39948;40121.197576048464;41484.37773433393;41790.58209417905;41794.37308440376;41846.58153418465;41918.37122443158;9154.588043538024;9350.25197984163;9359.48522831244;9370.578323975438;9371.76570585734;9250.814983700322;9353.579088941015;9360;9367.531623418836;9377.296702747288;9317.580708868118;9382.967873533906;9383.765405864873;9400.576974036185;9405.388649737744;9316.394434361744;9371.109744574254;9373.06269373064;9378;9385.671501497483;9233.215176709988;9293.721188364292;9309.34834561581;9314.906850931471;9316.906830931672;39022.219555609016;39360.60639393598;39440.80279598584;39445.21109577821;39457.40813887794;2765.5575107982627;3229.3702727968057;3258.5112233164978;3806.6764325032127;3876.7197140632356;28377.432451350858;28596.857015714995;30154.84922575395;30649;30996.53505197424;3146.606674165729;3158.4946408574747;3162.762792790527;3229.8223597702117;3260.543523906655;496158.5192074053;496428;496543;496693;510605;27479;32594.83702581519;33386.49920251198;34465.483017754756;60827;35066.474002889976;35101.771437999545;35188.29623407518;35198.472022919676;35234.11914702153;13956.511522096678;14073.507427239998;14119.364628591738;14119.85880141206;14129.646758831112;11783.646490605295;11886.821697674543;11900.464479098462;11903.226290291102;12075.879241207476;7650.770476885702;7689.115751688516;7738.226177382329;7748.883766743504;7763.767086987399;3159.873605055818;3204.327091310804;3210.2134976930333;3212.1166679163416;3232.7413806895393;890.9331800115017;895.3195571365678;898.0929261445987;917.4128557723062;925.1627277314042;28300.575491367646;29166.708332916824;29199.85400072986;29893.850530747208;30209.69790302069;863.6070587882498;939.6523286384082;945.5839430650492;1014.4369874719602;1035.5857656937285;14499;15239.695206095816;15355.769663455058;15407.768883466757;15612.92193539025;671428;676790;677452;678045;678841;57674.711626441596;58288.12567811486;58330;58347.4165258342;58349.416505835245;1185.8161984892356;1240.838690970168;1242.19257482637;1339.2767905331011;1341.1014620204487;26719.866400667874;30013.699863001963;30527.23681907881;31008;31339;46136.53863461457;56353.18234088361;56437.43562564486;56682.43317566771;56924.71537642285;585678;587895;591374;591897;592762;23260.418489537897;23869.403264917823;26553.203403897915;26871.865640672455;26909.596356055066;43597.782011089745;46042.30936535887;46625.53374466212;48761.75619121882;49297.260541092626;37375.62624373721;38067.778655019865;38302.80848595851;38468;38954;38435.61564384432;39408.40887386749;39577.406338904366;41052.79473602613;41850;30947.84526077445;38665.03337416587;39810;39847;39881.0029749247;39925.60074399219;39973;40092.1981560373;40950.59049409587;41242;29280.7071929278;29803;30968.690313097482;31084.844575776977;31682.683173168894 05:21:28 INFO - PROCESS | 1937 | |2;jquery.html;350.84212104552955;363.19733389209847;369.77443759306834;384.1050352678259;398.8105649816338;14361;14516.564503064912;15363.078215307076;19388.4183474496;20041.09815058322;18984.145713442893;19086.713699294487;19594.90202548989;19873.701894471593;20357.694634580494;5323.920141197876;5615.80344687936;5616.971915140419;5617.41017193195;5633.971830140844;5014.272930425086;5135.743212839362;5520;5584.357798853143;5590.077637189859;4738.265568836819;4865.878353041185;5457.263269458612;5534.557235421177;5545.667259964415;5257.316548848645;5456.0997435423305;5495.065838807413;5496.7526461308935;5504.587155963319;4873.074115917964;4874.683145595561;4875.122477953967;4876.536729010738;4880.585150262232;23781.167659131846;24166.516669666686;24573.89417476218;24857.1300004499;24941.376465588324;2309.561183375153;2349.635806450003;2360.3273067175774;3031.969680303213;3097.7831551791382;9757.316987810857;10288.382697038202;10303.27877048607;10396.636117735843;14104.71790564183;1458.2125652147934;1839.705647096458;1886.2737845929278;1898.1932678611536;2040.897955102239;112616.43691781489;119251;119651;119707;120304;11414.828777568344;11529.365884876244;11597.247806908437;11637.709057273467;11789.056875449944;14404.279786010658;14431.134131952116;14433.061850979653;14442.422303107967;14450.855491445162;7850.725224617111;8008.159143289987;8036.196380361917;8159.796005099801;8162.591870406456;7671;7690.577018264049;7801.024871891013;7815.53106813593;7838.647260873275;3859.3439115350457;4445.399871017371;4464.9107017860115;4519.570640789119;4521.050579378368;1837.7886543047453;1875.9349755659773;1893.7443445134732;1900.3443811885154;1940.6118776244807;309.8899890538871;384.8903062627194;706.8019706597269;811.2982270336234;816.6036078306045;11068.721201010816;11754.412279385997;12071.155019148751;12174.573889913987;12199.817002744965;419.1616766467066;429.9978500107479;439.52970321755413;446.0298850001299;447.92161371760466;5833.125031245348;6172.259328880563;6213.285472170758;6271.27880293763;6407.391297826699;554621;557584;559256;565453;573990;89293.55353223192;133888;136114;136879;138593;386.7234927027189;419.28093319956423;446.2057537583107;456.2289730355688;456.9520200378979;7438.256174382518;11081.058110060412;11503.309801411608;11514.30914145154;11824.586139485076;47852.5214747862;49412.01175976532;57132.42867571271;57625.135622966525;57890.710546447;76949.2305076942;77428;78049.04877377884;81261.28017838296;82728.58635706783;6081.935661259174;7505.624718763931;7974.282314591715;8364.623591938256;8533.701320453754;11655.708607284885;12713.87286127127;12777.808332874829;12998.480060797463;13006.804897926728;5515.22786809855;6664.600123992576;9895.554700038372;10337.74155646115;11472.024877885473;10999.560017599206;11025.889741102486;11177.453547823918;11407.37259450705;11449.713757155805;9901.851472228067;9929.60613191182;11219.49512271966;11386.886131138583;11525.88474115248;7994.480358776661;8018.077921038922;8209.79475513117;8252.917470825216;8429.62066707012;8281.917180828355;11095.72260693489;11113.555457781922;11170.27393219438;11521.94239028833 05:21:28 INFO - PROCESS | 1937 | |3;mootools.html;1327.8978447888253;1471.2570152073204;1511.79590755248;1747.7815273090864;1794.2284817528457;7998.7600371988865;8402.49585024899;8411.327093832486;8416.83166336673;8435.662573497053;7387.926120738791;7614.695412183506;7615.657295421705;7958.76123716288;8156.673733050686;2286.622707253305;2328.639060945556;2330.2310237621627;2334.171369163948;2339.1345202275097;2305.976940230593;2332.3119679694446;2342.590046741822;2347.753485883983;2356.045801450415;2293.5871543122316;2305.319930620471;2321.4892723600865;2328.3829785106877;2336.8013718833918;2296.0471404367167;2299.8965046572775;2319.4549280918973;2323.268170526279;2324.628059510487;2311.63013917774;2333.871637059961;2334.2647066174272;2335.649652552114;2344.5310937812505;7712.3444507216955;7719.726245169541;8139.633716482773;8331.000279966382;8332.791680207984;4983.7757300921185;5168.431472538018;5169.276301317781;5176.327077479941;5181.663191892515;3277.705006549398;3305.206750379916;3481.529993450877;3553.1117220694828;3557.3596752584785;996.2577879479834;1002.4185972136216;1005.0602686488083;1088.4231357380602;1093.9398333091676;10406.115480184193;10734.570617175305;10744.785104297871;10784.946075269574;10807;3729.179580492287;4222.451081359403;4367.170237654836;4488.214562451558;4494.10117976406;8256.711015114442;8260.339172866155;8263.95868020656;8265.090840007595;8270.586470676562;4941.332920055818;4947.307376967227;4950.579200767903;4951.480094590016;4951.678140920829;4210.810513526899;4259.637930775857;4639.025804581008;4659.370984917061;4697.436307643105;3594.8022858742497;3609.8628931886374;3664.303782281358;3692.2061756721896;3695.2609478104487;1128.6614015795228;1136.6760473265244;1142.0635079234949;1152.9538818447168;1156.8322593224104;845.8350621628723;848.898132224137;866.2939704141105;890.8619164029586;902.120432578231;5359.598030147755;5375.81184658535;5464.792110075299;5487.259220005328;5655.349634792052;404.74501064329286;428.7598944591039;432.59336223949384;439.8064851465345;455.949037468638;3278.8196649184047;3294.225856923621;3348.68187522185;3435.553378060886;3446.7070299024363;9753.804923901624;10099.7475063124;10197.898021019695;10222.284440089272;10258.17934565233;10129.139023182965;10132.797344053077;10145.64490242838;10148;10168.542415591317;7562.205968373241;7661.540307581563;7673.577953212627;7849.803754906059;7876.960615196887;5317.867053323698;5449.12813949782;5535.557155427637;5644.604877658525;5661.0659241226385;9190.908090919005;9270.397424167408;9339.626414943597;9345.392549484262;9497.952510237681;9411.435313880915;9431.764205894908;9467.621295148117;9506.239500839778;9610.567524461556;6211.472024877936;6428.614283143026;6431.935680643321;6650.767223147167;6749.358810912955;7447.069116360455;7488;7598;7893.052833659997;7895.289423951872;7007.369336759716;7100.289971002859;7109.715611375487;7155.928440715735;7290.307420795016;6985.126859142607;7007.859842803217;7034.226235114235;7045.506814522934;7141.535800172972;6593.307702691149;6623.768168114093;6706.463482921257;6713.6643167842385;6728.899066513908;4594.287885377638;4617.63058955276;4676;4709.0817290628;4711.293306004127;4728.432588089451;4773.04539092176;4913.9017219656125;4929.7535123244415;5041.495850414929 05:21:28 INFO - PROCESS | 1937 | |4;prototype.html;297.474956701422;297.60567248395876;307.2472442515836;311.6135991370701;311.80823793367085;5321.467853214676;5343.839684809457;5346.198070289459;5377.247185394038;5429.945700542999;4978.241427722065;5068.949310506894;5162.432132465435;5181.637285390024;5335.679859208451;3533.717302615785;3698.6486283802997;3769;3879.1078052048047;3992.7205095643317;4057.2696914555368;4068.4914385701786;4080.204360149772;4100.79496025199;4109.91780164398;3869.4776205212356;3889.2804831106337;4013.5986401359924;4021.2158629067344;4097.627294856226;4035.7982100895133;4052.918941621151;4054.8241010107035;4085.346344584867;4088.325426304656;2945.7496112830436;3520.507129001942;3528.417811061172;3530.135116896351;3538.274653695986;4389.978050109729;4561.885952851205;4787.257975013878;5152.974235128838;5173.679977219027;4177.657873701058;4268.786560671954;4675.7895894684825;4717.410323709536;4721.905561888777;4673.509281525459;5257.815976440845;5551.694656793875;5604.243427137366;5621;3172.4448221561365;3972.364421692544;3990.60328884888;3992.6007399260134;3992.800359982018;4252.936205956913;5108.164968442513;5816.331121920991;5870.735816888207;5873.239577398615;3644.817759112034;3842.1163132479687;3845.673117784964;3847.53793558446;3849.480320156799;3897.395903634941;3904.6095390460728;3914.236723838881;3918.3534716771983;3923.391874259494;4420.270655341897;4451.265541185733;4532.592066714012;4594.95405045952;4598.642990616869;5589.329280486287;5595.38450770415;5605.299337582802;5623.493885550321;5640;1258.5028913579085;1302.1796268351068;1318.3586434888625;1319.5843309357522;1320.6001638263278;761.432732614195;826.0912995704797;849.7663142635824;875.1336177184542;914.5641343091415;4204.91590168201;4623.029163875556;4675.696079754805;4930.383702037246;4934.4572097069295;4598.160735705677;4706.164595807638;4774.904501909942;5195;5349;4272.9359059614135;4725.9054818904115;4793;4879.731614761222;4888.291197776302;2609.830361026527;2640.4851054044275;2821.5908693239367;2828.2081017315186;2849.5155823510054;4838;5326.121190003683;5588.379369982741;5616.634918730269;5842.737076831553;5247.658902171346;5435.701036443033;5507;5540.094233565327;5589;5092.472258322488;5143.897122057538;5520.475554822286;5549.752350298136;5593.615862403269;4578.40480737502;5017.397912250553;5205.947940520698;5225.145073499005;5358.973205134105;4783.473817879961;4818.180909245367;5254.579633629377;5329.946700532945;5335.759890804846;4389.7489215573405;5076.847694569169;5580.972095139498;5583.720813959205;5603.814512340109;3530.2939412117166;3745.8314375853697;3749.075720937673;3775.981120094382;3802.7147963902266;5690.800821971211;5695.886082278248;5716.456936591017;5723.399043100415;5756.539476841759;5542.861428464321;5562.860928476821;5566.610337276352;5617.915731263953;5656;5307.708076055854;5580.795585743527;5601.831945041656;5653.915191272052;5684.459976302244;5022.121128802518;5114.616403769806;5195.4280233394375;5233.848553318264;5258.97370513145;4499.707519011254;4680.602148817388;4797.352357431702;5008.4240312364545;5608.588307027854;4117.547069822377;4816.759162041813;5293.77980872481;5293.988284451883;5518.889622207453 05:21:28 INFO - PROCESS | 1937 | |5;yui.html;665.2648823050532;684.1345697692417;719.8956151358053;722.4256715910852;730.9415246780258;12033.338166400845;13917.44330226791;14449.711005779891;14455.855441445583;14513.782293265609;12670.113092083559;12774.233545987225;12792.936035319834;12797.424115914782;12805.231686098841;630.7603110817882;646.4602057282151;790.4387884601939;802.8233788566506;805.9355251579859;654.921409430869;783;798.664560884427;811.5090370325956;813.7029984055836;851.2679095977454;851.4848516647401;852.9189726975958;853.8804567360575;855.0978717453085;663.7278715726529;845.8731190321439;847.5973912391627;848.825990671917;850.2730165708354;509.5134146889711;610.9725062372204;624.356912380249;628.5851338116867;629.4209327418786;13524.661883452894;13564.525241616595;13566.864331356757;13568.660783480493;13574.321283935862;12561.560345387868;12802.231866087972;12814.103012789108;12915.160514566523;13048.478060877553;11818.468168932333;11887.881121188764;11959.40202989847;12022;12075.57735479254;4143.502779666459;4158.209940111399;4191.140816132685;4352.934705979413;4450.643948484114;79136.20863791346;80942;88770.5561472195;89184;89652.10347896503;9833.26250531213;10155.7461063474;10361.948190259;10396.844047339297;10432.843507347396;18798.154083066296;18802.247910083715;18850.52873678169;19008.619827603372;19071.427857164308;7491.726406510907;7509.36170425509;7573.507721998052;7653.15815260321;7672.73145439907;4120.546739858613;4243.448351714319;4283.443152390231;4409.603135717801;4449.57729015743;3271.705546500779;3374.865005399806;3379.172102834772;3379.4930760386137;3384.0862966999266;604.7218279591438;612.3294991983771;620.2061361457341;626.1578177351455;643.1895811277824;443.1204059941015;450.7656018870235;533.9960873557686;562.7692646015114;594.5563733057131;6872.896906546406;8561.400701950804;8693.261072808755;8704.956475217583;8767;546.868409168734;558.4722437296715;592.5733471900224;602.9969850150809;619.5198720991268;4039.6162364575316;4106.609872062149;4140.668746500272;4157.210130075337;4239.0674051708575;72655;89828;93781.5310923441;95483.52258238665;98535;57826.7108664454;62195.6890215546;75396.49207015829;84267;87474.56262718646;81173;85713.57143214245;86356.13643863607;87916;88349.55825220705;8767.561621919007;9051.818963620823;9159.725208243763;9352.018038105902;9418.62325506972;44100;48996.265056023476;50240;51170.48829511657;51334.973300533034;69066.65466672635;75090;77694.611526942;79677.60161199157;80894;11993.580224692094;12052.276863388224;12057.698557536132;12183.086268529718;12539.184952978027;13908;14092.36584353727;14113.717725645634;14127.717445651235;14182.716345673236;10450.529726162189;10669.093127084285;12354.567590134302;12725;13752.243626600633;11999.1600587958;12037.638870833889;12143.210691305037;12354.073444491878;12674.936625317183;11882.405879705808;12450.564230251897;12571.622851314109;12689.23864568129;13604.65988350299;9790;10352.482375881327;10422.635207767691;11307.604233851776;11546.19176657626;10911.83632245501;10931.070858977077;10945.56217751313;11210.66368008961;11241.71895702614 05:21:28 INFO - PROCESS | 1937 | __end_tp_report 05:21:28 INFO - PROCESS | 1937 | __start_cc_report 05:21:28 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1483 05:21:28 INFO - PROCESS | 1937 | __end_cc_report 05:21:28 INFO - PROCESS | 1937 | __startTimestamp1481548888591__endTimestamp 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | ------- Summary: start ------- 05:21:28 INFO - PROCESS | 1937 | Number of tests: 6 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5769.22 Median:5976.58 stddev:668.24 (11.2%) stddev-sans-first:626.52 05:21:28 INFO - PROCESS | 1937 | Values: 2736.6 2771.2 3296.9 3305.8 3323.5 5714.7 5767.7 5818.1 5828.1 5852.0 5760.1 5790.4 5859.9 5880.5 5882.7 5235.7 5237.8 5250.3 5277.5 5320.7 5166.3 5191.5 5209.8 5286.7 5352.8 5305.0 5307.9 5324.7 5326.2 5335.9 5226.3 5289.2 5290.0 5295.1 5335.4 5236.3 5244.8 5258.1 5301.8 5359.4 5892.8 5928.6 5957.3 5986.4 5988.3 6121.8 6124.6 6146.5 6163.5 6220.3 6105.8 6159.4 6165.8 6198.9 6208.3 5913.3 5915.1 5929.3 5932.1 5943.9 6151.0 6158.7 6195.1 6303.5 6308.8 5555.4 5967.7 6219.8 6227.3 6258.2 6295.9 6300.7 6307.0 6312.8 6316.6 6092.8 6114.0 6155.9 6261.8 6261.8 6157.8 6249.5 6275.1 6290.1 6315.0 4953.9 4958.2 4965.4 4984.8 4995.9 4395.5 4443.5 4453.2 4474.1 4490.9 5753.4 5793.4 5818.7 5904.4 5906.6 6191.6 6276.0 6291.8 6298.0 6309.4 5742.7 5882.4 5884.0 5899.1 5908.0 5256.3 5387.3 5408.5 5423.7 5432.1 6211.9 6220.3 6263.5 6361.9 6384.1 6314.7 6316.5 6327.3 6329.0 6342.9 6272.8 6323.6 6343.1 6350.6 6350.8 5926.7 6039.8 6130.9 6175.9 6218.2 5566.1 6296.5 6308.4 6326.1 6328.4 4851.2 5976.6 6151.3 6177.5 6228.7 5962.0 6032.4 6040.2 6113.5 6228.1 6007.2 6046.7 6050.8 6051.2 6115.7 6151.7 6172.0 6172.7 6182.3 6185.5 5777.2 5876.2 5880.4 5978.3 5990.5 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:70167.15 Median:39864.00 stddev:159015.23 (398.9%) stddev-sans-first:159375.17 05:21:28 INFO - PROCESS | 1937 | Values: 455.8 501.2 510.3 513.2 521.8 29689.6 31601.7 38675.8 39948.0 40121.2 41484.4 41790.6 41794.4 41846.6 41918.4 9154.6 9350.3 9359.5 9370.6 9371.8 9250.8 9353.6 9360.0 9367.5 9377.3 9317.6 9383.0 9383.8 9400.6 9405.4 9316.4 9371.1 9373.1 9378.0 9385.7 9233.2 9293.7 9309.3 9314.9 9316.9 39022.2 39360.6 39440.8 39445.2 39457.4 2765.6 3229.4 3258.5 3806.7 3876.7 28377.4 28596.9 30154.8 30649.0 30996.5 3146.6 3158.5 3162.8 3229.8 3260.5 496158.5 496428.0 496543.0 496693.0 510605.0 27479.0 32594.8 33386.5 34465.5 60827.0 35066.5 35101.8 35188.3 35198.5 35234.1 13956.5 14073.5 14119.4 14119.9 14129.6 11783.6 11886.8 11900.5 11903.2 12075.9 7650.8 7689.1 7738.2 7748.9 7763.8 3159.9 3204.3 3210.2 3212.1 3232.7 890.9 895.3 898.1 917.4 925.2 28300.6 29166.7 29199.9 29893.9 30209.7 863.6 939.7 945.6 1014.4 1035.6 14499.0 15239.7 15355.8 15407.8 15612.9 671428.0 676790.0 677452.0 678045.0 678841.0 57674.7 58288.1 58330.0 58347.4 58349.4 1185.8 1240.8 1242.2 1339.3 1341.1 26719.9 30013.7 30527.2 31008.0 31339.0 46136.5 56353.2 56437.4 56682.4 56924.7 585678.0 587895.0 591374.0 591897.0 592762.0 23260.4 23869.4 26553.2 26871.9 26909.6 43597.8 46042.3 46625.5 48761.8 49297.3 37375.6 38067.8 38302.8 38468.0 38954.0 38435.6 39408.4 39577.4 41052.8 41850.0 30947.8 38665.0 39810.0 39847.0 39881.0 39925.6 39973.0 40092.2 40950.6 41242.0 29280.7 29803.0 30968.7 31084.8 31682.7 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:33109.61 Median:419.22 stddev:94453.48 (22530.7%) stddev-sans-first:94686.42 05:21:28 INFO - PROCESS | 1937 | Values: 350.8 363.2 369.8 384.1 398.8 14361.0 14516.6 15363.1 19388.4 20041.1 18984.1 19086.7 19594.9 19873.7 20357.7 5323.9 5615.8 5617.0 5617.4 5634.0 5014.3 5135.7 5520.0 5584.4 5590.1 4738.3 4865.9 5457.3 5534.6 5545.7 5257.3 5456.1 5495.1 5496.8 5504.6 4873.1 4874.7 4875.1 4876.5 4880.6 23781.2 24166.5 24573.9 24857.1 24941.4 2309.6 2349.6 2360.3 3032.0 3097.8 9757.3 10288.4 10303.3 10396.6 14104.7 1458.2 1839.7 1886.3 1898.2 2040.9 112616.4 119251.0 119651.0 119707.0 120304.0 11414.8 11529.4 11597.2 11637.7 11789.1 14404.3 14431.1 14433.1 14442.4 14450.9 7850.7 8008.2 8036.2 8159.8 8162.6 7671.0 7690.6 7801.0 7815.5 7838.6 3859.3 4445.4 4464.9 4519.6 4521.1 1837.8 1875.9 1893.7 1900.3 1940.6 309.9 384.9 706.8 811.3 816.6 11068.7 11754.4 12071.2 12174.6 12199.8 419.2 430.0 439.5 446.0 447.9 5833.1 6172.3 6213.3 6271.3 6407.4 554621.0 557584.0 559256.0 565453.0 573990.0 89293.6 133888.0 136114.0 136879.0 138593.0 386.7 419.3 446.2 456.2 457.0 7438.3 11081.1 11503.3 11514.3 11824.6 47852.5 49412.0 57132.4 57625.1 57890.7 76949.2 77428.0 78049.0 81261.3 82728.6 6081.9 7505.6 7974.3 8364.6 8533.7 11655.7 12713.9 12777.8 12998.5 13006.8 5515.2 6664.6 9895.6 10337.7 11472.0 10999.6 11025.9 11177.5 11407.4 11449.7 9901.9 9929.6 11219.5 11386.9 11525.9 7994.5 8018.1 8209.8 8252.9 8429.6 8281.9 11095.7 11113.6 11170.3 11521.9 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5315.96 Median:4921.83 stddev:2952.80 (60.0%) stddev-sans-first:2945.88 05:21:28 INFO - PROCESS | 1937 | Values: 1327.9 1471.3 1511.8 1747.8 1794.2 7998.8 8402.5 8411.3 8416.8 8435.7 7387.9 7614.7 7615.7 7958.8 8156.7 2286.6 2328.6 2330.2 2334.2 2339.1 2306.0 2332.3 2342.6 2347.8 2356.0 2293.6 2305.3 2321.5 2328.4 2336.8 2296.0 2299.9 2319.5 2323.3 2324.6 2311.6 2333.9 2334.3 2335.6 2344.5 7712.3 7719.7 8139.6 8331.0 8332.8 4983.8 5168.4 5169.3 5176.3 5181.7 3277.7 3305.2 3481.5 3553.1 3557.4 996.3 1002.4 1005.1 1088.4 1093.9 10406.1 10734.6 10744.8 10784.9 10807.0 3729.2 4222.5 4367.2 4488.2 4494.1 8256.7 8260.3 8264.0 8265.1 8270.6 4941.3 4947.3 4950.6 4951.5 4951.7 4210.8 4259.6 4639.0 4659.4 4697.4 3594.8 3609.9 3664.3 3692.2 3695.3 1128.7 1136.7 1142.1 1153.0 1156.8 845.8 848.9 866.3 890.9 902.1 5359.6 5375.8 5464.8 5487.3 5655.3 404.7 428.8 432.6 439.8 455.9 3278.8 3294.2 3348.7 3435.6 3446.7 9753.8 10099.7 10197.9 10222.3 10258.2 10129.1 10132.8 10145.6 10148.0 10168.5 7562.2 7661.5 7673.6 7849.8 7877.0 5317.9 5449.1 5535.6 5644.6 5661.1 9190.9 9270.4 9339.6 9345.4 9498.0 9411.4 9431.8 9467.6 9506.2 9610.6 6211.5 6428.6 6431.9 6650.8 6749.4 7447.1 7488.0 7598.0 7893.1 7895.3 7007.4 7100.3 7109.7 7155.9 7290.3 6985.1 7007.9 7034.2 7045.5 7141.5 6593.3 6623.8 6706.5 6713.7 6728.9 4594.3 4617.6 4676.0 4709.1 4711.3 4728.4 4773.0 4913.9 4929.8 5041.5 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:4401.63 Median:4807.06 stddev:1326.47 (27.6%) stddev-sans-first:1293.93 05:21:28 INFO - PROCESS | 1937 | Values: 297.5 297.6 307.2 311.6 311.8 5321.5 5343.8 5346.2 5377.2 5429.9 4978.2 5068.9 5162.4 5181.6 5335.7 3533.7 3698.6 3769.0 3879.1 3992.7 4057.3 4068.5 4080.2 4100.8 4109.9 3869.5 3889.3 4013.6 4021.2 4097.6 4035.8 4052.9 4054.8 4085.3 4088.3 2945.7 3520.5 3528.4 3530.1 3538.3 4390.0 4561.9 4787.3 5153.0 5173.7 4177.7 4268.8 4675.8 4717.4 4721.9 4673.5 5257.8 5551.7 5604.2 5621.0 3172.4 3972.4 3990.6 3992.6 3992.8 4252.9 5108.2 5816.3 5870.7 5873.2 3644.8 3842.1 3845.7 3847.5 3849.5 3897.4 3904.6 3914.2 3918.4 3923.4 4420.3 4451.3 4532.6 4595.0 4598.6 5589.3 5595.4 5605.3 5623.5 5640.0 1258.5 1302.2 1318.4 1319.6 1320.6 761.4 826.1 849.8 875.1 914.6 4204.9 4623.0 4675.7 4930.4 4934.5 4598.2 4706.2 4774.9 5195.0 5349.0 4272.9 4725.9 4793.0 4879.7 4888.3 2609.8 2640.5 2821.6 2828.2 2849.5 4838.0 5326.1 5588.4 5616.6 5842.7 5247.7 5435.7 5507.0 5540.1 5589.0 5092.5 5143.9 5520.5 5549.8 5593.6 4578.4 5017.4 5205.9 5225.1 5359.0 4783.5 4818.2 5254.6 5329.9 5335.8 4389.7 5076.8 5581.0 5583.7 5603.8 3530.3 3745.8 3749.1 3776.0 3802.7 5690.8 5695.9 5716.5 5723.4 5756.5 5542.9 5562.9 5566.6 5617.9 5656.0 5307.7 5580.8 5601.8 5653.9 5684.5 5022.1 5114.6 5195.4 5233.8 5259.0 4499.7 4680.6 4797.4 5008.4 5608.6 4117.5 4816.8 5293.8 5294.0 5518.9 05:21:28 INFO - PROCESS | 1937 | 05:21:28 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:19079.54 Median:24723.52 stddev:27084.84 (109.6%) stddev-sans-first:27125.53 05:21:28 INFO - PROCESS | 1937 | Values: 665.3 684.1 719.9 722.4 730.9 12033.3 13917.4 14449.7 14455.9 14513.8 12670.1 12774.2 12792.9 12797.4 12805.2 630.8 646.5 790.4 802.8 805.9 654.9 783.0 798.7 811.5 813.7 851.3 851.5 852.9 853.9 855.1 663.7 845.9 847.6 848.8 850.3 509.5 611.0 624.4 628.6 629.4 13524.7 13564.5 13566.9 13568.7 13574.3 12561.6 12802.2 12814.1 12915.2 13048.5 11818.5 11887.9 11959.4 12022.0 12075.6 4143.5 4158.2 4191.1 4352.9 4450.6 79136.2 80942.0 88770.6 89184.0 89652.1 9833.3 10155.7 10361.9 10396.8 10432.8 18798.2 18802.2 18850.5 19008.6 19071.4 7491.7 7509.4 7573.5 7653.2 7672.7 4120.5 4243.4 4283.4 4409.6 4449.6 3271.7 3374.9 3379.2 3379.5 3384.1 604.7 612.3 620.2 626.2 643.2 443.1 450.8 534.0 562.8 594.6 6872.9 8561.4 8693.3 8705.0 8767.0 546.9 558.5 592.6 603.0 619.5 4039.6 4106.6 4140.7 4157.2 4239.1 72655.0 89828.0 93781.5 95483.5 98535.0 57826.7 62195.7 75396.5 84267.0 87474.6 81173.0 85713.6 86356.1 87916.0 88349.6 8767.6 9051.8 9159.7 9352.0 9418.6 44100.0 48996.3 50240.0 51170.5 51335.0 69066.7 75090.0 77694.6 79677.6 80894.0 11993.6 12052.3 12057.7 12183.1 12539.2 13908.0 14092.4 14113.7 14127.7 14182.7 10450.5 10669.1 12354.6 12725.0 13752.2 11999.2 12037.6 12143.2 12354.1 12674.9 11882.4 12450.6 12571.6 12689.2 13604.7 9790.0 10352.5 10422.6 11307.6 11546.2 10911.8 10931.1 10945.6 11210.7 11241.7 05:21:28 INFO - PROCESS | 1937 | -------- Summary: end -------- 05:21:28 INFO - PROCESS | 1937 | 05:21:29 INFO - TEST-INFO | 1937: exit 0 05:21:29 INFO - TEST-OK | dromaeo_css | took 1120596ms 05:21:29 INFO - TEST-START | kraken 05:21:29 INFO - operating with platform_type : mac_ 05:21:29 INFO - Initialising browser for kraken test... 05:21:29 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpDkL0uo/profile http://localhost:52425/getInfo.html) 05:21:31 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 05:21:31 INFO - PROCESS | 1942 | colorDepth:24 05:21:31 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 05:21:31 INFO - PROCESS | 1942 | __metrics 05:21:31 INFO - PROCESS | 1942 | 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" 05:21:31 INFO - PROCESS | 1942 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:32 INFO - TEST-INFO | 1942: exit 0 05:21:32 INFO - Browser initialized. 05:21:32 INFO - Running cycle 1/1 for kraken test... 05:21:32 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpDkL0uo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:44 INFO - PROCESS | 1943 | RSS: Main: 222715904 05:21:44 INFO - PROCESS | 1943 | 05:21:47 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52425/tests/kraken/driver.html?testName=audio-beat-detection) 05:21:48 INFO - PROCESS | 1943 | RSS: Main: 272183296 05:21:48 INFO - PROCESS | 1943 | 05:21:51 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52425/tests/kraken/driver.html?testName=audio-dft) 05:21:51 INFO - PROCESS | 1943 | RSS: Main: 282894336 05:21:51 INFO - PROCESS | 1943 | 05:21:55 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52425/tests/kraken/driver.html?testName=audio-fft) 05:21:55 INFO - PROCESS | 1943 | RSS: Main: 380297216 05:21:55 INFO - PROCESS | 1943 | 05:21:57 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52425/tests/kraken/driver.html?testName=audio-oscillator) 05:21:58 INFO - PROCESS | 1943 | RSS: Main: 282992640 05:21:58 INFO - PROCESS | 1943 | 05:22:00 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52425/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:01 INFO - PROCESS | 1943 | RSS: Main: 284708864 05:22:01 INFO - PROCESS | 1943 | 05:22:03 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52425/tests/kraken/driver.html?testName=imaging-darkroom) 05:22:04 INFO - PROCESS | 1943 | RSS: Main: 324313088 05:22:04 INFO - PROCESS | 1943 | 05:22:07 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52425/tests/kraken/driver.html?testName=imaging-desaturate) 05:22:07 INFO - PROCESS | 1943 | RSS: Main: 325791744 05:22:07 INFO - PROCESS | 1943 | 05:22:10 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52425/tests/kraken/driver.html?testName=json-parse-financial) 05:22:11 INFO - PROCESS | 1943 | RSS: Main: 323211264 05:22:11 INFO - PROCESS | 1943 | 05:22:12 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52425/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:22:13 INFO - PROCESS | 1943 | RSS: Main: 299040768 05:22:13 INFO - PROCESS | 1943 | 05:22:15 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:22:16 INFO - PROCESS | 1943 | RSS: Main: 301965312 05:22:16 INFO - PROCESS | 1943 | 05:22:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:22:18 INFO - PROCESS | 1943 | RSS: Main: 304136192 05:22:18 INFO - PROCESS | 1943 | 05:22:21 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:22:22 INFO - PROCESS | 1943 | RSS: Main: 312356864 05:22:22 INFO - PROCESS | 1943 | 05:22:25 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:22:25 INFO - PROCESS | 1943 | RSS: Main: 312573952 05:22:25 INFO - PROCESS | 1943 | 05:22:27 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52425/tests/kraken/driver.html?testName=ai-astar) 05:22:28 INFO - PROCESS | 1943 | RSS: Main: 355942400 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | __start_tp_report 05:22:28 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 05:22:28 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 05:22:28 INFO - PROCESS | 1943 | |i|pagename|runs| 05:22:28 INFO - PROCESS | 1943 | |0;ai-astar;117;114;116;116;116;116;115;116;115;115 05:22:28 INFO - PROCESS | 1943 | |1;audio-beat-detection;145;111;118;175;140;124;111;115;160;140 05:22:28 INFO - PROCESS | 1943 | |2;audio-dft;184;183;255;229;182;183;182;183;183;184 05:22:28 INFO - PROCESS | 1943 | |3;audio-fft;81;60;59;90;61;73;86;82;99;59 05:22:28 INFO - PROCESS | 1943 | |4;audio-oscillator;90;70;96;69;69;70;89;68;69;93 05:22:28 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;87;85;100;130;87;84;88;84;87;84 05:22:28 INFO - PROCESS | 1943 | |6;imaging-darkroom;93;93;94;93;93;93;94;93;93;92 05:22:28 INFO - PROCESS | 1943 | |7;imaging-desaturate;84;84;100;122;122;85;86;83;88;66 05:22:28 INFO - PROCESS | 1943 | |8;json-parse-financial;55;55;53;53;57;59;58;59;58;59 05:22:28 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;70;65;71;83;76;72;69;70;72;72 05:22:28 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;84;84;83;83;81;80;83;83;85;84 05:22:28 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;143;141;141;140;147;164;138;139;179;150 05:22:28 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;166;165;165;165;165;166;186;226;165;166 05:22:28 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;68;67;87;70;67;68;67;68;66;67 05:22:28 INFO - PROCESS | 1943 | __end_tp_report 05:22:28 INFO - PROCESS | 1943 | __start_cc_report 05:22:28 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2003 05:22:28 INFO - PROCESS | 1943 | __end_cc_report 05:22:28 INFO - PROCESS | 1943 | __startTimestamp1481548948011__endTimestamp 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | ------- Summary: start ------- 05:22:28 INFO - PROCESS | 1943 | Number of tests: 14 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.00 stddev:0.84 (0.7%) stddev-sans-first:0.73 05:22:28 INFO - PROCESS | 1943 | Values: 117.0 114.0 116.0 116.0 116.0 116.0 115.0 116.0 115.0 115.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:140.00 stddev:21.92 (15.7%) stddev-sans-first:22.88 05:22:28 INFO - PROCESS | 1943 | Values: 145.0 111.0 118.0 175.0 140.0 124.0 111.0 115.0 160.0 140.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:194.80 Median:183.50 stddev:25.63 (14.0%) stddev-sans-first:26.88 05:22:28 INFO - PROCESS | 1943 | Values: 184.0 183.0 255.0 229.0 182.0 183.0 182.0 183.0 183.0 184.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:75.00 Median:81.50 stddev:14.70 (18.0%) stddev-sans-first:15.43 05:22:28 INFO - PROCESS | 1943 | Values: 81.0 60.0 59.0 90.0 61.0 73.0 86.0 82.0 99.0 59.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:79.50 stddev:11.94 (15.0%) stddev-sans-first:11.90 05:22:28 INFO - PROCESS | 1943 | Values: 90.0 70.0 96.0 69.0 69.0 70.0 89.0 68.0 69.0 93.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:86.00 stddev:14.29 (16.6%) stddev-sans-first:15.06 05:22:28 INFO - PROCESS | 1943 | Values: 87.0 85.0 100.0 130.0 87.0 84.0 88.0 84.0 87.0 84.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:22:28 INFO - PROCESS | 1943 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 93.0 92.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:84.00 stddev:17.80 (21.2%) stddev-sans-first:18.64 05:22:28 INFO - PROCESS | 1943 | Values: 84.0 84.0 100.0 122.0 122.0 85.0 86.0 83.0 88.0 66.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:58.00 stddev:2.41 (4.2%) stddev-sans-first:2.49 05:22:28 INFO - PROCESS | 1943 | Values: 55.0 55.0 53.0 53.0 57.0 59.0 58.0 59.0 58.0 59.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.76 (6.6%) stddev-sans-first:4.99 05:22:28 INFO - PROCESS | 1943 | Values: 70.0 65.0 71.0 83.0 76.0 72.0 69.0 70.0 72.0 72.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.49 (1.8%) stddev-sans-first:1.54 05:22:28 INFO - PROCESS | 1943 | Values: 84.0 84.0 83.0 83.0 81.0 80.0 83.0 83.0 85.0 84.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:145.00 stddev:13.29 (9.2%) stddev-sans-first:13.96 05:22:28 INFO - PROCESS | 1943 | Values: 143.0 141.0 141.0 140.0 147.0 164.0 138.0 139.0 179.0 150.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:166.00 stddev:19.56 (11.8%) stddev-sans-first:20.55 05:22:28 INFO - PROCESS | 1943 | Values: 166.0 165.0 165.0 165.0 165.0 166.0 186.0 226.0 165.0 166.0 05:22:28 INFO - PROCESS | 1943 | 05:22:28 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:68.00 stddev:6.24 (9.2%) stddev-sans-first:6.60 05:22:28 INFO - PROCESS | 1943 | Values: 68.0 67.0 87.0 70.0 67.0 68.0 67.0 68.0 66.0 67.0 05:22:28 INFO - PROCESS | 1943 | -------- Summary: end -------- 05:22:28 INFO - PROCESS | 1943 | 05:22:29 INFO - TEST-INFO | 1943: exit 0 05:22:29 INFO - TEST-OK | kraken | took 60036ms 05:22:29 INFO - SUITE-END | took 1180s 05:22:29 INFO - Completed test suite (00:19:41) 05:22:29 INFO - javascript benchmark 05:22:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2736.6031925370817, 2771.1963530576136, 3296.9010929672118, 3305.8016519008847, 3323.451630480971, 5714.657120572763, 5767.682777447239, 5818.1272809078655, 5828.0675091985295, 5851.970740146293, 5760.135979603051, 5790.449907258814, 5859.882802343951, 5880.470757631812, 5882.6764527950945, 5235.65968212065, 5237.764300606482, 5250.343707036611, 5277.47225277473, 5320.707361095138, 5166.328377310944, 5191.506806853343, 5209.765560549766, 5286.65636733613, 5352.812651557197, 5304.9655317213155, 5307.893842123155, 5324.733763311839, 5326.174442961347, 5335.866603334909, 5226.346706661668, 5289.233061206142, 5289.994900968823, 5295.073362161606, 5335.439778823245, 5236.266922630834, 5244.79020839166, 5258.07983602868, 5301.761420736076, 5359.38367087786, 5892.823215303548, 5928.555358348098, 5957.255343082114, 5986.371430999726, 5988.31134419543, 6121.785737499166, 6124.594542075431, 6146.524834039843, 6163.537734669872, 6220.315765265817, 6105.793350785086, 6159.414855588712, 6165.7533698652, 6198.876022479525, 6208.348123447018, 5913.319968203712, 5915.08316210988, 5929.318128415244, 5932.139839723216, 5943.881122377528, 6151.0, 6158.692065396713, 6195.132681424604, 6303.495720342362, 6308.779192728232, 5555.361133469622, 5967.671778052249, 6219.844503887439, 6227.294246629974, 6258.186435763413, 6295.937040629627, 6300.653464059521, 6306.9684651576445, 6312.810615681445, 6316.557840951089, 6092.81190167913, 6113.993245360534, 6155.922713525205, 6261.812145635638, 6261.843453913689, 6157.768446310756, 6249.500039996789, 6275.058741188767, 6290.087937249074, 6315.021171718391, 4953.90092198154, 4958.206686930074, 4965.428975667773, 4984.800607975713, 4995.925061124013, 4395.53846846083, 4443.533428989974, 4453.19842428366, 4474.105178964221, 4490.887727806766, 5753.3958934311295, 5793.449622285876, 5818.738156782955, 5904.380040095728, 5906.645601263852, 6191.597546159485, 6276.0, 6291.779787707408, 6297.96851015742, 6309.400606942333, 5742.712864356766, 5882.411758824169, 5883.970305196659, 5899.144624029492, 5908.0, 5256.26412302278, 5387.326584176978, 5408.513233808976, 5423.728813559306, 5432.076546987022, 6211.875762484816, 6220.2535695715, 6263.530235232284, 6361.904571431525, 6384.106225128578, 6314.715837787218, 6316.494680425647, 6327.335629758809, 6329.019002054689, 6342.904856427065, 6272.843178920563, 6323.557350985387, 6343.080253363144, 6350.587211831216, 6350.841228969312, 5926.733297001731, 6039.818805435844, 6130.887362651087, 6175.938240617536, 6218.22272215973, 5566.081596536526, 6296.496280297657, 6308.369163083655, 6326.114343991753, 6328.367163283635, 4851.199552073868, 5976.581639285208, 6151.292601350902, 6177.536684748572, 6228.657423841732, 5962.0, 6032.366601506778, 6040.214772079602, 6113.51091912637, 6228.096925945803, 6007.189029481139, 6046.697665116814, 6050.818475445743, 6051.243594550681, 6115.724792384264, 6151.72317245716, 6172.0, 6172.722227499863, 6182.319944805978, 6185.536084793568, 5777.2200752897825, 5876.236089308533, 5880.441358070976, 5978.312494063239, 5990.520758339409], "value": 5722.511084573003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.79944824277317, 501.2331133365951, 510.2983397827987, 513.2404042017813, 521.7782442461954, 29689.554656680168, 31601.68398316019, 38675.80662096693, 39948.0, 40121.197576048464, 41484.37773433393, 41790.58209417905, 41794.37308440376, 41846.58153418465, 41918.37122443158, 9154.588043538024, 9350.25197984163, 9359.48522831244, 9370.578323975438, 9371.76570585734, 9250.814983700322, 9353.579088941015, 9360.0, 9367.531623418836, 9377.296702747288, 9317.580708868118, 9382.967873533906, 9383.765405864873, 9400.576974036185, 9405.388649737744, 9316.394434361744, 9371.109744574254, 9373.06269373064, 9378.0, 9385.671501497483, 9233.215176709988, 9293.721188364292, 9309.34834561581, 9314.906850931471, 9316.906830931672, 39022.219555609016, 39360.60639393598, 39440.80279598584, 39445.21109577821, 39457.40813887794, 2765.5575107982627, 3229.3702727968057, 3258.5112233164978, 3806.6764325032127, 3876.7197140632356, 28377.432451350858, 28596.857015714995, 30154.84922575395, 30649.0, 30996.53505197424, 3146.606674165729, 3158.4946408574747, 3162.762792790527, 3229.8223597702117, 3260.543523906655, 496158.5192074053, 496428.0, 496543.0, 496693.0, 510605.0, 27479.0, 32594.83702581519, 33386.49920251198, 34465.483017754756, 60827.0, 35066.474002889976, 35101.771437999545, 35188.29623407518, 35198.472022919676, 35234.11914702153, 13956.511522096678, 14073.507427239998, 14119.364628591738, 14119.85880141206, 14129.646758831112, 11783.646490605295, 11886.821697674543, 11900.464479098462, 11903.226290291102, 12075.879241207476, 7650.770476885702, 7689.115751688516, 7738.226177382329, 7748.883766743504, 7763.767086987399, 3159.873605055818, 3204.327091310804, 3210.2134976930333, 3212.1166679163416, 3232.7413806895393, 890.9331800115017, 895.3195571365678, 898.0929261445987, 917.4128557723062, 925.1627277314042, 28300.575491367646, 29166.708332916824, 29199.85400072986, 29893.850530747208, 30209.69790302069, 863.6070587882498, 939.6523286384082, 945.5839430650492, 1014.4369874719602, 1035.5857656937285, 14499.0, 15239.695206095816, 15355.769663455058, 15407.768883466757, 15612.92193539025, 671428.0, 676790.0, 677452.0, 678045.0, 678841.0, 57674.711626441596, 58288.12567811486, 58330.0, 58347.4165258342, 58349.416505835245, 1185.8161984892356, 1240.838690970168, 1242.19257482637, 1339.2767905331011, 1341.1014620204487, 26719.866400667874, 30013.699863001963, 30527.23681907881, 31008.0, 31339.0, 46136.53863461457, 56353.18234088361, 56437.43562564486, 56682.43317566771, 56924.71537642285, 585678.0, 587895.0, 591374.0, 591897.0, 592762.0, 23260.418489537897, 23869.403264917823, 26553.203403897915, 26871.865640672455, 26909.596356055066, 43597.782011089745, 46042.30936535887, 46625.53374466212, 48761.75619121882, 49297.260541092626, 37375.62624373721, 38067.778655019865, 38302.80848595851, 38468.0, 38954.0, 38435.61564384432, 39408.40887386749, 39577.406338904366, 41052.79473602613, 41850.0, 30947.84526077445, 38665.03337416587, 39810.0, 39847.0, 39881.0029749247, 39925.60074399219, 39973.0, 40092.1981560373, 40950.59049409587, 41242.0, 29280.7071929278, 29803.0, 30968.690313097482, 31084.844575776977, 31682.683173168894], "value": 18331.978518832162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.84212104552955, 363.19733389209847, 369.77443759306834, 384.1050352678259, 398.8105649816338, 14361.0, 14516.564503064912, 15363.078215307076, 19388.4183474496, 20041.09815058322, 18984.145713442893, 19086.713699294487, 19594.90202548989, 19873.701894471593, 20357.694634580494, 5323.920141197876, 5615.80344687936, 5616.971915140419, 5617.41017193195, 5633.971830140844, 5014.272930425086, 5135.743212839362, 5520.0, 5584.357798853143, 5590.077637189859, 4738.265568836819, 4865.878353041185, 5457.263269458612, 5534.557235421177, 5545.667259964415, 5257.316548848645, 5456.0997435423305, 5495.065838807413, 5496.7526461308935, 5504.587155963319, 4873.074115917964, 4874.683145595561, 4875.122477953967, 4876.536729010738, 4880.585150262232, 23781.167659131846, 24166.516669666686, 24573.89417476218, 24857.1300004499, 24941.376465588324, 2309.561183375153, 2349.635806450003, 2360.3273067175774, 3031.969680303213, 3097.7831551791382, 9757.316987810857, 10288.382697038202, 10303.27877048607, 10396.636117735843, 14104.71790564183, 1458.2125652147934, 1839.705647096458, 1886.2737845929278, 1898.1932678611536, 2040.897955102239, 112616.43691781489, 119251.0, 119651.0, 119707.0, 120304.0, 11414.828777568344, 11529.365884876244, 11597.247806908437, 11637.709057273467, 11789.056875449944, 14404.279786010658, 14431.134131952116, 14433.061850979653, 14442.422303107967, 14450.855491445162, 7850.725224617111, 8008.159143289987, 8036.196380361917, 8159.796005099801, 8162.591870406456, 7671.0, 7690.577018264049, 7801.024871891013, 7815.53106813593, 7838.647260873275, 3859.3439115350457, 4445.399871017371, 4464.9107017860115, 4519.570640789119, 4521.050579378368, 1837.7886543047453, 1875.9349755659773, 1893.7443445134732, 1900.3443811885154, 1940.6118776244807, 309.8899890538871, 384.8903062627194, 706.8019706597269, 811.2982270336234, 816.6036078306045, 11068.721201010816, 11754.412279385997, 12071.155019148751, 12174.573889913987, 12199.817002744965, 419.1616766467066, 429.9978500107479, 439.52970321755413, 446.0298850001299, 447.92161371760466, 5833.125031245348, 6172.259328880563, 6213.285472170758, 6271.27880293763, 6407.391297826699, 554621.0, 557584.0, 559256.0, 565453.0, 573990.0, 89293.55353223192, 133888.0, 136114.0, 136879.0, 138593.0, 386.7234927027189, 419.28093319956423, 446.2057537583107, 456.2289730355688, 456.9520200378979, 7438.256174382518, 11081.058110060412, 11503.309801411608, 11514.30914145154, 11824.586139485076, 47852.5214747862, 49412.01175976532, 57132.42867571271, 57625.135622966525, 57890.710546447, 76949.2305076942, 77428.0, 78049.04877377884, 81261.28017838296, 82728.58635706783, 6081.935661259174, 7505.624718763931, 7974.282314591715, 8364.623591938256, 8533.701320453754, 11655.708607284885, 12713.87286127127, 12777.808332874829, 12998.480060797463, 13006.804897926728, 5515.22786809855, 6664.600123992576, 9895.554700038372, 10337.74155646115, 11472.024877885473, 10999.560017599206, 11025.889741102486, 11177.453547823918, 11407.37259450705, 11449.713757155805, 9901.851472228067, 9929.60613191182, 11219.49512271966, 11386.886131138583, 11525.88474115248, 7994.480358776661, 8018.077921038922, 8209.79475513117, 8252.917470825216, 8429.62066707012, 8281.917180828355, 11095.72260693489, 11113.555457781922, 11170.27393219438, 11521.94239028833], "value": 8369.357155604665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.8978447888253, 1471.2570152073204, 1511.79590755248, 1747.7815273090864, 1794.2284817528457, 7998.7600371988865, 8402.49585024899, 8411.327093832486, 8416.83166336673, 8435.662573497053, 7387.926120738791, 7614.695412183506, 7615.657295421705, 7958.76123716288, 8156.673733050686, 2286.622707253305, 2328.639060945556, 2330.2310237621627, 2334.171369163948, 2339.1345202275097, 2305.976940230593, 2332.3119679694446, 2342.590046741822, 2347.753485883983, 2356.045801450415, 2293.5871543122316, 2305.319930620471, 2321.4892723600865, 2328.3829785106877, 2336.8013718833918, 2296.0471404367167, 2299.8965046572775, 2319.4549280918973, 2323.268170526279, 2324.628059510487, 2311.63013917774, 2333.871637059961, 2334.2647066174272, 2335.649652552114, 2344.5310937812505, 7712.3444507216955, 7719.726245169541, 8139.633716482773, 8331.000279966382, 8332.791680207984, 4983.7757300921185, 5168.431472538018, 5169.276301317781, 5176.327077479941, 5181.663191892515, 3277.705006549398, 3305.206750379916, 3481.529993450877, 3553.1117220694828, 3557.3596752584785, 996.2577879479834, 1002.4185972136216, 1005.0602686488083, 1088.4231357380602, 1093.9398333091676, 10406.115480184193, 10734.570617175305, 10744.785104297871, 10784.946075269574, 10807.0, 3729.179580492287, 4222.451081359403, 4367.170237654836, 4488.214562451558, 4494.10117976406, 8256.711015114442, 8260.339172866155, 8263.95868020656, 8265.090840007595, 8270.586470676562, 4941.332920055818, 4947.307376967227, 4950.579200767903, 4951.480094590016, 4951.678140920829, 4210.810513526899, 4259.637930775857, 4639.025804581008, 4659.370984917061, 4697.436307643105, 3594.8022858742497, 3609.8628931886374, 3664.303782281358, 3692.2061756721896, 3695.2609478104487, 1128.6614015795228, 1136.6760473265244, 1142.0635079234949, 1152.9538818447168, 1156.8322593224104, 845.8350621628723, 848.898132224137, 866.2939704141105, 890.8619164029586, 902.120432578231, 5359.598030147755, 5375.81184658535, 5464.792110075299, 5487.259220005328, 5655.349634792052, 404.74501064329286, 428.7598944591039, 432.59336223949384, 439.8064851465345, 455.949037468638, 3278.8196649184047, 3294.225856923621, 3348.68187522185, 3435.553378060886, 3446.7070299024363, 9753.804923901624, 10099.7475063124, 10197.898021019695, 10222.284440089272, 10258.17934565233, 10129.139023182965, 10132.797344053077, 10145.64490242838, 10148.0, 10168.542415591317, 7562.205968373241, 7661.540307581563, 7673.577953212627, 7849.803754906059, 7876.960615196887, 5317.867053323698, 5449.12813949782, 5535.557155427637, 5644.604877658525, 5661.0659241226385, 9190.908090919005, 9270.397424167408, 9339.626414943597, 9345.392549484262, 9497.952510237681, 9411.435313880915, 9431.764205894908, 9467.621295148117, 9506.239500839778, 9610.567524461556, 6211.472024877936, 6428.614283143026, 6431.935680643321, 6650.767223147167, 6749.358810912955, 7447.069116360455, 7488.0, 7598.0, 7893.052833659997, 7895.289423951872, 7007.369336759716, 7100.289971002859, 7109.715611375487, 7155.928440715735, 7290.307420795016, 6985.126859142607, 7007.859842803217, 7034.226235114235, 7045.506814522934, 7141.535800172972, 6593.307702691149, 6623.768168114093, 6706.463482921257, 6713.6643167842385, 6728.899066513908, 4594.287885377638, 4617.63058955276, 4676.0, 4709.0817290628, 4711.293306004127, 4728.432588089451, 4773.04539092176, 4913.9017219656125, 4929.7535123244415, 5041.495850414929], "value": 4232.55503920125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.474956701422, 297.60567248395876, 307.2472442515836, 311.6135991370701, 311.80823793367085, 5321.467853214676, 5343.839684809457, 5346.198070289459, 5377.247185394038, 5429.945700542999, 4978.241427722065, 5068.949310506894, 5162.432132465435, 5181.637285390024, 5335.679859208451, 3533.717302615785, 3698.6486283802997, 3769.0, 3879.1078052048047, 3992.7205095643317, 4057.2696914555368, 4068.4914385701786, 4080.204360149772, 4100.79496025199, 4109.91780164398, 3869.4776205212356, 3889.2804831106337, 4013.5986401359924, 4021.2158629067344, 4097.627294856226, 4035.7982100895133, 4052.918941621151, 4054.8241010107035, 4085.346344584867, 4088.325426304656, 2945.7496112830436, 3520.507129001942, 3528.417811061172, 3530.135116896351, 3538.274653695986, 4389.978050109729, 4561.885952851205, 4787.257975013878, 5152.974235128838, 5173.679977219027, 4177.657873701058, 4268.786560671954, 4675.7895894684825, 4717.410323709536, 4721.905561888777, 4673.509281525459, 5257.815976440845, 5551.694656793875, 5604.243427137366, 5621.0, 3172.4448221561365, 3972.364421692544, 3990.60328884888, 3992.6007399260134, 3992.800359982018, 4252.936205956913, 5108.164968442513, 5816.331121920991, 5870.735816888207, 5873.239577398615, 3644.817759112034, 3842.1163132479687, 3845.673117784964, 3847.53793558446, 3849.480320156799, 3897.395903634941, 3904.6095390460728, 3914.236723838881, 3918.3534716771983, 3923.391874259494, 4420.270655341897, 4451.265541185733, 4532.592066714012, 4594.95405045952, 4598.642990616869, 5589.329280486287, 5595.38450770415, 5605.299337582802, 5623.493885550321, 5640.0, 1258.5028913579085, 1302.1796268351068, 1318.3586434888625, 1319.5843309357522, 1320.6001638263278, 761.432732614195, 826.0912995704797, 849.7663142635824, 875.1336177184542, 914.5641343091415, 4204.91590168201, 4623.029163875556, 4675.696079754805, 4930.383702037246, 4934.4572097069295, 4598.160735705677, 4706.164595807638, 4774.904501909942, 5195.0, 5349.0, 4272.9359059614135, 4725.9054818904115, 4793.0, 4879.731614761222, 4888.291197776302, 2609.830361026527, 2640.4851054044275, 2821.5908693239367, 2828.2081017315186, 2849.5155823510054, 4838.0, 5326.121190003683, 5588.379369982741, 5616.634918730269, 5842.737076831553, 5247.658902171346, 5435.701036443033, 5507.0, 5540.094233565327, 5589.0, 5092.472258322488, 5143.897122057538, 5520.475554822286, 5549.752350298136, 5593.615862403269, 4578.40480737502, 5017.397912250553, 5205.947940520698, 5225.145073499005, 5358.973205134105, 4783.473817879961, 4818.180909245367, 5254.579633629377, 5329.946700532945, 5335.759890804846, 4389.7489215573405, 5076.847694569169, 5580.972095139498, 5583.720813959205, 5603.814512340109, 3530.2939412117166, 3745.8314375853697, 3749.075720937673, 3775.981120094382, 3802.7147963902266, 5690.800821971211, 5695.886082278248, 5716.456936591017, 5723.399043100415, 5756.539476841759, 5542.861428464321, 5562.860928476821, 5566.610337276352, 5617.915731263953, 5656.0, 5307.708076055854, 5580.795585743527, 5601.831945041656, 5653.915191272052, 5684.459976302244, 5022.121128802518, 5114.616403769806, 5195.4280233394375, 5233.848553318264, 5258.97370513145, 4499.707519011254, 4680.602148817388, 4797.352357431702, 5008.4240312364545, 5608.588307027854, 4117.547069822377, 4816.759162041813, 5293.77980872481, 5293.988284451883, 5518.889622207453], "value": 3978.8292046549327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.2648823050532, 684.1345697692417, 719.8956151358053, 722.4256715910852, 730.9415246780258, 12033.338166400845, 13917.44330226791, 14449.711005779891, 14455.855441445583, 14513.782293265609, 12670.113092083559, 12774.233545987225, 12792.936035319834, 12797.424115914782, 12805.231686098841, 630.7603110817882, 646.4602057282151, 790.4387884601939, 802.8233788566506, 805.9355251579859, 654.921409430869, 783.0, 798.664560884427, 811.5090370325956, 813.7029984055836, 851.2679095977454, 851.4848516647401, 852.9189726975958, 853.8804567360575, 855.0978717453085, 663.7278715726529, 845.8731190321439, 847.5973912391627, 848.825990671917, 850.2730165708354, 509.5134146889711, 610.9725062372204, 624.356912380249, 628.5851338116867, 629.4209327418786, 13524.661883452894, 13564.525241616595, 13566.864331356757, 13568.660783480493, 13574.321283935862, 12561.560345387868, 12802.231866087972, 12814.103012789108, 12915.160514566523, 13048.478060877553, 11818.468168932333, 11887.881121188764, 11959.40202989847, 12022.0, 12075.57735479254, 4143.502779666459, 4158.209940111399, 4191.140816132685, 4352.934705979413, 4450.643948484114, 79136.20863791346, 80942.0, 88770.5561472195, 89184.0, 89652.10347896503, 9833.26250531213, 10155.7461063474, 10361.948190259, 10396.844047339297, 10432.843507347396, 18798.154083066296, 18802.247910083715, 18850.52873678169, 19008.619827603372, 19071.427857164308, 7491.726406510907, 7509.36170425509, 7573.507721998052, 7653.15815260321, 7672.73145439907, 4120.546739858613, 4243.448351714319, 4283.443152390231, 4409.603135717801, 4449.57729015743, 3271.705546500779, 3374.865005399806, 3379.172102834772, 3379.4930760386137, 3384.0862966999266, 604.7218279591438, 612.3294991983771, 620.2061361457341, 626.1578177351455, 643.1895811277824, 443.1204059941015, 450.7656018870235, 533.9960873557686, 562.7692646015114, 594.5563733057131, 6872.896906546406, 8561.400701950804, 8693.261072808755, 8704.956475217583, 8767.0, 546.868409168734, 558.4722437296715, 592.5733471900224, 602.9969850150809, 619.5198720991268, 4039.6162364575316, 4106.609872062149, 4140.668746500272, 4157.210130075337, 4239.0674051708575, 72655.0, 89828.0, 93781.5310923441, 95483.52258238665, 98535.0, 57826.7108664454, 62195.6890215546, 75396.49207015829, 84267.0, 87474.56262718646, 81173.0, 85713.57143214245, 86356.13643863607, 87916.0, 88349.55825220705, 8767.561621919007, 9051.818963620823, 9159.725208243763, 9352.018038105902, 9418.62325506972, 44100.0, 48996.265056023476, 50240.0, 51170.48829511657, 51334.973300533034, 69066.65466672635, 75090.0, 77694.611526942, 79677.60161199157, 80894.0, 11993.580224692094, 12052.276863388224, 12057.698557536132, 12183.086268529718, 12539.184952978027, 13908.0, 14092.36584353727, 14113.717725645634, 14127.717445651235, 14182.716345673236, 10450.529726162189, 10669.093127084285, 12354.567590134302, 12725.0, 13752.243626600633, 11999.1600587958, 12037.638870833889, 12143.210691305037, 12354.073444491878, 12674.936625317183, 11882.405879705808, 12450.564230251897, 12571.622851314109, 12689.23864568129, 13604.65988350299, 9790.0, 10352.482375881327, 10422.635207767691, 11307.604233851776, 11546.19176657626, 10911.83632245501, 10931.070858977077, 10945.56217751313, 11210.66368008961, 11241.71895702614], "value": 6969.27166029435, "unit": "score"}], "name": "dromaeo_css", "value": 6847.2007485387785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 116.0, 116.0, 116.0, 116.0, 115.0, 116.0, 115.0, 115.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 118.0, 175.0, 140.0, 124.0, 111.0, 115.0, 160.0, 140.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 255.0, 229.0, 182.0, 183.0, 182.0, 183.0, 183.0, 184.0], "value": 194.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 59.0, 90.0, 61.0, 73.0, 86.0, 82.0, 99.0, 59.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 96.0, 69.0, 69.0, 70.0, 89.0, 68.0, 69.0, 93.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 100.0, 130.0, 87.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 100.0, 122.0, 122.0, 85.0, 86.0, 83.0, 88.0, 66.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 53.0, 57.0, 59.0, 58.0, 59.0, 58.0, 59.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 71.0, 83.0, 76.0, 72.0, 69.0, 70.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 83.0, 81.0, 80.0, 83.0, 83.0, 85.0, 84.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 141.0, 140.0, 147.0, 164.0, 138.0, 139.0, 179.0, 150.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 165.0, 165.0, 166.0, 186.0, 226.0, 165.0, 166.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 87.0, 70.0, 67.0, 68.0, 67.0, 68.0, 66.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1477.1000000000001, "alertThreshold": 2.0}]} 05:22:29 INFO - Return code: 0 05:22:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:22:29 INFO - # TBPL SUCCESS # 05:22:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:22:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:22:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:22:29 INFO - 2016-12-12 05:22:29.847 screenresolution[1947:20732] starting screenresolution argv=screenresolution get 05:22:29 INFO - 2016-12-12 05:22:29.850 screenresolution[1947:20732] Display 0: 1600x1200x32@60 05:22:29 INFO - 2016-12-12 05:22:29.856 screenresolution[1948:20735] starting screenresolution argv=screenresolution list 05:22:29 INFO - Available Modes on Display 0 05:22:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:22:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:22:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:22:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:22:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:22:30 INFO - Intel Iris: 05:22:30 INFO - Chipset Model: Intel Iris 05:22:30 INFO - Type: GPU 05:22:30 INFO - Bus: Built-In 05:22:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:22:30 INFO - Vendor: Intel (0x8086) 05:22:30 INFO - Device ID: 0x0a2e 05:22:30 INFO - Revision ID: 0x0009 05:22:30 INFO - Displays: 05:22:30 INFO - Display: 05:22:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:22:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:22:30 INFO - Display Serial Number: 1600x1200 60 05:22:30 INFO - Main Display: Yes 05:22:30 INFO - Mirror: Off 05:22:30 INFO - Online: Yes 05:22:30 INFO - Rotation: Supported 05:22:30 INFO - Return code: 0 05:22:30 INFO - Running post-action listener: _resource_record_post_action 05:22:30 INFO - [mozharness: 2016-12-12 13:22:30.091950Z] Finished run-tests step (success) 05:22:30 INFO - Running post-run listener: _resource_record_post_run 05:22:30 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 1495040; Write bytes: 662639616; Read time: 11; Write time: 1850 05:22:30 INFO - TinderboxPrint: CPU usage
53.4% 05:22:30 INFO - TinderboxPrint: I/O read bytes / time
1,495,040 / 11 05:22:30 INFO - TinderboxPrint: I/O write bytes / time
662,639,616 / 1,850 05:22:30 INFO - TinderboxPrint: CPU idle
2,262.1 (46.6%) 05:22:30 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 05:22:30 INFO - TinderboxPrint: CPU user
1,621.1 (33.4%) 05:22:30 INFO - TinderboxPrint: Swap in / out
349,609,984 / 0 05:22:30 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349350400; Write bytes: 353101824; Read time: 26928; Write time: 652 05:22:30 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 516096; Write bytes: 308059136; Read time: 6; Write time: 1190 05:22:30 INFO - Running post-run listener: _upload_blobber_files 05:22:30 INFO - Blob upload gear active. 05:22:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:22:30 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 05:22:30 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'] 05:22:30 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 05:22:30 INFO - (blobuploader) - INFO - Open directory for files ... 05:22:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:22:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:22:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:32 INFO - (blobuploader) - INFO - Done attempting. 05:22:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:22:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:22:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:33 INFO - (blobuploader) - INFO - Done attempting. 05:22:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:22:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:22:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:22:36 INFO - (blobuploader) - INFO - Done attempting. 05:22:36 INFO - (blobuploader) - INFO - Iteration through files over. 05:22:36 INFO - Return code: 0 05:22:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:22:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:22:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40226d8ec61123e5b71322f41c709a67b59e54b7511805b41b236771f90c3fb73f65a087f6dd6873549b87a2c331f4a9fddd7aff0a4ae0d803ee30b4fa939445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04963d6bc1356f6f6c70133849e6ebe9255a2a6ea70553c358fff5bc65a10317ac600afc0a12b854eb0f99528e2f429b2fa27a84e93577ab17dd3ce448b8f121", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f34b629f5a7561a5fcdc97f4dda86bca40616c0782af4ded01deb54f8810675c81451cf7cb70c8fa351a15594d7a3ace4f27e5835ea099fbcf0da8497dcdc554"} 05:22:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:22:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:22:36 INFO - Contents: 05:22:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40226d8ec61123e5b71322f41c709a67b59e54b7511805b41b236771f90c3fb73f65a087f6dd6873549b87a2c331f4a9fddd7aff0a4ae0d803ee30b4fa939445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04963d6bc1356f6f6c70133849e6ebe9255a2a6ea70553c358fff5bc65a10317ac600afc0a12b854eb0f99528e2f429b2fa27a84e93577ab17dd3ce448b8f121", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f34b629f5a7561a5fcdc97f4dda86bca40616c0782af4ded01deb54f8810675c81451cf7cb70c8fa351a15594d7a3ace4f27e5835ea099fbcf0da8497dcdc554"} 05:22:36 INFO - Running post-run listener: copy_logs_to_upload_dir 05:22:36 INFO - Copying logs to upload dir... 05:22:36 INFO - mkdir: /builds/slave/test/build/upload/logs 05:22:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.499019 ========= master_lag: 2.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-12 05:22:38.680114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 05:22:38.683710) ========= 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.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/40226d8ec61123e5b71322f41c709a67b59e54b7511805b41b236771f90c3fb73f65a087f6dd6873549b87a2c331f4a9fddd7aff0a4ae0d803ee30b4fa939445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04963d6bc1356f6f6c70133849e6ebe9255a2a6ea70553c358fff5bc65a10317ac600afc0a12b854eb0f99528e2f429b2fa27a84e93577ab17dd3ce448b8f121", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f34b629f5a7561a5fcdc97f4dda86bca40616c0782af4ded01deb54f8810675c81451cf7cb70c8fa351a15594d7a3ace4f27e5835ea099fbcf0da8497dcdc554"} build_url:https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://queue.taskcluster.net/v1/task/FyBW7l-_SYeQhiM-zzAE3w/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/40226d8ec61123e5b71322f41c709a67b59e54b7511805b41b236771f90c3fb73f65a087f6dd6873549b87a2c331f4a9fddd7aff0a4ae0d803ee30b4fa939445", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04963d6bc1356f6f6c70133849e6ebe9255a2a6ea70553c358fff5bc65a10317ac600afc0a12b854eb0f99528e2f429b2fa27a84e93577ab17dd3ce448b8f121", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f34b629f5a7561a5fcdc97f4dda86bca40616c0782af4ded01deb54f8810675c81451cf7cb70c8fa351a15594d7a3ace4f27e5835ea099fbcf0da8497dcdc554"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 05:22:38.719284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:22:38.719581) ========= 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.StvSZRo9oV/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.FtTp0xbaOl/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005342 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 05:22:38.767903) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 05:22:38.768178) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 05:22:38.768480) ========= ========= Total master_lag: 2.35 =========