builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1484042732.86 results: success (0) buildid: 20170109234108 builduid: 548b00b206f84d6aafcc8b25872beebe revision: 0e5631748857e7df0b8e9c849fb7348f3318dfe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:32.855551) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:32.856241) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:32.856557) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:32.883882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:32.884216) ========= 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.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-10 02:05:32-- 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% 14.0M=0.001s 2017-01-10 02:05:33 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148400 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.057877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.058237) ========= 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.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.026867 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.109608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.110410) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0e5631748857e7df0b8e9c849fb7348f3318dfe4 --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 0e5631748857e7df0b8e9c849fb7348f3318dfe4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-10 02:05:33,205 truncating revision to first 12 chars 2017-01-10 02:05:33,205 Setting DEBUG logging. 2017-01-10 02:05:33,205 attempt 1/10 2017-01-10 02:05:33,205 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0e5631748857?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 02:05:33,747 unpacking tar archive at: mozilla-aurora-0e5631748857/testing/mozharness/ program finished with exit code 0 elapsedTime=0.790737 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.926498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.926882) ========= script_repo_revision: 0e5631748857e7df0b8e9c849fb7348f3318dfe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.927313) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.927615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 02:05:33.947729) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-01-10 02:05:33.948060) ========= /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.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:05:34 INFO - MultiFileLogger online at 20170110 02:05:34 in /builds/slave/test 02:05:34 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 02:05:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:05:34 INFO - {'append_to_log': False, 02:05:34 INFO - 'base_work_dir': '/builds/slave/test', 02:05:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:05:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:05:34 INFO - 'branch': 'Mozilla-Aurora', 02:05:34 INFO - 'buildbot_json_path': 'buildprops.json', 02:05:34 INFO - 'config_files': ('talos/mac_config.py',), 02:05:34 INFO - 'default_actions': ('clobber', 02:05:34 INFO - 'read-buildbot-config', 02:05:34 INFO - 'download-and-extract', 02:05:34 INFO - 'populate-webroot', 02:05:34 INFO - 'create-virtualenv', 02:05:34 INFO - 'install', 02:05:34 INFO - 'run-tests'), 02:05:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:05:34 INFO - 'download_minidump_stackwalk': True, 02:05:34 INFO - 'download_symbols': 'ondemand', 02:05:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:05:34 INFO - 'tooltool.py': '/tools/tooltool.py', 02:05:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:05:34 INFO - '/tools/misc-python/virtualenv.py')}, 02:05:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:05:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:05:34 INFO - 'installer_path': 'installer.exe', 02:05:34 INFO - 'log_level': 'info', 02:05:34 INFO - 'log_name': 'talos', 02:05:34 INFO - 'log_to_console': True, 02:05:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:05:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:05:34 INFO - 'opt_config_files': (), 02:05:34 INFO - 'pip_index': False, 02:05:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:05:34 INFO - 'cmd': ('bash', 02:05:34 INFO - '-c', 02:05:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:05:34 INFO - 'enabled': True, 02:05:34 INFO - 'halt_on_failure': False, 02:05:34 INFO - 'name': 'check_screen_resolution'},), 02:05:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:05:34 INFO - 'cmd': ('bash', 02:05:34 INFO - '-c', 02:05:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:05:34 INFO - 'enabled': True, 02:05:34 INFO - 'halt_on_failure': False, 02:05:34 INFO - 'name': 'check_screen_resolution'},), 02:05:34 INFO - 'run_cmd_checks_enabled': True, 02:05:34 INFO - 'sps_profile': False, 02:05:34 INFO - 'sps_profile_interval': 0, 02:05:34 INFO - 'suite': 'dromaeojs', 02:05:34 INFO - 'system_bits': '32', 02:05:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:05:34 INFO - 'title': 't-yosemite-r7-0154', 02:05:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:05:34 INFO - 'use_talos_json': True, 02:05:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:05:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:05:34 INFO - 'work_dir': 'build'} 02:05:34 INFO - [mozharness: 2017-01-10 10:05:34.155536Z] Running clobber step. 02:05:34 INFO - Running pre-action listener: _resource_record_pre_action 02:05:34 INFO - Running main action method: clobber 02:05:34 INFO - rmtree: /builds/slave/test/build 02:05:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:05:36 INFO - Running post-action listener: _resource_record_post_action 02:05:36 INFO - [mozharness: 2017-01-10 10:05:36.507934Z] Finished clobber step (success) 02:05:36 INFO - [mozharness: 2017-01-10 10:05:36.508095Z] Running read-buildbot-config step. 02:05:36 INFO - Running pre-action listener: _resource_record_pre_action 02:05:36 INFO - Running main action method: read_buildbot_config 02:05:36 INFO - Using buildbot properties: 02:05:36 INFO - { 02:05:36 INFO - "project": "", 02:05:36 INFO - "product": "firefox", 02:05:36 INFO - "script_repo_revision": "production", 02:05:36 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:05:36 INFO - "repository": "", 02:05:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:05:36 INFO - "buildid": "20170109234108", 02:05:36 INFO - "pgo_build": "False", 02:05:36 INFO - "basedir": "/builds/slave/test", 02:05:36 INFO - "buildnumber": 20, 02:05:36 INFO - "slavename": "t-yosemite-r7-0154", 02:05:36 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:05:36 INFO - "platform": "yosemite_r7", 02:05:36 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:05:36 INFO - "branch": "mozilla-aurora", 02:05:36 INFO - "repo_path": "releases/mozilla-aurora", 02:05:36 INFO - "revision": "0e5631748857e7df0b8e9c849fb7348f3318dfe4", 02:05:36 INFO - "stage_platform": "macosx64", 02:05:36 INFO - "builduid": "548b00b206f84d6aafcc8b25872beebe", 02:05:36 INFO - "slavebuilddir": "test" 02:05:36 INFO - } 02:05:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:05:36 INFO - Running post-action listener: _resource_record_post_action 02:05:36 INFO - [mozharness: 2017-01-10 10:05:36.511514Z] Finished read-buildbot-config step (success) 02:05:36 INFO - [mozharness: 2017-01-10 10:05:36.511648Z] Running download-and-extract step. 02:05:36 INFO - Running pre-action listener: _resource_record_pre_action 02:05:36 INFO - Running main action method: download_and_extract 02:05:36 INFO - mkdir: /builds/slave/test/build/tests 02:05:36 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')]} 02:05:36 INFO - trying https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:05:36 INFO - Downloading https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/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 02:05:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/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 02:05:37 INFO - Downloaded 1199 bytes. 02:05:37 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:05:37 INFO - Using the following test package requirements: 02:05:37 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:05:37 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:05:37 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:05:37 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'jsshell-mac.zip'], 02:05:37 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:05:37 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:05:37 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:05:37 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:05:37 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:05:37 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:05:37 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:05:37 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:05:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:05:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:05:37 INFO - Fetch https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:05:38 INFO - Http code: 200 02:05:38 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 02:05:38 INFO - x-amz-cf-id: CH701k0cvkHpe4AlU-mjaTOeICmEQPIaFWujbmlBo9O_MnCizKafGg== 02:05:38 INFO - x-amz-version-id: BrAIHTdp56yS7imUK3bvxgClNkIV4mRb 02:05:38 INFO - Expected file size: 16271728 02:05:38 INFO - Obtained file size: 16271728 02:05:45 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 02:05:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:05:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:05:45 INFO - Fetch https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:05:46 INFO - Http code: 200 02:05:46 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 02:05:46 INFO - x-amz-cf-id: eAnPOgkIIWb0EdVDOWZCEODVRqSoMlWERsmRbZJSnyb9JnzSBguiOA== 02:05:46 INFO - x-amz-version-id: BrAIHTdp56yS7imUK3bvxgClNkIV4mRb 02:05:46 INFO - Expected file size: 16271728 02:05:46 INFO - Obtained file size: 16271728 02:05:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:05:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:05:52 INFO - Fetch https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:05:53 INFO - Http code: 200 02:05:53 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 02:05:53 INFO - x-amz-cf-id: pCwWZUyOlBdHNtCRxrW3TKFcLJ7uttUmRA9OEi26Cz2mJ3hGJMtFKQ== 02:05:53 INFO - x-amz-version-id: J95lMWOnCoXpN1541TX0ry_NSwaYLGH9 02:05:54 INFO - Expected file size: 14409038 02:05:54 INFO - Obtained file size: 14409038 02:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:05:54 INFO - trying https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:05:54 INFO - Downloading https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:05:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/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 02:05:57 INFO - Downloaded 109869787 bytes. 02:05:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:05:57 INFO - mkdir: /builds/slave/test/properties 02:05:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:05:57 INFO - Writing to file /builds/slave/test/properties/build_url 02:05:57 INFO - Contents: 02:05:57 INFO - build_url:https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:05:57 INFO - Running post-action listener: _resource_record_post_action 02:05:57 INFO - Running post-action listener: set_extra_try_arguments 02:05:57 INFO - [mozharness: 2017-01-10 10:05:57.839453Z] Finished download-and-extract step (success) 02:05:57 INFO - [mozharness: 2017-01-10 10:05:57.839611Z] Running populate-webroot step. 02:05:57 INFO - Running pre-action listener: _resource_record_pre_action 02:05:57 INFO - Running main action method: populate_webroot 02:05:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:05:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:05:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:05:57 INFO - '--tppagecycles', 02:05:57 INFO - '7'], 02:05:57 INFO - 'tests': ['tsvgm']}}, 02:05:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:05:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:05:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:05:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:05:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:05:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:05:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['damp', 'tps']}, 02:05:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'tests': ['damp', 'tps']}, 02:05:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['dromaeo_dom']}, 02:05:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:05:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['basic_compositor_video']}, 02:05:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:05:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'other-e10s': {'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['a11yr', 02:05:57 INFO - 'ts_paint', 02:05:57 INFO - 'tpaint', 02:05:57 INFO - 'sessionrestore', 02:05:57 INFO - 'sessionrestore_no_auto_restore', 02:05:57 INFO - 'tabpaint']}, 02:05:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['tsvgx', 02:05:57 INFO - 'tsvgr_opacity', 02:05:57 INFO - 'tart', 02:05:57 INFO - 'tscrollx', 02:05:57 INFO - 'cart']}, 02:05:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:05:57 INFO - 'tsvgr_opacity', 02:05:57 INFO - 'tart', 02:05:57 INFO - 'tscrollx', 02:05:57 INFO - 'cart']}, 02:05:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'talos_options': ['--disable-e10s'], 02:05:57 INFO - 'tests': ['tp5o']}, 02:05:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'tests': ['tp5o']}, 02:05:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'talos_options': ['--disable-e10s', 02:05:57 INFO - '--xperf_path', 02:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:05:57 INFO - 'tests': ['tp5n']}, 02:05:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:05:57 INFO - 'talos_options': ['--xperf_path', 02:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:05:57 INFO - 'tests': ['tp5n']}}, 02:05:57 INFO - 'talos.zip': {'path': '', 02:05:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:05:57 INFO - Running post-action listener: _resource_record_post_action 02:05:57 INFO - [mozharness: 2017-01-10 10:05:57.859218Z] Finished populate-webroot step (success) 02:05:57 INFO - [mozharness: 2017-01-10 10:05:57.859350Z] Running create-virtualenv step. 02:05:57 INFO - Running pre-action listener: _resource_record_pre_action 02:05:57 INFO - Running main action method: create_virtualenv 02:05:57 INFO - Creating virtualenv /builds/slave/test/build/venv 02:05:57 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 02:05:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:05:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:05:57 INFO - Using real prefix '/tools/python27' 02:05:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:05:58 INFO - Installing distribute.............................................................................................................................................................................................done. 02:06:01 INFO - Installing pip.................done. 02:06:01 INFO - Return code: 0 02:06:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:06:01 INFO - Reading from file tmpfile_stdout 02:06:01 INFO - Output received: 02:06:01 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) 02:06:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:01 INFO - 'HOME': '/Users/cltbld', 02:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:01 INFO - 'LOGNAME': 'cltbld', 02:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:01 INFO - 'MOZ_NO_REMOTE': '1', 02:06:01 INFO - 'NO_EM_RESTART': '1', 02:06:01 INFO - 'PAGER': '/bin/cat', 02:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:01 INFO - 'PWD': '/builds/slave/test', 02:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:01 INFO - 'SHELL': '/bin/bash', 02:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:01 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:01 INFO - 'USER': 'cltbld', 02:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:01 INFO - 'XPC_FLAGS': '0x0', 02:06:01 INFO - 'XPC_SERVICE_NAME': '0', 02:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:02 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 02:06:02 INFO - Cleaning up... 02:06:02 INFO - Return code: 0 02:06:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:06: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')]} 02:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06: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')]} 02:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06: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', '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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:06: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 psutil>=3.1.1 02:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:02 INFO - 'HOME': '/Users/cltbld', 02:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:02 INFO - 'LOGNAME': 'cltbld', 02:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:02 INFO - 'MOZ_NO_REMOTE': '1', 02:06:02 INFO - 'NO_EM_RESTART': '1', 02:06:02 INFO - 'PAGER': '/bin/cat', 02:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:02 INFO - 'PWD': '/builds/slave/test', 02:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:02 INFO - 'SHELL': '/bin/bash', 02:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:02 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:02 INFO - 'USER': 'cltbld', 02:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:02 INFO - 'XPC_FLAGS': '0x0', 02:06:02 INFO - 'XPC_SERVICE_NAME': '0', 02:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:02 INFO - Downloading/unpacking psutil>=3.1.1 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:06:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:06:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:06:05 INFO - Installing collected packages: psutil 02:06:05 INFO - Running setup.py install for psutil 02:06:06 INFO - building 'psutil._psutil_osx' extension 02:06:06 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 02:06:06 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 02:06:06 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 02:06:06 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 02:06:06 INFO - building 'psutil._psutil_posix' extension 02:06:06 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 02:06:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:06:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:06:06 INFO - ^ 02:06:07 INFO - 1 warning generated. 02:06:07 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 02:06:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:06:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:06:07 INFO - Successfully installed psutil 02:06:07 INFO - Cleaning up... 02:06:07 INFO - Return code: 0 02:06:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:06: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')]} 02:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06: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')]} 02:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:06:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:07 INFO - 'HOME': '/Users/cltbld', 02:06:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:07 INFO - 'LOGNAME': 'cltbld', 02:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:07 INFO - 'MOZ_NO_REMOTE': '1', 02:06:07 INFO - 'NO_EM_RESTART': '1', 02:06:07 INFO - 'PAGER': '/bin/cat', 02:06:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:07 INFO - 'PWD': '/builds/slave/test', 02:06:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:07 INFO - 'SHELL': '/bin/bash', 02:06:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:07 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:07 INFO - 'USER': 'cltbld', 02:06:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:07 INFO - 'XPC_FLAGS': '0x0', 02:06:07 INFO - 'XPC_SERVICE_NAME': '0', 02:06:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:06:10 INFO - Installing collected packages: mozsystemmonitor 02:06:10 INFO - Running setup.py install for mozsystemmonitor 02:06:10 INFO - Successfully installed mozsystemmonitor 02:06:10 INFO - Cleaning up... 02:06:10 INFO - Return code: 0 02:06:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:10 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:10 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 02:06:10 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 02:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:10 INFO - 'HOME': '/Users/cltbld', 02:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:10 INFO - 'LOGNAME': 'cltbld', 02:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:10 INFO - 'MOZ_NO_REMOTE': '1', 02:06:10 INFO - 'NO_EM_RESTART': '1', 02:06:10 INFO - 'PAGER': '/bin/cat', 02:06:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:10 INFO - 'PWD': '/builds/slave/test', 02:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:10 INFO - 'SHELL': '/bin/bash', 02:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:10 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:10 INFO - 'USER': 'cltbld', 02:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:10 INFO - 'XPC_FLAGS': '0x0', 02:06:10 INFO - 'XPC_SERVICE_NAME': '0', 02:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:10 INFO - Downloading/unpacking jsonschema==2.5.1 02:06:10 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 02:06:10 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 02:06:10 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 02:06:10 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 02:06:10 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 02:06:10 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 02:06:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:06:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:06:13 INFO - Downloading functools32-3.2.3-2.tar.gz 02:06:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:06:13 INFO - warning: no files found matching '*.txt' 02:06:13 INFO - no previously-included directories found matching 'build' 02:06:13 INFO - no previously-included directories found matching 'dist' 02:06:13 INFO - no previously-included directories found matching '.git*' 02:06:13 INFO - Installing collected packages: jsonschema, functools32 02:06:13 INFO - Running setup.py install for functools32 02:06:13 INFO - warning: no files found matching '*.txt' 02:06:13 INFO - no previously-included directories found matching 'build' 02:06:13 INFO - no previously-included directories found matching 'dist' 02:06:13 INFO - no previously-included directories found matching '.git*' 02:06:13 INFO - Successfully installed jsonschema functools32 02:06:13 INFO - Cleaning up... 02:06:14 INFO - Return code: 0 02:06:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:06:14 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')]} 02:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:14 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')]} 02:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:14 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:14 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 02:06:14 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 02:06:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:14 INFO - 'HOME': '/Users/cltbld', 02:06:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:14 INFO - 'LOGNAME': 'cltbld', 02:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:14 INFO - 'MOZ_NO_REMOTE': '1', 02:06:14 INFO - 'NO_EM_RESTART': '1', 02:06:14 INFO - 'PAGER': '/bin/cat', 02:06:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:14 INFO - 'PWD': '/builds/slave/test', 02:06:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:14 INFO - 'SHELL': '/bin/bash', 02:06:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:14 INFO - 'USER': 'cltbld', 02:06:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:14 INFO - 'XPC_FLAGS': '0x0', 02:06:14 INFO - 'XPC_SERVICE_NAME': '0', 02:06:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:06:14 INFO - Cleaning up... 02:06:14 INFO - Return code: 0 02:06:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:06:14 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')]} 02:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:14 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')]} 02:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:14 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:14 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 02:06:14 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 02:06:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:14 INFO - 'HOME': '/Users/cltbld', 02:06:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:14 INFO - 'LOGNAME': 'cltbld', 02:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:14 INFO - 'MOZ_NO_REMOTE': '1', 02:06:14 INFO - 'NO_EM_RESTART': '1', 02:06:14 INFO - 'PAGER': '/bin/cat', 02:06:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:14 INFO - 'PWD': '/builds/slave/test', 02:06:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:14 INFO - 'SHELL': '/bin/bash', 02:06:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:14 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:14 INFO - 'USER': 'cltbld', 02:06:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:14 INFO - 'XPC_FLAGS': '0x0', 02:06:14 INFO - 'XPC_SERVICE_NAME': '0', 02:06:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:14 INFO - Downloading/unpacking blobuploader==1.2.4 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06: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 02:06:17 INFO - Downloading blobuploader-1.2.4.tar.gz 02:06:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:06:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:06:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:17 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 02:06:18 INFO - Downloading docopt-0.6.1.tar.gz 02:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:06:18 INFO - Installing collected packages: blobuploader, requests, docopt 02:06:18 INFO - Running setup.py install for blobuploader 02:06:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:06:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:06:18 INFO - Running setup.py install for requests 02:06:19 INFO - Running setup.py install for docopt 02:06:19 INFO - Successfully installed blobuploader requests docopt 02:06:19 INFO - Cleaning up... 02:06:19 INFO - Return code: 0 02:06:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:19 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:19 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 02:06:19 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 02:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:19 INFO - 'HOME': '/Users/cltbld', 02:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:19 INFO - 'LOGNAME': 'cltbld', 02:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:19 INFO - 'MOZ_NO_REMOTE': '1', 02:06:19 INFO - 'NO_EM_RESTART': '1', 02:06:19 INFO - 'PAGER': '/bin/cat', 02:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:19 INFO - 'PWD': '/builds/slave/test', 02:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:19 INFO - 'SHELL': '/bin/bash', 02:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:19 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:19 INFO - 'USER': 'cltbld', 02:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:19 INFO - 'XPC_FLAGS': '0x0', 02:06:19 INFO - 'XPC_SERVICE_NAME': '0', 02:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:06:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-VIqHF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:06:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-SXY158-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:06:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-EelPrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:06:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bY_ZNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-DVLBM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-LofneU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-q1qms1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-O0RDdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-sDq0Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-eSTXNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qnn2kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QiPJi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7LrH8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:06:20 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hnRb4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bPezxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:06:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dCFwfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:06:21 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-58zZMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:06:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:06:21 INFO - Running setup.py install for manifestparser 02:06:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:06:21 INFO - Running setup.py install for mozcrash 02:06:21 INFO - Running setup.py install for mozdebug 02:06:21 INFO - Running setup.py install for mozdevice 02:06:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:06:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:06:21 INFO - Running setup.py install for mozfile 02:06:22 INFO - Running setup.py install for mozhttpd 02:06:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Running setup.py install for mozinfo 02:06:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Running setup.py install for mozInstall 02:06:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Running setup.py install for mozleak 02:06:22 INFO - Running setup.py install for mozlog 02:06:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:06:22 INFO - Running setup.py install for moznetwork 02:06:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Running setup.py install for mozprocess 02:06:23 INFO - Running setup.py install for mozprofile 02:06:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Running setup.py install for mozrunner 02:06:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Running setup.py install for mozscreenshot 02:06:23 INFO - Running setup.py install for moztest 02:06:23 INFO - Running setup.py install for mozversion 02:06:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:06:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:06:23 INFO - Cleaning up... 02:06:23 INFO - Return code: 0 02:06:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:23 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:23 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 02:06:23 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 02:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:23 INFO - 'HOME': '/Users/cltbld', 02:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:23 INFO - 'LOGNAME': 'cltbld', 02:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:23 INFO - 'MOZ_NO_REMOTE': '1', 02:06:23 INFO - 'NO_EM_RESTART': '1', 02:06:23 INFO - 'PAGER': '/bin/cat', 02:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:23 INFO - 'PWD': '/builds/slave/test', 02:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:23 INFO - 'SHELL': '/bin/bash', 02:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:23 INFO - 'USER': 'cltbld', 02:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:23 INFO - 'XPC_FLAGS': '0x0', 02:06:23 INFO - 'XPC_SERVICE_NAME': '0', 02:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dZAlvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-pbN38V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-GaX3UG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2kPHZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ifJzWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-DPcEyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-pImKGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:06:24 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)) 02:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:06:24 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-z54tTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vN7Zq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UEAZ9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-dFbRDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7l2OrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-SwpvB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-PXH5ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Gq9PPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:06:25 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Rr1T6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:06:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 02:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:06:25 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-xYNfqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:06:26 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)) 02:06:26 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)) 02:06:26 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)) 02:06:26 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)) 02:06:26 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)) 02:06:26 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)) 02:06:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:06:26 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 02:06:26 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 02:06:26 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 02:06:26 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 02:06:26 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 02:06:26 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 02:06:28 INFO - Downloading blessings-1.6.tar.gz 02:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:06:28 INFO - Installing collected packages: blessings 02:06:28 INFO - Running setup.py install for blessings 02:06:28 INFO - Successfully installed blessings 02:06:28 INFO - Cleaning up... 02:06:29 INFO - Return code: 0 02:06:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:06:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:29 INFO - Reading from file tmpfile_stdout 02:06:29 INFO - Current package versions: 02:06:29 INFO - blessings == 1.6 02:06:29 INFO - blobuploader == 1.2.4 02:06:29 INFO - docopt == 0.6.1 02:06:29 INFO - functools32 == 3.2.3-2 02:06:29 INFO - jsonschema == 2.5.1 02:06:29 INFO - manifestparser == 1.1 02:06:29 INFO - mozInstall == 1.12 02:06:29 INFO - mozcrash == 1.0 02:06:29 INFO - mozdebug == 0.1 02:06:29 INFO - mozdevice == 0.48 02:06:29 INFO - mozfile == 1.2 02:06:29 INFO - mozhttpd == 0.7 02:06:29 INFO - mozinfo == 0.9 02:06:29 INFO - mozleak == 0.1 02:06:29 INFO - mozlog == 3.4 02:06:29 INFO - moznetwork == 0.27 02:06:29 INFO - mozprocess == 0.23 02:06:29 INFO - mozprofile == 0.28 02:06:29 INFO - mozrunner == 6.13 02:06:29 INFO - mozscreenshot == 0.1 02:06:29 INFO - mozsystemmonitor == 0.3 02:06:29 INFO - moztest == 0.8 02:06:29 INFO - mozversion == 1.4 02:06:29 INFO - psutil == 3.1.1 02:06:29 INFO - requests == 1.2.3 02:06:29 INFO - wsgiref == 0.1.2 02:06:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:06:29 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')]} 02:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 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')]} 02:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:29 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:29 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 02:06:29 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 02:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:29 INFO - 'HOME': '/Users/cltbld', 02:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:29 INFO - 'LOGNAME': 'cltbld', 02:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:29 INFO - 'MOZ_NO_REMOTE': '1', 02:06:29 INFO - 'NO_EM_RESTART': '1', 02:06:29 INFO - 'PAGER': '/bin/cat', 02:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:29 INFO - 'PWD': '/builds/slave/test', 02:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:29 INFO - 'SHELL': '/bin/bash', 02:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:29 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:29 INFO - 'USER': 'cltbld', 02:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:29 INFO - 'XPC_FLAGS': '0x0', 02:06:29 INFO - 'XPC_SERVICE_NAME': '0', 02:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 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)) 02:06:30 INFO - Cleaning up... 02:06:30 INFO - Return code: 0 02:06:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:06:30 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')]} 02:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:30 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')]} 02:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:30 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 0x1074c6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10743c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8badbe900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, '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.9qwA4vIpWN/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.Geim3XsPBz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 02:06:30 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 02:06:30 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 02:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:30 INFO - 'HOME': '/Users/cltbld', 02:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:30 INFO - 'LOGNAME': 'cltbld', 02:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:30 INFO - 'MOZ_NO_REMOTE': '1', 02:06:30 INFO - 'NO_EM_RESTART': '1', 02:06:30 INFO - 'PAGER': '/bin/cat', 02:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:30 INFO - 'PWD': '/builds/slave/test', 02:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:30 INFO - 'SHELL': '/bin/bash', 02:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:06:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:30 INFO - 'USER': 'cltbld', 02:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:30 INFO - 'XPC_FLAGS': '0x0', 02:06:30 INFO - 'XPC_SERVICE_NAME': '0', 02:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:06:30 INFO - Cleaning up... 02:06:30 INFO - Return code: 0 02:06:30 INFO - Running post-action listener: _resource_record_post_action 02:06:30 INFO - Running post-action listener: _start_resource_monitoring 02:06:30 INFO - Starting resource monitoring. 02:06:30 INFO - [mozharness: 2017-01-10 10:06:30.913641Z] Finished create-virtualenv step (success) 02:06:30 INFO - [mozharness: 2017-01-10 10:06:30.914597Z] Running install step. 02:06:30 INFO - Running pre-action listener: _resource_record_pre_action 02:06:30 INFO - Running main action method: install 02:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:06:31 INFO - Reading from file tmpfile_stdout 02:06:31 INFO - Detecting whether we're running mozinstall >=1.0... 02:06:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:06:31 INFO - Reading from file tmpfile_stdout 02:06:31 INFO - Output received: 02:06:31 INFO - Usage: mozinstall [options] installer 02:06:31 INFO - Options: 02:06:31 INFO - -h, --help show this help message and exit 02:06:31 INFO - -d DEST, --destination=DEST 02:06:31 INFO - Directory to install application into. [default: 02:06:31 INFO - "/builds/slave/test"] 02:06:31 INFO - --app=APP Application being installed. [default: firefox] 02:06:31 INFO - mkdir: /builds/slave/test/build/application 02:06:31 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'] 02:06:31 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 02:07:02 INFO - Reading from file tmpfile_stdout 02:07:02 INFO - Output received: 02:07:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:07:02 INFO - Running post-action listener: _resource_record_post_action 02:07:02 INFO - [mozharness: 2017-01-10 10:07:02.738779Z] Finished install step (success) 02:07:02 INFO - [mozharness: 2017-01-10 10:07:02.738916Z] Running run-tests step. 02:07:02 INFO - Running pre-action listener: _resource_record_pre_action 02:07:02 INFO - Running main action method: run_tests 02:07:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:07:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:07:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:07:02 INFO - 2017-01-10 02:07:02.753 screenresolution[1623:7537] starting screenresolution argv=screenresolution get 02:07:02 INFO - 2017-01-10 02:07:02.756 screenresolution[1623:7537] Display 0: 1600x1200x32@60 02:07:02 INFO - 2017-01-10 02:07:02.762 screenresolution[1624:7542] starting screenresolution argv=screenresolution list 02:07:02 INFO - Available Modes on Display 0 02:07:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:07:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:07:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:07:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:07:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:07:02 INFO - Intel Iris: 02:07:02 INFO - Chipset Model: Intel Iris 02:07:02 INFO - Type: GPU 02:07:02 INFO - Bus: Built-In 02:07:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:07:02 INFO - Vendor: Intel (0x8086) 02:07:02 INFO - Device ID: 0x0a2e 02:07:02 INFO - Revision ID: 0x0009 02:07:02 INFO - Displays: 02:07:02 INFO - Display: 02:07:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:07:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:07:02 INFO - Display Serial Number: 1600x1200 60 02:07:02 INFO - Main Display: Yes 02:07:02 INFO - Mirror: Off 02:07:02 INFO - Online: Yes 02:07:02 INFO - Rotation: Supported 02:07:02 INFO - Return code: 0 02:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:07:02 INFO - Python 2.7.3 02:07:03 INFO - Return code: 0 02:07:03 INFO - grabbing minidump binary from tooltool 02:07:03 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')]} 02:07:03 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 0x1072dad30>, '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 0x107140db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1072d2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:07:03 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 02:07:03 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 02:07:03 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 02:07:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:07:03 INFO - Return code: 0 02:07:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:07:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:07:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:07:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:07:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:07:03 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/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 02:07:03 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/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 02:07:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9qwA4vIpWN/Render', 02:07:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:03 INFO - 'HOME': '/Users/cltbld', 02:07:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:03 INFO - 'LOGNAME': 'cltbld', 02:07:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:07:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:07:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:03 INFO - 'MOZ_NO_REMOTE': '1', 02:07:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:07:03 INFO - 'NO_EM_RESTART': '1', 02:07:03 INFO - 'PAGER': '/bin/cat', 02:07:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:03 INFO - 'PWD': '/builds/slave/test', 02:07:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:07:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:03 INFO - 'SHELL': '/bin/bash', 02:07:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Geim3XsPBz/Listeners', 02:07:03 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 02:07:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:03 INFO - 'USER': 'cltbld', 02:07:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:03 INFO - 'XPC_FLAGS': '0x0', 02:07:03 INFO - 'XPC_SERVICE_NAME': '0', 02:07:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:03 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/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 02:07:03 INFO - mozversion application_buildid: 20170109234108 02:07:03 INFO - mozversion application_changeset: 0e5631748857e7df0b8e9c849fb7348f3318dfe4 02:07:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:07:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:07:03 INFO - mozversion application_name: Firefox 02:07:03 INFO - mozversion application_remotingname: firefox-dev 02:07:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:07:03 INFO - mozversion application_vendor: Mozilla 02:07:03 INFO - mozversion application_version: 52.0a2 02:07:03 INFO - mozversion platform_buildid: 20170109234108 02:07:03 INFO - mozversion platform_changeset: 0e5631748857e7df0b8e9c849fb7348f3318dfe4 02:07:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:07:03 INFO - mozversion platform_version: 52.0a2 02:07:03 INFO - using testdate: 1484042823 02:07:03 INFO - actual date: 1484042823 02:07:03 INFO - starting webserver on 'localhost:49247' 02:07:03 INFO - SUITE-START | Running 2 tests 02:07:03 INFO - TEST-START | dromaeo_css 02:07:03 INFO - operating with platform_type : mac_ 02:07:03 INFO - Initialising browser for dromaeo_css test... 02:07:03 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp2JFvWt/profile http://localhost:49247/getInfo.html) 02:07:06 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 02:07:06 INFO - PROCESS | 1634 | colorDepth:24 02:07:06 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 02:07:06 INFO - PROCESS | 1634 | __metrics 02:07:06 INFO - PROCESS | 1634 | 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" 02:07:06 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:07:07 INFO - TEST-INFO | 1634: exit 0 02:07:07 INFO - Browser initialized. 02:07:07 INFO - Running cycle 1/1 for dromaeo_css test... 02:07:07 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp2JFvWt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:07:19 INFO - PROCESS | 1636 | RSS: Main: 222310400 02:07:19 INFO - PROCESS | 1636 | 02:10:23 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 02:10:25 INFO - PROCESS | 1636 | RSS: Main: 347316224 02:10:25 INFO - PROCESS | 1636 | 02:13:28 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 02:13:29 INFO - PROCESS | 1636 | RSS: Main: 347914240 02:13:29 INFO - PROCESS | 1636 | 02:16:31 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 02:16:32 INFO - PROCESS | 1636 | RSS: Main: 310403072 02:16:32 INFO - PROCESS | 1636 | 02:19:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 02:19:35 INFO - PROCESS | 1636 | RSS: Main: 317157376 02:19:35 INFO - PROCESS | 1636 | 02:22:38 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 02:22:39 INFO - PROCESS | 1636 | RSS: Main: 306216960 02:22:39 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 02:25:42 INFO - PROCESS | 1636 | RSS: Main: 367927296 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | __start_tp_report 02:25:42 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 02:25:42 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 02:25:42 INFO - PROCESS | 1636 | |i|pagename|runs| 02:25:42 INFO - PROCESS | 1636 | |0;dojo.html;3242.173245822315;3359.932801343973;3370.3259348130364;3394.964535816575;3397.9490307645374;5656.236408084907;5712.285964254468;5717.085266357373;5729.914051289234;5732.2261494698205;5687.345955215152;5701.77192912284;5770.798022069229;5785.190073389729;5797.5651826113;5210.843674689765;5229.477052294759;5229.50319719627;5249.527542521172;5286.10136276832;5162;5184.429712731597;5188.740562971855;5217.86955326118;5318.707471089088;4255.851045213418;5280.9207861882105;5288.629795914288;5290.973545132289;5320.9467905321035;4293.184294983958;4386.428706816573;5295.311609490742;5310.964361949422;5347.6256662033675;5026.899462010776;5145.897082058375;5209.791608335662;5228.843134705965;5242.2136679498;5629.8311050668135;5641.633293835928;5663.8584035399035;5702.372738998748;5703.4011428799795;6132;6159;6194.256689197326;6244.406781355764;6268.247810262752;6303.5902666327;6331.746730130789;6341;6341.8414539636415;6352.237731472207;5631.831045068655;5661.490465858093;5720.799772007959;5740.282464691913;5767.307923049261;6337.6197428154455;6353.142325786052;6359.141515895295;6368.904466433008;6370.140031095742;6148.6925653717135;6156.6921653917125;6170.691465426711;6174.567780255339;6176.166217560662;5385.838424847182;6111.694415279218;6149.985252433388;6150.261968563801;6264.780732674426;6004.7598096076545;6091.238595175603;6127.816165515042;6140.942784167265;6149.907751383733;6161.784337548254;6227.875442491216;6250.843728906813;6269.028300613321;6284.591501552384;4800.0639875224;4846.612271018381;4854.2233242681;4877.512248775094;4908.950910490849;4396.560343965578;4454.420925279693;4454.799534020977;4481.686281960231;4508.45898492183;5592.077307244341;5659.5755318351285;5674.205611214434;5674.432556744375;5676.290463692038;6177.907331390033;6191.19514463117;6191.226096737908;6208.1308616793685;6209.161763162006;5641.323041235078;5676.829695109153;5687.260656114761;5691.402402747734;5691.430856914276;5317.601179911522;5326.6537675050995;5341.786328546815;5342.866428339323;5346.064438723208;6226.59527130735;6296.024116261987;6346.30190679025;6351.491880649629;6356.0465930109;6278.152449419453;6336.239651241881;6364.64994425311;6372.745090196526;6383.297837237993;6179.907301390393;6307.495400368051;6322.620642761449;6324.1146239527425;6332.746690132527;6049.334573197033;6105.633661980295;6113.572049956639;6251.093591429309;6261.843453913689;6141.232345956755;6204.13142160089;6215.192025036717;6267.62394256346;6278.780242691484;5710.314762228559;6093.573449858467;6094.7257373417415;6109.3890610940325;6137.263528376623;6076.787312444043;6096.603720758137;6108.083787431743;6113.205283313141;6122.2959359674205;5472.699001554953;5615.466530679579;5694;5809.767609295581;5918.644881307136;4973.5523802857915;6062.484688801501;6070.6357618543025;6100.572959892765;6127.14220009208;5878.2064421304285;5893.233879595625;5905.763769449174;5914.526837852875;5951.732172052181 02:25:42 INFO - PROCESS | 1636 | |1;ext.html;451.3027372709164;498.2426711398674;512.2597846112368;529.3277537527338;556.2823957095347;36008.81995590022;38889.41665875014;39057.21885562287;39077;39122;39852;40341.99145021376;40342;40394.79802600983;40394.7980260099;8490.957545212264;8700.825983480327;9292.72121836346;9471.90528094717;9477.95261023694;8510.78723031926;8603.741887743377;8603.95698021509;8612.655493780241;11179.66461006171;11246.156538259622;11461.885381146207;11469.369184694839;11496.770064598702;11497.885021149765;11332.943335283353;11425.31448113116;11449.255798373079;11449.96950274471;11483.885161148366;8574.571271436404;8750.912490875136;11258.493367798468;11339.943300283447;11341.489632966537;39350.606493935265;39857.00357491057;40108.398374024415;41632.79183604093;41646.79176604128;3232.983835080833;3236.6925142111463;3389.9830500847584;3778.7165962552644;3802.619738026203;27540.311492212655;28661.28346791347;29487;29830.55254171189;30620;3197.728193103589;3234.191452136966;3254.3653987472453;3284.998075586945;3294.2093897464674;457524;460296;463209;464253;472068;61302.08046879301;61625.69187154035;61697.69151154305;61748;61757.69121154366;65139;65348;65428.672856635414;65453.01820472697;65525.67237163784;15647.530574082795;16237.837621623868;16252.512424627044;16254.674906501803;16260.67478650444;11302.265352752209;11667.474963626657;11747.177697561088;11751.294922304689;11778.411079445994;16363.345466181458;16786.4124755633;18360.65249335769;18554;19131.90434047821;6612;6693.698783554752;6804.455643548505;7321.597312147883;7590.240975902366;824.7278398128603;863.5552690364467;907.3503292484345;921.5530467723149;1009.5204777730519;28829;29242;29267;29779;29905.700942990727;889.235257678396;1001.5543083327849;1016.9491525423699;1024.882138554061;1047.1884289675563;13251.602451926457;14776.704465910621;15030.248487575578;15044;15354.309056092503;626855;629329;631126;634346;634578;49389.50610493921;50553.49446505561;55564;56798;56821;1332.0342751505082;1358.9875542720538;1366.050594836599;1371.6228037289427;1377.9035467517378;29209.415811684074;30070.69929300679;30490.847545762128;30613.846930765205;30920.22699432442;49615.759606010135;50029.74985125051;50172;50199.49800501948;50280.49719502758;580135;583671;583680;586133.0693346506;586996;25906.35234119162;26283;26296;26675.866620666773;26699.33251668724;44536.33195502135;44609.77695111504;44870.77564612156;45496.54503454923;46187.538124618324;36594.26811463702;36881.44677829781;37284;37381.62618373781;37511;31586;33129.668703313626;36383.81808090943;37730.62269377381;38035.809820950715;36869.70956016527;37109.62890371062;37586;37811.243775123796;38169.427458588696;32120.839395802872;33698.49452258169;33705.686716082295;34136.31727365389;34186;22015.779842201373;24989.625155622318;25179.370515737253;25209.722736044198;25752.742472575785 02:25:42 INFO - PROCESS | 1636 | |2;jquery.html;347.659293891986;363.93813051781194;369.38497401825947;376.74569665475815;396.91863168050537;14746.63133421665;14974.550763477086;15828.287727052268;20016;20546.075426605803;18405;19408.514787130294;19495.610087798235;20047;20290.39128826134;5680.31836179658;5817.650940943536;5855.47300742933;5921.763129474816;5981.222441082653;4649.232876575361;4785.66500344976;5898.675572843492;5978.133170690247;5984.910226346608;5912.054071348585;5920;5925.525957923377;5936.673482958436;5939.316978547479;5623.915641265385;5956.821295361146;5992.850178745566;6046.576739628228;6057.42454466825;5565.443455654443;5735.942640573624;5833.766649334021;5841.85395365115;5846.064629659277;12891.226526408353;13101.08292419531;13225;13243.668908277274;13256.536021239306;2006.4482267376443;2174.402039439151;2310.90756369745;2355.599548076831;2449.1795248591566;9860.852087218698;9908.702738917844;9941.75145621358;10219.080282774587;10251.199492446225;1800.1629242402214;1841.5672317005492;1874.1940965384879;1878.7839398469214;1923.2210954563425;107585.99999999921;109060;110614.44692776565;112886;121001.39499302447;11118.388488633203;11627.476763545661;11632.709182270342;11634.418279086181;11768.88231117695;14412.711745765235;14427.206503642401;14434.639134021734;14446;14453.349599268058;8219.794505137299;8314.542700151429;8317.293030092509;8390.244877961013;8456.619452124669;6519.804405867737;8326.70856520031;8351.164883511721;8412.200840920103;8495.617697203641;3795.8802153364823;4421.668374871898;4516.096780643818;4570.497245303014;4678.508756580578;1753.1321995612254;1826.8264514871066;1839.7516335294831;1908.5610309628862;1916.8403116114828;774.7598244544097;874.7900503879023;888.3648191543077;897.4660077254024;906.2297047509564;19305.324313649235;20834.47913802167;20922.16311347529;21026.474338141365;21064.78935210659;924.7410724997012;925.421611492817;933.6078846884325;946.3659348236698;975.8292298847673;8238.299744521662;9022;10301.845472317922;10713.83929241062;10772.299800513098;567345.5716199735;570273;576386;586323;592409;76121.23878761141;78296;117314;121852.39073804574;122516;951.6050838901779;963.5182408795603;1004.8844382895915;1010.6765834932751;1012.7518757904213;18117.547061323574;18320.72518912244;18649.440516784518;18766.343177988703;19024.334148304744;51779.482205178974;53959;54434.72782636061;56602.15096773469;56880.862382752945;79193;79613.60193199229;81327.59336203517;82996.17003829885;84490;7836;7852;7971.242731940456;8109.635066422144;8179.182081791773;10307.849214286385;12150.392480376122;12415.130940834055;12507.499700011898;12616;6865.382115609619;10369.170466362824;10782.083522900328;10955.233133680566;10999.890001099886;10495.422751748727;10591.258611897094;10657.41384223844;10918.01737843598;11002.50488728025;10932.234743567873;11102.444877756241;11137.665870023913;11251.718707032389;11382.130075774276;6200;6866.141732283465;7312.41500679955;7959.880601790862;8113.878291825509;9945.602175912882;10122.949385253027;10324.896751032393;10700.143988480748;11084.334939903632 02:25:42 INFO - PROCESS | 1636 | |3;mootools.html;1431.1127101197255;1462.5977856089573;1589.928453219605;1782;1799.1004497751132;7437.107547094342;7992.560409177493;8337.95831020844;8353.122922093176;8359.540225287607;7543.057117860268;7574.091109066906;7930.405219608538;8126.431149819515;8146.511209327445;2285.02886273334;2322.500662357593;2327.44141406063;2329.0683726509365;2332.766723327662;2302.9424264393356;2343.6367363058753;2351.200591798788;2357.882105894707;2358.870262135582;2013.4060452166573;2089.7283353164066;2089.7910208979056;2258.062903894882;2338.497223097034;1990.8606397552178;2312;2333.626619740833;2336.497653004612;2337.8714170720605;2292.255017119426;2340.4967931894726;2344.6951896253377;2345.2143531916827;2350.059976009593;8172.223638754309;8356.623951922118;8357.122502137248;8373.49759014461;8396.958015209946;5031.773570189313;5189.014087323397;5190;5192.402873669501;5213.191955246943;3262.5106234064842;3303.124671961932;3336.2326664866973;3392.3724111039537;3395.3209358128474;1062.097217365241;1079.7786453776957;1100.2408338246664;1102.72431892027;1102.7518808268155;10538.947305263502;10719.892801071968;10888.346699198073;10977.231593788438;10994.065504432212;4282.764447955392;4365.3670217818235;4460.643148548108;4496;4504.662150338738;8542.10307917672;8553.615087321086;8558.871616925751;8569.914300856912;8575.528345941033;4971.875703107379;4973.278874563241;5065.240213967934;5078.289039534468;5089.592832573385;4116.176764647083;4548.47692515365;4689;4708.728643266299;4778.259369797687;3621.2436968070465;3649.5802982656805;3651.6896063834342;3704.6110158433517;3724.366857634209;1123.224974767408;1130.5421304371744;1143.685486491205;1144.753877916252;1145.1468655851277;606.8634557224589;772.403933501772;857.1257317536079;881.5504092912661;918.4351623751478;5223.451537588575;5429.53848922846;5452.464603959099;5460.781568737206;5551.72241387929;867.6442658509981;872.5070335260557;873.6549063120032;923.5013092929743;945.9169251207329;3560.608333083359;3602.7390413355115;3622.239329740731;3623.438367053111;3724.3854763964187;9469.857952130724;9708.514574271258;9832.115109640024;9980.600775969026;10147.695569132939;9809.901900981042;9873.160781333523;10064.295499315123;10071.748206294902;10090.142337901214;7656.540607563564;7951.522908625501;7967.960160199162;7985.081715602664;7986.720464783821;5333.493318134771;5387.703676297841;5533.335999680065;5594.300712410949;5672.602917795714;9402.905970940203;9407.670731524637;9522.952475227858;9550;9599.232061434928;9524.333296669167;9596.32825702222;9611.134997850228;9631.903680963382;9724.9027509724;6830.419414349637;7171.103612048494;7322.377597904025;7325.01112349826;7325.597092159983;7669.23307669251;7910.9604451977375;7912.762617121265;7917;7973.122956474899;6933.4106600939485;7074.398676112588;7078.539894906815;7078.964605176941;7158.749443769443;6938.306169383223;6971.337722916315;6988.266232045766;6992.930070699228;7012.929870701431;6533.346665333429;6573.704183311666;6595.340465953366;6632.966835165793;6649.833754155991;4745.217039188496;4789.209780386197;4795.688280261772;4859.927101093415;4889.706617602955;4739.502352252964;4743.644226682944;4760.476347601831;4869.853904382874;5021.824236151717 02:25:42 INFO - PROCESS | 1636 | |4;prototype.html;248.72640095894513;285.83707286846504;295.1838167466954;302.2308225565935;309.16525381470035;4755.952440475594;5013.17282648363;5042.537664077413;5199.428062913086;5353.06321393756;4995.602110986729;5132.8973420531565;5137.869668672895;5159.871003224912;5212.504812042859;3310.8013519188926;3570.8750193743226;3802.182530755887;3969.2061587682433;4000.3399439092514;3917.9608203918024;3997.900052498682;4007.178528401685;4030.919381612366;4042.333015052513;3365.3774051800374;3989.561148273702;3993.6805055595482;3997.960020399788;4000.3399439092514;3969.2656858481128;3975.9602403975878;3989.40158976153;3994.840206391741;3997.160596274786;3501.0547152269;3522.894313170609;3526.5944416392185;3538.610752817188;3544.521489598897;4595.103954728829;4727.739974301412;4868.732219727914;5062.721550314731;5171.586273098181;3580.5524309461316;4394.604485596312;4490.483594386622;4510.143072816154;4563.657725670588;5433.310511296933;5476.698781567012;5488.9725551372385;5510.3744395403455;5515.145152501369;3605.3330133925306;3723.8138093095235;3754.624537546251;3764.604716504782;3765.2281282337053;5204.9219261711105;5354.732263386854;5708.971455142698;5725.856853578653;5753.769849206069;3813.427985802152;3822.904427389282;3828.617138286205;3843.2313537292653;3846.615338466131;3199.6800319968283;3731.8541838443;3862.575116737157;3870.2259548090497;3879.9612003880166;3956.169204467094;4141.109661422808;4313.223619748475;4496.213162696515;4565.762776239619;4659.603933665609;4814.542618451242;4873.975630121827;5474.288342515448;5520.392756796749;1110.4059328259343;1175.6649354933975;1187.8158885372782;1205.9939700301443;1234.8641649418557;713.8144082538578;755.316438623047;835.2065537739172;844.4257904624914;860.7503823891002;4200.886765007275;4790.640701947298;4805.783739731721;4809.114531281203;4848.75756212195;4684.391029166255;4959.628027897922;5127.897442051213;5274.3670759509105;5425.9186112208345;4744.288356746516;4774.061399345554;4774.665773395829;4797.7601119943865;4816.494268101869;2408.7229968553843;2570.2674737699667;2769.1861830501052;2769.376890199689;2775.139706690892;4459.687821852439;5383.031054410244;5429.538489228381;5483.930633526504;5523.917141242885;5018.31154101735;5390.897549955521;5397.0062154005755;5407.621056630534;5438.728063596884;4472.597466228055;4949.406071271398;5452.291202143696;5463.699496527729;5475.808346707846;4830.420349558076;5189.6886186828915;5214.843554693213;5259.868503287448;5329.307190065267;4689.788959496763;4750.477447480844;4793.472718001087;5174.922376164285;5214.608904332114;4508.45898492183;4874.003317279797;4881.8535443936735;4903.8528844134735;5432.538234250133;3230.3216324571163;3349.8097152461996;3491.633378495221;3537.4693795930816;3557.6264492227942;4672.168821566283;4895.804167833389;4998.300577803564;5224.47348040079;5234.560495863668;4259.083412464342;4855.75721213945;5056.342675452168;5093.898122037613;5152.381714194321;4244.596763307481;4454.799534020912;4658.67389282747;4907.871189626478;5097.0315640028275;4644.512326205835;4711.408725201194;5080.415752188398;5097.241451699158;5243.318368612208;4572.862814115582;5349.277847490539;5350.277712508761;5366.463353664602;5386.730663466733;5175.560077393464;5373.489518495737;5423.8439371314635;5430.565554755689;5437.918431223456 02:25:42 INFO - PROCESS | 1636 | |5;yui.html;689.2693744630692;748.7903387051624;781.2617076862367;803.8673618852889;812.508432398399;13812.654683632914;14034.649133771649;14499.637509062266;14583.270836458189;14936.701265974702;12808.039397045211;13118.672033199175;13226.272555009495;13260.336983150852;13272.402741876615;658.387699439521;670.4267850987409;825.2572684583863;832.8459235041506;833.3374966901298;802.6909639788665;806.4395245304515;809.4293523066224;839.2866063845713;840.36552402936;882.7748924024365;886.0474989386403;888.103015953888;888.4669198480924;889.3018980100622;877.784942689042;878.5519385113638;879.147227189625;879.3580686099151;880.2429910277157;634.6572850660638;651.4983462733669;652.0219670494258;652.1228947066188;653.2063542795479;12397;12461.75076498474;12522.311272879988;12592.622221333375;12618.432170552347;11609.651710448616;11695.122865785015;11698.2981021139;11739.765204695943;11774.352410617412;10194.9490252549;10215.080642742189;10215.693529194063;10302.484875756183;10577.576896924114;3370.6966373026544;3410.6255637449135;3619.7828130312264;3641.326354624403;3667.523221981152;81138;83546.16453835445;83596.58201709013;84144;85556.57221713851;8407.663693452316;8563.400561960727;8726.08376120511;8863.556822158866;8972.820543589092;14531;17332.566685832957;18091.276348945896;18261.26954921815;18309.267629294944;7495;7561.395088392916;7640.274173953466;7722.45942784011;7816.7264145754625;4095.5290141634096;4137.834486620502;4231.682623803165;4259.893502662459;4327.8485253016;3242.854071566785;3341.682540158681;3345.1469875181674;3348.380549598332;3352.3630510202984;497.8108318838818;623.2738859229038;629.5718911140377;648.1703419622891;688.8036909480874;506.5947242206294;642.9132067170966;645.9935400645934;664.6145235763246;677.7661706711177;8556.27271681914;8937.329700272505;9055;9179.219766319937;9275.118863707938;546.3443867359106;594.313567829158;651.820749293948;663.6781161136845;677.5562006885498;4162.854300099542;4411.8014689338415;4434.445694288214;4454.443194600675;4506.842260520866;71040.22399439952;72582;85076.14923850806;112917.43541282241;116523.669526609;63319;71429.28570714331;73091.63454182801;99254.5037274809;101379.49310253402;66716.6664166676;67442;70042.64978675074;71624.28375716176;83742.58128709317;9255.583498742439;9586.760330991501;9671.17794987434;9683.32216744821;9738.902610973799;43649.78175109104;47802;51844.70388240324;53216.20175697445;54909;54088.729556351966;67175.66412167907;69791;71553.92669110071;74571;12171.939140304241;12366.228320189233;12386.690332741753;12392.876071239172;12908.806367904677;13749.243791591158;14133.010689252065;14180.858191417954;14201.644958875697;14255.215963122128;12701.745965080461;12881.549145779853;12888.033397495037;12924.353782310658;13590.93204533971;13158.60524184276;13249;13289.933550332187;13511.932440337736;13764.862351376358;12338.198017129216;12633.178843375152;12679.936600317309;12975.740485190432;13026.348682565644;10814.863689529948;10831.2959657622;10871.347719136877;11215.60745373908;11645.126615503701;10547.947260263649;11179;11212.27120237182;11405.258658187191;11412.771744564896 02:25:42 INFO - PROCESS | 1636 | __end_tp_report 02:25:42 INFO - PROCESS | 1636 | __start_cc_report 02:25:42 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1860 02:25:42 INFO - PROCESS | 1636 | __end_cc_report 02:25:42 INFO - PROCESS | 1636 | __startTimestamp1484043942358__endTimestamp 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | ------- Summary: start ------- 02:25:42 INFO - PROCESS | 1636 | Number of tests: 6 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5707.84 Median:5878.21 stddev:664.11 (11.3%) stddev-sans-first:637.36 02:25:42 INFO - PROCESS | 1636 | Values: 3242.2 3359.9 3370.3 3395.0 3397.9 5656.2 5712.3 5717.1 5729.9 5732.2 5687.3 5701.8 5770.8 5785.2 5797.6 5210.8 5229.5 5229.5 5249.5 5286.1 5162.0 5184.4 5188.7 5217.9 5318.7 4255.9 5280.9 5288.6 5291.0 5320.9 4293.2 4386.4 5295.3 5311.0 5347.6 5026.9 5145.9 5209.8 5228.8 5242.2 5629.8 5641.6 5663.9 5702.4 5703.4 6132.0 6159.0 6194.3 6244.4 6268.2 6303.6 6331.7 6341.0 6341.8 6352.2 5631.8 5661.5 5720.8 5740.3 5767.3 6337.6 6353.1 6359.1 6368.9 6370.1 6148.7 6156.7 6170.7 6174.6 6176.2 5385.8 6111.7 6150.0 6150.3 6264.8 6004.8 6091.2 6127.8 6140.9 6149.9 6161.8 6227.9 6250.8 6269.0 6284.6 4800.1 4846.6 4854.2 4877.5 4909.0 4396.6 4454.4 4454.8 4481.7 4508.5 5592.1 5659.6 5674.2 5674.4 5676.3 6177.9 6191.2 6191.2 6208.1 6209.2 5641.3 5676.8 5687.3 5691.4 5691.4 5317.6 5326.7 5341.8 5342.9 5346.1 6226.6 6296.0 6346.3 6351.5 6356.0 6278.2 6336.2 6364.6 6372.7 6383.3 6179.9 6307.5 6322.6 6324.1 6332.7 6049.3 6105.6 6113.6 6251.1 6261.8 6141.2 6204.1 6215.2 6267.6 6278.8 5710.3 6093.6 6094.7 6109.4 6137.3 6076.8 6096.6 6108.1 6113.2 6122.3 5472.7 5615.5 5694.0 5809.8 5918.6 4973.6 6062.5 6070.6 6100.6 6127.1 5878.2 5893.2 5905.8 5914.5 5951.7 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:69074.87 Median:37332.81 stddev:150758.52 (403.8%) stddev-sans-first:151093.39 02:25:42 INFO - PROCESS | 1636 | Values: 451.3 498.2 512.3 529.3 556.3 36008.8 38889.4 39057.2 39077.0 39122.0 39852.0 40342.0 40342.0 40394.8 40394.8 8491.0 8700.8 9292.7 9471.9 9478.0 8510.8 8603.7 8604.0 8612.7 11179.7 11246.2 11461.9 11469.4 11496.8 11497.9 11332.9 11425.3 11449.3 11450.0 11483.9 8574.6 8750.9 11258.5 11339.9 11341.5 39350.6 39857.0 40108.4 41632.8 41646.8 3233.0 3236.7 3390.0 3778.7 3802.6 27540.3 28661.3 29487.0 29830.6 30620.0 3197.7 3234.2 3254.4 3285.0 3294.2 457524.0 460296.0 463209.0 464253.0 472068.0 61302.1 61625.7 61697.7 61748.0 61757.7 65139.0 65348.0 65428.7 65453.0 65525.7 15647.5 16237.8 16252.5 16254.7 16260.7 11302.3 11667.5 11747.2 11751.3 11778.4 16363.3 16786.4 18360.7 18554.0 19131.9 6612.0 6693.7 6804.5 7321.6 7590.2 824.7 863.6 907.4 921.6 1009.5 28829.0 29242.0 29267.0 29779.0 29905.7 889.2 1001.6 1016.9 1024.9 1047.2 13251.6 14776.7 15030.2 15044.0 15354.3 626855.0 629329.0 631126.0 634346.0 634578.0 49389.5 50553.5 55564.0 56798.0 56821.0 1332.0 1359.0 1366.1 1371.6 1377.9 29209.4 30070.7 30490.8 30613.8 30920.2 49615.8 50029.7 50172.0 50199.5 50280.5 580135.0 583671.0 583680.0 586133.1 586996.0 25906.4 26283.0 26296.0 26675.9 26699.3 44536.3 44609.8 44870.8 45496.5 46187.5 36594.3 36881.4 37284.0 37381.6 37511.0 31586.0 33129.7 36383.8 37730.6 38035.8 36869.7 37109.6 37586.0 37811.2 38169.4 32120.8 33698.5 33705.7 34136.3 34186.0 22015.8 24989.6 25179.4 25209.7 25752.7 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:33156.39 Median:366.66 stddev:96275.02 (26257.2%) stddev-sans-first:96513.58 02:25:42 INFO - PROCESS | 1636 | Values: 347.7 363.9 369.4 376.7 396.9 14746.6 14974.6 15828.3 20016.0 20546.1 18405.0 19408.5 19495.6 20047.0 20290.4 5680.3 5817.7 5855.5 5921.8 5981.2 4649.2 4785.7 5898.7 5978.1 5984.9 5912.1 5920.0 5925.5 5936.7 5939.3 5623.9 5956.8 5992.9 6046.6 6057.4 5565.4 5735.9 5833.8 5841.9 5846.1 12891.2 13101.1 13225.0 13243.7 13256.5 2006.4 2174.4 2310.9 2355.6 2449.2 9860.9 9908.7 9941.8 10219.1 10251.2 1800.2 1841.6 1874.2 1878.8 1923.2 107586.0 109060.0 110614.4 112886.0 121001.4 11118.4 11627.5 11632.7 11634.4 11768.9 14412.7 14427.2 14434.6 14446.0 14453.3 8219.8 8314.5 8317.3 8390.2 8456.6 6519.8 8326.7 8351.2 8412.2 8495.6 3795.9 4421.7 4516.1 4570.5 4678.5 1753.1 1826.8 1839.8 1908.6 1916.8 774.8 874.8 888.4 897.5 906.2 19305.3 20834.5 20922.2 21026.5 21064.8 924.7 925.4 933.6 946.4 975.8 8238.3 9022.0 10301.8 10713.8 10772.3 567345.6 570273.0 576386.0 586323.0 592409.0 76121.2 78296.0 117314.0 121852.4 122516.0 951.6 963.5 1004.9 1010.7 1012.8 18117.5 18320.7 18649.4 18766.3 19024.3 51779.5 53959.0 54434.7 56602.2 56880.9 79193.0 79613.6 81327.6 82996.2 84490.0 7836.0 7852.0 7971.2 8109.6 8179.2 10307.8 12150.4 12415.1 12507.5 12616.0 6865.4 10369.2 10782.1 10955.2 10999.9 10495.4 10591.3 10657.4 10918.0 11002.5 10932.2 11102.4 11137.7 11251.7 11382.1 6200.0 6866.1 7312.4 7959.9 8113.9 9945.6 10122.9 10324.9 10700.1 11084.3 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5377.17 Median:5189.51 stddev:2958.64 (57.0%) stddev-sans-first:2952.08 02:25:42 INFO - PROCESS | 1636 | Values: 1431.1 1462.6 1589.9 1782.0 1799.1 7437.1 7992.6 8338.0 8353.1 8359.5 7543.1 7574.1 7930.4 8126.4 8146.5 2285.0 2322.5 2327.4 2329.1 2332.8 2302.9 2343.6 2351.2 2357.9 2358.9 2013.4 2089.7 2089.8 2258.1 2338.5 1990.9 2312.0 2333.6 2336.5 2337.9 2292.3 2340.5 2344.7 2345.2 2350.1 8172.2 8356.6 8357.1 8373.5 8397.0 5031.8 5189.0 5190.0 5192.4 5213.2 3262.5 3303.1 3336.2 3392.4 3395.3 1062.1 1079.8 1100.2 1102.7 1102.8 10538.9 10719.9 10888.3 10977.2 10994.1 4282.8 4365.4 4460.6 4496.0 4504.7 8542.1 8553.6 8558.9 8569.9 8575.5 4971.9 4973.3 5065.2 5078.3 5089.6 4116.2 4548.5 4689.0 4708.7 4778.3 3621.2 3649.6 3651.7 3704.6 3724.4 1123.2 1130.5 1143.7 1144.8 1145.1 606.9 772.4 857.1 881.6 918.4 5223.5 5429.5 5452.5 5460.8 5551.7 867.6 872.5 873.7 923.5 945.9 3560.6 3602.7 3622.2 3623.4 3724.4 9469.9 9708.5 9832.1 9980.6 10147.7 9809.9 9873.2 10064.3 10071.7 10090.1 7656.5 7951.5 7968.0 7985.1 7986.7 5333.5 5387.7 5533.3 5594.3 5672.6 9402.9 9407.7 9523.0 9550.0 9599.2 9524.3 9596.3 9611.1 9631.9 9724.9 6830.4 7171.1 7322.4 7325.0 7325.6 7669.2 7911.0 7912.8 7917.0 7973.1 6933.4 7074.4 7078.5 7079.0 7158.7 6938.3 6971.3 6988.3 6992.9 7012.9 6533.3 6573.7 6595.3 6633.0 6649.8 4745.2 4789.2 4795.7 4859.9 4889.7 4739.5 4743.6 4760.5 4869.9 5021.8 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4276.28 Median:4774.36 stddev:1286.25 (26.9%) stddev-sans-first:1253.84 02:25:42 INFO - PROCESS | 1636 | Values: 248.7 285.8 295.2 302.2 309.2 4756.0 5013.2 5042.5 5199.4 5353.1 4995.6 5132.9 5137.9 5159.9 5212.5 3310.8 3570.9 3802.2 3969.2 4000.3 3918.0 3997.9 4007.2 4030.9 4042.3 3365.4 3989.6 3993.7 3998.0 4000.3 3969.3 3976.0 3989.4 3994.8 3997.2 3501.1 3522.9 3526.6 3538.6 3544.5 4595.1 4727.7 4868.7 5062.7 5171.6 3580.6 4394.6 4490.5 4510.1 4563.7 5433.3 5476.7 5489.0 5510.4 5515.1 3605.3 3723.8 3754.6 3764.6 3765.2 5204.9 5354.7 5709.0 5725.9 5753.8 3813.4 3822.9 3828.6 3843.2 3846.6 3199.7 3731.9 3862.6 3870.2 3880.0 3956.2 4141.1 4313.2 4496.2 4565.8 4659.6 4814.5 4874.0 5474.3 5520.4 1110.4 1175.7 1187.8 1206.0 1234.9 713.8 755.3 835.2 844.4 860.8 4200.9 4790.6 4805.8 4809.1 4848.8 4684.4 4959.6 5127.9 5274.4 5425.9 4744.3 4774.1 4774.7 4797.8 4816.5 2408.7 2570.3 2769.2 2769.4 2775.1 4459.7 5383.0 5429.5 5483.9 5523.9 5018.3 5390.9 5397.0 5407.6 5438.7 4472.6 4949.4 5452.3 5463.7 5475.8 4830.4 5189.7 5214.8 5259.9 5329.3 4689.8 4750.5 4793.5 5174.9 5214.6 4508.5 4874.0 4881.9 4903.9 5432.5 3230.3 3349.8 3491.6 3537.5 3557.6 4672.2 4895.8 4998.3 5224.5 5234.6 4259.1 4855.8 5056.3 5093.9 5152.4 4244.6 4454.8 4658.7 4907.9 5097.0 4644.5 4711.4 5080.4 5097.2 5243.3 4572.9 5349.3 5350.3 5366.5 5386.7 5175.6 5373.5 5423.8 5430.6 5437.9 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18634.51 Median:502.20 stddev:26478.90 (5272.6%) stddev-sans-first:26518.89 02:25:42 INFO - PROCESS | 1636 | Values: 689.3 748.8 781.3 803.9 812.5 13812.7 14034.6 14499.6 14583.3 14936.7 12808.0 13118.7 13226.3 13260.3 13272.4 658.4 670.4 825.3 832.8 833.3 802.7 806.4 809.4 839.3 840.4 882.8 886.0 888.1 888.5 889.3 877.8 878.6 879.1 879.4 880.2 634.7 651.5 652.0 652.1 653.2 12397.0 12461.8 12522.3 12592.6 12618.4 11609.7 11695.1 11698.3 11739.8 11774.4 10194.9 10215.1 10215.7 10302.5 10577.6 3370.7 3410.6 3619.8 3641.3 3667.5 81138.0 83546.2 83596.6 84144.0 85556.6 8407.7 8563.4 8726.1 8863.6 8972.8 14531.0 17332.6 18091.3 18261.3 18309.3 7495.0 7561.4 7640.3 7722.5 7816.7 4095.5 4137.8 4231.7 4259.9 4327.8 3242.9 3341.7 3345.1 3348.4 3352.4 497.8 623.3 629.6 648.2 688.8 506.6 642.9 646.0 664.6 677.8 8556.3 8937.3 9055.0 9179.2 9275.1 546.3 594.3 651.8 663.7 677.6 4162.9 4411.8 4434.4 4454.4 4506.8 71040.2 72582.0 85076.1 112917.4 116523.7 63319.0 71429.3 73091.6 99254.5 101379.5 66716.7 67442.0 70042.6 71624.3 83742.6 9255.6 9586.8 9671.2 9683.3 9738.9 43649.8 47802.0 51844.7 53216.2 54909.0 54088.7 67175.7 69791.0 71553.9 74571.0 12171.9 12366.2 12386.7 12392.9 12908.8 13749.2 14133.0 14180.9 14201.6 14255.2 12701.7 12881.5 12888.0 12924.4 13590.9 13158.6 13249.0 13289.9 13511.9 13764.9 12338.2 12633.2 12679.9 12975.7 13026.3 10814.9 10831.3 10871.3 11215.6 11645.1 10547.9 11179.0 11212.3 11405.3 11412.8 02:25:42 INFO - PROCESS | 1636 | -------- Summary: end -------- 02:25:42 INFO - PROCESS | 1636 | 02:25:42 INFO - TEST-INFO | 1636: exit 0 02:25:42 INFO - TEST-OK | dromaeo_css | took 1119654ms 02:25:42 INFO - TEST-START | kraken 02:25:42 INFO - operating with platform_type : mac_ 02:25:42 INFO - Initialising browser for kraken test... 02:25:43 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpMIVMKx/profile http://localhost:49247/getInfo.html) 02:25:45 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 02:25:45 INFO - PROCESS | 1645 | colorDepth:24 02:25:45 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 02:25:45 INFO - PROCESS | 1645 | __metrics 02:25:45 INFO - PROCESS | 1645 | 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" 02:25:45 INFO - PROCESS | 1645 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:25:46 INFO - TEST-INFO | 1645: exit 0 02:25:46 INFO - Browser initialized. 02:25:46 INFO - Running cycle 1/1 for kraken test... 02:25:46 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpMIVMKx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:58 INFO - PROCESS | 1646 | RSS: Main: 222568448 02:25:58 INFO - PROCESS | 1646 | 02:26:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 02:26:02 INFO - PROCESS | 1646 | RSS: Main: 269848576 02:26:02 INFO - PROCESS | 1646 | 02:26:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 02:26:06 INFO - PROCESS | 1646 | RSS: Main: 283750400 02:26:06 INFO - PROCESS | 1646 | 02:26:09 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 02:26:09 INFO - PROCESS | 1646 | RSS: Main: 381308928 02:26:09 INFO - PROCESS | 1646 | 02:26:11 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 02:26:12 INFO - PROCESS | 1646 | RSS: Main: 283918336 02:26:12 INFO - PROCESS | 1646 | 02:26:14 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:26:15 INFO - PROCESS | 1646 | RSS: Main: 286281728 02:26:15 INFO - PROCESS | 1646 | 02:26:17 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 02:26:18 INFO - PROCESS | 1646 | RSS: Main: 325414912 02:26:18 INFO - PROCESS | 1646 | 02:26:21 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 02:26:22 INFO - PROCESS | 1646 | RSS: Main: 326385664 02:26:22 INFO - PROCESS | 1646 | 02:26:24 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 02:26:25 INFO - PROCESS | 1646 | RSS: Main: 323301376 02:26:25 INFO - PROCESS | 1646 | 02:26:27 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:26:27 INFO - PROCESS | 1646 | RSS: Main: 299868160 02:26:27 INFO - PROCESS | 1646 | 02:26:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:26:30 INFO - PROCESS | 1646 | RSS: Main: 300785664 02:26:30 INFO - PROCESS | 1646 | 02:26:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:26:33 INFO - PROCESS | 1646 | RSS: Main: 304037888 02:26:33 INFO - PROCESS | 1646 | 02:26:35 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:26:36 INFO - PROCESS | 1646 | RSS: Main: 318115840 02:26:36 INFO - PROCESS | 1646 | 02:26:39 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:26:40 INFO - PROCESS | 1646 | RSS: Main: 311435264 02:26:40 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 02:26:42 INFO - PROCESS | 1646 | RSS: Main: 355868672 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | __start_tp_report 02:26:42 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 02:26:42 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 02:26:42 INFO - PROCESS | 1646 | |i|pagename|runs| 02:26:42 INFO - PROCESS | 1646 | |0;ai-astar;129;109;148;163;159;114;114;114;114;114 02:26:42 INFO - PROCESS | 1646 | |1;audio-beat-detection;147;124;139;174;107;110;108;110;140;110 02:26:42 INFO - PROCESS | 1646 | |2;audio-dft;183;184;185;184;184;184;185;188;185;183 02:26:42 INFO - PROCESS | 1646 | |3;audio-fft;82;59;58;88;61;58;60;59;83;58 02:26:42 INFO - PROCESS | 1646 | |4;audio-oscillator;89;71;122;100;96;69;91;68;68;93 02:26:42 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;90;88;91;88;88;90;92;89;91;87 02:26:42 INFO - PROCESS | 1646 | |6;imaging-darkroom;95;94;94;96;95;104;146;125;95;95 02:26:42 INFO - PROCESS | 1646 | |7;imaging-desaturate;81;80;80;83;81;80;87;81;81;81 02:26:42 INFO - PROCESS | 1646 | |8;json-parse-financial;56;56;56;54;58;60;59;58;57;57 02:26:42 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;65;77;65;75;72;68;74;100;100;92 02:26:42 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;83;84;85;84;84;84;80;83;84;82 02:26:42 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;139;139;140;147;151;157;145;143;190;142 02:26:42 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;167;166;167;166;164;163;216;222;166;165 02:26:42 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;69;69;67;68;68;66;66;68;77;82 02:26:42 INFO - PROCESS | 1646 | __end_tp_report 02:26:42 INFO - PROCESS | 1646 | __start_cc_report 02:26:42 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2031 02:26:42 INFO - PROCESS | 1646 | __end_cc_report 02:26:42 INFO - PROCESS | 1646 | __startTimestamp1484044002374__endTimestamp 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | ------- Summary: start ------- 02:26:42 INFO - PROCESS | 1646 | Number of tests: 14 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:127.80 Median:121.50 stddev:20.89 (17.2%) stddev-sans-first:22.15 02:26:42 INFO - PROCESS | 1646 | Values: 129.0 109.0 148.0 163.0 159.0 114.0 114.0 114.0 114.0 114.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:131.50 stddev:22.51 (17.1%) stddev-sans-first:22.67 02:26:42 INFO - PROCESS | 1646 | Values: 147.0 124.0 139.0 174.0 107.0 110.0 108.0 110.0 140.0 110.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.43 (0.8%) stddev-sans-first:1.41 02:26:42 INFO - PROCESS | 1646 | Values: 183.0 184.0 185.0 184.0 184.0 184.0 185.0 188.0 185.0 183.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.37 (20.4%) stddev-sans-first:11.79 02:26:42 INFO - PROCESS | 1646 | Values: 82.0 59.0 58.0 88.0 61.0 58.0 60.0 59.0 83.0 58.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:80.00 stddev:17.74 (22.2%) stddev-sans-first:18.80 02:26:42 INFO - PROCESS | 1646 | Values: 89.0 71.0 122.0 100.0 96.0 69.0 91.0 68.0 68.0 93.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:90.00 stddev:1.65 (1.8%) stddev-sans-first:1.73 02:26:42 INFO - PROCESS | 1646 | Values: 90.0 88.0 91.0 88.0 88.0 90.0 92.0 89.0 91.0 87.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:95.00 stddev:17.62 (18.5%) stddev-sans-first:18.39 02:26:42 INFO - PROCESS | 1646 | Values: 95.0 94.0 94.0 96.0 95.0 104.0 146.0 125.0 95.0 95.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:81.50 Median:81.00 stddev:2.12 (2.6%) stddev-sans-first:2.24 02:26:42 INFO - PROCESS | 1646 | Values: 81.0 80.0 80.0 83.0 81.0 80.0 87.0 81.0 81.0 81.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:1.73 (3.0%) stddev-sans-first:1.79 02:26:42 INFO - PROCESS | 1646 | Values: 56.0 56.0 56.0 54.0 58.0 60.0 59.0 58.0 57.0 57.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:73.00 stddev:13.57 (18.6%) stddev-sans-first:13.44 02:26:42 INFO - PROCESS | 1646 | Values: 65.0 77.0 65.0 75.0 72.0 68.0 74.0 100.0 100.0 92.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.42 (1.7%) stddev-sans-first:1.50 02:26:42 INFO - PROCESS | 1646 | Values: 83.0 84.0 85.0 84.0 84.0 84.0 80.0 83.0 84.0 82.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:146.00 stddev:15.40 (10.5%) stddev-sans-first:15.88 02:26:42 INFO - PROCESS | 1646 | Values: 139.0 139.0 140.0 147.0 151.0 157.0 145.0 143.0 190.0 142.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:166.50 stddev:22.64 (13.6%) stddev-sans-first:23.76 02:26:42 INFO - PROCESS | 1646 | Values: 167.0 166.0 167.0 166.0 164.0 163.0 216.0 222.0 166.0 165.0 02:26:42 INFO - PROCESS | 1646 | 02:26:42 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:68.50 stddev:5.25 (7.7%) stddev-sans-first:5.56 02:26:42 INFO - PROCESS | 1646 | Values: 69.0 69.0 67.0 68.0 68.0 66.0 66.0 68.0 77.0 82.0 02:26:42 INFO - PROCESS | 1646 | -------- Summary: end -------- 02:26:42 INFO - PROCESS | 1646 | 02:26:43 INFO - TEST-INFO | 1646: exit 0 02:26:43 INFO - TEST-OK | kraken | took 60459ms 02:26:43 INFO - SUITE-END | took 1180s 02:26:43 INFO - Completed test suite (00:19:40) 02:26:43 INFO - javascript benchmark 02:26:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3242.173245822315, 3359.932801343973, 3370.3259348130364, 3394.964535816575, 3397.9490307645374, 5656.236408084907, 5712.285964254468, 5717.085266357373, 5729.914051289234, 5732.2261494698205, 5687.345955215152, 5701.77192912284, 5770.798022069229, 5785.190073389729, 5797.5651826113, 5210.843674689765, 5229.477052294759, 5229.50319719627, 5249.527542521172, 5286.10136276832, 5162.0, 5184.429712731597, 5188.740562971855, 5217.86955326118, 5318.707471089088, 4255.851045213418, 5280.9207861882105, 5288.629795914288, 5290.973545132289, 5320.9467905321035, 4293.184294983958, 4386.428706816573, 5295.311609490742, 5310.964361949422, 5347.6256662033675, 5026.899462010776, 5145.897082058375, 5209.791608335662, 5228.843134705965, 5242.2136679498, 5629.8311050668135, 5641.633293835928, 5663.8584035399035, 5702.372738998748, 5703.4011428799795, 6132.0, 6159.0, 6194.256689197326, 6244.406781355764, 6268.247810262752, 6303.5902666327, 6331.746730130789, 6341.0, 6341.8414539636415, 6352.237731472207, 5631.831045068655, 5661.490465858093, 5720.799772007959, 5740.282464691913, 5767.307923049261, 6337.6197428154455, 6353.142325786052, 6359.141515895295, 6368.904466433008, 6370.140031095742, 6148.6925653717135, 6156.6921653917125, 6170.691465426711, 6174.567780255339, 6176.166217560662, 5385.838424847182, 6111.694415279218, 6149.985252433388, 6150.261968563801, 6264.780732674426, 6004.7598096076545, 6091.238595175603, 6127.816165515042, 6140.942784167265, 6149.907751383733, 6161.784337548254, 6227.875442491216, 6250.843728906813, 6269.028300613321, 6284.591501552384, 4800.0639875224, 4846.612271018381, 4854.2233242681, 4877.512248775094, 4908.950910490849, 4396.560343965578, 4454.420925279693, 4454.799534020977, 4481.686281960231, 4508.45898492183, 5592.077307244341, 5659.5755318351285, 5674.205611214434, 5674.432556744375, 5676.290463692038, 6177.907331390033, 6191.19514463117, 6191.226096737908, 6208.1308616793685, 6209.161763162006, 5641.323041235078, 5676.829695109153, 5687.260656114761, 5691.402402747734, 5691.430856914276, 5317.601179911522, 5326.6537675050995, 5341.786328546815, 5342.866428339323, 5346.064438723208, 6226.59527130735, 6296.024116261987, 6346.30190679025, 6351.491880649629, 6356.0465930109, 6278.152449419453, 6336.239651241881, 6364.64994425311, 6372.745090196526, 6383.297837237993, 6179.907301390393, 6307.495400368051, 6322.620642761449, 6324.1146239527425, 6332.746690132527, 6049.334573197033, 6105.633661980295, 6113.572049956639, 6251.093591429309, 6261.843453913689, 6141.232345956755, 6204.13142160089, 6215.192025036717, 6267.62394256346, 6278.780242691484, 5710.314762228559, 6093.573449858467, 6094.7257373417415, 6109.3890610940325, 6137.263528376623, 6076.787312444043, 6096.603720758137, 6108.083787431743, 6113.205283313141, 6122.2959359674205, 5472.699001554953, 5615.466530679579, 5694.0, 5809.767609295581, 5918.644881307136, 4973.5523802857915, 6062.484688801501, 6070.6357618543025, 6100.572959892765, 6127.14220009208, 5878.2064421304285, 5893.233879595625, 5905.763769449174, 5914.526837852875, 5951.732172052181], "value": 5665.091519538429, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.3027372709164, 498.2426711398674, 512.2597846112368, 529.3277537527338, 556.2823957095347, 36008.81995590022, 38889.41665875014, 39057.21885562287, 39077.0, 39122.0, 39852.0, 40341.99145021376, 40342.0, 40394.79802600983, 40394.7980260099, 8490.957545212264, 8700.825983480327, 9292.72121836346, 9471.90528094717, 9477.95261023694, 8510.78723031926, 8603.741887743377, 8603.95698021509, 8612.655493780241, 11179.66461006171, 11246.156538259622, 11461.885381146207, 11469.369184694839, 11496.770064598702, 11497.885021149765, 11332.943335283353, 11425.31448113116, 11449.255798373079, 11449.96950274471, 11483.885161148366, 8574.571271436404, 8750.912490875136, 11258.493367798468, 11339.943300283447, 11341.489632966537, 39350.606493935265, 39857.00357491057, 40108.398374024415, 41632.79183604093, 41646.79176604128, 3232.983835080833, 3236.6925142111463, 3389.9830500847584, 3778.7165962552644, 3802.619738026203, 27540.311492212655, 28661.28346791347, 29487.0, 29830.55254171189, 30620.0, 3197.728193103589, 3234.191452136966, 3254.3653987472453, 3284.998075586945, 3294.2093897464674, 457524.0, 460296.0, 463209.0, 464253.0, 472068.0, 61302.08046879301, 61625.69187154035, 61697.69151154305, 61748.0, 61757.69121154366, 65139.0, 65348.0, 65428.672856635414, 65453.01820472697, 65525.67237163784, 15647.530574082795, 16237.837621623868, 16252.512424627044, 16254.674906501803, 16260.67478650444, 11302.265352752209, 11667.474963626657, 11747.177697561088, 11751.294922304689, 11778.411079445994, 16363.345466181458, 16786.4124755633, 18360.65249335769, 18554.0, 19131.90434047821, 6612.0, 6693.698783554752, 6804.455643548505, 7321.597312147883, 7590.240975902366, 824.7278398128603, 863.5552690364467, 907.3503292484345, 921.5530467723149, 1009.5204777730519, 28829.0, 29242.0, 29267.0, 29779.0, 29905.700942990727, 889.235257678396, 1001.5543083327849, 1016.9491525423699, 1024.882138554061, 1047.1884289675563, 13251.602451926457, 14776.704465910621, 15030.248487575578, 15044.0, 15354.309056092503, 626855.0, 629329.0, 631126.0, 634346.0, 634578.0, 49389.50610493921, 50553.49446505561, 55564.0, 56798.0, 56821.0, 1332.0342751505082, 1358.9875542720538, 1366.050594836599, 1371.6228037289427, 1377.9035467517378, 29209.415811684074, 30070.69929300679, 30490.847545762128, 30613.846930765205, 30920.22699432442, 49615.759606010135, 50029.74985125051, 50172.0, 50199.49800501948, 50280.49719502758, 580135.0, 583671.0, 583680.0, 586133.0693346506, 586996.0, 25906.35234119162, 26283.0, 26296.0, 26675.866620666773, 26699.33251668724, 44536.33195502135, 44609.77695111504, 44870.77564612156, 45496.54503454923, 46187.538124618324, 36594.26811463702, 36881.44677829781, 37284.0, 37381.62618373781, 37511.0, 31586.0, 33129.668703313626, 36383.81808090943, 37730.62269377381, 38035.809820950715, 36869.70956016527, 37109.62890371062, 37586.0, 37811.243775123796, 38169.427458588696, 32120.839395802872, 33698.49452258169, 33705.686716082295, 34136.31727365389, 34186.0, 22015.779842201373, 24989.625155622318, 25179.370515737253, 25209.722736044198, 25752.742472575785], "value": 19701.499154583624, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.659293891986, 363.93813051781194, 369.38497401825947, 376.74569665475815, 396.91863168050537, 14746.63133421665, 14974.550763477086, 15828.287727052268, 20016.0, 20546.075426605803, 18405.0, 19408.514787130294, 19495.610087798235, 20047.0, 20290.39128826134, 5680.31836179658, 5817.650940943536, 5855.47300742933, 5921.763129474816, 5981.222441082653, 4649.232876575361, 4785.66500344976, 5898.675572843492, 5978.133170690247, 5984.910226346608, 5912.054071348585, 5920.0, 5925.525957923377, 5936.673482958436, 5939.316978547479, 5623.915641265385, 5956.821295361146, 5992.850178745566, 6046.576739628228, 6057.42454466825, 5565.443455654443, 5735.942640573624, 5833.766649334021, 5841.85395365115, 5846.064629659277, 12891.226526408353, 13101.08292419531, 13225.0, 13243.668908277274, 13256.536021239306, 2006.4482267376443, 2174.402039439151, 2310.90756369745, 2355.599548076831, 2449.1795248591566, 9860.852087218698, 9908.702738917844, 9941.75145621358, 10219.080282774587, 10251.199492446225, 1800.1629242402214, 1841.5672317005492, 1874.1940965384879, 1878.7839398469214, 1923.2210954563425, 107585.99999999921, 109060.0, 110614.44692776565, 112886.0, 121001.39499302447, 11118.388488633203, 11627.476763545661, 11632.709182270342, 11634.418279086181, 11768.88231117695, 14412.711745765235, 14427.206503642401, 14434.639134021734, 14446.0, 14453.349599268058, 8219.794505137299, 8314.542700151429, 8317.293030092509, 8390.244877961013, 8456.619452124669, 6519.804405867737, 8326.70856520031, 8351.164883511721, 8412.200840920103, 8495.617697203641, 3795.8802153364823, 4421.668374871898, 4516.096780643818, 4570.497245303014, 4678.508756580578, 1753.1321995612254, 1826.8264514871066, 1839.7516335294831, 1908.5610309628862, 1916.8403116114828, 774.7598244544097, 874.7900503879023, 888.3648191543077, 897.4660077254024, 906.2297047509564, 19305.324313649235, 20834.47913802167, 20922.16311347529, 21026.474338141365, 21064.78935210659, 924.7410724997012, 925.421611492817, 933.6078846884325, 946.3659348236698, 975.8292298847673, 8238.299744521662, 9022.0, 10301.845472317922, 10713.83929241062, 10772.299800513098, 567345.5716199735, 570273.0, 576386.0, 586323.0, 592409.0, 76121.23878761141, 78296.0, 117314.0, 121852.39073804574, 122516.0, 951.6050838901779, 963.5182408795603, 1004.8844382895915, 1010.6765834932751, 1012.7518757904213, 18117.547061323574, 18320.72518912244, 18649.440516784518, 18766.343177988703, 19024.334148304744, 51779.482205178974, 53959.0, 54434.72782636061, 56602.15096773469, 56880.862382752945, 79193.0, 79613.60193199229, 81327.59336203517, 82996.17003829885, 84490.0, 7836.0, 7852.0, 7971.242731940456, 8109.635066422144, 8179.182081791773, 10307.849214286385, 12150.392480376122, 12415.130940834055, 12507.499700011898, 12616.0, 6865.382115609619, 10369.170466362824, 10782.083522900328, 10955.233133680566, 10999.890001099886, 10495.422751748727, 10591.258611897094, 10657.41384223844, 10918.01737843598, 11002.50488728025, 10932.234743567873, 11102.444877756241, 11137.665870023913, 11251.718707032389, 11382.130075774276, 6200.0, 6866.141732283465, 7312.41500679955, 7959.880601790862, 8113.878291825509, 9945.602175912882, 10122.949385253027, 10324.896751032393, 10700.143988480748, 11084.334939903632], "value": 9092.08759137958, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.1127101197255, 1462.5977856089573, 1589.928453219605, 1782.0, 1799.1004497751132, 7437.107547094342, 7992.560409177493, 8337.95831020844, 8353.122922093176, 8359.540225287607, 7543.057117860268, 7574.091109066906, 7930.405219608538, 8126.431149819515, 8146.511209327445, 2285.02886273334, 2322.500662357593, 2327.44141406063, 2329.0683726509365, 2332.766723327662, 2302.9424264393356, 2343.6367363058753, 2351.200591798788, 2357.882105894707, 2358.870262135582, 2013.4060452166573, 2089.7283353164066, 2089.7910208979056, 2258.062903894882, 2338.497223097034, 1990.8606397552178, 2312.0, 2333.626619740833, 2336.497653004612, 2337.8714170720605, 2292.255017119426, 2340.4967931894726, 2344.6951896253377, 2345.2143531916827, 2350.059976009593, 8172.223638754309, 8356.623951922118, 8357.122502137248, 8373.49759014461, 8396.958015209946, 5031.773570189313, 5189.014087323397, 5190.0, 5192.402873669501, 5213.191955246943, 3262.5106234064842, 3303.124671961932, 3336.2326664866973, 3392.3724111039537, 3395.3209358128474, 1062.097217365241, 1079.7786453776957, 1100.2408338246664, 1102.72431892027, 1102.7518808268155, 10538.947305263502, 10719.892801071968, 10888.346699198073, 10977.231593788438, 10994.065504432212, 4282.764447955392, 4365.3670217818235, 4460.643148548108, 4496.0, 4504.662150338738, 8542.10307917672, 8553.615087321086, 8558.871616925751, 8569.914300856912, 8575.528345941033, 4971.875703107379, 4973.278874563241, 5065.240213967934, 5078.289039534468, 5089.592832573385, 4116.176764647083, 4548.47692515365, 4689.0, 4708.728643266299, 4778.259369797687, 3621.2436968070465, 3649.5802982656805, 3651.6896063834342, 3704.6110158433517, 3724.366857634209, 1123.224974767408, 1130.5421304371744, 1143.685486491205, 1144.753877916252, 1145.1468655851277, 606.8634557224589, 772.403933501772, 857.1257317536079, 881.5504092912661, 918.4351623751478, 5223.451537588575, 5429.53848922846, 5452.464603959099, 5460.781568737206, 5551.72241387929, 867.6442658509981, 872.5070335260557, 873.6549063120032, 923.5013092929743, 945.9169251207329, 3560.608333083359, 3602.7390413355115, 3622.239329740731, 3623.438367053111, 3724.3854763964187, 9469.857952130724, 9708.514574271258, 9832.115109640024, 9980.600775969026, 10147.695569132939, 9809.901900981042, 9873.160781333523, 10064.295499315123, 10071.748206294902, 10090.142337901214, 7656.540607563564, 7951.522908625501, 7967.960160199162, 7985.081715602664, 7986.720464783821, 5333.493318134771, 5387.703676297841, 5533.335999680065, 5594.300712410949, 5672.602917795714, 9402.905970940203, 9407.670731524637, 9522.952475227858, 9550.0, 9599.232061434928, 9524.333296669167, 9596.32825702222, 9611.134997850228, 9631.903680963382, 9724.9027509724, 6830.419414349637, 7171.103612048494, 7322.377597904025, 7325.01112349826, 7325.597092159983, 7669.23307669251, 7910.9604451977375, 7912.762617121265, 7917.0, 7973.122956474899, 6933.4106600939485, 7074.398676112588, 7078.539894906815, 7078.964605176941, 7158.749443769443, 6938.306169383223, 6971.337722916315, 6988.266232045766, 6992.930070699228, 7012.929870701431, 6533.346665333429, 6573.704183311666, 6595.340465953366, 6632.966835165793, 6649.833754155991, 4745.217039188496, 4789.209780386197, 4795.688280261772, 4859.927101093415, 4889.706617602955, 4739.502352252964, 4743.644226682944, 4760.476347601831, 4869.853904382874, 5021.824236151717], "value": 4345.832848855897, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.72640095894513, 285.83707286846504, 295.1838167466954, 302.2308225565935, 309.16525381470035, 4755.952440475594, 5013.17282648363, 5042.537664077413, 5199.428062913086, 5353.06321393756, 4995.602110986729, 5132.8973420531565, 5137.869668672895, 5159.871003224912, 5212.504812042859, 3310.8013519188926, 3570.8750193743226, 3802.182530755887, 3969.2061587682433, 4000.3399439092514, 3917.9608203918024, 3997.900052498682, 4007.178528401685, 4030.919381612366, 4042.333015052513, 3365.3774051800374, 3989.561148273702, 3993.6805055595482, 3997.960020399788, 4000.3399439092514, 3969.2656858481128, 3975.9602403975878, 3989.40158976153, 3994.840206391741, 3997.160596274786, 3501.0547152269, 3522.894313170609, 3526.5944416392185, 3538.610752817188, 3544.521489598897, 4595.103954728829, 4727.739974301412, 4868.732219727914, 5062.721550314731, 5171.586273098181, 3580.5524309461316, 4394.604485596312, 4490.483594386622, 4510.143072816154, 4563.657725670588, 5433.310511296933, 5476.698781567012, 5488.9725551372385, 5510.3744395403455, 5515.145152501369, 3605.3330133925306, 3723.8138093095235, 3754.624537546251, 3764.604716504782, 3765.2281282337053, 5204.9219261711105, 5354.732263386854, 5708.971455142698, 5725.856853578653, 5753.769849206069, 3813.427985802152, 3822.904427389282, 3828.617138286205, 3843.2313537292653, 3846.615338466131, 3199.6800319968283, 3731.8541838443, 3862.575116737157, 3870.2259548090497, 3879.9612003880166, 3956.169204467094, 4141.109661422808, 4313.223619748475, 4496.213162696515, 4565.762776239619, 4659.603933665609, 4814.542618451242, 4873.975630121827, 5474.288342515448, 5520.392756796749, 1110.4059328259343, 1175.6649354933975, 1187.8158885372782, 1205.9939700301443, 1234.8641649418557, 713.8144082538578, 755.316438623047, 835.2065537739172, 844.4257904624914, 860.7503823891002, 4200.886765007275, 4790.640701947298, 4805.783739731721, 4809.114531281203, 4848.75756212195, 4684.391029166255, 4959.628027897922, 5127.897442051213, 5274.3670759509105, 5425.9186112208345, 4744.288356746516, 4774.061399345554, 4774.665773395829, 4797.7601119943865, 4816.494268101869, 2408.7229968553843, 2570.2674737699667, 2769.1861830501052, 2769.376890199689, 2775.139706690892, 4459.687821852439, 5383.031054410244, 5429.538489228381, 5483.930633526504, 5523.917141242885, 5018.31154101735, 5390.897549955521, 5397.0062154005755, 5407.621056630534, 5438.728063596884, 4472.597466228055, 4949.406071271398, 5452.291202143696, 5463.699496527729, 5475.808346707846, 4830.420349558076, 5189.6886186828915, 5214.843554693213, 5259.868503287448, 5329.307190065267, 4689.788959496763, 4750.477447480844, 4793.472718001087, 5174.922376164285, 5214.608904332114, 4508.45898492183, 4874.003317279797, 4881.8535443936735, 4903.8528844134735, 5432.538234250133, 3230.3216324571163, 3349.8097152461996, 3491.633378495221, 3537.4693795930816, 3557.6264492227942, 4672.168821566283, 4895.804167833389, 4998.300577803564, 5224.47348040079, 5234.560495863668, 4259.083412464342, 4855.75721213945, 5056.342675452168, 5093.898122037613, 5152.381714194321, 4244.596763307481, 4454.799534020912, 4658.67389282747, 4907.871189626478, 5097.0315640028275, 4644.512326205835, 4711.408725201194, 5080.415752188398, 5097.241451699158, 5243.318368612208, 4572.862814115582, 5349.277847490539, 5350.277712508761, 5366.463353664602, 5386.730663466733, 5175.560077393464, 5373.489518495737, 5423.8439371314635, 5430.565554755689, 5437.918431223456], "value": 3857.1628299530644, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.2693744630692, 748.7903387051624, 781.2617076862367, 803.8673618852889, 812.508432398399, 13812.654683632914, 14034.649133771649, 14499.637509062266, 14583.270836458189, 14936.701265974702, 12808.039397045211, 13118.672033199175, 13226.272555009495, 13260.336983150852, 13272.402741876615, 658.387699439521, 670.4267850987409, 825.2572684583863, 832.8459235041506, 833.3374966901298, 802.6909639788665, 806.4395245304515, 809.4293523066224, 839.2866063845713, 840.36552402936, 882.7748924024365, 886.0474989386403, 888.103015953888, 888.4669198480924, 889.3018980100622, 877.784942689042, 878.5519385113638, 879.147227189625, 879.3580686099151, 880.2429910277157, 634.6572850660638, 651.4983462733669, 652.0219670494258, 652.1228947066188, 653.2063542795479, 12397.0, 12461.75076498474, 12522.311272879988, 12592.622221333375, 12618.432170552347, 11609.651710448616, 11695.122865785015, 11698.2981021139, 11739.765204695943, 11774.352410617412, 10194.9490252549, 10215.080642742189, 10215.693529194063, 10302.484875756183, 10577.576896924114, 3370.6966373026544, 3410.6255637449135, 3619.7828130312264, 3641.326354624403, 3667.523221981152, 81138.0, 83546.16453835445, 83596.58201709013, 84144.0, 85556.57221713851, 8407.663693452316, 8563.400561960727, 8726.08376120511, 8863.556822158866, 8972.820543589092, 14531.0, 17332.566685832957, 18091.276348945896, 18261.26954921815, 18309.267629294944, 7495.0, 7561.395088392916, 7640.274173953466, 7722.45942784011, 7816.7264145754625, 4095.5290141634096, 4137.834486620502, 4231.682623803165, 4259.893502662459, 4327.8485253016, 3242.854071566785, 3341.682540158681, 3345.1469875181674, 3348.380549598332, 3352.3630510202984, 497.8108318838818, 623.2738859229038, 629.5718911140377, 648.1703419622891, 688.8036909480874, 506.5947242206294, 642.9132067170966, 645.9935400645934, 664.6145235763246, 677.7661706711177, 8556.27271681914, 8937.329700272505, 9055.0, 9179.219766319937, 9275.118863707938, 546.3443867359106, 594.313567829158, 651.820749293948, 663.6781161136845, 677.5562006885498, 4162.854300099542, 4411.8014689338415, 4434.445694288214, 4454.443194600675, 4506.842260520866, 71040.22399439952, 72582.0, 85076.14923850806, 112917.43541282241, 116523.669526609, 63319.0, 71429.28570714331, 73091.63454182801, 99254.5037274809, 101379.49310253402, 66716.6664166676, 67442.0, 70042.64978675074, 71624.28375716176, 83742.58128709317, 9255.583498742439, 9586.760330991501, 9671.17794987434, 9683.32216744821, 9738.902610973799, 43649.78175109104, 47802.0, 51844.70388240324, 53216.20175697445, 54909.0, 54088.729556351966, 67175.66412167907, 69791.0, 71553.92669110071, 74571.0, 12171.939140304241, 12366.228320189233, 12386.690332741753, 12392.876071239172, 12908.806367904677, 13749.243791591158, 14133.010689252065, 14180.858191417954, 14201.644958875697, 14255.215963122128, 12701.745965080461, 12881.549145779853, 12888.033397495037, 12924.353782310658, 13590.93204533971, 13158.60524184276, 13249.0, 13289.933550332187, 13511.932440337736, 13764.862351376358, 12338.198017129216, 12633.178843375152, 12679.936600317309, 12975.740485190432, 13026.348682565644, 10814.863689529948, 10831.2959657622, 10871.347719136877, 11215.60745373908, 11645.126615503701, 10547.947260263649, 11179.0, 11212.27120237182, 11405.258658187191, 11412.771744564896], "value": 7003.434555309412, "unit": "score"}], "name": "dromaeo_css", "value": 7014.745214021558, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 109.0, 148.0, 163.0, 159.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 139.0, 174.0, 107.0, 110.0, 108.0, 110.0, 140.0, 110.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 184.0, 184.0, 184.0, 185.0, 188.0, 185.0, 183.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 88.0, 61.0, 58.0, 60.0, 59.0, 83.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 122.0, 100.0, 96.0, 69.0, 91.0, 68.0, 68.0, 93.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 88.0, 88.0, 90.0, 92.0, 89.0, 91.0, 87.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 95.0, 104.0, 146.0, 125.0, 95.0, 95.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 83.0, 81.0, 80.0, 87.0, 81.0, 81.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 54.0, 58.0, 60.0, 59.0, 58.0, 57.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 65.0, 75.0, 72.0, 68.0, 74.0, 100.0, 100.0, 92.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 84.0, 84.0, 84.0, 80.0, 83.0, 84.0, 82.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 140.0, 147.0, 151.0, 157.0, 145.0, 143.0, 190.0, 142.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 166.0, 164.0, 163.0, 216.0, 222.0, 166.0, 165.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 67.0, 68.0, 68.0, 66.0, 66.0, 68.0, 77.0, 82.0], "value": 70.0, "unit": "score"}], "name": "kraken", "value": 1482.0, "alertThreshold": 2.0}]} 02:26:43 INFO - Return code: 0 02:26:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:26:43 INFO - # TBPL SUCCESS # 02:26:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:26:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:26:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:26:43 INFO - 2017-01-10 02:26:43.670 screenresolution[1650:9888] starting screenresolution argv=screenresolution get 02:26:43 INFO - 2017-01-10 02:26:43.673 screenresolution[1650:9888] Display 0: 1600x1200x32@60 02:26:43 INFO - 2017-01-10 02:26:43.680 screenresolution[1651:9891] starting screenresolution argv=screenresolution list 02:26:43 INFO - Available Modes on Display 0 02:26:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:26:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:26:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:26:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:26:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:26:43 INFO - Intel Iris: 02:26:43 INFO - Chipset Model: Intel Iris 02:26:43 INFO - Type: GPU 02:26:43 INFO - Bus: Built-In 02:26:43 INFO - VRAM (Dynamic, Max): 1536 MB 02:26:43 INFO - Vendor: Intel (0x8086) 02:26:43 INFO - Device ID: 0x0a2e 02:26:43 INFO - Revision ID: 0x0009 02:26:43 INFO - Displays: 02:26:43 INFO - Display: 02:26:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:26:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:26:43 INFO - Display Serial Number: 1600x1200 60 02:26:43 INFO - Main Display: Yes 02:26:43 INFO - Mirror: Off 02:26:43 INFO - Online: Yes 02:26:43 INFO - Rotation: Supported 02:26:43 INFO - Return code: 0 02:26:43 INFO - Running post-action listener: _resource_record_post_action 02:26:43 INFO - [mozharness: 2017-01-10 10:26:43.927000Z] Finished run-tests step (success) 02:26:43 INFO - Running post-run listener: _resource_record_post_run 02:26:43 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38769664; Write bytes: 657519616; Read time: 404; Write time: 1987 02:26:43 INFO - TinderboxPrint: CPU usage
53.6% 02:26:43 INFO - TinderboxPrint: I/O read bytes / time
38,769,664 / 404 02:26:43 INFO - TinderboxPrint: I/O write bytes / time
657,519,616 / 1,987 02:26:43 INFO - TinderboxPrint: CPU idle
2,250.2 (46.4%) 02:26:43 INFO - TinderboxPrint: CPU system
974.8 (20.1%) 02:26:43 INFO - TinderboxPrint: CPU user
1,624.7 (33.5%) 02:26:43 INFO - TinderboxPrint: Swap in / out
387,817,472 / 0 02:26:43 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 338868736; Write bytes: 334051328; Read time: 26627; Write time: 618 02:26:44 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37794816; Write bytes: 305765376; Read time: 361; Write time: 1317 02:26:44 INFO - Running post-run listener: _upload_blobber_files 02:26:44 INFO - Blob upload gear active. 02:26:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:26:44 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 02:26:44 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'] 02:26:44 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 02:26:45 INFO - (blobuploader) - INFO - Open directory for files ... 02:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:26:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:26:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:26:47 INFO - (blobuploader) - INFO - Done attempting. 02:26:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:26:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:26:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:26:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:26:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:26:50 INFO - (blobuploader) - INFO - Done attempting. 02:26:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:26:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:26:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:26:58 INFO - (blobuploader) - INFO - Done attempting. 02:26:58 INFO - (blobuploader) - INFO - Iteration through files over. 02:26:58 INFO - Return code: 0 02:26:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:26:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:26:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29598048d9ef8fb471418d02089d9068735f580a637d71469f0598810ebc7d4bca632521eebe37839045355870f0cdf1584ecc9c26689248ba7deb3b6f6afc27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f3aee17659fd0ea97f2ca400e63c7ed72f76589dab7a39201825d927f1c38fc9609defb5cb60f5eb22c51df0bf6f6e9dba09b96909063f7d01472f58ae54f95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cfcf375c52f94dd0ff5dca06c9f77bb001b89ad4e682c1d6dc525711a22d2a9726a28d0bdc79badee281492eaa1be431e6eadb64d5f0dd920030a5b60e10b5c"} 02:26:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:26:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:26:58 INFO - Contents: 02:26:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29598048d9ef8fb471418d02089d9068735f580a637d71469f0598810ebc7d4bca632521eebe37839045355870f0cdf1584ecc9c26689248ba7deb3b6f6afc27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f3aee17659fd0ea97f2ca400e63c7ed72f76589dab7a39201825d927f1c38fc9609defb5cb60f5eb22c51df0bf6f6e9dba09b96909063f7d01472f58ae54f95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cfcf375c52f94dd0ff5dca06c9f77bb001b89ad4e682c1d6dc525711a22d2a9726a28d0bdc79badee281492eaa1be431e6eadb64d5f0dd920030a5b60e10b5c"} 02:26:58 INFO - Running post-run listener: copy_logs_to_upload_dir 02:26:58 INFO - Copying logs to upload dir... 02:26:58 INFO - mkdir: /builds/slave/test/build/upload/logs 02:26:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.228117 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-01-10 02:26:58.215719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 02:26:58.219136) ========= 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.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/29598048d9ef8fb471418d02089d9068735f580a637d71469f0598810ebc7d4bca632521eebe37839045355870f0cdf1584ecc9c26689248ba7deb3b6f6afc27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f3aee17659fd0ea97f2ca400e63c7ed72f76589dab7a39201825d927f1c38fc9609defb5cb60f5eb22c51df0bf6f6e9dba09b96909063f7d01472f58ae54f95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cfcf375c52f94dd0ff5dca06c9f77bb001b89ad4e682c1d6dc525711a22d2a9726a28d0bdc79badee281492eaa1be431e6eadb64d5f0dd920030a5b60e10b5c"} build_url:https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011286 build_url: 'https://queue.taskcluster.net/v1/task/HrHgrLOtRHylZoesyHfHFw/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/29598048d9ef8fb471418d02089d9068735f580a637d71469f0598810ebc7d4bca632521eebe37839045355870f0cdf1584ecc9c26689248ba7deb3b6f6afc27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f3aee17659fd0ea97f2ca400e63c7ed72f76589dab7a39201825d927f1c38fc9609defb5cb60f5eb22c51df0bf6f6e9dba09b96909063f7d01472f58ae54f95", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1cfcf375c52f94dd0ff5dca06c9f77bb001b89ad4e682c1d6dc525711a22d2a9726a28d0bdc79badee281492eaa1be431e6eadb64d5f0dd920030a5b60e10b5c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 02:26:58.256262) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:26:58.256854) ========= 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.9qwA4vIpWN/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.Geim3XsPBz/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 02:26:58.306913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 02:26:58.307295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 02:26:58.307633) ========= ========= Total master_lag: 0.19 =========