builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0090 starttime: 1481800880.27 results: success (0) buildid: 20161215004017 builduid: e94fb94d74f4400bbe46f07620f1fc7a revision: 7e3a96f3ee277bc54a695ed54f60228984de2a7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.270644) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.271062) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.271340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.317099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.317397) ========= 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.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-15 03:21:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.39M=0.001s 2016-12-15 03:21:20 (9.39 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.549046 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.890002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.890305) ========= 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.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.027711 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.939270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:20.939646) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e3a96f3ee277bc54a695ed54f60228984de2a7f --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 7e3a96f3ee277bc54a695ed54f60228984de2a7f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-15 03:21:21,036 truncating revision to first 12 chars 2016-12-15 03:21:21,036 Setting DEBUG logging. 2016-12-15 03:21:21,036 attempt 1/10 2016-12-15 03:21:21,036 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7e3a96f3ee27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 03:21:21,725 unpacking tar archive at: mozilla-aurora-7e3a96f3ee27/testing/mozharness/ program finished with exit code 0 elapsedTime=0.955498 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:21.916894) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:21.917202) ========= script_repo_revision: 7e3a96f3ee277bc54a695ed54f60228984de2a7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:21.917603) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:21.917878) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 03:21:21.938339) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 03:21:21.938748) ========= /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.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:21:22 INFO - MultiFileLogger online at 20161215 03:21:22 in /builds/slave/test 03:21:22 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 03:21:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:22 INFO - {'append_to_log': False, 03:21:22 INFO - 'base_work_dir': '/builds/slave/test', 03:21:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:21:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:22 INFO - 'branch': 'Mozilla-Aurora', 03:21:22 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:22 INFO - 'config_files': ('talos/mac_config.py',), 03:21:22 INFO - 'default_actions': ('clobber', 03:21:22 INFO - 'read-buildbot-config', 03:21:22 INFO - 'download-and-extract', 03:21:22 INFO - 'populate-webroot', 03:21:22 INFO - 'create-virtualenv', 03:21:22 INFO - 'install', 03:21:22 INFO - 'run-tests'), 03:21:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:22 INFO - 'download_minidump_stackwalk': True, 03:21:22 INFO - 'download_symbols': 'ondemand', 03:21:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:22 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:22 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:22 INFO - 'installer_path': 'installer.exe', 03:21:22 INFO - 'log_level': 'info', 03:21:22 INFO - 'log_name': 'talos', 03:21:22 INFO - 'log_to_console': True, 03:21:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:21:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:21:22 INFO - 'opt_config_files': (), 03:21:22 INFO - 'pip_index': False, 03:21:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:22 INFO - 'cmd': ('bash', 03:21:22 INFO - '-c', 03:21:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:22 INFO - 'enabled': True, 03:21:22 INFO - 'halt_on_failure': False, 03:21:22 INFO - 'name': 'check_screen_resolution'},), 03:21:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:21:22 INFO - 'cmd': ('bash', 03:21:22 INFO - '-c', 03:21:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:21:22 INFO - 'enabled': True, 03:21:22 INFO - 'halt_on_failure': False, 03:21:22 INFO - 'name': 'check_screen_resolution'},), 03:21:22 INFO - 'run_cmd_checks_enabled': True, 03:21:22 INFO - 'sps_profile': False, 03:21:22 INFO - 'sps_profile_interval': 0, 03:21:22 INFO - 'suite': 'dromaeojs', 03:21:22 INFO - 'system_bits': '32', 03:21:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:22 INFO - 'title': 't-yosemite-r7-0090', 03:21:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:22 INFO - 'use_talos_json': True, 03:21:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:22 INFO - 'work_dir': 'build'} 03:21:22 INFO - [mozharness: 2016-12-15 11:21:22.148921Z] Running clobber step. 03:21:22 INFO - Running pre-action listener: _resource_record_pre_action 03:21:22 INFO - Running main action method: clobber 03:21:22 INFO - rmtree: /builds/slave/test/build 03:21:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:24 INFO - Running post-action listener: _resource_record_post_action 03:21:24 INFO - [mozharness: 2016-12-15 11:21:24.596324Z] Finished clobber step (success) 03:21:24 INFO - [mozharness: 2016-12-15 11:21:24.596471Z] Running read-buildbot-config step. 03:21:24 INFO - Running pre-action listener: _resource_record_pre_action 03:21:24 INFO - Running main action method: read_buildbot_config 03:21:24 INFO - Using buildbot properties: 03:21:24 INFO - { 03:21:24 INFO - "project": "", 03:21:24 INFO - "product": "firefox", 03:21:24 INFO - "script_repo_revision": "production", 03:21:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:21:24 INFO - "repository": "", 03:21:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:21:24 INFO - "buildid": "20161215004017", 03:21:24 INFO - "stage_platform": "macosx64", 03:21:24 INFO - "basedir": "/builds/slave/test", 03:21:24 INFO - "nightly_build": "True", 03:21:24 INFO - "buildnumber": 8, 03:21:24 INFO - "slavename": "t-yosemite-r7-0090", 03:21:24 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:21:24 INFO - "platform": "yosemite_r7", 03:21:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:21:24 INFO - "branch": "mozilla-aurora", 03:21:24 INFO - "repo_path": "releases/mozilla-aurora", 03:21:24 INFO - "revision": "7e3a96f3ee277bc54a695ed54f60228984de2a7f", 03:21:24 INFO - "pgo_build": "False", 03:21:24 INFO - "builduid": "e94fb94d74f4400bbe46f07620f1fc7a", 03:21:24 INFO - "slavebuilddir": "test" 03:21:24 INFO - } 03:21:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:21:24 INFO - Running post-action listener: _resource_record_post_action 03:21:24 INFO - [mozharness: 2016-12-15 11:21:24.599725Z] Finished read-buildbot-config step (success) 03:21:24 INFO - [mozharness: 2016-12-15 11:21:24.599841Z] Running download-and-extract step. 03:21:24 INFO - Running pre-action listener: _resource_record_pre_action 03:21:24 INFO - Running main action method: download_and_extract 03:21:24 INFO - mkdir: /builds/slave/test/build/tests 03:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:24 INFO - trying https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:21:24 INFO - Downloading https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/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 03:21:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/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 03:21:25 INFO - Downloaded 1199 bytes. 03:21:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:21:26 INFO - Using the following test package requirements: 03:21:26 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:21:26 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:21:26 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:21:26 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'jsshell-mac.zip'], 03:21:26 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:21:26 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:21:26 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:21:26 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:21:26 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:21:26 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:21:26 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:21:26 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:21:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:21:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:26 INFO - Fetch https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:21:26 INFO - Http code: 200 03:21:26 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 03:21:26 INFO - x-amz-cf-id: 4yf1YxDNumu8tcTUGtuCh1gz2JyYC6A_JVpNFuGo6nemCOfVIDHTaA== 03:21:26 INFO - x-amz-version-id: HyKujzCmXzAR9xXV.C7mGr9l4mR7h9P8 03:21:26 INFO - Expected file size: 16281115 03:21:26 INFO - Obtained file size: 16281115 03:21:33 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 03:21:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:21:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:21:33 INFO - Fetch https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:21:34 INFO - Http code: 200 03:21:34 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 03:21:34 INFO - x-amz-cf-id: 83ZC29UUmnPT2w5LB4TyWiTEbXnaVFhEKreFVVulaDwP8Q50CW78vQ== 03:21:34 INFO - x-amz-version-id: HyKujzCmXzAR9xXV.C7mGr9l4mR7h9P8 03:21:34 INFO - Expected file size: 16281115 03:21:34 INFO - Obtained file size: 16281115 03:21:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:21:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:21:40 INFO - Fetch https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:21:41 INFO - Http code: 200 03:21:41 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 03:21:41 INFO - x-amz-cf-id: pvugQQxQqCUtNbTUNI5cN60omkCY1jxqG7WWdMGyAc4vX4igkmnSaw== 03:21:41 INFO - x-amz-version-id: x1Q9RCQG2vDEC3RfAAbOC42dZSrtoe6z 03:21:41 INFO - Expected file size: 14409038 03:21:41 INFO - Obtained file size: 14409038 03:21:42 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')]} 03:21:42 INFO - trying https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:42 INFO - Downloading https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:21:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/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 03:21:45 INFO - Downloaded 109850057 bytes. 03:21:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:45 INFO - mkdir: /builds/slave/test/properties 03:21:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:45 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:45 INFO - Contents: 03:21:45 INFO - build_url:https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:21:45 INFO - Running post-action listener: _resource_record_post_action 03:21:45 INFO - Running post-action listener: set_extra_try_arguments 03:21:45 INFO - [mozharness: 2016-12-15 11:21:45.721889Z] Finished download-and-extract step (success) 03:21:45 INFO - [mozharness: 2016-12-15 11:21:45.722011Z] Running populate-webroot step. 03:21:45 INFO - Running pre-action listener: _resource_record_pre_action 03:21:45 INFO - Running main action method: populate_webroot 03:21:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:45 INFO - '--tppagecycles', 03:21:45 INFO - '7'], 03:21:45 INFO - 'tests': ['tsvgm']}}, 03:21:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['damp', 'tps']}, 03:21:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'tests': ['damp', 'tps']}, 03:21:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['dromaeo_dom']}, 03:21:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['basic_compositor_video']}, 03:21:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['a11yr', 03:21:45 INFO - 'ts_paint', 03:21:45 INFO - 'tpaint', 03:21:45 INFO - 'sessionrestore', 03:21:45 INFO - 'sessionrestore_no_auto_restore', 03:21:45 INFO - 'tabpaint']}, 03:21:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['tsvgx', 03:21:45 INFO - 'tsvgr_opacity', 03:21:45 INFO - 'tart', 03:21:45 INFO - 'tscrollx', 03:21:45 INFO - 'cart']}, 03:21:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:45 INFO - 'tsvgr_opacity', 03:21:45 INFO - 'tart', 03:21:45 INFO - 'tscrollx', 03:21:45 INFO - 'cart']}, 03:21:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'talos_options': ['--disable-e10s'], 03:21:45 INFO - 'tests': ['tp5o']}, 03:21:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'tests': ['tp5o']}, 03:21:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'talos_options': ['--disable-e10s', 03:21:45 INFO - '--xperf_path', 03:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:45 INFO - 'tests': ['tp5n']}, 03:21:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:45 INFO - 'talos_options': ['--xperf_path', 03:21:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:45 INFO - 'tests': ['tp5n']}}, 03:21:45 INFO - 'talos.zip': {'path': '', 03:21:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:45 INFO - Running post-action listener: _resource_record_post_action 03:21:45 INFO - [mozharness: 2016-12-15 11:21:45.740694Z] Finished populate-webroot step (success) 03:21:45 INFO - [mozharness: 2016-12-15 11:21:45.740831Z] Running create-virtualenv step. 03:21:45 INFO - Running pre-action listener: _resource_record_pre_action 03:21:45 INFO - Running main action method: create_virtualenv 03:21:45 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:45 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 03:21:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:45 INFO - Using real prefix '/tools/python27' 03:21:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:46 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:49 INFO - Installing pip.................done. 03:21:49 INFO - Return code: 0 03:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:21:49 INFO - Reading from file tmpfile_stdout 03:21:49 INFO - Output received: 03:21:49 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) 03:21:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21: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')]} 03:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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')]} 03:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:21: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', 'pip>=1.5'] in /builds/slave/test/build 03:21: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 pip>=1.5 03:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:49 INFO - 'HOME': '/Users/cltbld', 03:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:49 INFO - 'LOGNAME': 'cltbld', 03:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:49 INFO - 'MOZ_NO_REMOTE': '1', 03:21:49 INFO - 'NO_EM_RESTART': '1', 03:21:49 INFO - 'PAGER': '/bin/cat', 03:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:49 INFO - 'PWD': '/builds/slave/test', 03:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:49 INFO - 'SHELL': '/bin/bash', 03:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:21:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:49 INFO - 'USER': 'cltbld', 03:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:49 INFO - 'XPC_FLAGS': '0x0', 03:21:49 INFO - 'XPC_SERVICE_NAME': '0', 03:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21: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 03:21:49 INFO - Cleaning up... 03:21:50 INFO - Return code: 0 03:21:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:50 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:21:50 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 03:21:50 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 03:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:50 INFO - 'HOME': '/Users/cltbld', 03:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:50 INFO - 'LOGNAME': 'cltbld', 03:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:50 INFO - 'MOZ_NO_REMOTE': '1', 03:21:50 INFO - 'NO_EM_RESTART': '1', 03:21:50 INFO - 'PAGER': '/bin/cat', 03:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:50 INFO - 'PWD': '/builds/slave/test', 03:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:50 INFO - 'SHELL': '/bin/bash', 03:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:21:50 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:50 INFO - 'USER': 'cltbld', 03:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:50 INFO - 'XPC_FLAGS': '0x0', 03:21:50 INFO - 'XPC_SERVICE_NAME': '0', 03:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:50 INFO - Downloading/unpacking psutil>=3.1.1 03:21:50 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 03:21:50 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 03:21:50 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 03:21:50 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 03:21:50 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 03:21:50 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 03:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:54 INFO - Installing collected packages: psutil 03:21:54 INFO - Running setup.py install for psutil 03:21:54 INFO - building 'psutil._psutil_osx' extension 03:21:54 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 03:21:54 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 03:21:54 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 03:21:55 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 03:21:55 INFO - building 'psutil._psutil_posix' extension 03:21:55 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 03:21:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:55 INFO - ^ 03:21:55 INFO - 1 warning generated. 03:21:55 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 03:21:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:55 INFO - Successfully installed psutil 03:21:55 INFO - Cleaning up... 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:55 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')]} 03:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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')]} 03:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:21:55 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 03:21:55 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 03:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:55 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 03:21:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:58 INFO - Installing collected packages: mozsystemmonitor 03:21:58 INFO - Running setup.py install for mozsystemmonitor 03:21:58 INFO - Successfully installed mozsystemmonitor 03:21:58 INFO - Cleaning up... 03:21:58 INFO - Return code: 0 03:21:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:58 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')]} 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:58 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')]} 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:58 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:21:58 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 03:21:58 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 03:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:58 INFO - 'HOME': '/Users/cltbld', 03:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:58 INFO - 'LOGNAME': 'cltbld', 03:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:58 INFO - 'MOZ_NO_REMOTE': '1', 03:21:58 INFO - 'NO_EM_RESTART': '1', 03:21:58 INFO - 'PAGER': '/bin/cat', 03:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:58 INFO - 'PWD': '/builds/slave/test', 03:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:58 INFO - 'SHELL': '/bin/bash', 03:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:21:58 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:58 INFO - 'USER': 'cltbld', 03:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:58 INFO - 'XPC_FLAGS': '0x0', 03:21:58 INFO - 'XPC_SERVICE_NAME': '0', 03:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:58 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:58 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 03:21:58 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 03:21:58 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 03:21:58 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 03:21:58 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 03:21:58 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 03:22:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:01 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:02 INFO - warning: no files found matching '*.txt' 03:22:02 INFO - no previously-included directories found matching 'build' 03:22:02 INFO - no previously-included directories found matching 'dist' 03:22:02 INFO - no previously-included directories found matching '.git*' 03:22:02 INFO - Installing collected packages: jsonschema, functools32 03:22:02 INFO - Running setup.py install for functools32 03:22:02 INFO - warning: no files found matching '*.txt' 03:22:02 INFO - no previously-included directories found matching 'build' 03:22:02 INFO - no previously-included directories found matching 'dist' 03:22:02 INFO - no previously-included directories found matching '.git*' 03:22:02 INFO - Successfully installed jsonschema functools32 03:22:02 INFO - Cleaning up... 03:22:02 INFO - Return code: 0 03:22:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:22:02 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')]} 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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')]} 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:02 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 03:22:02 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 03:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:02 INFO - 'HOME': '/Users/cltbld', 03:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:02 INFO - 'LOGNAME': 'cltbld', 03:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:02 INFO - 'MOZ_NO_REMOTE': '1', 03:22:02 INFO - 'NO_EM_RESTART': '1', 03:22:02 INFO - 'PAGER': '/bin/cat', 03:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:02 INFO - 'PWD': '/builds/slave/test', 03:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:02 INFO - 'SHELL': '/bin/bash', 03:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:02 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:02 INFO - 'USER': 'cltbld', 03:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:02 INFO - 'XPC_FLAGS': '0x0', 03:22:02 INFO - 'XPC_SERVICE_NAME': '0', 03:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:22:02 INFO - Cleaning up... 03:22:02 INFO - Return code: 0 03:22:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:02 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')]} 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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')]} 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:02 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:02 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 03:22:02 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 03:22:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:02 INFO - 'HOME': '/Users/cltbld', 03:22:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:02 INFO - 'LOGNAME': 'cltbld', 03:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:02 INFO - 'MOZ_NO_REMOTE': '1', 03:22:02 INFO - 'NO_EM_RESTART': '1', 03:22:02 INFO - 'PAGER': '/bin/cat', 03:22:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:02 INFO - 'PWD': '/builds/slave/test', 03:22:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:02 INFO - 'SHELL': '/bin/bash', 03:22:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:02 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:02 INFO - 'USER': 'cltbld', 03:22:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:02 INFO - 'XPC_FLAGS': '0x0', 03:22:02 INFO - 'XPC_SERVICE_NAME': '0', 03:22:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:02 INFO - Downloading/unpacking blobuploader==1.2.4 03:22:02 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 03:22:02 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 03:22:02 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 03:22:02 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 03:22:02 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 03:22:02 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 03:22:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:05 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 03:22:05 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 03:22:05 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 03:22:05 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 03:22:05 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 03:22:05 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 03:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 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 03:22:06 INFO - Downloading docopt-0.6.1.tar.gz 03:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:06 INFO - Running setup.py install for blobuploader 03:22:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:06 INFO - Running setup.py install for requests 03:22:07 INFO - Running setup.py install for docopt 03:22:07 INFO - Successfully installed blobuploader requests docopt 03:22:07 INFO - Cleaning up... 03:22:07 INFO - Return code: 0 03:22:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:07 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:07 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 03:22:07 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 03:22:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:07 INFO - 'HOME': '/Users/cltbld', 03:22:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:07 INFO - 'LOGNAME': 'cltbld', 03:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:07 INFO - 'MOZ_NO_REMOTE': '1', 03:22:07 INFO - 'NO_EM_RESTART': '1', 03:22:07 INFO - 'PAGER': '/bin/cat', 03:22:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:07 INFO - 'PWD': '/builds/slave/test', 03:22:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:07 INFO - 'SHELL': '/bin/bash', 03:22:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:07 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:07 INFO - 'USER': 'cltbld', 03:22:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:07 INFO - 'XPC_FLAGS': '0x0', 03:22:07 INFO - 'XPC_SERVICE_NAME': '0', 03:22:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-VdstTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-a33TiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-P1kYap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9OFbRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7lpoy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-EU4lFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-E4whdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ApJ0j8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-SFUcEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-zYurTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ZIPkRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-3haHpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-4IWOht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-G4yJmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-OBe1E8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mi7z00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:09 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-_wg4S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:09 INFO - Running setup.py install for manifestparser 03:22:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:09 INFO - Running setup.py install for mozcrash 03:22:09 INFO - Running setup.py install for mozdebug 03:22:10 INFO - Running setup.py install for mozdevice 03:22:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Running setup.py install for mozfile 03:22:10 INFO - Running setup.py install for mozhttpd 03:22:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Running setup.py install for mozinfo 03:22:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Running setup.py install for mozInstall 03:22:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:10 INFO - Running setup.py install for mozleak 03:22:10 INFO - Running setup.py install for mozlog 03:22:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Running setup.py install for moznetwork 03:22:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Running setup.py install for mozprocess 03:22:11 INFO - Running setup.py install for mozprofile 03:22:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Running setup.py install for mozrunner 03:22:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:11 INFO - Running setup.py install for mozscreenshot 03:22:11 INFO - Running setup.py install for moztest 03:22:12 INFO - Running setup.py install for mozversion 03:22:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:12 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:12 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 03:22:12 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 03:22:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:12 INFO - 'HOME': '/Users/cltbld', 03:22:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PAGER': '/bin/cat', 03:22:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:12 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - 'XPC_FLAGS': '0x0', 03:22:12 INFO - 'XPC_SERVICE_NAME': '0', 03:22:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-RD_EWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:12 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)) 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WlFCx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:12 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)) 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-m_QX5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:12 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)) 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-T0PT1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:12 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)) 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9cp19P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:12 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)) 03:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:12 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mHhvdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-JSq8LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-nqzvJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9kR3ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-48LZ01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-7yvVfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-D_IBAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-bKPRjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-grJiTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:13 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)) 03:22:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:13 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-iiUT9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:14 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)) 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:14 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-D_SZHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:14 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)) 03:22:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:14 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-JWhoPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:14 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)) 03:22:14 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)) 03:22:14 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)) 03:22:14 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)) 03:22:14 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)) 03:22:14 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)) 03:22:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:14 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 03:22:14 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 03:22:14 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 03:22:14 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 03:22:14 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 03:22:14 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 03:22:16 INFO - Downloading blessings-1.6.tar.gz 03:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:16 INFO - Installing collected packages: blessings 03:22:16 INFO - Running setup.py install for blessings 03:22:17 INFO - Successfully installed blessings 03:22:17 INFO - Cleaning up... 03:22:17 INFO - Return code: 0 03:22:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:17 INFO - Reading from file tmpfile_stdout 03:22:17 INFO - Current package versions: 03:22:17 INFO - blessings == 1.6 03:22:17 INFO - blobuploader == 1.2.4 03:22:17 INFO - docopt == 0.6.1 03:22:17 INFO - functools32 == 3.2.3-2 03:22:17 INFO - jsonschema == 2.5.1 03:22:17 INFO - manifestparser == 1.1 03:22:17 INFO - mozInstall == 1.12 03:22:17 INFO - mozcrash == 1.0 03:22:17 INFO - mozdebug == 0.1 03:22:17 INFO - mozdevice == 0.48 03:22:17 INFO - mozfile == 1.2 03:22:17 INFO - mozhttpd == 0.7 03:22:17 INFO - mozinfo == 0.9 03:22:17 INFO - mozleak == 0.1 03:22:17 INFO - mozlog == 3.4 03:22:17 INFO - moznetwork == 0.27 03:22:17 INFO - mozprocess == 0.23 03:22:17 INFO - mozprofile == 0.28 03:22:17 INFO - mozrunner == 6.13 03:22:17 INFO - mozscreenshot == 0.1 03:22:17 INFO - mozsystemmonitor == 0.3 03:22:17 INFO - moztest == 0.7 03:22:17 INFO - mozversion == 1.4 03:22:17 INFO - psutil == 3.1.1 03:22:17 INFO - requests == 1.2.3 03:22:17 INFO - wsgiref == 0.1.2 03:22:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:17 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 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:17 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 03:22:17 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 03:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:17 INFO - 'HOME': '/Users/cltbld', 03:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:17 INFO - 'LOGNAME': 'cltbld', 03:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:17 INFO - 'MOZ_NO_REMOTE': '1', 03:22:17 INFO - 'NO_EM_RESTART': '1', 03:22:17 INFO - 'PAGER': '/bin/cat', 03:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:17 INFO - 'PWD': '/builds/slave/test', 03:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:17 INFO - 'SHELL': '/bin/bash', 03:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:17 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:17 INFO - 'USER': 'cltbld', 03:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:17 INFO - 'XPC_FLAGS': '0x0', 03:22:17 INFO - 'XPC_SERVICE_NAME': '0', 03:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 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)) 03:22:17 INFO - Cleaning up... 03:22:17 INFO - Return code: 0 03:22:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe839d805e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, '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.uaoJmsXMEA/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.onv1WZ21GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:22:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:17 INFO - 'HOME': '/Users/cltbld', 03:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:17 INFO - 'LOGNAME': 'cltbld', 03:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:17 INFO - 'MOZ_NO_REMOTE': '1', 03:22:17 INFO - 'NO_EM_RESTART': '1', 03:22:17 INFO - 'PAGER': '/bin/cat', 03:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:17 INFO - 'PWD': '/builds/slave/test', 03:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:17 INFO - 'SHELL': '/bin/bash', 03:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:17 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:17 INFO - 'USER': 'cltbld', 03:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:17 INFO - 'XPC_FLAGS': '0x0', 03:22:17 INFO - 'XPC_SERVICE_NAME': '0', 03:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:18 INFO - Cleaning up... 03:22:18 INFO - Return code: 0 03:22:18 INFO - Running post-action listener: _resource_record_post_action 03:22:18 INFO - Running post-action listener: _start_resource_monitoring 03:22:18 INFO - Starting resource monitoring. 03:22:18 INFO - [mozharness: 2016-12-15 11:22:18.051042Z] Finished create-virtualenv step (success) 03:22:18 INFO - [mozharness: 2016-12-15 11:22:18.052065Z] Running install step. 03:22:18 INFO - Running pre-action listener: _resource_record_pre_action 03:22:18 INFO - Running main action method: install 03:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:18 INFO - Reading from file tmpfile_stdout 03:22:18 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:18 INFO - Reading from file tmpfile_stdout 03:22:18 INFO - Output received: 03:22:18 INFO - Usage: mozinstall [options] installer 03:22:18 INFO - Options: 03:22:18 INFO - -h, --help show this help message and exit 03:22:18 INFO - -d DEST, --destination=DEST 03:22:18 INFO - Directory to install application into. [default: 03:22:18 INFO - "/builds/slave/test"] 03:22:18 INFO - --app=APP Application being installed. [default: firefox] 03:22:18 INFO - mkdir: /builds/slave/test/build/application 03:22:18 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'] 03:22:18 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 03:22:49 INFO - Reading from file tmpfile_stdout 03:22:49 INFO - Output received: 03:22:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:22:49 INFO - Running post-action listener: _resource_record_post_action 03:22:49 INFO - [mozharness: 2016-12-15 11:22:49.332758Z] Finished install step (success) 03:22:49 INFO - [mozharness: 2016-12-15 11:22:49.332900Z] Running run-tests step. 03:22:49 INFO - Running pre-action listener: _resource_record_pre_action 03:22:49 INFO - Running main action method: run_tests 03:22:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:49 INFO - 2016-12-15 03:22:49.347 screenresolution[1629:8346] starting screenresolution argv=screenresolution get 03:22:49 INFO - 2016-12-15 03:22:49.350 screenresolution[1629:8346] Display 0: 1600x1200x32@60 03:22:49 INFO - 2016-12-15 03:22:49.355 screenresolution[1630:8348] starting screenresolution argv=screenresolution list 03:22:49 INFO - Available Modes on Display 0 03:22:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:49 INFO - Intel Iris: 03:22:49 INFO - Chipset Model: Intel Iris 03:22:49 INFO - Type: GPU 03:22:49 INFO - Bus: Built-In 03:22:49 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:49 INFO - Vendor: Intel (0x8086) 03:22:49 INFO - Device ID: 0x0a2e 03:22:49 INFO - Revision ID: 0x0009 03:22:49 INFO - Displays: 03:22:49 INFO - Display: 03:22:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:49 INFO - Display Serial Number: 1600x1200 60 03:22:49 INFO - Main Display: Yes 03:22:49 INFO - Mirror: Off 03:22:49 INFO - Online: Yes 03:22:49 INFO - Rotation: Supported 03:22:49 INFO - Return code: 0 03:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:49 INFO - Python 2.7.3 03:22:49 INFO - Return code: 0 03:22:49 INFO - grabbing minidump binary from tooltool 03:22: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')]} 03:22:49 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 0x10ee4fd30>, '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 0x10ecb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:49 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 03:22:49 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 03:22:49 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 03:22:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:49 INFO - Return code: 0 03:22:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:49 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/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 03:22:49 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/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 03:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uaoJmsXMEA/Render', 03:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:49 INFO - 'HOME': '/Users/cltbld', 03:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:49 INFO - 'LOGNAME': 'cltbld', 03:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:49 INFO - 'MOZ_NO_REMOTE': '1', 03:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:49 INFO - 'NO_EM_RESTART': '1', 03:22:49 INFO - 'PAGER': '/bin/cat', 03:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:49 INFO - 'PWD': '/builds/slave/test', 03:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:49 INFO - 'SHELL': '/bin/bash', 03:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.onv1WZ21GI/Listeners', 03:22:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:49 INFO - 'USER': 'cltbld', 03:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:49 INFO - 'XPC_FLAGS': '0x0', 03:22:49 INFO - 'XPC_SERVICE_NAME': '0', 03:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:49 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/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 03:22:49 INFO - mozversion application_buildid: 20161215004017 03:22:49 INFO - mozversion application_changeset: 7e3a96f3ee277bc54a695ed54f60228984de2a7f 03:22:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:22:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:49 INFO - mozversion application_name: Firefox 03:22:49 INFO - mozversion application_remotingname: firefox-dev 03:22:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:49 INFO - mozversion application_vendor: Mozilla 03:22:49 INFO - mozversion application_version: 52.0a2 03:22:49 INFO - mozversion platform_buildid: 20161215004017 03:22:49 INFO - mozversion platform_changeset: 7e3a96f3ee277bc54a695ed54f60228984de2a7f 03:22:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:22:49 INFO - mozversion platform_version: 52.0a2 03:22:49 INFO - using testdate: 1481800969 03:22:49 INFO - actual date: 1481800969 03:22:49 INFO - starting webserver on 'localhost:49249' 03:22:49 INFO - SUITE-START | Running 2 tests 03:22:49 INFO - TEST-START | dromaeo_css 03:22:49 INFO - operating with platform_type : mac_ 03:22:49 INFO - Initialising browser for dromaeo_css test... 03:22:49 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp6hBlhh/profile http://localhost:49249/getInfo.html) 03:22:52 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 03:22:52 INFO - PROCESS | 1640 | colorDepth:24 03:22:52 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 03:22:52 INFO - PROCESS | 1640 | __metrics 03:22:52 INFO - PROCESS | 1640 | 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" 03:22:52 INFO - PROCESS | 1640 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:22:53 INFO - TEST-INFO | 1640: exit 0 03:22:53 INFO - Browser initialized. 03:22:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:53 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp6hBlhh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:05 INFO - PROCESS | 1644 | RSS: Main: 222527488 03:23:05 INFO - PROCESS | 1644 | 03:23:56 INFO - PROCESS | 1644 | 1481801036594 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] 03:26:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 03:26:12 INFO - PROCESS | 1644 | RSS: Main: 336994304 03:26:12 INFO - PROCESS | 1644 | 03:29:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 03:29:15 INFO - PROCESS | 1644 | RSS: Main: 346763264 03:29:15 INFO - PROCESS | 1644 | 03:32:18 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 03:32:18 INFO - PROCESS | 1644 | RSS: Main: 318545920 03:32:18 INFO - PROCESS | 1644 | 03:35:21 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 03:35:21 INFO - PROCESS | 1644 | RSS: Main: 330752000 03:35:21 INFO - PROCESS | 1644 | 03:38:24 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 03:38:25 INFO - PROCESS | 1644 | RSS: Main: 306139136 03:38:25 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 03:41:28 INFO - PROCESS | 1644 | RSS: Main: 379035648 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | __start_tp_report 03:41:28 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 03:41:28 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 03:41:28 INFO - PROCESS | 1644 | |i|pagename|runs| 03:41:28 INFO - PROCESS | 1644 | |0;dojo.html;3308.768386212965;3317.988013655835;3325.434676105062;3354.8658053677855;3377.7128944039755;5589.944100558993;5668.914966275509;5677.205191273225;5702.68635225063;5721.828345149642;5631.690257035862;5700.059490184115;5705.51503122234;5717.113847353657;5719.256496655439;5173.379194496647;5182.2226666000115;5196.454372290912;5210.348706411699;5256.237845512398;4433.689641725081;5118.360204974379;5137.409197942228;5141.383034035902;5222.63441559091;5142.742862856842;5183.041137389595;5224.529792318691;5232.7383630818495;5235.685858848462;5148.5881129509735;5174.2238664200295;5188.844334669966;5221.791128354861;5277.683338999634;5113.2330150477355;5207.192885102815;5223.955208958224;5232.189010703347;5271.024860400838;5490.835274941758;5561.360443549003;5578.693171875545;5628.26832511775;5723.370429252779;6121.449069583714;6139.259792094406;6146.7541298348015;6185.350538193515;6201;6173.938260617381;6174.96912515439;6191.721372538246;6197.845053873689;6199.287081985584;5639.859003524863;5720.199172115824;5822.016902307835;5830.941690583125;5882.088276317178;6114.397440808724;6157.9384206157365;6163.7842675506145;6173.938260617426;6235.719392627343;6108.877822443526;6139.84650383744;6142.385761423822;6144.109104179869;6150.846228844314;5195;6074.483668888106;6163.537734669918;6223.937760622336;6227.595206311576;4949.579285760678;6066.272047354346;6108.267007959073;6131.172291740647;6137;6099.512039036867;6161.907571386433;6161.938380616137;6230.875382492415;6235;4900.950990490121;4912.680675756064;4912.975435122801;4919.163742163841;4942.481039490874;4327.156204540147;4380.9780951095045;4385.9692972151515;4402.471703395613;4438.800253988578;5628.183913332544;5706.857328566736;5707.1153971134545;5726.828195154152;5736.741846616912;6237.500999919996;6246.6876656167005;6262.812115636538;6268.1851359323;6275.780347687809;5736;5754.2202762543575;5767.913481297784;5795.478406943395;5819.476247137778;5275.076861549213;5314.4951229633125;5348.625596208228;5387;5394.703291318937;6197.96901015492;6231.4391704746795;6255;6273.278572964167;6292.590981586182;6156.661383623944;6201.286852012076;6206.162168107247;6208.565400421927;6228.252609686867;6045.183900173421;6236.438720515175;6279.497640188865;6280.623162610258;6281.497480201481;5955.255593050869;5993.880122397615;6015.6691381974415;6075.726592303446;6097.329293777592;6119.502553941335;6131.743695906945;6135.2326666699655;6200.906986395298;6240.812775616739;5811.070228763378;6076.422739839708;6093.268807743099;6105.743089885168;6139.754409823557;6033.366496517802;6061.575689701678;6070.696465176812;6075.36208698098;6086.117512960507;5719.542436605145;5787.36339002701;5811.593188476766;5841.153032810304;5869.765209391577;6086.513078953762;6087.604305720114;6099.11562823397;6111.1444397785235;6116.908246376219;5857.970710146422;5863.472287494146;5897.882042359214;5913.438223368773;5959.95700752358 03:41:28 INFO - PROCESS | 1644 | |1;ext.html;469.5421963585505;498.4691303761494;505.8390992671818;515.9484051594841;523.1342128776873;28750;31311.060668179933;31342.059738207834;31345.373092538135;31441.05676829699;40091.79954100233;40746.38880416796;40814.79592602033;42337.15325693484;42366;9169.495677737705;9323.20752736015;9346.485943273117;9347.439153650768;9348.57931393089;9257.814843703121;9342.2526197904;9349.065093490664;9349.439033657969;9354;8559.101294364096;8782.868256976151;9326.813463730721;9363.85954210684;9370.484623345714;9250.028746981538;9323.860142097874;9330.766730831716;9355;9359.48522831244;9264.675736349196;9357.906420935771;9358.4384936904;9365.297602679759;9369.156775890135;38779.612203877885;39000;39128.02179945496;39165.21669566593;39232.01919951996;2890.3785686077385;3160.668129846379;3243.5459035735016;3292.3250733599552;3317.1872891141584;28250.858745706344;29026.838926442917;29487.115386538437;30254.941077062416;30257.94097206609;3129.449498658586;3169.7147256747003;3192.6009248843893;3204.9198770030935;3236.6925142111695;480987;482207;482594;482685;483480;33136.50295245573;55157.724211378685;55219.89560208773;55284.44715552793;55356.44643553513;33828.985130446126;34903.82548087294;34949.12627184341;35000;35033.82483087568;11942.402879855972;14314.212718300594;14435.783463248059;14440.277986100653;14451.349689264009;12115.818262726067;12245.510179592895;12249.265044097383;12265;12267.877321226673;6876.449884009267;7597.088349398107;7736.071671399355;7808.258215469521;7831.569263690438;3144;3198.9360212796078;3212.646608873022;3220.565223694818;3240.0927740232773;948.2840455456087;971.3006635222629;988.1847475832467;999.2705325112709;1016.2225897188656;23282.41793955131;29675.25811854678;29849.85075074654;29859;29961.70038299633;862.5471627395542;864.5288317866778;914.5655813488539;977.2182254196756;1008.9596416143419;10726.785464290671;14797.890158238175;15139.697206055816;15188.088714677155;15355.082534318444;623364;626620;628481;628672;630032;47383.52616473791;54935.72532137314;55510;55535.444645553835;56085;1239.2254840724547;1262.693112628425;1263.8420197475316;1311.2591385866954;1382.654336415896;27587.44825103527;29070.7092929068;29745.958891438695;30248.243793905327;30287.242818929702;46028.7698561505;46398.304025440324;46535.301970469794;46584;46720.53279467162;405915.8816823706;410498.9475052606;439862;448107;453643;39652.60347396489;40293.59706403016;40559;40657;40674.593254067084;43915.682529524165;44309;44492.55507444884;44810.77594612006;44824.879378014506;31199.84400077985;35364.469532956515;37219.81390093032;37526;37627.43558846565;35879.820600897874;37472.063198420256;37536;38159;38338.23323533458;35934.10164745902;36421.81789091037;36884.63115368812;37130.44304335383;37715.245695085396;32938.341233174724;33700.12448568446;34588.82705586456;34653.48019779655;34813.30373392467;19327.936963467146;21078.47303817417;25004.49991000206;25465.363365916;25908.740912590634 03:41:28 INFO - PROCESS | 1644 | |2;jquery.html;334.7573009568063;353.1665269962889;380.2129591745087;384.4137690022716;403.97778122203283;14597.270136493173;14691;15913;20053.1978720851;20195.990200489952;18132.637347253047;18871.24515019401;18875.433736987878;19127.713084303712;19148.04259787012;5810.56420768443;5928.288605367362;5967.313758917715;5977.282726072877;6070.969645151768;5492.890142197154;5572.275604171452;5969.761209551612;6003.939960600382;6029.035354343306;5062.544371006608;5771.903338365697;5947.226860508128;5961.493273071794;5988.490978266831;5506.421825708303;5816.272965879265;5854.18041474194;5901.645901245939;5911.615744976606;5529.364123125852;5696.914546281768;5706.201131841545;5707.800226992035;5844.532437404998;13228;13484.39320230592;13526.797098043438;13542.119762215425;18377.540561485937;2156.0728886578763;2202.5815095131875;2215.523662412573;2551.3366524703524;2908;10250.026247506476;10362.533685984148;10390.428526431044;10420.270581059329;12836.920690323857;1744.4853768138303;1807.5154590137895;1896.2604584212168;1909.455805095541;1913.1911850163162;86925.56537217337;114330;121658;121749;122268;10681.83977240342;10855.402952837512;11057.11543076552;11380.946249764795;11687.88312116868;14646.560603181922;14665.266736663125;14670.046446980912;14688.339024743913;14702.26488675562;7422.5175363602275;7815.804604884924;8444.071152173256;8472.99122715878;8605.139486051345;8374.706885258987;8376.49741015541;8379.916200837913;8518.574071296534;8573.485590864568;4316.352547117895;4372.125574885035;4415.536368681306;4456.487503937024;4532.4334458192725;1646.8435498627612;1844.8155184481445;1875.6530041942285;1908.1127275816812;1913.2251438167286;830.6303694855852;965.9082387173207;966.4104896013426;972.5963725054165;1023.6366090038078;18870.358149859032;19987.900060499895;20055.699164512545;20087.296944606867;20450.590988180153;879.7140929198036;916.8028873792167;942.7266092833139;955.1690029674228;968.9224862011022;9795.902040979641;10085.394876307446;10487.94756026215;10640.414777187329;10653.360798352123;567108;569781;570861;574746.1262693744;598096;86151.70772438418;106406;126651.36674316754;128031.71968280384;134719;943.712167788831;954.346272803132;972.0473935543066;983.8832924630595;984.6947446291673;17874.910625446788;18244.361447349278;18780.7182892254;18885.339013134475;18961.43115706531;36571;47993;48629.5137048625;53668;54965.72517137549;76962.23037769551;78531.33559849647;79897.20102798898;80261;80832.3833523338;7867.17394673551;7966.243206895336;8036.794480827923;8052.919470805451;8141.755747327589;11771.705707357385;12173.817392738938;12307.646188896682;12318.568850090203;12623.431945562656;9442.197413219954;10218.693439196835;10761.515731791931;10821.585651878442;11081.5567377304;10419.895801041892;10746.46267686597;10752.677419677422;10791.082757965662;10828.783424331627;10689.572417103229;10741.516631751434;10787.568497260336;10946.945265273622;11073.723156921142;7852.13626501073;7883.44815862907;8006.799830004296;8013.3589312856;8087.878681819895;9607.759806004906;10142.391456512632;10252.692419227136;10286.639967601099;10616.416097114732 03:41:28 INFO - PROCESS | 1644 | |3;mootools.html;1295.7214198947224;1431.1270125223616;1471.5438214153612;1580.1862041048862;1781.4922747017094;7515.511491753039;7924.6433910474025;8199.754007379774;8438.662453501867;8441.78895527612;8302;8395.706150284741;8402.327813774898;8409.83180336393;8430.409871309012;2168.3711723600136;2207.415035015722;2210.9668354974688;2214.8560343577615;2215.667649852519;2266.286119872243;2296.494771150353;2338.4621537046405;2341.2273949596593;2342.672025916373;2307.538492301538;2314.109068008812;2340.8127349811975;2346.941326466835;2352.776486233805;2257.1986944634664;2286.6684330771945;2320.211128216398;2322.6980492535945;2327.185485080225;1928.951776205592;1970.8620396572246;2322.9186978455673;2350.13045173287;2351.470919043202;8326.542040187787;8409.705660301921;8423.620937057847;8443.071262161156;8450.985881694174;5022.748862556894;5221.895562088737;5239.397469291042;5265.973670131625;5277.340332458442;3259.071164718044;3375.8987230383127;3576.5886923003927;3647.854085836563;3653.6163702811086;1049.3284298049255;1058.7035630023606;1080.36258607422;1082.8127183090191;1086.5653738504582;10972;11034.668959931216;11133.888661113366;11142.832857507145;11177.608783692533;4242.448481697421;4418.690691651618;4430.756308403004;4520.141173177086;4542.295944128666;8675.305975521944;8686.609102590399;8710.956445217733;8711.564421778885;8714.346424018097;5028.120078986236;5028.17035189197;5029.446760856303;5030.874228144326;5033.245013247969;4024.7383920045104;4281.079567892918;4439.001224724411;4701.388819453449;4704.506026867198;3647.9785660015236;3676.7426280160666;3758.661720445173;3762;3769.754965927206;1030.3457304611475;1140.2132528555271;1157.1726215755775;1165.3241120150292;1169.1114752787942;784.7567254151118;824.6371596497522;840.7267638017669;848.974530764078;867.6832955971025;5228.660137091077;5484.7531861066345;5751.252337196138;5758.481736643722;5911.9704401478575;820.9507429554246;863.5207459859838;865.8960924688952;877.8946526416743;936.8922573904089;3668.6331366863633;3688.1517251032415;3816.46569480273;3943.11279962012;3956.485656864589;9851.704448866545;10106.595736170619;10205;10205.183585313158;10224.437655928996;10189.949050254701;10194;10199.847002294971;10231.897681023094;10306.896931030744;7485.7754267372065;7813.374930005586;7845.0193725784275;7902;7903.735402335714;5736.4550367715;5747.942520574741;5783.132530120515;5873.177755114373;5895.233619629421;8823.470591764515;9106.271498280254;9237.676681316121;9253.028432014542;9470.100340467645;9449.716508504756;9483.620655173716;9550.617975280911;9584.9041509584;9589.712308630753;7218.242084581253;7285.453590980804;7286.817829554304;7330.266973302627;7344.70621175147;6763.6618169092335;7769.378449724121;7942.324902383362;7958.442908996546;7984.720534781255;7099.112610923635;7200.531965422231;7225.819354516179;7288.635568221674;7310.488265821342;6997.4052205561065;7049.753258635923;7086.610236437045;7120.786376408716;7166.1400631922415;6595.868082638224;6722.1261236040955;6803.387695107464;6817.5909445433435;6826.795196144124;4924.113659541317;4945.208766597327;4961.107000739901;4987.900241995212;5006.374203224597;4859.514048595112;4864.586510146677;4878.0975519529;4927.876803079952;5171.715555644325 03:41:28 INFO - PROCESS | 1644 | |4;prototype.html;293.33266817989073;299.9475091858925;307.9969200307997;312.3581040960826;313.84464689978466;4722.409698787652;5174.2756014158;5219.321488206532;5255.553277971372;5283.622369933536;4324.243257429945;5025.773840177191;5082.4917508249155;5121.359830021247;5122.282880396748;3970.186111847079;3981.442598036277;3986.744175584696;3993.880183594497;4019.77891215983;3797.791121488317;4009.578994205596;4027.4764280643476;4028.7582745035393;4045.4943132108488;3461.16931936336;3648.4892115103776;3834.2523207974455;4018.0959284160976;4054.2296963576828;3558.804265765382;4021.3766866135797;4029.959700403017;4043.1711499142607;4066.9186616267657;3532.6046211746475;3536.4341705327024;3536.8875389656937;3538.6284440133672;3545.9113522161892;4500.752458614775;4979.904421027368;5020.221865610836;5060.468650791688;5104.213525266165;4558.407407037064;4616.884577885546;4619.006913513611;4642.168277350814;4646.512116227781;5443.727813609344;5497.979712215118;5510.614257002011;5513.696746678931;5553.222548843165;3454.809985450799;3759.9284204002;3898.688104951597;3922.156736301709;3932.8230229639735;5370.812021579226;5404.7838086476495;5611.410801865827;5687.090065589486;5714.285714285715;3380.4253276942486;3496.3182179474757;3628.637136286403;3973.364261718169;3978.124812541236;4013.618706222904;4051.513818341759;4054.6350828425584;4058.431819545266;4059.289624315733;3544.822758862098;3689.5572531296416;4472.9776351118035;4595.839145629887;4659.718577391244;4161.791910404529;4643;5344.893102138013;5506.752196151183;5509.256250406224;1236.9072319576069;1292.4442489729317;1296.6110166949877;1297.228149251194;1297.5069473599972;779.5946108023796;840.3235395506675;892.2371372476568;902.634433054614;904.5070436612062;4904;4909.877253068702;4916.852494425173;4921.753912304442;4924.217816933421;4535.233507560385;4773.782685415197;5297.549708274762;5352.19717042439;5363.157526510955;4800.231962885921;4882.51174882516;4886.731229782325;4888.804447822118;4893.535114164149;2319.677783663296;2565.6921169459783;2659.8670066496984;2850.930900912158;2852.9857350713114;4964.503549645079;5435.673859568438;5539.41836107211;5597.74810133545;5613.37937030613;4635.745034023161;4868.68545492722;4963.875903102451;5265.130129354921;5509.071824861267;5443.945560544344;5473.589480788957;5481.260029895992;5487.5884308677005;5642.78680083784;5309.725670835015;5315.920261196008;5339.813106541253;5371.785128594813;5462.617616766788;4956.826511072095;5313.362396512289;5368.677879327252;5395.4604539545735;5404.243405923253;4772;4803.447603525639;5576.860578485408;5597.776088956396;5628.352739435018;3266.428375034407;3662.6154253802965;3666.908327291839;3673.3368307976502;3716.201016781297;4894.804207831647;5322.973385133049;5516.9448305516435;5532.253145825262;5563.610547261653;5246.921296180484;5387.084195686664;5405.621606487665;5407.594430417813;5421.864453388697;5504.834854954358;5513.724313784375;5529.253550770594;5566.471185237396;5589.037809960727;5431.528654462131;5466.315032366295;5475.891781664168;5483.505005222534;5538.3661819764075;4430.955690443183;5165.535101840853;5530.446955304437;5531.640443371168;5543.196236545759;5413.945860541344;5427.83716488506;5455.699936503372;5470.8085217017215;5472.835814925558 03:41:28 INFO - PROCESS | 1644 | |5;yui.html;676.9593824370538;695.2039914298128;697.1111832413669;750.8610906982212;762.7444805989993;13990.580282591525;14235.43058277669;14751.631209219775;14763.188024658653;14918.552443426688;12910.354482275896;13132.73734525309;13275.070745047826;13276.203427794338;13322;799.2447137455107;821.0311832038193;821.5070957425556;823.9958800205991;824.3364091906013;653.4674240494006;671.0571646836203;807.7092246791151;841.6465084664455;843.2832092721169;865.9004214515317;878.4509681449094;880.6829541365104;880.933929955256;883.5538053283083;868.405142477405;870.2602787630527;871.0853603716105;872.7120050383422;873.0309356614185;500.31956539106784;512.7923191107608;606.2270604978644;630.6027202862214;635.8219698484431;14365.856341436556;14408.766641170636;14412.207328596924;14434.855651443457;14441.205733684643;13119.344032798417;13182.802257966137;13262.602121936357;13329.600111996655;13405;11681.941590291994;11923.880761192451;12027.51889924402;12352.876471235353;12465.252084874846;3694.9630503695157;4402.779861006937;4529.773511324421;4535.682502224846;4562.931556026662;60867;80165.39669206583;80522;80922.59538702328;81200;10147.594096236215;10510.211734119819;10559.260851740459;10609.257351985287;10958.726031849108;19180.61638767217;19541.609167816565;20005.899970500053;20089.698654520194;20268.290609828586;7516.398688105048;7564.773056808304;7583.5070720403;7657.272559106876;7697.0378702662165;4162.146759914211;4214.072903961123;4235.78821058952;4289.0992891492515;4434.201843668171;3291.6214635316765;3398.813065281384;3400.7449441291506;3403.4554471284478;3408.284260305314;564.745864361031;622.4273668225244;641.6310621392718;652.0935899100255;652.5595223224304;546.5572885962304;552.3813329071386;592.6562593695646;669.8191488298132;687.151368060445;7598.7193169690945;8325.8334833303;8782.297416206688;9025.232855207381;9061.275097992146;576.8500189950643;604.392585451627;625.7309356976452;628.3779058731914;673.2224280955504;4046.433499310099;4524.773761311922;4542.750148741785;4555.658325625525;4651.930221046687;71149.64425177842;71528;72083.2791672087;97001;110386;80955.5952220247;95071;97006.51496742471;101337.98662013432;101516.49241753743;71967.6401617999;87142;87367;87989;89163;7241.746538871114;8603.698870539502;8883.33374996865;8905;8978;52777.736111319195;54296.45703542914;54593.63515912134;54839.177412339646;55669.721651393105;74487.62756186184;78873;79052.60473697595;80810.19189808027;81814.77277840718;10927.235093543375;10959.83560246581;11108.333499990027;11293.548258069584;11313.943430283125;11603.245789023686;12302;12349.629511114681;12796.36018199105;12841.101122921305;11559.19085664029;11666.650000499998;11800.232984855957;12012.399380031138;12028.699282518008;9580.18568421692;12306.446209920396;12512.687182820138;12600;12957.611271661865;12191.634250972485;12192.878071219175;12348.691282718004;12778.169418987736;12782.552610658582;10634.042936135786;10670.8399374011;10714.357138571711;10910.727231819268;11214.7757044857;11016.504257308601;11066.170037247077;11166.776664466828;11220.326780393203;11243.943780281374 03:41:28 INFO - PROCESS | 1644 | __end_tp_report 03:41:28 INFO - PROCESS | 1644 | __start_cc_report 03:41:28 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1390 03:41:28 INFO - PROCESS | 1644 | __end_cc_report 03:41:28 INFO - PROCESS | 1644 | __startTimestamp1481802088822__endTimestamp 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | ------- Summary: start ------- 03:41:28 INFO - PROCESS | 1644 | Number of tests: 6 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5689.74 Median:5863.47 stddev:633.70 (10.8%) stddev-sans-first:607.50 03:41:28 INFO - PROCESS | 1644 | Values: 3308.8 3318.0 3325.4 3354.9 3377.7 5589.9 5668.9 5677.2 5702.7 5721.8 5631.7 5700.1 5705.5 5717.1 5719.3 5173.4 5182.2 5196.5 5210.3 5256.2 4433.7 5118.4 5137.4 5141.4 5222.6 5142.7 5183.0 5224.5 5232.7 5235.7 5148.6 5174.2 5188.8 5221.8 5277.7 5113.2 5207.2 5224.0 5232.2 5271.0 5490.8 5561.4 5578.7 5628.3 5723.4 6121.4 6139.3 6146.8 6185.4 6201.0 6173.9 6175.0 6191.7 6197.8 6199.3 5639.9 5720.2 5822.0 5830.9 5882.1 6114.4 6157.9 6163.8 6173.9 6235.7 6108.9 6139.8 6142.4 6144.1 6150.8 5195.0 6074.5 6163.5 6223.9 6227.6 4949.6 6066.3 6108.3 6131.2 6137.0 6099.5 6161.9 6161.9 6230.9 6235.0 4901.0 4912.7 4913.0 4919.2 4942.5 4327.2 4381.0 4386.0 4402.5 4438.8 5628.2 5706.9 5707.1 5726.8 5736.7 6237.5 6246.7 6262.8 6268.2 6275.8 5736.0 5754.2 5767.9 5795.5 5819.5 5275.1 5314.5 5348.6 5387.0 5394.7 6198.0 6231.4 6255.0 6273.3 6292.6 6156.7 6201.3 6206.2 6208.6 6228.3 6045.2 6236.4 6279.5 6280.6 6281.5 5955.3 5993.9 6015.7 6075.7 6097.3 6119.5 6131.7 6135.2 6200.9 6240.8 5811.1 6076.4 6093.3 6105.7 6139.8 6033.4 6061.6 6070.7 6075.4 6086.1 5719.5 5787.4 5811.6 5841.2 5869.8 6086.5 6087.6 6099.1 6111.1 6116.9 5858.0 5863.5 5897.9 5913.4 5960.0 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:63497.76 Median:39146.62 stddev:139233.98 (355.7%) stddev-sans-first:139544.15 03:41:28 INFO - PROCESS | 1644 | Values: 469.5 498.5 505.8 515.9 523.1 28750.0 31311.1 31342.1 31345.4 31441.1 40091.8 40746.4 40814.8 42337.2 42366.0 9169.5 9323.2 9346.5 9347.4 9348.6 9257.8 9342.3 9349.1 9349.4 9354.0 8559.1 8782.9 9326.8 9363.9 9370.5 9250.0 9323.9 9330.8 9355.0 9359.5 9264.7 9357.9 9358.4 9365.3 9369.2 38779.6 39000.0 39128.0 39165.2 39232.0 2890.4 3160.7 3243.5 3292.3 3317.2 28250.9 29026.8 29487.1 30254.9 30257.9 3129.4 3169.7 3192.6 3204.9 3236.7 480987.0 482207.0 482594.0 482685.0 483480.0 33136.5 55157.7 55219.9 55284.4 55356.4 33829.0 34903.8 34949.1 35000.0 35033.8 11942.4 14314.2 14435.8 14440.3 14451.3 12115.8 12245.5 12249.3 12265.0 12267.9 6876.4 7597.1 7736.1 7808.3 7831.6 3144.0 3198.9 3212.6 3220.6 3240.1 948.3 971.3 988.2 999.3 1016.2 23282.4 29675.3 29849.9 29859.0 29961.7 862.5 864.5 914.6 977.2 1009.0 10726.8 14797.9 15139.7 15188.1 15355.1 623364.0 626620.0 628481.0 628672.0 630032.0 47383.5 54935.7 55510.0 55535.4 56085.0 1239.2 1262.7 1263.8 1311.3 1382.7 27587.4 29070.7 29746.0 30248.2 30287.2 46028.8 46398.3 46535.3 46584.0 46720.5 405915.9 410498.9 439862.0 448107.0 453643.0 39652.6 40293.6 40559.0 40657.0 40674.6 43915.7 44309.0 44492.6 44810.8 44824.9 31199.8 35364.5 37219.8 37526.0 37627.4 35879.8 37472.1 37536.0 38159.0 38338.2 35934.1 36421.8 36884.6 37130.4 37715.2 32938.3 33700.1 34588.8 34653.5 34813.3 19327.9 21078.5 25004.5 25465.4 25908.7 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:33308.59 Median:1621.38 stddev:96142.21 (5929.7%) stddev-sans-first:96380.03 03:41:28 INFO - PROCESS | 1644 | Values: 334.8 353.2 380.2 384.4 404.0 14597.3 14691.0 15913.0 20053.2 20196.0 18132.6 18871.2 18875.4 19127.7 19148.0 5810.6 5928.3 5967.3 5977.3 6071.0 5492.9 5572.3 5969.8 6003.9 6029.0 5062.5 5771.9 5947.2 5961.5 5988.5 5506.4 5816.3 5854.2 5901.6 5911.6 5529.4 5696.9 5706.2 5707.8 5844.5 13228.0 13484.4 13526.8 13542.1 18377.5 2156.1 2202.6 2215.5 2551.3 2908.0 10250.0 10362.5 10390.4 10420.3 12836.9 1744.5 1807.5 1896.3 1909.5 1913.2 86925.6 114330.0 121658.0 121749.0 122268.0 10681.8 10855.4 11057.1 11380.9 11687.9 14646.6 14665.3 14670.0 14688.3 14702.3 7422.5 7815.8 8444.1 8473.0 8605.1 8374.7 8376.5 8379.9 8518.6 8573.5 4316.4 4372.1 4415.5 4456.5 4532.4 1646.8 1844.8 1875.7 1908.1 1913.2 830.6 965.9 966.4 972.6 1023.6 18870.4 19987.9 20055.7 20087.3 20450.6 879.7 916.8 942.7 955.2 968.9 9795.9 10085.4 10487.9 10640.4 10653.4 567108.0 569781.0 570861.0 574746.1 598096.0 86151.7 106406.0 126651.4 128031.7 134719.0 943.7 954.3 972.0 983.9 984.7 17874.9 18244.4 18780.7 18885.3 18961.4 36571.0 47993.0 48629.5 53668.0 54965.7 76962.2 78531.3 79897.2 80261.0 80832.4 7867.2 7966.2 8036.8 8052.9 8141.8 11771.7 12173.8 12307.6 12318.6 12623.4 9442.2 10218.7 10761.5 10821.6 11081.6 10419.9 10746.5 10752.7 10791.1 10828.8 10689.6 10741.5 10787.6 10946.9 11073.7 7852.1 7883.4 8006.8 8013.4 8087.9 9607.8 10142.4 10252.7 10286.6 10616.4 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5439.33 Median:5028.81 stddev:3002.55 (59.7%) stddev-sans-first:2994.82 03:41:28 INFO - PROCESS | 1644 | Values: 1295.7 1431.1 1471.5 1580.2 1781.5 7515.5 7924.6 8199.8 8438.7 8441.8 8302.0 8395.7 8402.3 8409.8 8430.4 2168.4 2207.4 2211.0 2214.9 2215.7 2266.3 2296.5 2338.5 2341.2 2342.7 2307.5 2314.1 2340.8 2346.9 2352.8 2257.2 2286.7 2320.2 2322.7 2327.2 1929.0 1970.9 2322.9 2350.1 2351.5 8326.5 8409.7 8423.6 8443.1 8451.0 5022.7 5221.9 5239.4 5266.0 5277.3 3259.1 3375.9 3576.6 3647.9 3653.6 1049.3 1058.7 1080.4 1082.8 1086.6 10972.0 11034.7 11133.9 11142.8 11177.6 4242.4 4418.7 4430.8 4520.1 4542.3 8675.3 8686.6 8711.0 8711.6 8714.3 5028.1 5028.2 5029.4 5030.9 5033.2 4024.7 4281.1 4439.0 4701.4 4704.5 3648.0 3676.7 3758.7 3762.0 3769.8 1030.3 1140.2 1157.2 1165.3 1169.1 784.8 824.6 840.7 849.0 867.7 5228.7 5484.8 5751.3 5758.5 5912.0 821.0 863.5 865.9 877.9 936.9 3668.6 3688.2 3816.5 3943.1 3956.5 9851.7 10106.6 10205.0 10205.2 10224.4 10189.9 10194.0 10199.8 10231.9 10306.9 7485.8 7813.4 7845.0 7902.0 7903.7 5736.5 5747.9 5783.1 5873.2 5895.2 8823.5 9106.3 9237.7 9253.0 9470.1 9449.7 9483.6 9550.6 9584.9 9589.7 7218.2 7285.5 7286.8 7330.3 7344.7 6763.7 7769.4 7942.3 7958.4 7984.7 7099.1 7200.5 7225.8 7288.6 7310.5 6997.4 7049.8 7086.6 7120.8 7166.1 6595.9 6722.1 6803.4 6817.6 6826.8 4924.1 4945.2 4961.1 4987.9 5006.4 4859.5 4864.6 4878.1 4927.9 5171.7 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4405.56 Median:4919.30 stddev:1331.31 (27.1%) stddev-sans-first:1298.78 03:41:28 INFO - PROCESS | 1644 | Values: 293.3 299.9 308.0 312.4 313.8 4722.4 5174.3 5219.3 5255.6 5283.6 4324.2 5025.8 5082.5 5121.4 5122.3 3970.2 3981.4 3986.7 3993.9 4019.8 3797.8 4009.6 4027.5 4028.8 4045.5 3461.2 3648.5 3834.3 4018.1 4054.2 3558.8 4021.4 4030.0 4043.2 4066.9 3532.6 3536.4 3536.9 3538.6 3545.9 4500.8 4979.9 5020.2 5060.5 5104.2 4558.4 4616.9 4619.0 4642.2 4646.5 5443.7 5498.0 5510.6 5513.7 5553.2 3454.8 3759.9 3898.7 3922.2 3932.8 5370.8 5404.8 5611.4 5687.1 5714.3 3380.4 3496.3 3628.6 3973.4 3978.1 4013.6 4051.5 4054.6 4058.4 4059.3 3544.8 3689.6 4473.0 4595.8 4659.7 4161.8 4643.0 5344.9 5506.8 5509.3 1236.9 1292.4 1296.6 1297.2 1297.5 779.6 840.3 892.2 902.6 904.5 4904.0 4909.9 4916.9 4921.8 4924.2 4535.2 4773.8 5297.5 5352.2 5363.2 4800.2 4882.5 4886.7 4888.8 4893.5 2319.7 2565.7 2659.9 2850.9 2853.0 4964.5 5435.7 5539.4 5597.7 5613.4 4635.7 4868.7 4963.9 5265.1 5509.1 5443.9 5473.6 5481.3 5487.6 5642.8 5309.7 5315.9 5339.8 5371.8 5462.6 4956.8 5313.4 5368.7 5395.5 5404.2 4772.0 4803.4 5576.9 5597.8 5628.4 3266.4 3662.6 3666.9 3673.3 3716.2 4894.8 5323.0 5516.9 5532.3 5563.6 5246.9 5387.1 5405.6 5407.6 5421.9 5504.8 5513.7 5529.3 5566.5 5589.0 5431.5 5466.3 5475.9 5483.5 5538.4 4431.0 5165.5 5530.4 5531.6 5543.2 5413.9 5427.8 5455.7 5470.8 5472.8 03:41:28 INFO - PROCESS | 1644 | 03:41:28 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19492.28 Median:4607.43 stddev:27901.82 (605.6%) stddev-sans-first:27944.32 03:41:28 INFO - PROCESS | 1644 | Values: 677.0 695.2 697.1 750.9 762.7 13990.6 14235.4 14751.6 14763.2 14918.6 12910.4 13132.7 13275.1 13276.2 13322.0 799.2 821.0 821.5 824.0 824.3 653.5 671.1 807.7 841.6 843.3 865.9 878.5 880.7 880.9 883.6 868.4 870.3 871.1 872.7 873.0 500.3 512.8 606.2 630.6 635.8 14365.9 14408.8 14412.2 14434.9 14441.2 13119.3 13182.8 13262.6 13329.6 13405.0 11681.9 11923.9 12027.5 12352.9 12465.3 3695.0 4402.8 4529.8 4535.7 4562.9 60867.0 80165.4 80522.0 80922.6 81200.0 10147.6 10510.2 10559.3 10609.3 10958.7 19180.6 19541.6 20005.9 20089.7 20268.3 7516.4 7564.8 7583.5 7657.3 7697.0 4162.1 4214.1 4235.8 4289.1 4434.2 3291.6 3398.8 3400.7 3403.5 3408.3 564.7 622.4 641.6 652.1 652.6 546.6 552.4 592.7 669.8 687.2 7598.7 8325.8 8782.3 9025.2 9061.3 576.9 604.4 625.7 628.4 673.2 4046.4 4524.8 4542.8 4555.7 4651.9 71149.6 71528.0 72083.3 97001.0 110386.0 80955.6 95071.0 97006.5 101338.0 101516.5 71967.6 87142.0 87367.0 87989.0 89163.0 7241.7 8603.7 8883.3 8905.0 8978.0 52777.7 54296.5 54593.6 54839.2 55669.7 74487.6 78873.0 79052.6 80810.2 81814.8 10927.2 10959.8 11108.3 11293.5 11313.9 11603.2 12302.0 12349.6 12796.4 12841.1 11559.2 11666.7 11800.2 12012.4 12028.7 9580.2 12306.4 12512.7 12600.0 12957.6 12191.6 12192.9 12348.7 12778.2 12782.6 10634.0 10670.8 10714.4 10910.7 11214.8 11016.5 11066.2 11166.8 11220.3 11243.9 03:41:28 INFO - PROCESS | 1644 | -------- Summary: end -------- 03:41:28 INFO - PROCESS | 1644 | 03:41:29 INFO - TEST-INFO | 1644: exit 0 03:41:29 INFO - TEST-OK | dromaeo_css | took 1119548ms 03:41:29 INFO - TEST-START | kraken 03:41:29 INFO - operating with platform_type : mac_ 03:41:29 INFO - Initialising browser for kraken test... 03:41:29 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpe54uxX/profile http://localhost:49249/getInfo.html) 03:41:32 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 03:41:32 INFO - PROCESS | 1673 | colorDepth:24 03:41:32 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 03:41:32 INFO - PROCESS | 1673 | __metrics 03:41:32 INFO - PROCESS | 1673 | 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" 03:41:32 INFO - PROCESS | 1673 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:41:33 INFO - TEST-INFO | 1673: exit 0 03:41:33 INFO - Browser initialized. 03:41:33 INFO - Running cycle 1/1 for kraken test... 03:41:33 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpe54uxX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:45 INFO - PROCESS | 1675 | RSS: Main: 222355456 03:41:45 INFO - PROCESS | 1675 | 03:41:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:49 INFO - PROCESS | 1675 | RSS: Main: 272711680 03:41:49 INFO - PROCESS | 1675 | 03:41:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 03:41:52 INFO - PROCESS | 1675 | RSS: Main: 283234304 03:41:52 INFO - PROCESS | 1675 | 03:41:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 03:41:56 INFO - PROCESS | 1675 | RSS: Main: 380534784 03:41:56 INFO - PROCESS | 1675 | 03:41:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 03:41:59 INFO - PROCESS | 1675 | RSS: Main: 283086848 03:41:59 INFO - PROCESS | 1675 | 03:42:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:42:01 INFO - PROCESS | 1675 | RSS: Main: 284127232 03:42:01 INFO - PROCESS | 1675 | 03:42:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 03:42:05 INFO - PROCESS | 1675 | RSS: Main: 323252224 03:42:05 INFO - PROCESS | 1675 | 03:42:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 03:42:08 INFO - PROCESS | 1675 | RSS: Main: 324669440 03:42:08 INFO - PROCESS | 1675 | 03:42:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 03:42:11 INFO - PROCESS | 1675 | RSS: Main: 322928640 03:42:11 INFO - PROCESS | 1675 | 03:42:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:42:14 INFO - PROCESS | 1675 | RSS: Main: 297816064 03:42:14 INFO - PROCESS | 1675 | 03:42:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:42:16 INFO - PROCESS | 1675 | RSS: Main: 299188224 03:42:16 INFO - PROCESS | 1675 | 03:42:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:19 INFO - PROCESS | 1675 | RSS: Main: 302538752 03:42:19 INFO - PROCESS | 1675 | 03:42:22 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:23 INFO - PROCESS | 1675 | RSS: Main: 321191936 03:42:23 INFO - PROCESS | 1675 | 03:42:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:26 INFO - PROCESS | 1675 | RSS: Main: 314671104 03:42:26 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 03:42:28 INFO - PROCESS | 1675 | RSS: Main: 358326272 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | __start_tp_report 03:42:28 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 03:42:28 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 03:42:28 INFO - PROCESS | 1675 | |i|pagename|runs| 03:42:28 INFO - PROCESS | 1675 | |0;ai-astar;125;170;139;113;113;112;112;113;114;112 03:42:28 INFO - PROCESS | 1675 | |1;audio-beat-detection;143;111;109;142;111;111;109;108;142;109 03:42:28 INFO - PROCESS | 1675 | |2;audio-dft;183;182;185;183;183;183;184;185;183;185 03:42:28 INFO - PROCESS | 1675 | |3;audio-fft;82;59;60;89;59;60;93;83;111;71 03:42:28 INFO - PROCESS | 1675 | |4;audio-oscillator;89;71;95;69;69;69;90;68;69;92 03:42:28 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;87;87;122;84;86;84;88;84;88;84 03:42:28 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;93;92;92;92;92;94;92;93;93 03:42:28 INFO - PROCESS | 1675 | |7;imaging-desaturate;85;83;80;80;84;84;89;125;81;81 03:42:28 INFO - PROCESS | 1675 | |8;json-parse-financial;59;56;54;53;54;57;58;57;59;59 03:42:28 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;66;71;71;77;72;70;69;72;71;72 03:42:28 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;84;84;84;84;83;83;82;83;84;84 03:42:28 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;140;140;153;150;153;142;143;144;182;153 03:42:28 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;165;166;167;228;225;166;165;167;165;163 03:42:28 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;67;68;68;67;67;67;65;66;67 03:42:28 INFO - PROCESS | 1675 | __end_tp_report 03:42:28 INFO - PROCESS | 1675 | __start_cc_report 03:42:28 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2024 03:42:28 INFO - PROCESS | 1675 | __end_cc_report 03:42:28 INFO - PROCESS | 1675 | __startTimestamp1481802148652__endTimestamp 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | ------- Summary: start ------- 03:42:28 INFO - PROCESS | 1675 | Number of tests: 14 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:122.30 Median:113.50 stddev:18.88 (16.6%) stddev-sans-first:20.00 03:42:28 INFO - PROCESS | 1675 | Values: 125.0 170.0 139.0 113.0 113.0 112.0 112.0 113.0 114.0 112.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:111.00 stddev:15.79 (14.2%) stddev-sans-first:14.28 03:42:28 INFO - PROCESS | 1675 | Values: 143.0 111.0 109.0 142.0 111.0 111.0 109.0 108.0 142.0 109.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.60 Median:183.50 stddev:1.07 (0.6%) stddev-sans-first:1.12 03:42:28 INFO - PROCESS | 1675 | Values: 183.0 182.0 185.0 183.0 183.0 183.0 184.0 185.0 183.0 185.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:76.70 Median:76.50 stddev:17.88 (23.4%) stddev-sans-first:18.86 03:42:28 INFO - PROCESS | 1675 | Values: 82.0 59.0 60.0 89.0 59.0 60.0 93.0 83.0 111.0 71.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:80.00 stddev:11.66 (14.6%) stddev-sans-first:11.68 03:42:28 INFO - PROCESS | 1675 | Values: 89.0 71.0 95.0 69.0 69.0 69.0 90.0 68.0 69.0 92.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:86.50 stddev:11.58 (13.4%) stddev-sans-first:12.25 03:42:28 INFO - PROCESS | 1675 | Values: 87.0 87.0 122.0 84.0 86.0 84.0 88.0 84.0 88.0 84.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 03:42:28 INFO - PROCESS | 1675 | Values: 93.0 93.0 92.0 92.0 92.0 92.0 94.0 92.0 93.0 93.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:87.20 Median:83.50 stddev:13.56 (16.2%) stddev-sans-first:14.36 03:42:28 INFO - PROCESS | 1675 | Values: 85.0 83.0 80.0 80.0 84.0 84.0 89.0 125.0 81.0 81.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.50 stddev:2.27 (3.9%) stddev-sans-first:2.24 03:42:28 INFO - PROCESS | 1675 | Values: 59.0 56.0 54.0 53.0 54.0 57.0 58.0 57.0 59.0 59.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:2.77 (3.9%) stddev-sans-first:2.24 03:42:28 INFO - PROCESS | 1675 | Values: 66.0 71.0 71.0 77.0 72.0 70.0 69.0 72.0 71.0 72.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 03:42:28 INFO - PROCESS | 1675 | Values: 84.0 84.0 84.0 84.0 83.0 83.0 82.0 83.0 84.0 84.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:151.50 stddev:12.47 (8.2%) stddev-sans-first:12.69 03:42:28 INFO - PROCESS | 1675 | Values: 140.0 140.0 153.0 150.0 153.0 142.0 143.0 144.0 182.0 153.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:166.50 stddev:25.76 (15.5%) stddev-sans-first:26.90 03:42:28 INFO - PROCESS | 1675 | Values: 165.0 166.0 167.0 228.0 225.0 166.0 165.0 167.0 165.0 163.0 03:42:28 INFO - PROCESS | 1675 | 03:42:28 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.94 (1.4%) stddev-sans-first:0.93 03:42:28 INFO - PROCESS | 1675 | Values: 68.0 67.0 68.0 68.0 67.0 67.0 67.0 65.0 66.0 67.0 03:42:28 INFO - PROCESS | 1675 | -------- Summary: end -------- 03:42:28 INFO - PROCESS | 1675 | 03:42:29 INFO - TEST-INFO | 1675: exit 0 03:42:29 INFO - TEST-OK | kraken | took 60227ms 03:42:29 INFO - SUITE-END | took 1179s 03:42:29 INFO - Completed test suite (00:19:39) 03:42:29 INFO - javascript benchmark 03:42:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.768386212965, 3317.988013655835, 3325.434676105062, 3354.8658053677855, 3377.7128944039755, 5589.944100558993, 5668.914966275509, 5677.205191273225, 5702.68635225063, 5721.828345149642, 5631.690257035862, 5700.059490184115, 5705.51503122234, 5717.113847353657, 5719.256496655439, 5173.379194496647, 5182.2226666000115, 5196.454372290912, 5210.348706411699, 5256.237845512398, 4433.689641725081, 5118.360204974379, 5137.409197942228, 5141.383034035902, 5222.63441559091, 5142.742862856842, 5183.041137389595, 5224.529792318691, 5232.7383630818495, 5235.685858848462, 5148.5881129509735, 5174.2238664200295, 5188.844334669966, 5221.791128354861, 5277.683338999634, 5113.2330150477355, 5207.192885102815, 5223.955208958224, 5232.189010703347, 5271.024860400838, 5490.835274941758, 5561.360443549003, 5578.693171875545, 5628.26832511775, 5723.370429252779, 6121.449069583714, 6139.259792094406, 6146.7541298348015, 6185.350538193515, 6201.0, 6173.938260617381, 6174.96912515439, 6191.721372538246, 6197.845053873689, 6199.287081985584, 5639.859003524863, 5720.199172115824, 5822.016902307835, 5830.941690583125, 5882.088276317178, 6114.397440808724, 6157.9384206157365, 6163.7842675506145, 6173.938260617426, 6235.719392627343, 6108.877822443526, 6139.84650383744, 6142.385761423822, 6144.109104179869, 6150.846228844314, 5195.0, 6074.483668888106, 6163.537734669918, 6223.937760622336, 6227.595206311576, 4949.579285760678, 6066.272047354346, 6108.267007959073, 6131.172291740647, 6137.0, 6099.512039036867, 6161.907571386433, 6161.938380616137, 6230.875382492415, 6235.0, 4900.950990490121, 4912.680675756064, 4912.975435122801, 4919.163742163841, 4942.481039490874, 4327.156204540147, 4380.9780951095045, 4385.9692972151515, 4402.471703395613, 4438.800253988578, 5628.183913332544, 5706.857328566736, 5707.1153971134545, 5726.828195154152, 5736.741846616912, 6237.500999919996, 6246.6876656167005, 6262.812115636538, 6268.1851359323, 6275.780347687809, 5736.0, 5754.2202762543575, 5767.913481297784, 5795.478406943395, 5819.476247137778, 5275.076861549213, 5314.4951229633125, 5348.625596208228, 5387.0, 5394.703291318937, 6197.96901015492, 6231.4391704746795, 6255.0, 6273.278572964167, 6292.590981586182, 6156.661383623944, 6201.286852012076, 6206.162168107247, 6208.565400421927, 6228.252609686867, 6045.183900173421, 6236.438720515175, 6279.497640188865, 6280.623162610258, 6281.497480201481, 5955.255593050869, 5993.880122397615, 6015.6691381974415, 6075.726592303446, 6097.329293777592, 6119.502553941335, 6131.743695906945, 6135.2326666699655, 6200.906986395298, 6240.812775616739, 5811.070228763378, 6076.422739839708, 6093.268807743099, 6105.743089885168, 6139.754409823557, 6033.366496517802, 6061.575689701678, 6070.696465176812, 6075.36208698098, 6086.117512960507, 5719.542436605145, 5787.36339002701, 5811.593188476766, 5841.153032810304, 5869.765209391577, 6086.513078953762, 6087.604305720114, 6099.11562823397, 6111.1444397785235, 6116.908246376219, 5857.970710146422, 5863.472287494146, 5897.882042359214, 5913.438223368773, 5959.95700752358], "value": 5649.43148167795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.5421963585505, 498.4691303761494, 505.8390992671818, 515.9484051594841, 523.1342128776873, 28750.0, 31311.060668179933, 31342.059738207834, 31345.373092538135, 31441.05676829699, 40091.79954100233, 40746.38880416796, 40814.79592602033, 42337.15325693484, 42366.0, 9169.495677737705, 9323.20752736015, 9346.485943273117, 9347.439153650768, 9348.57931393089, 9257.814843703121, 9342.2526197904, 9349.065093490664, 9349.439033657969, 9354.0, 8559.101294364096, 8782.868256976151, 9326.813463730721, 9363.85954210684, 9370.484623345714, 9250.028746981538, 9323.860142097874, 9330.766730831716, 9355.0, 9359.48522831244, 9264.675736349196, 9357.906420935771, 9358.4384936904, 9365.297602679759, 9369.156775890135, 38779.612203877885, 39000.0, 39128.02179945496, 39165.21669566593, 39232.01919951996, 2890.3785686077385, 3160.668129846379, 3243.5459035735016, 3292.3250733599552, 3317.1872891141584, 28250.858745706344, 29026.838926442917, 29487.115386538437, 30254.941077062416, 30257.94097206609, 3129.449498658586, 3169.7147256747003, 3192.6009248843893, 3204.9198770030935, 3236.6925142111695, 480987.0, 482207.0, 482594.0, 482685.0, 483480.0, 33136.50295245573, 55157.724211378685, 55219.89560208773, 55284.44715552793, 55356.44643553513, 33828.985130446126, 34903.82548087294, 34949.12627184341, 35000.0, 35033.82483087568, 11942.402879855972, 14314.212718300594, 14435.783463248059, 14440.277986100653, 14451.349689264009, 12115.818262726067, 12245.510179592895, 12249.265044097383, 12265.0, 12267.877321226673, 6876.449884009267, 7597.088349398107, 7736.071671399355, 7808.258215469521, 7831.569263690438, 3144.0, 3198.9360212796078, 3212.646608873022, 3220.565223694818, 3240.0927740232773, 948.2840455456087, 971.3006635222629, 988.1847475832467, 999.2705325112709, 1016.2225897188656, 23282.41793955131, 29675.25811854678, 29849.85075074654, 29859.0, 29961.70038299633, 862.5471627395542, 864.5288317866778, 914.5655813488539, 977.2182254196756, 1008.9596416143419, 10726.785464290671, 14797.890158238175, 15139.697206055816, 15188.088714677155, 15355.082534318444, 623364.0, 626620.0, 628481.0, 628672.0, 630032.0, 47383.52616473791, 54935.72532137314, 55510.0, 55535.444645553835, 56085.0, 1239.2254840724547, 1262.693112628425, 1263.8420197475316, 1311.2591385866954, 1382.654336415896, 27587.44825103527, 29070.7092929068, 29745.958891438695, 30248.243793905327, 30287.242818929702, 46028.7698561505, 46398.304025440324, 46535.301970469794, 46584.0, 46720.53279467162, 405915.8816823706, 410498.9475052606, 439862.0, 448107.0, 453643.0, 39652.60347396489, 40293.59706403016, 40559.0, 40657.0, 40674.593254067084, 43915.682529524165, 44309.0, 44492.55507444884, 44810.77594612006, 44824.879378014506, 31199.84400077985, 35364.469532956515, 37219.81390093032, 37526.0, 37627.43558846565, 35879.820600897874, 37472.063198420256, 37536.0, 38159.0, 38338.23323533458, 35934.10164745902, 36421.81789091037, 36884.63115368812, 37130.44304335383, 37715.245695085396, 32938.341233174724, 33700.12448568446, 34588.82705586456, 34653.48019779655, 34813.30373392467, 19327.936963467146, 21078.47303817417, 25004.49991000206, 25465.363365916, 25908.740912590634], "value": 17881.191216002877, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.7573009568063, 353.1665269962889, 380.2129591745087, 384.4137690022716, 403.97778122203283, 14597.270136493173, 14691.0, 15913.0, 20053.1978720851, 20195.990200489952, 18132.637347253047, 18871.24515019401, 18875.433736987878, 19127.713084303712, 19148.04259787012, 5810.56420768443, 5928.288605367362, 5967.313758917715, 5977.282726072877, 6070.969645151768, 5492.890142197154, 5572.275604171452, 5969.761209551612, 6003.939960600382, 6029.035354343306, 5062.544371006608, 5771.903338365697, 5947.226860508128, 5961.493273071794, 5988.490978266831, 5506.421825708303, 5816.272965879265, 5854.18041474194, 5901.645901245939, 5911.615744976606, 5529.364123125852, 5696.914546281768, 5706.201131841545, 5707.800226992035, 5844.532437404998, 13228.0, 13484.39320230592, 13526.797098043438, 13542.119762215425, 18377.540561485937, 2156.0728886578763, 2202.5815095131875, 2215.523662412573, 2551.3366524703524, 2908.0, 10250.026247506476, 10362.533685984148, 10390.428526431044, 10420.270581059329, 12836.920690323857, 1744.4853768138303, 1807.5154590137895, 1896.2604584212168, 1909.455805095541, 1913.1911850163162, 86925.56537217337, 114330.0, 121658.0, 121749.0, 122268.0, 10681.83977240342, 10855.402952837512, 11057.11543076552, 11380.946249764795, 11687.88312116868, 14646.560603181922, 14665.266736663125, 14670.046446980912, 14688.339024743913, 14702.26488675562, 7422.5175363602275, 7815.804604884924, 8444.071152173256, 8472.99122715878, 8605.139486051345, 8374.706885258987, 8376.49741015541, 8379.916200837913, 8518.574071296534, 8573.485590864568, 4316.352547117895, 4372.125574885035, 4415.536368681306, 4456.487503937024, 4532.4334458192725, 1646.8435498627612, 1844.8155184481445, 1875.6530041942285, 1908.1127275816812, 1913.2251438167286, 830.6303694855852, 965.9082387173207, 966.4104896013426, 972.5963725054165, 1023.6366090038078, 18870.358149859032, 19987.900060499895, 20055.699164512545, 20087.296944606867, 20450.590988180153, 879.7140929198036, 916.8028873792167, 942.7266092833139, 955.1690029674228, 968.9224862011022, 9795.902040979641, 10085.394876307446, 10487.94756026215, 10640.414777187329, 10653.360798352123, 567108.0, 569781.0, 570861.0, 574746.1262693744, 598096.0, 86151.70772438418, 106406.0, 126651.36674316754, 128031.71968280384, 134719.0, 943.712167788831, 954.346272803132, 972.0473935543066, 983.8832924630595, 984.6947446291673, 17874.910625446788, 18244.361447349278, 18780.7182892254, 18885.339013134475, 18961.43115706531, 36571.0, 47993.0, 48629.5137048625, 53668.0, 54965.72517137549, 76962.23037769551, 78531.33559849647, 79897.20102798898, 80261.0, 80832.3833523338, 7867.17394673551, 7966.243206895336, 8036.794480827923, 8052.919470805451, 8141.755747327589, 11771.705707357385, 12173.817392738938, 12307.646188896682, 12318.568850090203, 12623.431945562656, 9442.197413219954, 10218.693439196835, 10761.515731791931, 10821.585651878442, 11081.5567377304, 10419.895801041892, 10746.46267686597, 10752.677419677422, 10791.082757965662, 10828.783424331627, 10689.572417103229, 10741.516631751434, 10787.568497260336, 10946.945265273622, 11073.723156921142, 7852.13626501073, 7883.44815862907, 8006.799830004296, 8013.3589312856, 8087.878681819895, 9607.759806004906, 10142.391456512632, 10252.692419227136, 10286.639967601099, 10616.416097114732], "value": 9183.249876089028, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.7214198947224, 1431.1270125223616, 1471.5438214153612, 1580.1862041048862, 1781.4922747017094, 7515.511491753039, 7924.6433910474025, 8199.754007379774, 8438.662453501867, 8441.78895527612, 8302.0, 8395.706150284741, 8402.327813774898, 8409.83180336393, 8430.409871309012, 2168.3711723600136, 2207.415035015722, 2210.9668354974688, 2214.8560343577615, 2215.667649852519, 2266.286119872243, 2296.494771150353, 2338.4621537046405, 2341.2273949596593, 2342.672025916373, 2307.538492301538, 2314.109068008812, 2340.8127349811975, 2346.941326466835, 2352.776486233805, 2257.1986944634664, 2286.6684330771945, 2320.211128216398, 2322.6980492535945, 2327.185485080225, 1928.951776205592, 1970.8620396572246, 2322.9186978455673, 2350.13045173287, 2351.470919043202, 8326.542040187787, 8409.705660301921, 8423.620937057847, 8443.071262161156, 8450.985881694174, 5022.748862556894, 5221.895562088737, 5239.397469291042, 5265.973670131625, 5277.340332458442, 3259.071164718044, 3375.8987230383127, 3576.5886923003927, 3647.854085836563, 3653.6163702811086, 1049.3284298049255, 1058.7035630023606, 1080.36258607422, 1082.8127183090191, 1086.5653738504582, 10972.0, 11034.668959931216, 11133.888661113366, 11142.832857507145, 11177.608783692533, 4242.448481697421, 4418.690691651618, 4430.756308403004, 4520.141173177086, 4542.295944128666, 8675.305975521944, 8686.609102590399, 8710.956445217733, 8711.564421778885, 8714.346424018097, 5028.120078986236, 5028.17035189197, 5029.446760856303, 5030.874228144326, 5033.245013247969, 4024.7383920045104, 4281.079567892918, 4439.001224724411, 4701.388819453449, 4704.506026867198, 3647.9785660015236, 3676.7426280160666, 3758.661720445173, 3762.0, 3769.754965927206, 1030.3457304611475, 1140.2132528555271, 1157.1726215755775, 1165.3241120150292, 1169.1114752787942, 784.7567254151118, 824.6371596497522, 840.7267638017669, 848.974530764078, 867.6832955971025, 5228.660137091077, 5484.7531861066345, 5751.252337196138, 5758.481736643722, 5911.9704401478575, 820.9507429554246, 863.5207459859838, 865.8960924688952, 877.8946526416743, 936.8922573904089, 3668.6331366863633, 3688.1517251032415, 3816.46569480273, 3943.11279962012, 3956.485656864589, 9851.704448866545, 10106.595736170619, 10205.0, 10205.183585313158, 10224.437655928996, 10189.949050254701, 10194.0, 10199.847002294971, 10231.897681023094, 10306.896931030744, 7485.7754267372065, 7813.374930005586, 7845.0193725784275, 7902.0, 7903.735402335714, 5736.4550367715, 5747.942520574741, 5783.132530120515, 5873.177755114373, 5895.233619629421, 8823.470591764515, 9106.271498280254, 9237.676681316121, 9253.028432014542, 9470.100340467645, 9449.716508504756, 9483.620655173716, 9550.617975280911, 9584.9041509584, 9589.712308630753, 7218.242084581253, 7285.453590980804, 7286.817829554304, 7330.266973302627, 7344.70621175147, 6763.6618169092335, 7769.378449724121, 7942.324902383362, 7958.442908996546, 7984.720534781255, 7099.112610923635, 7200.531965422231, 7225.819354516179, 7288.635568221674, 7310.488265821342, 6997.4052205561065, 7049.753258635923, 7086.610236437045, 7120.786376408716, 7166.1400631922415, 6595.868082638224, 6722.1261236040955, 6803.387695107464, 6817.5909445433435, 6826.795196144124, 4924.113659541317, 4945.208766597327, 4961.107000739901, 4987.900241995212, 5006.374203224597, 4859.514048595112, 4864.586510146677, 4878.0975519529, 4927.876803079952, 5171.715555644325], "value": 4379.958602735707, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.33266817989073, 299.9475091858925, 307.9969200307997, 312.3581040960826, 313.84464689978466, 4722.409698787652, 5174.2756014158, 5219.321488206532, 5255.553277971372, 5283.622369933536, 4324.243257429945, 5025.773840177191, 5082.4917508249155, 5121.359830021247, 5122.282880396748, 3970.186111847079, 3981.442598036277, 3986.744175584696, 3993.880183594497, 4019.77891215983, 3797.791121488317, 4009.578994205596, 4027.4764280643476, 4028.7582745035393, 4045.4943132108488, 3461.16931936336, 3648.4892115103776, 3834.2523207974455, 4018.0959284160976, 4054.2296963576828, 3558.804265765382, 4021.3766866135797, 4029.959700403017, 4043.1711499142607, 4066.9186616267657, 3532.6046211746475, 3536.4341705327024, 3536.8875389656937, 3538.6284440133672, 3545.9113522161892, 4500.752458614775, 4979.904421027368, 5020.221865610836, 5060.468650791688, 5104.213525266165, 4558.407407037064, 4616.884577885546, 4619.006913513611, 4642.168277350814, 4646.512116227781, 5443.727813609344, 5497.979712215118, 5510.614257002011, 5513.696746678931, 5553.222548843165, 3454.809985450799, 3759.9284204002, 3898.688104951597, 3922.156736301709, 3932.8230229639735, 5370.812021579226, 5404.7838086476495, 5611.410801865827, 5687.090065589486, 5714.285714285715, 3380.4253276942486, 3496.3182179474757, 3628.637136286403, 3973.364261718169, 3978.124812541236, 4013.618706222904, 4051.513818341759, 4054.6350828425584, 4058.431819545266, 4059.289624315733, 3544.822758862098, 3689.5572531296416, 4472.9776351118035, 4595.839145629887, 4659.718577391244, 4161.791910404529, 4643.0, 5344.893102138013, 5506.752196151183, 5509.256250406224, 1236.9072319576069, 1292.4442489729317, 1296.6110166949877, 1297.228149251194, 1297.5069473599972, 779.5946108023796, 840.3235395506675, 892.2371372476568, 902.634433054614, 904.5070436612062, 4904.0, 4909.877253068702, 4916.852494425173, 4921.753912304442, 4924.217816933421, 4535.233507560385, 4773.782685415197, 5297.549708274762, 5352.19717042439, 5363.157526510955, 4800.231962885921, 4882.51174882516, 4886.731229782325, 4888.804447822118, 4893.535114164149, 2319.677783663296, 2565.6921169459783, 2659.8670066496984, 2850.930900912158, 2852.9857350713114, 4964.503549645079, 5435.673859568438, 5539.41836107211, 5597.74810133545, 5613.37937030613, 4635.745034023161, 4868.68545492722, 4963.875903102451, 5265.130129354921, 5509.071824861267, 5443.945560544344, 5473.589480788957, 5481.260029895992, 5487.5884308677005, 5642.78680083784, 5309.725670835015, 5315.920261196008, 5339.813106541253, 5371.785128594813, 5462.617616766788, 4956.826511072095, 5313.362396512289, 5368.677879327252, 5395.4604539545735, 5404.243405923253, 4772.0, 4803.447603525639, 5576.860578485408, 5597.776088956396, 5628.352739435018, 3266.428375034407, 3662.6154253802965, 3666.908327291839, 3673.3368307976502, 3716.201016781297, 4894.804207831647, 5322.973385133049, 5516.9448305516435, 5532.253145825262, 5563.610547261653, 5246.921296180484, 5387.084195686664, 5405.621606487665, 5407.594430417813, 5421.864453388697, 5504.834854954358, 5513.724313784375, 5529.253550770594, 5566.471185237396, 5589.037809960727, 5431.528654462131, 5466.315032366295, 5475.891781664168, 5483.505005222534, 5538.3661819764075, 4430.955690443183, 5165.535101840853, 5530.446955304437, 5531.640443371168, 5543.196236545759, 5413.945860541344, 5427.83716488506, 5455.699936503372, 5470.8085217017215, 5472.835814925558], "value": 3980.029010456458, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.9593824370538, 695.2039914298128, 697.1111832413669, 750.8610906982212, 762.7444805989993, 13990.580282591525, 14235.43058277669, 14751.631209219775, 14763.188024658653, 14918.552443426688, 12910.354482275896, 13132.73734525309, 13275.070745047826, 13276.203427794338, 13322.0, 799.2447137455107, 821.0311832038193, 821.5070957425556, 823.9958800205991, 824.3364091906013, 653.4674240494006, 671.0571646836203, 807.7092246791151, 841.6465084664455, 843.2832092721169, 865.9004214515317, 878.4509681449094, 880.6829541365104, 880.933929955256, 883.5538053283083, 868.405142477405, 870.2602787630527, 871.0853603716105, 872.7120050383422, 873.0309356614185, 500.31956539106784, 512.7923191107608, 606.2270604978644, 630.6027202862214, 635.8219698484431, 14365.856341436556, 14408.766641170636, 14412.207328596924, 14434.855651443457, 14441.205733684643, 13119.344032798417, 13182.802257966137, 13262.602121936357, 13329.600111996655, 13405.0, 11681.941590291994, 11923.880761192451, 12027.51889924402, 12352.876471235353, 12465.252084874846, 3694.9630503695157, 4402.779861006937, 4529.773511324421, 4535.682502224846, 4562.931556026662, 60867.0, 80165.39669206583, 80522.0, 80922.59538702328, 81200.0, 10147.594096236215, 10510.211734119819, 10559.260851740459, 10609.257351985287, 10958.726031849108, 19180.61638767217, 19541.609167816565, 20005.899970500053, 20089.698654520194, 20268.290609828586, 7516.398688105048, 7564.773056808304, 7583.5070720403, 7657.272559106876, 7697.0378702662165, 4162.146759914211, 4214.072903961123, 4235.78821058952, 4289.0992891492515, 4434.201843668171, 3291.6214635316765, 3398.813065281384, 3400.7449441291506, 3403.4554471284478, 3408.284260305314, 564.745864361031, 622.4273668225244, 641.6310621392718, 652.0935899100255, 652.5595223224304, 546.5572885962304, 552.3813329071386, 592.6562593695646, 669.8191488298132, 687.151368060445, 7598.7193169690945, 8325.8334833303, 8782.297416206688, 9025.232855207381, 9061.275097992146, 576.8500189950643, 604.392585451627, 625.7309356976452, 628.3779058731914, 673.2224280955504, 4046.433499310099, 4524.773761311922, 4542.750148741785, 4555.658325625525, 4651.930221046687, 71149.64425177842, 71528.0, 72083.2791672087, 97001.0, 110386.0, 80955.5952220247, 95071.0, 97006.51496742471, 101337.98662013432, 101516.49241753743, 71967.6401617999, 87142.0, 87367.0, 87989.0, 89163.0, 7241.746538871114, 8603.698870539502, 8883.33374996865, 8905.0, 8978.0, 52777.736111319195, 54296.45703542914, 54593.63515912134, 54839.177412339646, 55669.721651393105, 74487.62756186184, 78873.0, 79052.60473697595, 80810.19189808027, 81814.77277840718, 10927.235093543375, 10959.83560246581, 11108.333499990027, 11293.548258069584, 11313.943430283125, 11603.245789023686, 12302.0, 12349.629511114681, 12796.36018199105, 12841.101122921305, 11559.19085664029, 11666.650000499998, 11800.232984855957, 12012.399380031138, 12028.699282518008, 9580.18568421692, 12306.446209920396, 12512.687182820138, 12600.0, 12957.611271661865, 12191.634250972485, 12192.878071219175, 12348.691282718004, 12778.169418987736, 12782.552610658582, 10634.042936135786, 10670.8399374011, 10714.357138571711, 10910.727231819268, 11214.7757044857, 11016.504257308601, 11066.170037247077, 11166.776664466828, 11220.326780393203, 11243.943780281374], "value": 7103.063592101996, "unit": "score"}], "name": "dromaeo_css", "value": 6972.265943273799, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 170.0, 139.0, 113.0, 113.0, 112.0, 112.0, 113.0, 114.0, 112.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 109.0, 142.0, 111.0, 111.0, 109.0, 108.0, 142.0, 109.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 185.0, 183.0, 183.0, 183.0, 184.0, 185.0, 183.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 60.0, 89.0, 59.0, 60.0, 93.0, 83.0, 111.0, 71.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 95.0, 69.0, 69.0, 69.0, 90.0, 68.0, 69.0, 92.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 122.0, 84.0, 86.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 92.0, 92.0, 92.0, 94.0, 92.0, 93.0, 93.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 80.0, 80.0, 84.0, 84.0, 89.0, 125.0, 81.0, 81.0], "value": 87.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 54.0, 53.0, 54.0, 57.0, 58.0, 57.0, 59.0, 59.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 71.0, 77.0, 72.0, 70.0, 69.0, 72.0, 71.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 83.0, 83.0, 82.0, 83.0, 84.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 153.0, 150.0, 153.0, 142.0, 143.0, 144.0, 182.0, 153.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 167.0, 228.0, 225.0, 166.0, 165.0, 167.0, 165.0, 163.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 65.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1455.3, "alertThreshold": 2.0}]} 03:42:29 INFO - Return code: 0 03:42:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:29 INFO - # TBPL SUCCESS # 03:42:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:29 INFO - 2016-12-15 03:42:29.984 screenresolution[1679:10790] starting screenresolution argv=screenresolution get 03:42:29 INFO - 2016-12-15 03:42:29.987 screenresolution[1679:10790] Display 0: 1600x1200x32@60 03:42:29 INFO - 2016-12-15 03:42:29.993 screenresolution[1680:10793] starting screenresolution argv=screenresolution list 03:42:29 INFO - Available Modes on Display 0 03:42:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:30 INFO - Intel Iris: 03:42:30 INFO - Chipset Model: Intel Iris 03:42:30 INFO - Type: GPU 03:42:30 INFO - Bus: Built-In 03:42:30 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:30 INFO - Vendor: Intel (0x8086) 03:42:30 INFO - Device ID: 0x0a2e 03:42:30 INFO - Revision ID: 0x0009 03:42:30 INFO - Displays: 03:42:30 INFO - Display: 03:42:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:30 INFO - Display Serial Number: 1600x1200 60 03:42:30 INFO - Main Display: Yes 03:42:30 INFO - Mirror: Off 03:42:30 INFO - Online: Yes 03:42:30 INFO - Rotation: Supported 03:42:30 INFO - Return code: 0 03:42:30 INFO - Running post-action listener: _resource_record_post_action 03:42:30 INFO - [mozharness: 2016-12-15 11:42:30.235695Z] Finished run-tests step (success) 03:42:30 INFO - Running post-run listener: _resource_record_post_run 03:42:30 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 33459200; Write bytes: 657364992; Read time: 311; Write time: 1977 03:42:30 INFO - TinderboxPrint: CPU usage
53.3% 03:42:30 INFO - TinderboxPrint: I/O read bytes / time
33,459,200 / 311 03:42:30 INFO - TinderboxPrint: I/O write bytes / time
657,364,992 / 1,977 03:42:30 INFO - TinderboxPrint: CPU idle
2,260.6 (46.7%) 03:42:30 INFO - TinderboxPrint: CPU system
964.3 (19.9%) 03:42:30 INFO - TinderboxPrint: CPU user
1,620.8 (33.4%) 03:42:30 INFO - TinderboxPrint: Swap in / out
378,621,952 / 0 03:42:30 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347873792; Write bytes: 346650624; Read time: 26974; Write time: 823 03:42:30 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 31378432; Write bytes: 303525888; Read time: 289; Write time: 1124 03:42:30 INFO - Running post-run listener: _upload_blobber_files 03:42:30 INFO - Blob upload gear active. 03:42:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42: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 03:42: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'] 03:42: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 03:42:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:32 INFO - (blobuploader) - INFO - Done attempting. 03:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:33 INFO - (blobuploader) - INFO - Done attempting. 03:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:36 INFO - (blobuploader) - INFO - Done attempting. 03:42:36 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:36 INFO - Return code: 0 03:42:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d6160087c48f8f0353ce4c12b844c049b7126d230e089bb43aba817322d12635981606a41371bf27d8cdc6cf49f714614d57928fc7fe464f0c9f9bd252d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a276d707c7d867f68a0c8f7793035f092036b4f3da4b82768b0249e1744c0bbbedff8cea07d97687ebd775196ccf9e19f2de6225e38160006594ddbf8745cb69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a4caaae7e14a2b79cf31db2481b89a30e177257f89184c0cc6bcf73a7f136c44abfc9ee71cee2c4d73f1c4f08208b446691885dfedcdbe2ed9ec55568cb74f0"} 03:42:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:36 INFO - Contents: 03:42:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/940d6160087c48f8f0353ce4c12b844c049b7126d230e089bb43aba817322d12635981606a41371bf27d8cdc6cf49f714614d57928fc7fe464f0c9f9bd252d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a276d707c7d867f68a0c8f7793035f092036b4f3da4b82768b0249e1744c0bbbedff8cea07d97687ebd775196ccf9e19f2de6225e38160006594ddbf8745cb69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a4caaae7e14a2b79cf31db2481b89a30e177257f89184c0cc6bcf73a7f136c44abfc9ee71cee2c4d73f1c4f08208b446691885dfedcdbe2ed9ec55568cb74f0"} 03:42:36 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:36 INFO - Copying logs to upload dir... 03:42:36 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.255469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-15 03:42:36.232754) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 03:42:36.236869) ========= 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.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/940d6160087c48f8f0353ce4c12b844c049b7126d230e089bb43aba817322d12635981606a41371bf27d8cdc6cf49f714614d57928fc7fe464f0c9f9bd252d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a276d707c7d867f68a0c8f7793035f092036b4f3da4b82768b0249e1744c0bbbedff8cea07d97687ebd775196ccf9e19f2de6225e38160006594ddbf8745cb69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a4caaae7e14a2b79cf31db2481b89a30e177257f89184c0cc6bcf73a7f136c44abfc9ee71cee2c4d73f1c4f08208b446691885dfedcdbe2ed9ec55568cb74f0"} build_url:https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/O9LDZHwuTuaLM7aK8MEJYw/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/940d6160087c48f8f0353ce4c12b844c049b7126d230e089bb43aba817322d12635981606a41371bf27d8cdc6cf49f714614d57928fc7fe464f0c9f9bd252d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a276d707c7d867f68a0c8f7793035f092036b4f3da4b82768b0249e1744c0bbbedff8cea07d97687ebd775196ccf9e19f2de6225e38160006594ddbf8745cb69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a4caaae7e14a2b79cf31db2481b89a30e177257f89184c0cc6bcf73a7f136c44abfc9ee71cee2c4d73f1c4f08208b446691885dfedcdbe2ed9ec55568cb74f0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 03:42:36.272481) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:42:36.272901) ========= 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.uaoJmsXMEA/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.onv1WZ21GI/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005332 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 03:42:36.322711) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 03:42:36.323059) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 03:42:36.323592) ========= ========= Total master_lag: 0.17 =========