builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0172 starttime: 1479131229.4 results: success (0) buildid: 20161114030203 builduid: 2925079056124088a53f561f478b114c revision: 1196bf3032e1bce1fb07a01fd9082a767426c5fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.399733) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.400214) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.400493) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.424504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.424891) ========= 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.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 05:47:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.27M=0.001s 2016-11-14 05:47:09 (9.27 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.545580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.545900) ========= 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.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.020082 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:09.594903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 05:47:09.595275) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1196bf3032e1bce1fb07a01fd9082a767426c5fb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1196bf3032e1bce1fb07a01fd9082a767426c5fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 05:47:09,672 truncating revision to first 12 chars 2016-11-14 05:47:09,672 Setting DEBUG logging. 2016-11-14 05:47:09,672 attempt 1/10 2016-11-14 05:47:09,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1196bf3032e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 05:47:11,419 unpacking tar archive at: mozilla-central-1196bf3032e1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.002949 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 05:47:11.621938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:11.622500) ========= script_repo_revision: 1196bf3032e1bce1fb07a01fd9082a767426c5fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:11.623271) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:11.623778) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 05:47:11.642478) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-14 05:47:11.642784) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:47:11 INFO - MultiFileLogger online at 20161114 05:47:11 in /builds/slave/test 05:47:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:47:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:11 INFO - {'append_to_log': False, 05:47:11 INFO - 'base_work_dir': '/builds/slave/test', 05:47:11 INFO - 'blob_upload_branch': 'Firefox', 05:47:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:11 INFO - 'branch': 'Firefox', 05:47:11 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:11 INFO - 'config_files': ('talos/mac_config.py',), 05:47:11 INFO - 'default_actions': ('clobber', 05:47:11 INFO - 'read-buildbot-config', 05:47:11 INFO - 'download-and-extract', 05:47:11 INFO - 'populate-webroot', 05:47:11 INFO - 'create-virtualenv', 05:47:11 INFO - 'install', 05:47:11 INFO - 'run-tests'), 05:47:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:11 INFO - 'download_minidump_stackwalk': True, 05:47:11 INFO - 'download_symbols': 'ondemand', 05:47:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:11 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:11 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:11 INFO - 'installer_path': 'installer.exe', 05:47:11 INFO - 'log_level': 'info', 05:47:11 INFO - 'log_name': 'talos', 05:47:11 INFO - 'log_to_console': True, 05:47:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:11 INFO - 'opt_config_files': (), 05:47:11 INFO - 'pip_index': False, 05:47:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:11 INFO - 'cmd': ('bash', 05:47:11 INFO - '-c', 05:47:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:11 INFO - 'enabled': True, 05:47:11 INFO - 'halt_on_failure': False, 05:47:11 INFO - 'name': 'check_screen_resolution'},), 05:47:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:11 INFO - 'cmd': ('bash', 05:47:11 INFO - '-c', 05:47:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:11 INFO - 'enabled': True, 05:47:11 INFO - 'halt_on_failure': False, 05:47:11 INFO - 'name': 'check_screen_resolution'},), 05:47:11 INFO - 'run_cmd_checks_enabled': True, 05:47:11 INFO - 'sps_profile': False, 05:47:11 INFO - 'sps_profile_interval': 0, 05:47:11 INFO - 'suite': 'dromaeojs', 05:47:11 INFO - 'system_bits': '32', 05:47:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:11 INFO - 'title': 't-yosemite-r7-0172', 05:47:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:11 INFO - 'use_talos_json': True, 05:47:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:11 INFO - 'work_dir': 'build'} 05:47:11 INFO - [mozharness: 2016-11-14 13:47:11.846385Z] Running clobber step. 05:47:11 INFO - Running pre-action listener: _resource_record_pre_action 05:47:11 INFO - Running main action method: clobber 05:47:11 INFO - rmtree: /builds/slave/test/build 05:47:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:12 INFO - Running post-action listener: _resource_record_post_action 05:47:12 INFO - [mozharness: 2016-11-14 13:47:12.796388Z] Finished clobber step (success) 05:47:12 INFO - [mozharness: 2016-11-14 13:47:12.796506Z] Running read-buildbot-config step. 05:47:12 INFO - Running pre-action listener: _resource_record_pre_action 05:47:12 INFO - Running main action method: read_buildbot_config 05:47:12 INFO - Using buildbot properties: 05:47:12 INFO - { 05:47:12 INFO - "project": "", 05:47:12 INFO - "product": "firefox", 05:47:12 INFO - "script_repo_revision": "production", 05:47:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:47:12 INFO - "repository": "", 05:47:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:47:12 INFO - "buildid": "20161114030203", 05:47:12 INFO - "stage_platform": "macosx64", 05:47:12 INFO - "basedir": "/builds/slave/test", 05:47:12 INFO - "nightly_build": "True", 05:47:12 INFO - "buildnumber": 9, 05:47:12 INFO - "slavename": "t-yosemite-r7-0172", 05:47:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:47:12 INFO - "platform": "yosemite_r7", 05:47:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:47:12 INFO - "branch": "mozilla-central", 05:47:12 INFO - "repo_path": "mozilla-central", 05:47:12 INFO - "revision": "1196bf3032e1bce1fb07a01fd9082a767426c5fb", 05:47:12 INFO - "pgo_build": "False", 05:47:12 INFO - "builduid": "2925079056124088a53f561f478b114c", 05:47:12 INFO - "slavebuilddir": "test" 05:47:12 INFO - } 05:47:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:47:12 INFO - Running post-action listener: _resource_record_post_action 05:47:12 INFO - [mozharness: 2016-11-14 13:47:12.800011Z] Finished read-buildbot-config step (success) 05:47:12 INFO - [mozharness: 2016-11-14 13:47:12.800153Z] Running download-and-extract step. 05:47:12 INFO - Running pre-action listener: _resource_record_pre_action 05:47:12 INFO - Running main action method: download_and_extract 05:47:12 INFO - mkdir: /builds/slave/test/build/tests 05:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:12 INFO - trying https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:12 INFO - Downloading https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:47:13 INFO - Downloaded 1199 bytes. 05:47:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:47:13 INFO - Using the following test package requirements: 05:47:13 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:47:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:47:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:47:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'jsshell-mac.zip'], 05:47:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:47:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:47:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:47:13 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:47:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:47:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:47:13 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:47:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:47:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:47:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:13 INFO - Fetch https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:47:14 INFO - Http code: 200 05:47:14 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 05:47:14 INFO - x-amz-cf-id: URMCv5HwBXnkmzU8FUaR3MPZYBNqSWh7D0q-GIg-00TG5W0oJiLsdw== 05:47:14 INFO - x-amz-version-id: Zln0psDCYounqj9A4Op.ffwwoaSy4Fav 05:47:14 INFO - Expected file size: 16328891 05:47:14 INFO - Obtained file size: 16328891 05:47:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:47:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:21 INFO - Fetch https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:47:22 INFO - Http code: 200 05:47:22 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 05:47:22 INFO - x-amz-cf-id: yK_pEGFbrkZwU6fSPRdjjl4Eqh7SZi9R4QGYO6qUvn_DszQ5iVV1vg== 05:47:22 INFO - x-amz-version-id: Zln0psDCYounqj9A4Op.ffwwoaSy4Fav 05:47:22 INFO - Expected file size: 16328891 05:47:22 INFO - Obtained file size: 16328891 05:47:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:47:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:47:29 INFO - Fetch https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:47:30 INFO - Http code: 200 05:47:30 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 05:47:30 INFO - x-amz-cf-id: 9aXm8L_zTARTH8_czv466OxNxbzbalAC5QqssyIPkRdsAIr4YNcq9w== 05:47:30 INFO - x-amz-version-id: IRiVa.sIZhg7g44Ds_YZnJAnm90Hzeow 05:47:30 INFO - Expected file size: 14409038 05:47:30 INFO - Obtained file size: 14409038 05:47: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')]} 05:47:30 INFO - trying https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:30 INFO - Downloading https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:47:33 INFO - Downloaded 111106455 bytes. 05:47:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:33 INFO - mkdir: /builds/slave/test/properties 05:47:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:33 INFO - Contents: 05:47:33 INFO - build_url:https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:47:34 INFO - Running post-action listener: _resource_record_post_action 05:47:34 INFO - Running post-action listener: set_extra_try_arguments 05:47:34 INFO - [mozharness: 2016-11-14 13:47:34.846079Z] Finished download-and-extract step (success) 05:47:34 INFO - [mozharness: 2016-11-14 13:47:34.846230Z] Running populate-webroot step. 05:47:34 INFO - Running pre-action listener: _resource_record_pre_action 05:47:34 INFO - Running main action method: populate_webroot 05:47:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:34 INFO - '--tppagecycles', 05:47:34 INFO - '7'], 05:47:34 INFO - 'tests': ['tsvgm']}}, 05:47:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:47:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['damp', 'tps']}, 05:47:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'tests': ['damp', 'tps']}, 05:47:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['dromaeo_dom']}, 05:47:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['basic_compositor_video']}, 05:47:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:47:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['a11yr', 05:47:34 INFO - 'ts_paint', 05:47:34 INFO - 'tpaint', 05:47:34 INFO - 'sessionrestore', 05:47:34 INFO - 'sessionrestore_no_auto_restore', 05:47:34 INFO - 'tabpaint']}, 05:47:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['tsvgx', 05:47:34 INFO - 'tsvgr_opacity', 05:47:34 INFO - 'tart', 05:47:34 INFO - 'tscrollx', 05:47:34 INFO - 'cart']}, 05:47:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:34 INFO - 'tsvgr_opacity', 05:47:34 INFO - 'tart', 05:47:34 INFO - 'tscrollx', 05:47:34 INFO - 'cart']}, 05:47:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'talos_options': ['--disable-e10s'], 05:47:34 INFO - 'tests': ['tp5o']}, 05:47:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'tests': ['tp5o']}, 05:47:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'talos_options': ['--disable-e10s', 05:47:34 INFO - '--xperf_path', 05:47:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:34 INFO - 'tests': ['tp5n']}, 05:47:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:34 INFO - 'talos_options': ['--xperf_path', 05:47:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:34 INFO - 'tests': ['tp5n']}}, 05:47:34 INFO - 'talos.zip': {'path': '', 05:47:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:34 INFO - Running post-action listener: _resource_record_post_action 05:47:34 INFO - [mozharness: 2016-11-14 13:47:34.865682Z] Finished populate-webroot step (success) 05:47:34 INFO - [mozharness: 2016-11-14 13:47:34.865815Z] Running create-virtualenv step. 05:47:34 INFO - Running pre-action listener: _resource_record_pre_action 05:47:34 INFO - Running main action method: create_virtualenv 05:47:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:47:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:34 INFO - Using real prefix '/tools/python27' 05:47:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:38 INFO - Installing pip.................done. 05:47:38 INFO - Return code: 0 05:47:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:47:38 INFO - Reading from file tmpfile_stdout 05:47:38 INFO - Output received: 05:47:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:47:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:38 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:47:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:47:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:38 INFO - 'HOME': '/Users/cltbld', 05:47:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:38 INFO - 'LOGNAME': 'cltbld', 05:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:38 INFO - 'MOZ_NO_REMOTE': '1', 05:47:38 INFO - 'NO_EM_RESTART': '1', 05:47:38 INFO - 'PAGER': '/bin/cat', 05:47:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:38 INFO - 'PWD': '/builds/slave/test', 05:47:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:38 INFO - 'SHELL': '/bin/bash', 05:47:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:38 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:38 INFO - 'USER': 'cltbld', 05:47:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:38 INFO - 'XPC_FLAGS': '0x0', 05:47:38 INFO - 'XPC_SERVICE_NAME': '0', 05:47:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:47:39 INFO - Cleaning up... 05:47:39 INFO - Return code: 0 05:47:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:39 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:39 INFO - 'HOME': '/Users/cltbld', 05:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:39 INFO - 'LOGNAME': 'cltbld', 05:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:39 INFO - 'MOZ_NO_REMOTE': '1', 05:47:39 INFO - 'NO_EM_RESTART': '1', 05:47:39 INFO - 'PAGER': '/bin/cat', 05:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:39 INFO - 'PWD': '/builds/slave/test', 05:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:39 INFO - 'SHELL': '/bin/bash', 05:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:39 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:39 INFO - 'USER': 'cltbld', 05:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:39 INFO - 'XPC_FLAGS': '0x0', 05:47:39 INFO - 'XPC_SERVICE_NAME': '0', 05:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:39 INFO - Downloading/unpacking psutil>=3.1.1 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:42 INFO - Installing collected packages: psutil 05:47:42 INFO - Running setup.py install for psutil 05:47:42 INFO - building 'psutil._psutil_osx' extension 05:47:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:47:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:47:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:47:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:47:43 INFO - building 'psutil._psutil_posix' extension 05:47:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:47:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:47:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:47:43 INFO - ^ 05:47:43 INFO - 1 warning generated. 05:47:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:47:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:43 INFO - Successfully installed psutil 05:47:43 INFO - Cleaning up... 05:47:43 INFO - Return code: 0 05:47:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:43 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:43 INFO - 'HOME': '/Users/cltbld', 05:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:43 INFO - 'LOGNAME': 'cltbld', 05:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:43 INFO - 'MOZ_NO_REMOTE': '1', 05:47:43 INFO - 'NO_EM_RESTART': '1', 05:47:43 INFO - 'PAGER': '/bin/cat', 05:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:43 INFO - 'PWD': '/builds/slave/test', 05:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:43 INFO - 'SHELL': '/bin/bash', 05:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:43 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:43 INFO - 'USER': 'cltbld', 05:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:43 INFO - 'XPC_FLAGS': '0x0', 05:47:43 INFO - 'XPC_SERVICE_NAME': '0', 05:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:47:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:47:46 INFO - Installing collected packages: mozsystemmonitor 05:47:46 INFO - Running setup.py install for mozsystemmonitor 05:47:46 INFO - Successfully installed mozsystemmonitor 05:47:46 INFO - Cleaning up... 05:47:46 INFO - Return code: 0 05:47:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:46 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:46 INFO - 'HOME': '/Users/cltbld', 05:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:46 INFO - 'LOGNAME': 'cltbld', 05:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:46 INFO - 'MOZ_NO_REMOTE': '1', 05:47:46 INFO - 'NO_EM_RESTART': '1', 05:47:46 INFO - 'PAGER': '/bin/cat', 05:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:46 INFO - 'PWD': '/builds/slave/test', 05:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:46 INFO - 'SHELL': '/bin/bash', 05:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:46 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:46 INFO - 'USER': 'cltbld', 05:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:46 INFO - 'XPC_FLAGS': '0x0', 05:47:46 INFO - 'XPC_SERVICE_NAME': '0', 05:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:46 INFO - Downloading/unpacking jsonschema==2.5.1 05:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:47:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:49 INFO - Downloading functools32-3.2.3-2.tar.gz 05:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:47:49 INFO - warning: no files found matching '*.txt' 05:47:49 INFO - no previously-included directories found matching 'build' 05:47:49 INFO - no previously-included directories found matching 'dist' 05:47:49 INFO - no previously-included directories found matching '.git*' 05:47:49 INFO - Installing collected packages: jsonschema, functools32 05:47:50 INFO - Running setup.py install for functools32 05:47:50 INFO - warning: no files found matching '*.txt' 05:47:50 INFO - no previously-included directories found matching 'build' 05:47:50 INFO - no previously-included directories found matching 'dist' 05:47:50 INFO - no previously-included directories found matching '.git*' 05:47:50 INFO - Successfully installed jsonschema functools32 05:47:50 INFO - Cleaning up... 05:47:50 INFO - Return code: 0 05:47:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:50 INFO - 'HOME': '/Users/cltbld', 05:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:50 INFO - 'LOGNAME': 'cltbld', 05:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:50 INFO - 'MOZ_NO_REMOTE': '1', 05:47:50 INFO - 'NO_EM_RESTART': '1', 05:47:50 INFO - 'PAGER': '/bin/cat', 05:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:50 INFO - 'PWD': '/builds/slave/test', 05:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:50 INFO - 'SHELL': '/bin/bash', 05:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:50 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:50 INFO - 'USER': 'cltbld', 05:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:50 INFO - 'XPC_FLAGS': '0x0', 05:47:50 INFO - 'XPC_SERVICE_NAME': '0', 05:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:47:50 INFO - Cleaning up... 05:47:50 INFO - Return code: 0 05:47:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:50 INFO - 'HOME': '/Users/cltbld', 05:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:50 INFO - 'LOGNAME': 'cltbld', 05:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:50 INFO - 'MOZ_NO_REMOTE': '1', 05:47:50 INFO - 'NO_EM_RESTART': '1', 05:47:50 INFO - 'PAGER': '/bin/cat', 05:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:50 INFO - 'PWD': '/builds/slave/test', 05:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:50 INFO - 'SHELL': '/bin/bash', 05:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:50 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:50 INFO - 'USER': 'cltbld', 05:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:50 INFO - 'XPC_FLAGS': '0x0', 05:47:50 INFO - 'XPC_SERVICE_NAME': '0', 05:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:50 INFO - Downloading/unpacking blobuploader==1.2.4 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:53 INFO - Downloading blobuploader-1.2.4.tar.gz 05:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:47:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:54 INFO - Downloading docopt-0.6.1.tar.gz 05:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:47:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:47:54 INFO - Running setup.py install for blobuploader 05:47:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:47:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:47:54 INFO - Running setup.py install for requests 05:47:55 INFO - Running setup.py install for docopt 05:47:55 INFO - Successfully installed blobuploader requests docopt 05:47:55 INFO - Cleaning up... 05:47:55 INFO - Return code: 0 05:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:55 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:55 INFO - 'HOME': '/Users/cltbld', 05:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:55 INFO - 'LOGNAME': 'cltbld', 05:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:55 INFO - 'MOZ_NO_REMOTE': '1', 05:47:55 INFO - 'NO_EM_RESTART': '1', 05:47:55 INFO - 'PAGER': '/bin/cat', 05:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:55 INFO - 'PWD': '/builds/slave/test', 05:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:55 INFO - 'SHELL': '/bin/bash', 05:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:47:55 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:55 INFO - 'USER': 'cltbld', 05:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:55 INFO - 'XPC_FLAGS': '0x0', 05:47:55 INFO - 'XPC_SERVICE_NAME': '0', 05:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:55 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-4mXkBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:55 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-mmLZ8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:55 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-nSdRcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-4k2NfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-iosJwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Y6Dp5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Tc1y4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-PgwaO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Tn3Mxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-son3bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-1Rmpos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:56 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-RcvsqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-f_jIv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-LjtFq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-KPFBCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-O4_cKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-WAWCge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:47:57 INFO - Running setup.py install for manifestparser 05:47:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:47:57 INFO - Running setup.py install for mozcrash 05:47:57 INFO - Running setup.py install for mozdebug 05:47:57 INFO - Running setup.py install for mozdevice 05:47:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Running setup.py install for mozfile 05:47:58 INFO - Running setup.py install for mozhttpd 05:47:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Running setup.py install for mozinfo 05:47:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Running setup.py install for mozInstall 05:47:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:47:58 INFO - Running setup.py install for mozleak 05:47:58 INFO - Running setup.py install for mozlog 05:47:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for moznetwork 05:47:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozprocess 05:47:59 INFO - Running setup.py install for mozprofile 05:47:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozrunner 05:47:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:47:59 INFO - Running setup.py install for mozscreenshot 05:47:59 INFO - Running setup.py install for moztest 05:47:59 INFO - Running setup.py install for mozversion 05:48:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:00 INFO - Cleaning up... 05:48:00 INFO - Return code: 0 05:48:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:00 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:48:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:48:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:00 INFO - 'HOME': '/Users/cltbld', 05:48:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:00 INFO - 'LOGNAME': 'cltbld', 05:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:00 INFO - 'MOZ_NO_REMOTE': '1', 05:48:00 INFO - 'NO_EM_RESTART': '1', 05:48:00 INFO - 'PAGER': '/bin/cat', 05:48:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:00 INFO - 'PWD': '/builds/slave/test', 05:48:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:00 INFO - 'SHELL': '/bin/bash', 05:48:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:48:00 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:48:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:00 INFO - 'USER': 'cltbld', 05:48:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:00 INFO - 'XPC_FLAGS': '0x0', 05:48:00 INFO - 'XPC_SERVICE_NAME': '0', 05:48:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-b5AKPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-o630a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-_xV9JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-C_FDtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-xKmGTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-umTi3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-QIP6Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-TZdX0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-fTk_ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-CpoS9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-44bOza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-HvHe8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-0uu5Tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-GkckUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-kUAbL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-5yIwVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-keONF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:48:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48: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 05:48:04 INFO - Downloading blessings-1.6.tar.gz 05:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:04 INFO - Installing collected packages: blessings 05:48:04 INFO - Running setup.py install for blessings 05:48:05 INFO - Successfully installed blessings 05:48:05 INFO - Cleaning up... 05:48:05 INFO - Return code: 0 05:48:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:05 INFO - Reading from file tmpfile_stdout 05:48:05 INFO - Current package versions: 05:48:05 INFO - blessings == 1.6 05:48:05 INFO - blobuploader == 1.2.4 05:48:05 INFO - docopt == 0.6.1 05:48:05 INFO - functools32 == 3.2.3-2 05:48:05 INFO - jsonschema == 2.5.1 05:48:05 INFO - manifestparser == 1.1 05:48:05 INFO - mozInstall == 1.12 05:48:05 INFO - mozcrash == 1.0 05:48:05 INFO - mozdebug == 0.1 05:48:05 INFO - mozdevice == 0.48 05:48:05 INFO - mozfile == 1.2 05:48:05 INFO - mozhttpd == 0.7 05:48:05 INFO - mozinfo == 0.9 05:48:05 INFO - mozleak == 0.1 05:48:05 INFO - mozlog == 3.4 05:48:05 INFO - moznetwork == 0.27 05:48:05 INFO - mozprocess == 0.23 05:48:05 INFO - mozprofile == 0.28 05:48:05 INFO - mozrunner == 6.13 05:48:05 INFO - mozscreenshot == 0.1 05:48:05 INFO - mozsystemmonitor == 0.3 05:48:05 INFO - moztest == 0.7 05:48:05 INFO - mozversion == 1.4 05:48:05 INFO - psutil == 3.1.1 05:48:05 INFO - requests == 1.2.3 05:48:05 INFO - wsgiref == 0.1.2 05:48:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:05 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:05 INFO - 'HOME': '/Users/cltbld', 05:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:05 INFO - 'LOGNAME': 'cltbld', 05:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:05 INFO - 'MOZ_NO_REMOTE': '1', 05:48:05 INFO - 'NO_EM_RESTART': '1', 05:48:05 INFO - 'PAGER': '/bin/cat', 05:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:05 INFO - 'PWD': '/builds/slave/test', 05:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:05 INFO - 'SHELL': '/bin/bash', 05:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:48:05 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:05 INFO - 'USER': 'cltbld', 05:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:05 INFO - 'XPC_FLAGS': '0x0', 05:48:05 INFO - 'XPC_SERVICE_NAME': '0', 05:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:48:05 INFO - Cleaning up... 05:48:05 INFO - Return code: 0 05:48:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:06 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 0x107c41490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4d149bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, '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.J3qjx1N4y5/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.jOJYQIeX19/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:06 INFO - 'HOME': '/Users/cltbld', 05:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:06 INFO - 'LOGNAME': 'cltbld', 05:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:06 INFO - 'MOZ_NO_REMOTE': '1', 05:48:06 INFO - 'NO_EM_RESTART': '1', 05:48:06 INFO - 'PAGER': '/bin/cat', 05:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:06 INFO - 'PWD': '/builds/slave/test', 05:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:06 INFO - 'SHELL': '/bin/bash', 05:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:48:06 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:06 INFO - 'USER': 'cltbld', 05:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:06 INFO - 'XPC_FLAGS': '0x0', 05:48:06 INFO - 'XPC_SERVICE_NAME': '0', 05:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:48:06 INFO - Cleaning up... 05:48:06 INFO - Return code: 0 05:48:06 INFO - Running post-action listener: _resource_record_post_action 05:48:06 INFO - Running post-action listener: _start_resource_monitoring 05:48:06 INFO - Starting resource monitoring. 05:48:06 INFO - [mozharness: 2016-11-14 13:48:06.400184Z] Finished create-virtualenv step (success) 05:48:06 INFO - [mozharness: 2016-11-14 13:48:06.401350Z] Running install step. 05:48:06 INFO - Running pre-action listener: _resource_record_pre_action 05:48:06 INFO - Running main action method: install 05:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:06 INFO - Reading from file tmpfile_stdout 05:48:06 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:06 INFO - Reading from file tmpfile_stdout 05:48:06 INFO - Output received: 05:48:06 INFO - Usage: mozinstall [options] installer 05:48:06 INFO - Options: 05:48:06 INFO - -h, --help show this help message and exit 05:48:06 INFO - -d DEST, --destination=DEST 05:48:06 INFO - Directory to install application into. [default: 05:48:06 INFO - "/builds/slave/test"] 05:48:06 INFO - --app=APP Application being installed. [default: firefox] 05:48:06 INFO - mkdir: /builds/slave/test/build/application 05:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:48:40 INFO - Reading from file tmpfile_stdout 05:48:40 INFO - Output received: 05:48:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:48:40 INFO - Running post-action listener: _resource_record_post_action 05:48:40 INFO - [mozharness: 2016-11-14 13:48:40.517438Z] Finished install step (success) 05:48:40 INFO - [mozharness: 2016-11-14 13:48:40.517575Z] Running run-tests step. 05:48:40 INFO - Running pre-action listener: _resource_record_pre_action 05:48:40 INFO - Running main action method: run_tests 05:48:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:40 INFO - 2016-11-14 05:48:40.532 screenresolution[2654:19514] starting screenresolution argv=screenresolution get 05:48:40 INFO - 2016-11-14 05:48:40.534 screenresolution[2654:19514] Display 0: 1600x1200x32@60 05:48:40 INFO - 2016-11-14 05:48:40.540 screenresolution[2655:19517] starting screenresolution argv=screenresolution list 05:48:40 INFO - Available Modes on Display 0 05:48:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:40 INFO - Intel Iris: 05:48:40 INFO - Chipset Model: Intel Iris 05:48:40 INFO - Type: GPU 05:48:40 INFO - Bus: Built-In 05:48:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:40 INFO - Vendor: Intel (0x8086) 05:48:40 INFO - Device ID: 0x0a2e 05:48:40 INFO - Revision ID: 0x0009 05:48:40 INFO - Displays: 05:48:40 INFO - Display: 05:48:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:40 INFO - Display Serial Number: 1600x1200 60 05:48:40 INFO - Main Display: Yes 05:48:40 INFO - Mirror: Off 05:48:40 INFO - Online: Yes 05:48:40 INFO - Rotation: Supported 05:48:40 INFO - Return code: 0 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:40 INFO - Python 2.7.3 05:48:40 INFO - Return code: 0 05:48:40 INFO - grabbing minidump binary from tooltool 05:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:48:40 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 0x107a55d30>, '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 0x10787bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a4db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:48:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:48:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:48:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:40 INFO - Return code: 0 05:48:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:48:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:48:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0172 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3qjx1N4y5/Render', 05:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:40 INFO - 'HOME': '/Users/cltbld', 05:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:40 INFO - 'LOGNAME': 'cltbld', 05:48:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:40 INFO - 'MOZ_NO_REMOTE': '1', 05:48:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:40 INFO - 'NO_EM_RESTART': '1', 05:48:40 INFO - 'PAGER': '/bin/cat', 05:48:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:40 INFO - 'PWD': '/builds/slave/test', 05:48:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:40 INFO - 'SHELL': '/bin/bash', 05:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jOJYQIeX19/Listeners', 05:48:40 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 05:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:40 INFO - 'USER': 'cltbld', 05:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:40 INFO - 'XPC_FLAGS': '0x0', 05:48:40 INFO - 'XPC_SERVICE_NAME': '0', 05:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:48:41 INFO - mozversion application_buildid: 20161114030203 05:48:41 INFO - mozversion application_changeset: 1196bf3032e1bce1fb07a01fd9082a767426c5fb 05:48:41 INFO - mozversion application_display_name: Nightly 05:48:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:41 INFO - mozversion application_name: Firefox 05:48:41 INFO - mozversion application_remotingname: firefox 05:48:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:48:41 INFO - mozversion application_vendor: Mozilla 05:48:41 INFO - mozversion application_version: 52.0a1 05:48:41 INFO - mozversion platform_buildid: 20161114030203 05:48:41 INFO - mozversion platform_changeset: 1196bf3032e1bce1fb07a01fd9082a767426c5fb 05:48:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:48:41 INFO - mozversion platform_version: 52.0a1 05:48:41 INFO - using testdate: 1479131321 05:48:41 INFO - actual date: 1479131321 05:48:41 INFO - starting webserver on 'localhost:51636' 05:48:41 INFO - SUITE-START | Running 2 tests 05:48:41 INFO - TEST-START | dromaeo_css 05:48:41 INFO - operating with platform_type : mac_ 05:48:41 INFO - Initialising browser for dromaeo_css test... 05:48:41 INFO - TEST-INFO | started process 2665 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpMFfNJZ/profile http://localhost:51636/getInfo.html) 05:48:43 INFO - PROCESS | 2665 | __metrics Screen width/height:1600/1200 05:48:43 INFO - PROCESS | 2665 | colorDepth:24 05:48:43 INFO - PROCESS | 2665 | Browser inner width/height: 1024/689 05:48:43 INFO - PROCESS | 2665 | __metrics 05:48:43 INFO - PROCESS | 2665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:48:43 INFO - PROCESS | 2665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:48:44 INFO - TEST-INFO | 2665: exit 0 05:48:44 INFO - Browser initialized. 05:48:44 INFO - Running cycle 1/1 for dromaeo_css test... 05:48:44 INFO - TEST-INFO | started process 2668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpMFfNJZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:56 INFO - PROCESS | 2668 | RSS: Main: 229314560 05:48:56 INFO - PROCESS | 2668 | 05:49:48 INFO - PROCESS | 2668 | 1479131388705 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:52:00 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51636/tests/dromaeo/cssquery-ext.html) 05:52:02 INFO - PROCESS | 2668 | RSS: Main: 403943424 05:52:02 INFO - PROCESS | 2668 | 05:55:05 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-ext.html (next: http://localhost:51636/tests/dromaeo/cssquery-jquery.html) 05:55:06 INFO - PROCESS | 2668 | RSS: Main: 371765248 05:55:06 INFO - PROCESS | 2668 | 05:58:08 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51636/tests/dromaeo/cssquery-mootools.html) 05:58:09 INFO - PROCESS | 2668 | RSS: Main: 355180544 05:58:09 INFO - PROCESS | 2668 | 06:01:11 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51636/tests/dromaeo/cssquery-prototype.html) 06:01:12 INFO - PROCESS | 2668 | RSS: Main: 343560192 06:01:12 INFO - PROCESS | 2668 | 06:04:15 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51636/tests/dromaeo/cssquery-yui.html) 06:04:16 INFO - PROCESS | 2668 | RSS: Main: 339243008 06:04:16 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | Cycle 1(1): loaded http://localhost:51636/tests/dromaeo/cssquery-yui.html (next: http://localhost:51636/tests/dromaeo/cssquery-dojo.html) 06:07:19 INFO - PROCESS | 2668 | RSS: Main: 431173632 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | __start_tp_report 06:07:19 INFO - PROCESS | 2668 | _x_x_mozilla_page_load 06:07:19 INFO - PROCESS | 2668 | _x_x_mozilla_page_load_details 06:07:19 INFO - PROCESS | 2668 | |i|pagename|runs| 06:07:19 INFO - PROCESS | 2668 | |0;dojo.html;3245.091374415163;3275.312184441266;3277;3301.042697617691;3343.9665603343974;5510.531604813596;5569.6658200508;5629.634073785206;5631.042722737135;5660.3490598581175;4634.606058485025;5595.916061259074;5678;5686.2039314496005;5714.371419143891;4245.766482843443;5012.9749351253195;5028.472010438888;5049.116404629193;5078.085944529983;5121.1038068338075;5221.373435187783;5228.398734145584;5235.528802407802;5290.470952904698;5051.191809310492;5167.173252279636;5197.246399272084;5205.869853253699;5278.049951008817;5089.6691715038405;5110.923336149961;5140.640155189139;5145.871353216199;5156.948430515685;5040.621953353513;5104.719240441774;5133.666311689765;5135.486451354872;5170.819021334273;5599.552035837123;5617.297837770278;5651.717414129318;5655.066913959192;5681.659100453945;6004.5796794224425;6054.334023257438;6054.666993315366;6073.817785466443;6113.052476866093;6154.815355539341;6161.4146656067605;6199.721012554446;6207.131001659771;6229.127922090911;5717.828465146053;5723.141528770718;5723.399043100456;5736.6558006519745;5780.421957804228;6154.292256390483;6157.384261573897;6226.968865155736;6227.657478838707;6254.655993920287;5969.582129250997;6027.276726792813;6030.6080104793045;6097.115918191837;6118.4668989547035;6200.503959683215;6203.906941395882;6207.720652570645;6211.81364559064;6212.4408803207025;6070.575059745775;6106.511479081662;6131;6135.630783975157;6147.44672979434;5997.400259974055;6043.788467403708;6047.667378294148;6121.449069583669;6124.418180272867;4807.990322032342;4838.879028024257;4858.028394321079;4861.805527778849;4862.95137048625;4297.892552686208;4301.591348821857;4426.667999900021;4435.977820110878;4436.556344365602;5614.045612245928;5641.012822756002;5642.815008848188;5645.068563687028;5648.63283886546;6094.199305899851;6103.246756470893;6115.94015414234;6127.525510585588;6136.641621527207;5595.552355811526;5643.576731745054;5646.24524691084;5653.575981801381;5668.773249070074;5283.973580132151;5290.365156181206;5306.336707911511;5309.150535914235;5348.893022139613;6256.624602523863;6267.6866156691985;6267.780627678009;6270.9372906270355;6274.843128921813;5968.313643931004;6023.45788879003;6113.938860611337;6214.9067763982675;6217.191765070512;6165.691715414122;6170.043643235305;6180.443760061616;6219.129321895029;6260.593061450991;6110.801781737137;6113.5675242835405;6120.386183820725;6120.416658368064;6140.412224875342;6190.2571691397325;6210.875782484415;6211.378862113933;6213.440790328892;6218.81343559694;5794.971025145016;5850.765969361178;5874.089516125007;5933.762649493972;5980.611260268069;4354.56454354562;5773.653580785166;5924.081767326072;5990.550708696778;6044.15381846533;5761.596688231783;5802.912956305574;5803.274590676166;5815.94184058154;5819.5635327351465;5794.985877471341;5961.4038596140035;5978.611390259619;5992.340842507234;5994.220751302303;5721.8569535761935;5745.597808153556;5751.626144300607;5779.757352169304;5782.045962416322 06:07:19 INFO - PROCESS | 2668 | |1;ext.html;484.9321095046693;487.3250547991034;507.6979197377561;525.5690333926179;544.4201924949931;30486.847565762197;39074.41388379173;40588.18823623533;40711.38932916001;40750.79624601873;41262;41770.582294177046;41772.37341439873;41840.37239441403;41951.853610979146;9316.627334906596;9512.904870951272;9518.571664275125;9518.666846660368;9526.523673816317;9325.067493250655;9425.670101546448;9431.905680943206;9433;9434.009429009959;9399.81200375992;9420.434773913586;9438.952805235964;9439.292053096045;9451.243900487978;9365.95317023414;9405.037413214815;9409.529523523865;9417.62329506819;9420.152186303265;9280.814383712355;9316.580753866025;9359.29805264601;9361.531923403803;9374.29692773038;32112.839435802904;38234;38623.03442413934;38697.03257418559;38826.61173388258;3278.622958359795;3284.704376606093;3335.216224187314;3366.4108780963234;3372.3255348930065;29143.979960701487;29569.96505122332;30085.699143008507;30663.38673226523;30707.539386909215;3125.1718294651937;3163.604549431321;3177.793443426193;3193.2815116598813;3277.8688852445875;483539;485526;485588;486204;487094.12905870844;55504.44495555074;55671;55683.443165568635;55809;55858.44141558532;33963.15092122717;34801.30397392038;60020;60131;60269.397306027255;15167.544973650809;15606;15682.921585392;15736.449224277096;15737.055776653437;11892.286462812086;11949.462274197682;11977;11980.820287695691;11992.100592455601;18612.255509779727;18613.906930465262;18644.110162924768;18669.786463879806;18693.532661683566;6643.468522518187;7643;7735.6905723770415;7743.051043967617;7832.412569057344;856.4433118473042;976.7558110472382;1008.3798463944768;1053.1890444357803;1071.3786004117594;28004.43991120166;29449.969251076538;29645.555316670267;30108.54837177444;30159;963.3882484622176;1044.9738756531146;1051.222095649214;1056.1761825775907;1085.56577369053;14759.557213283619;15499.5350139496;15711.764323535157;15958.521244362688;16373.672526549402;898437;919124;920317.9602549681;923987;925036;54431.9176061797;54811.72594137004;54931;55098.724506378014;55113.44886551083;1193.20651766576;1222.1261797814427;1322.2198902647485;1349.0826238157952;1373.3957058894055;27761.444771104867;29595.556066658588;29641.407171856874;30203.697963020968;30237.48812559407;48231.27653085277;48835.511644883096;48884.75557622189;48901.5109848897;49057;561643.5753463783;568909;569169;571089;571285;23922.760772392052;24363.26910192697;25587.872060639576;25790;26176;43293.567064328956;44210.336844948;45357.86605334893;45442;45660;33663.653453861574;35904.82047589745;35976.100597485274;36870.63129368672;37850.62149378471;36465.81767091148;36469.45295820618;37341.626583733814;37591.060223494635;37924.43113353247;35542.822285888404;36036;36789;36859.447108293934;37163;31206.843965780936;32868.55178066305;33061.00816975495;33177.83411082929;33493.330133397685;24266;24539.631905521073;24852.183198032304;24912.626310604992;24976.625350620117 06:07:19 INFO - PROCESS | 2668 | |2;jquery.html;336.27700444045314;369.6709928163934;370.6867696796206;383.1379396358194;402.1253773043631;14877.107373557581;14914.477993270224;15961.68076638468;20385;20467.795322046775;19509;19587.902060489712;20145.899270503665;20156.294529691462;20343.59312813743;5551.72241387931;5799.73901174446;5858.648481091127;5861.974154522963;5864.648121112726;3588.856445742167;6346.30190679025;6427.453666438335;6495.188101487314;6522.249941256745;5720.427957204288;6149.2313460817395;6348;6443.0657554654545;6445.4199122079235;6310.211223597051;6319.999999999977;6356.364363563653;6360.7137678804565;6391.744330226832;4779.904401911942;5277.683338999634;5461.262729531502;5522.779088836442;5533.391326954032;20702.58594828095;20758;22191.334259972227;25081;25459.745402545923;2383.07060246504;2476.863772492512;2800.2579316481147;3008.8944351362575;3130.89041883533;11828.881711182865;14789.482368117171;15557.066576005363;15751.212439377985;15892.966957147864;1572.1588949911857;1755.2978808476585;1774.299151835018;1789.579448829524;1859.1540848913712;115151;117260.41369793181;119095.4045229777;120659;120822.99999999913;9650.903490965;10375.84436233457;10548.630797922036;10661.893381066246;10699.572017119384;13875.791863122062;13882.791758123636;13892.23592702411;13913;13917.652058698613;6551.901721474182;8025.759227223192;8296.751097467086;8323;8431.40980131385;7374.373178279799;7738.761798112275;8154.266116049584;8167.060788009336;8334.333253339719;5581.860453488695;6499.220093588705;6609.900851487231;6622.139121914121;6646.933530664728;1804.9819501804814;1849.7317888906302;1850.0657168130276;1869.6541139889164;1880.6238752249606;684.4011489946297;822.1737154160031;863.935204859638;871.7515508080294;996.0437979539578;20706.68939965902;21775.128994840346;21793.891030545063;21807.563848723254;21875.562488750136;780.4029917113413;893.3433926064338;909.2044461096541;942.688912658821;1013.7465633591602;9065.864012039825;11300.547978080785;11594;11638.53445862156;11698.649040528799;465131;471210;472087;521056;586979;116899.2465113024;120527;125106;125794.63016977077;137476;1001.3140998416109;1004.3271008424373;1009.6668099527138;1011.1001208923916;1039.6984874386344;18626.720599191292;18944.905275473535;19114.23543058262;19222.903885480482;19299.131539080496;37659;46867;50608.240876386146;52735;52765.73617132043;75994;77280.61359693165;78783;81391;81664;7641.197674244347;7688.269614386624;7831.355415362837;8018.799530011796;8093.959530202312;9113.544322783702;12236.75526489483;12626.179298345578;13210.867891321348;13341.599752007456;10884;11159.27464714791;11326.093912487146;11340.09279257674;11520;10933.56265749361;10984.231103822656;11212.775744485229;11453.770924581295;11518;11053.502592383202;11146.275492092987;11301.943490282496;11498.25261358009;11588.536458541565;7701.576413297323;7878.015248093989;7963.40274479428;7992.40056995716;8074.596270186585;8638.913610864063;10837.837432438362;10965.671029869116;11116.44417779124;11226.550937962716 06:07:19 INFO - PROCESS | 2668 | |3;mootools.html;1325.1452812935663;1466.523379901532;1479.6522817137975;1647.36576418079;1837.7243413487975;7362.263773622635;7764.456488045832;8038.075621303552;8301.792455188623;8312.958435207831;8154.429189956705;8238.17618238176;8241.381896357781;8253.91746082539;8257.110011495974;1985.8609897307194;2256.289268880306;2278.1229226747737;2289.6794448777187;2292.002978704269;2252.3805953362876;2291.5302363015626;2299.2642354446584;2301.1600765720477;2303.3665741921022;2235.2065016919005;2238.55228954209;2265.8753768542724;2267.1384873748034;2269.2624896908487;1651.28169246377;2115.23851413491;2173.3805865328304;2177.052981952848;2177.314146044007;2271.716035495563;2299.4136495193784;2302.3668491164894;2303.285981345788;2305.319930620471;6940.618265995368;8266.13205613408;8338.499690018558;8369.539675317856;8370;4993.700377977333;5172.741362931876;5173.534381905646;5179.326687530634;5182.4299327073995;3173.714365707074;3175.634801997777;3190.4735718606403;3267.134209434502;3272.590926134233;896.8968568614628;900.1538553759445;906.8277027364779;963.0802583532709;971.1842052675715;10797.784044319069;10840.78318433627;10863.348199108079;10865.728356791064;10874.130069594414;4117.238310912491;4347.695661303678;4369.912601748011;4394.824207031683;4399.340098985178;8654.35092368075;8658.393912426196;8667.653293868301;8668.783280417914;8721.171488708562;5026.346574945307;5031.496850314939;5034.723090230073;5037.4458809530925;5040.294358789773;3959.5842436544326;4195.559466255999;4398.164348773723;4602.309653551994;4717.976410117996;3528.6471352865024;3545.539079919594;3633.1462106404974;3647.9817600911824;3653.214558869856;1105.7788442311569;1109.3288560420829;1118.82658187981;1133.7789131119519;1144.0961640304067;815;833.691534132375;850.7706364303646;886.2865393358409;890.3366991591306;4969.676970996874;5183.196604526229;5201.1678131498775;5390.784368625211;5411.594130440154;388.4134956216134;409.4860949508348;420.997895010523;432.2245188280368;440.4626355845863;3462.5498685170764;3470.479428085757;3569.7679650822615;3584.9641503585153;3629.564452265745;9634.373765705206;9930.354526955725;10065.295429320024;10069.69790906274;10091.848622270672;8536.8719469208;9912.603495860229;9967.003299669976;10018.048285412875;10019.749506262257;7656.583532046478;7662.501937374052;7713.8457230855065;7752.108507521707;7861.882071768928;4568.383268258875;5123.5910124716;5294.100002999577;5307.097793375213;5320.600954928318;9084.591193396445;9143.314251431037;9288.256939444693;9395.43627382332;9485.857712134462;7258;9393.530323483661;9439.764005899908;9472;9563.282753793354;6574.27682954865;6668.06647069401;6690.933090669226;6795.966020169867;7063.6821343038655;7830.491018083806;7932.682692692459;7939.642716077675;7945.20547945224;7957.7612671619945;6084.847878803065;6740.056392105207;7030.543014704028;7135.108111486064;7269.854602908018;6003.82925329574;6347;6698.196216454056;6975.581465112109;7048.929510704827;6340.4293613575;6356.77751278703;6367.87264254703;6421.935780642134;6443.871122577616;4578.6108180805;4588.816447342069;4634.582887540138;4652.069586082729;4760.571548560646;3616.6383361663625;4633.559811817872;4688.812447502061;4697.694649847749;4786.856394308176 06:07:19 INFO - PROCESS | 2668 | |4;prototype.html;297.8361900954475;307.58476057322616;307.9399517094167;317.0678206074142;318.14419212318876;4263.827447451952;4563.498015218323;4767.903382222086;5140.820071297505;5156.974215128928;4294.754521188859;4542.568455996675;5166.689998600086;5197.8440646780655;5295.537575027612;3257.96742032579;3581.982090089559;3732.2908647357053;3855.903602409934;3912.9043867717087;3409.9147521311916;3953.4500247340447;3986.3422535281643;4006.73956192848;4041.0301527633305;3509.31568344173;3775.828898383427;3911.882643520699;3927.8232479538487;3961.663258623006;2765.9308517287027;3758.586555478895;3972.543157536891;3989.9202015959663;4003.619656132663;2893.594896714462;3469.8265086745814;3472.461768425898;3479.7216222702377;3503.4744788281705;5064.215046667736;5074.8581569146645;5078.720670363128;5089.338386009795;5089.796408143669;3790.9620903790883;4092.4679791627195;4490.57339552742;4516.1419330327135;4531.297648864398;4213.325867861127;5255.9750848584545;5374.48942350476;5375.6774593524515;5406.621536492447;3140.733037691799;3407.5910890693276;3542.2030043240325;3719.7210209234418;3728.8508459661853;5377.316538171287;5380.354357477088;5553.611247212697;5616.354119276295;5641.323041235037;2899.1157696902224;3726.720495962814;3751.649406213761;3751.955184424495;3766.3785475396253;3112.081935828936;3468.531748213959;3559.288142371536;3664.358737220976;3804.8668296609485;4104.774237416911;4426.911461770746;4430.9808743988415;4434.534373890695;4439.023414848728;5155.716435596003;5393.83818485446;5410.404855465896;5431.619786614899;5443.945560544423;1087.3367245979946;1109.2567979453625;1199.5381778015353;1236.9010479161645;1249.2572860859232;730.747891977267;801.5511313664366;827.3753316246316;843.7890527368158;853.3855623950745;4309.612134907873;4951.653384263139;4988.55103040728;4993.50064993505;5146.948530514722;4623.42207224097;5352.849137435393;5521.3926468088475;5549.38956714761;5549.861253468695;4064.837406503766;4243.321068629005;4633.397658304466;4887.144749668793;5055.87360315995;2472.5302192583354;2525.2676723749905;2568.9595713735966;2745.6018877263086;2753.8898444062015;4418.513963464016;4958.545519967639;5268.762905669254;5327.3074500314715;5390.978383106335;5082.822101226439;5364.6512976656395;5364.834747498994;5368.570514358842;5400.271912987885;4639.420072490939;5167.715775632376;5379.973100134474;5389.571763482681;5426.414679595814;4254.766247719972;4462.866114016585;5216.921746173734;5221.686698798084;5246.685198888079;4847.975760121177;4863.7324947128245;5136.15098564509;5180.6891586504935;5191.870203244949;4429.886756276318;4667.509911459248;4792.712437253636;5402.515911793281;5447.428020057995;3318.9170270743425;3507.4212754895157;3518.7978939165237;3531.9625122394204;3533.5583052118486;4273.487181538236;4479.9328010079225;4919.631027672867;4943.77753001123;5050.747462626928;4159.646430053479;4764.737344603614;5002.849914502571;5101.92347114801;5158.498128407644;4362.7818609070055;4861.683990540603;5054.620903432184;5057.342545469065;5063.417706963599;4148.233666578694;4747.525247475225;4971.384300102409;4985.6011519077665;5000.849974500771;3841.4250157435317;4493.191225579297;4781.900162962607;4912.582430493448;5289.894202116013;4668.696534725232;5132.948670513247;5172.267290457738;5249.4251724483265;5276.129438642734 06:07:19 INFO - PROCESS | 2668 | |5;yui.html;685.448214187579;718.5006420537725;753.9886901696475;756.8032311598982;774.8411575626995;13843.792343114861;14584.197869117193;14596.635084122901;14626.48807291745;14641.926790366046;12988.155769874975;13129.67175820605;13137.67155821105;13140.080194386397;13145.211287322767;647.3267801486448;667.366002297819;668.4952860590272;795.4710117771675;814.3566582399897;818.8853560501534;844.7507985144397;844.9227235275013;853.9701110461135;854.5556310718423;887.2103827593447;894.463322006794;894.8031433084743;895.8924929008529;896.0054339682947;692.5809885019592;890.5591732092655;890.9955450222773;891.0332289465937;891.7948871759531;531.6145794299141;640.337250945274;659.7295109005332;661.1141070964931;662.4170729757831;13678.589642310746;13703.657408564866;13706.794398084037;13706.862931370759;13729;12760.425780839885;12932.224066555942;13005.40653045452;13038.217706937614;13059.412326445332;11829.822552661717;11894.286342819372;11960.401979901057;11962.521499140112;12285.69285767863;4310.396544483775;4329.978350108229;4406.889827754299;4510.774461276956;4527.139843429738;74810.87783683195;77260;85179.57410212971;86927;89309;10395.792084158275;10429.536703639078;10500.684979450629;10511.894881051245;10701.143908487458;19415.61168776645;19417.417477475698;19423.12595933158;19426.611467770847;19436.70844937327;7308.963455182797;7569.318761311509;7624.237576242443;7695.846083078307;7705.537667739954;3728.832202550892;4012.9799351003057;4183.184279065614;4293.3130699088;4321.222180007629;3228.257500774788;3379.543761592203;3381.1716129548417;3385.23832137772;3385.23832137772;556.5519756595899;625.2684359299631;628.1864984844638;638.658317799984;644.336333576417;555.7276934302243;584.7076461769116;651.4560491634761;661.6658587413325;683.2381894187945;8458.915410845935;8489.151084891584;8558;9065.954670226607;9185.586648600829;593.4569868570296;641.3842710997401;649.1118201970165;673.4208580620663;679.7688785812848;3766.227923275722;3812.733108682421;3981.203759248162;4013.91972160561;4392.560743925582;109340;110255;112928;114107;117366;64296.67851660805;73789;81564.59217703874;85452.57273713591;100451;77310.6134469324;79132;83149;84842.15157848466;84936.35572798477;9451.385659932082;9594.42433453995;9595.712128636153;9614.759631009001;9666.661666841626;50120.74939625279;51629.48370516247;53583;54625;55468.61328466821;69255.68931728225;76432;80715;81010.18989810262;82744;11540.711482213012;11885.16803823724;11910.880891190976;12098.213616114925;12309.384530773605;13674.589762307147;13736.93131534336;13737.175769453865;13937.442502299795;14433;12529;12835.93582032084;12871.420786064462;13027;13423.127496712683;12423.31671758062;12730.427130779322;12741.362931853555;12949.172872207357;13110.803337950128;12484.188527745668;12504.562340318045;12573.685657858627;12652.43064062101;12931.870681293067;8738.775632510105;9913.603455861685;10729.088027517748;10829.837552436877;11204.487394201678;9676;10607.94696026515;11270.436478176221;11276.830847537129;11354.54581816718 06:07:19 INFO - PROCESS | 2668 | __end_tp_report 06:07:19 INFO - PROCESS | 2668 | __start_cc_report 06:07:19 INFO - PROCESS | 2668 | _x_x_mozilla_cycle_collect,1719 06:07:19 INFO - PROCESS | 2668 | __end_cc_report 06:07:19 INFO - PROCESS | 2668 | __startTimestamp1479132439698__endTimestamp 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | ------- Summary: start ------- 06:07:19 INFO - PROCESS | 2668 | Number of tests: 6 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#0] dojo.html Cycles:165 Average:5636.07 Median:5782.05 stddev:647.01 (11.2%) stddev-sans-first:621.22 06:07:19 INFO - PROCESS | 2668 | Values: 3245.1 3275.3 3277.0 3301.0 3344.0 5510.5 5569.7 5629.6 5631.0 5660.3 4634.6 5595.9 5678.0 5686.2 5714.4 4245.8 5013.0 5028.5 5049.1 5078.1 5121.1 5221.4 5228.4 5235.5 5290.5 5051.2 5167.2 5197.2 5205.9 5278.0 5089.7 5110.9 5140.6 5145.9 5156.9 5040.6 5104.7 5133.7 5135.5 5170.8 5599.6 5617.3 5651.7 5655.1 5681.7 6004.6 6054.3 6054.7 6073.8 6113.1 6154.8 6161.4 6199.7 6207.1 6229.1 5717.8 5723.1 5723.4 5736.7 5780.4 6154.3 6157.4 6227.0 6227.7 6254.7 5969.6 6027.3 6030.6 6097.1 6118.5 6200.5 6203.9 6207.7 6211.8 6212.4 6070.6 6106.5 6131.0 6135.6 6147.4 5997.4 6043.8 6047.7 6121.4 6124.4 4808.0 4838.9 4858.0 4861.8 4863.0 4297.9 4301.6 4426.7 4436.0 4436.6 5614.0 5641.0 5642.8 5645.1 5648.6 6094.2 6103.2 6115.9 6127.5 6136.6 5595.6 5643.6 5646.2 5653.6 5668.8 5284.0 5290.4 5306.3 5309.2 5348.9 6256.6 6267.7 6267.8 6270.9 6274.8 5968.3 6023.5 6113.9 6214.9 6217.2 6165.7 6170.0 6180.4 6219.1 6260.6 6110.8 6113.6 6120.4 6120.4 6140.4 6190.3 6210.9 6211.4 6213.4 6218.8 5795.0 5850.8 5874.1 5933.8 5980.6 4354.6 5773.7 5924.1 5990.6 6044.2 5761.6 5802.9 5803.3 5815.9 5819.6 5795.0 5961.4 5978.6 5992.3 5994.2 5721.9 5745.6 5751.6 5779.8 5782.0 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#1] ext.html Cycles:180 Average:76411.97 Median:38079.22 stddev:184500.18 (484.5%) stddev-sans-first:184929.67 06:07:19 INFO - PROCESS | 2668 | Values: 484.9 487.3 507.7 525.6 544.4 30486.8 39074.4 40588.2 40711.4 40750.8 41262.0 41770.6 41772.4 41840.4 41951.9 9316.6 9512.9 9518.6 9518.7 9526.5 9325.1 9425.7 9431.9 9433.0 9434.0 9399.8 9420.4 9439.0 9439.3 9451.2 9366.0 9405.0 9409.5 9417.6 9420.2 9280.8 9316.6 9359.3 9361.5 9374.3 32112.8 38234.0 38623.0 38697.0 38826.6 3278.6 3284.7 3335.2 3366.4 3372.3 29144.0 29570.0 30085.7 30663.4 30707.5 3125.2 3163.6 3177.8 3193.3 3277.9 483539.0 485526.0 485588.0 486204.0 487094.1 55504.4 55671.0 55683.4 55809.0 55858.4 33963.2 34801.3 60020.0 60131.0 60269.4 15167.5 15606.0 15682.9 15736.4 15737.1 11892.3 11949.5 11977.0 11980.8 11992.1 18612.3 18613.9 18644.1 18669.8 18693.5 6643.5 7643.0 7735.7 7743.1 7832.4 856.4 976.8 1008.4 1053.2 1071.4 28004.4 29450.0 29645.6 30108.5 30159.0 963.4 1045.0 1051.2 1056.2 1085.6 14759.6 15499.5 15711.8 15958.5 16373.7 898437.0 919124.0 920318.0 923987.0 925036.0 54431.9 54811.7 54931.0 55098.7 55113.4 1193.2 1222.1 1322.2 1349.1 1373.4 27761.4 29595.6 29641.4 30203.7 30237.5 48231.3 48835.5 48884.8 48901.5 49057.0 561643.6 568909.0 569169.0 571089.0 571285.0 23922.8 24363.3 25587.9 25790.0 26176.0 43293.6 44210.3 45357.9 45442.0 45660.0 33663.7 35904.8 35976.1 36870.6 37850.6 36465.8 36469.5 37341.6 37591.1 37924.4 35542.8 36036.0 36789.0 36859.4 37163.0 31206.8 32868.6 33061.0 33177.8 33493.3 24266.0 24539.6 24852.2 24912.6 24976.6 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#2] jquery.html Cycles:180 Average:32183.17 Median:2429.97 stddev:85283.84 (3509.7%) stddev-sans-first:85489.56 06:07:19 INFO - PROCESS | 2668 | Values: 336.3 369.7 370.7 383.1 402.1 14877.1 14914.5 15961.7 20385.0 20467.8 19509.0 19587.9 20145.9 20156.3 20343.6 5551.7 5799.7 5858.6 5862.0 5864.6 3588.9 6346.3 6427.5 6495.2 6522.2 5720.4 6149.2 6348.0 6443.1 6445.4 6310.2 6320.0 6356.4 6360.7 6391.7 4779.9 5277.7 5461.3 5522.8 5533.4 20702.6 20758.0 22191.3 25081.0 25459.7 2383.1 2476.9 2800.3 3008.9 3130.9 11828.9 14789.5 15557.1 15751.2 15893.0 1572.2 1755.3 1774.3 1789.6 1859.2 115151.0 117260.4 119095.4 120659.0 120823.0 9650.9 10375.8 10548.6 10661.9 10699.6 13875.8 13882.8 13892.2 13913.0 13917.7 6551.9 8025.8 8296.8 8323.0 8431.4 7374.4 7738.8 8154.3 8167.1 8334.3 5581.9 6499.2 6609.9 6622.1 6646.9 1805.0 1849.7 1850.1 1869.7 1880.6 684.4 822.2 863.9 871.8 996.0 20706.7 21775.1 21793.9 21807.6 21875.6 780.4 893.3 909.2 942.7 1013.7 9065.9 11300.5 11594.0 11638.5 11698.6 465131.0 471210.0 472087.0 521056.0 586979.0 116899.2 120527.0 125106.0 125794.6 137476.0 1001.3 1004.3 1009.7 1011.1 1039.7 18626.7 18944.9 19114.2 19222.9 19299.1 37659.0 46867.0 50608.2 52735.0 52765.7 75994.0 77280.6 78783.0 81391.0 81664.0 7641.2 7688.3 7831.4 8018.8 8094.0 9113.5 12236.8 12626.2 13210.9 13341.6 10884.0 11159.3 11326.1 11340.1 11520.0 10933.6 10984.2 11212.8 11453.8 11518.0 11053.5 11146.3 11301.9 11498.3 11588.5 7701.6 7878.0 7963.4 7992.4 8074.6 8638.9 10837.8 10965.7 11116.4 11226.6 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#3] mootools.html Cycles:180 Average:5241.48 Median:5036.08 stddev:2959.55 (58.8%) stddev-sans-first:2953.21 06:07:19 INFO - PROCESS | 2668 | Values: 1325.1 1466.5 1479.7 1647.4 1837.7 7362.3 7764.5 8038.1 8301.8 8313.0 8154.4 8238.2 8241.4 8253.9 8257.1 1985.9 2256.3 2278.1 2289.7 2292.0 2252.4 2291.5 2299.3 2301.2 2303.4 2235.2 2238.6 2265.9 2267.1 2269.3 1651.3 2115.2 2173.4 2177.1 2177.3 2271.7 2299.4 2302.4 2303.3 2305.3 6940.6 8266.1 8338.5 8369.5 8370.0 4993.7 5172.7 5173.5 5179.3 5182.4 3173.7 3175.6 3190.5 3267.1 3272.6 896.9 900.2 906.8 963.1 971.2 10797.8 10840.8 10863.3 10865.7 10874.1 4117.2 4347.7 4369.9 4394.8 4399.3 8654.4 8658.4 8667.7 8668.8 8721.2 5026.3 5031.5 5034.7 5037.4 5040.3 3959.6 4195.6 4398.2 4602.3 4718.0 3528.6 3545.5 3633.1 3648.0 3653.2 1105.8 1109.3 1118.8 1133.8 1144.1 815.0 833.7 850.8 886.3 890.3 4969.7 5183.2 5201.2 5390.8 5411.6 388.4 409.5 421.0 432.2 440.5 3462.5 3470.5 3569.8 3585.0 3629.6 9634.4 9930.4 10065.3 10069.7 10091.8 8536.9 9912.6 9967.0 10018.0 10019.7 7656.6 7662.5 7713.8 7752.1 7861.9 4568.4 5123.6 5294.1 5307.1 5320.6 9084.6 9143.3 9288.3 9395.4 9485.9 7258.0 9393.5 9439.8 9472.0 9563.3 6574.3 6668.1 6690.9 6796.0 7063.7 7830.5 7932.7 7939.6 7945.2 7957.8 6084.8 6740.1 7030.5 7135.1 7269.9 6003.8 6347.0 6698.2 6975.6 7048.9 6340.4 6356.8 6367.9 6421.9 6443.9 4578.6 4588.8 4634.6 4652.1 4760.6 3616.6 4633.6 4688.8 4697.7 4786.9 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#4] prototype.html Cycles:180 Average:4196.28 Median:4636.41 stddev:1286.00 (27.7%) stddev-sans-first:1255.87 06:07:19 INFO - PROCESS | 2668 | Values: 297.8 307.6 307.9 317.1 318.1 4263.8 4563.5 4767.9 5140.8 5157.0 4294.8 4542.6 5166.7 5197.8 5295.5 3258.0 3582.0 3732.3 3855.9 3912.9 3409.9 3953.5 3986.3 4006.7 4041.0 3509.3 3775.8 3911.9 3927.8 3961.7 2765.9 3758.6 3972.5 3989.9 4003.6 2893.6 3469.8 3472.5 3479.7 3503.5 5064.2 5074.9 5078.7 5089.3 5089.8 3791.0 4092.5 4490.6 4516.1 4531.3 4213.3 5256.0 5374.5 5375.7 5406.6 3140.7 3407.6 3542.2 3719.7 3728.9 5377.3 5380.4 5553.6 5616.4 5641.3 2899.1 3726.7 3751.6 3752.0 3766.4 3112.1 3468.5 3559.3 3664.4 3804.9 4104.8 4426.9 4431.0 4434.5 4439.0 5155.7 5393.8 5410.4 5431.6 5443.9 1087.3 1109.3 1199.5 1236.9 1249.3 730.7 801.6 827.4 843.8 853.4 4309.6 4951.7 4988.6 4993.5 5146.9 4623.4 5352.8 5521.4 5549.4 5549.9 4064.8 4243.3 4633.4 4887.1 5055.9 2472.5 2525.3 2569.0 2745.6 2753.9 4418.5 4958.5 5268.8 5327.3 5391.0 5082.8 5364.7 5364.8 5368.6 5400.3 4639.4 5167.7 5380.0 5389.6 5426.4 4254.8 4462.9 5216.9 5221.7 5246.7 4848.0 4863.7 5136.2 5180.7 5191.9 4429.9 4667.5 4792.7 5402.5 5447.4 3318.9 3507.4 3518.8 3532.0 3533.6 4273.5 4479.9 4919.6 4943.8 5050.7 4159.6 4764.7 5002.8 5101.9 5158.5 4362.8 4861.7 5054.6 5057.3 5063.4 4148.2 4747.5 4971.4 4985.6 5000.8 3841.4 4493.2 4781.9 4912.6 5289.9 4668.7 5132.9 5172.3 5249.4 5276.1 06:07:19 INFO - PROCESS | 2668 | 06:07:19 INFO - PROCESS | 2668 | [#5] yui.html Cycles:180 Average:19984.29 Median:4458.83 stddev:29097.92 (652.6%) stddev-sans-first:29143.47 06:07:19 INFO - PROCESS | 2668 | Values: 685.4 718.5 754.0 756.8 774.8 13843.8 14584.2 14596.6 14626.5 14641.9 12988.2 13129.7 13137.7 13140.1 13145.2 647.3 667.4 668.5 795.5 814.4 818.9 844.8 844.9 854.0 854.6 887.2 894.5 894.8 895.9 896.0 692.6 890.6 891.0 891.0 891.8 531.6 640.3 659.7 661.1 662.4 13678.6 13703.7 13706.8 13706.9 13729.0 12760.4 12932.2 13005.4 13038.2 13059.4 11829.8 11894.3 11960.4 11962.5 12285.7 4310.4 4330.0 4406.9 4510.8 4527.1 74810.9 77260.0 85179.6 86927.0 89309.0 10395.8 10429.5 10500.7 10511.9 10701.1 19415.6 19417.4 19423.1 19426.6 19436.7 7309.0 7569.3 7624.2 7695.8 7705.5 3728.8 4013.0 4183.2 4293.3 4321.2 3228.3 3379.5 3381.2 3385.2 3385.2 556.6 625.3 628.2 638.7 644.3 555.7 584.7 651.5 661.7 683.2 8458.9 8489.2 8558.0 9066.0 9185.6 593.5 641.4 649.1 673.4 679.8 3766.2 3812.7 3981.2 4013.9 4392.6 109340.0 110255.0 112928.0 114107.0 117366.0 64296.7 73789.0 81564.6 85452.6 100451.0 77310.6 79132.0 83149.0 84842.2 84936.4 9451.4 9594.4 9595.7 9614.8 9666.7 50120.7 51629.5 53583.0 54625.0 55468.6 69255.7 76432.0 80715.0 81010.2 82744.0 11540.7 11885.2 11910.9 12098.2 12309.4 13674.6 13736.9 13737.2 13937.4 14433.0 12529.0 12835.9 12871.4 13027.0 13423.1 12423.3 12730.4 12741.4 12949.2 13110.8 12484.2 12504.6 12573.7 12652.4 12931.9 8738.8 9913.6 10729.1 10829.8 11204.5 9676.0 10607.9 11270.4 11276.8 11354.5 06:07:19 INFO - PROCESS | 2668 | -------- Summary: end -------- 06:07:19 INFO - PROCESS | 2668 | 06:07:20 INFO - TEST-INFO | 2668: exit 0 06:07:20 INFO - TEST-OK | dromaeo_css | took 1119257ms 06:07:20 INFO - TEST-START | kraken 06:07:20 INFO - operating with platform_type : mac_ 06:07:20 INFO - Initialising browser for kraken test... 06:07:20 INFO - TEST-INFO | started process 2673 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpydavfQ/profile http://localhost:51636/getInfo.html) 06:07:22 INFO - PROCESS | 2673 | __metrics Screen width/height:1600/1200 06:07:22 INFO - PROCESS | 2673 | colorDepth:24 06:07:22 INFO - PROCESS | 2673 | Browser inner width/height: 1024/689 06:07:22 INFO - PROCESS | 2673 | __metrics 06:07:22 INFO - PROCESS | 2673 | 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" 06:07:22 INFO - PROCESS | 2673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:07:23 INFO - TEST-INFO | 2673: exit 0 06:07:23 INFO - Browser initialized. 06:07:23 INFO - Running cycle 1/1 for kraken test... 06:07:23 INFO - TEST-INFO | started process 2675 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpydavfQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:35 INFO - PROCESS | 2675 | RSS: Main: 237965312 06:07:35 INFO - PROCESS | 2675 | 06:07:38 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51636/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:39 INFO - PROCESS | 2675 | RSS: Main: 289214464 06:07:39 INFO - PROCESS | 2675 | 06:07:42 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51636/tests/kraken/driver.html?testName=audio-dft) 06:07:42 INFO - PROCESS | 2675 | RSS: Main: 289521664 06:07:42 INFO - PROCESS | 2675 | 06:07:46 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51636/tests/kraken/driver.html?testName=audio-fft) 06:07:46 INFO - PROCESS | 2675 | RSS: Main: 381554688 06:07:46 INFO - PROCESS | 2675 | 06:07:48 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51636/tests/kraken/driver.html?testName=audio-oscillator) 06:07:49 INFO - PROCESS | 2675 | RSS: Main: 284471296 06:07:49 INFO - PROCESS | 2675 | 06:07:51 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51636/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:07:52 INFO - PROCESS | 2675 | RSS: Main: 286457856 06:07:52 INFO - PROCESS | 2675 | 06:07:54 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51636/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:55 INFO - PROCESS | 2675 | RSS: Main: 329613312 06:07:55 INFO - PROCESS | 2675 | 06:07:58 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51636/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:59 INFO - PROCESS | 2675 | RSS: Main: 328507392 06:07:59 INFO - PROCESS | 2675 | 06:08:02 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51636/tests/kraken/driver.html?testName=json-parse-financial) 06:08:02 INFO - PROCESS | 2675 | RSS: Main: 326447104 06:08:02 INFO - PROCESS | 2675 | 06:08:04 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51636/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:04 INFO - PROCESS | 2675 | RSS: Main: 304230400 06:08:04 INFO - PROCESS | 2675 | 06:08:06 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:07 INFO - PROCESS | 2675 | RSS: Main: 307073024 06:08:07 INFO - PROCESS | 2675 | 06:08:09 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:10 INFO - PROCESS | 2675 | RSS: Main: 309305344 06:08:10 INFO - PROCESS | 2675 | 06:08:13 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:14 INFO - PROCESS | 2675 | RSS: Main: 319819776 06:08:14 INFO - PROCESS | 2675 | 06:08:17 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:17 INFO - PROCESS | 2675 | RSS: Main: 319569920 06:08:17 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | Cycle 1(1): loaded http://localhost:51636/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51636/tests/kraken/driver.html?testName=ai-astar) 06:08:19 INFO - PROCESS | 2675 | RSS: Main: 363372544 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | __start_tp_report 06:08:19 INFO - PROCESS | 2675 | _x_x_mozilla_page_load 06:08:19 INFO - PROCESS | 2675 | _x_x_mozilla_page_load_details 06:08:19 INFO - PROCESS | 2675 | |i|pagename|runs| 06:08:19 INFO - PROCESS | 2675 | |0;ai-astar;113;112;113;113;118;166;166;118;118;165 06:08:19 INFO - PROCESS | 2675 | |1;audio-beat-detection;148;112;112;145;116;112;115;138;174;116 06:08:19 INFO - PROCESS | 2675 | |2;audio-dft;186;183;184;185;187;184;185;184;183;182 06:08:19 INFO - PROCESS | 2675 | |3;audio-fft;77;58;58;88;63;57;58;57;85;73 06:08:19 INFO - PROCESS | 2675 | |4;audio-oscillator;85;72;98;71;68;70;89;67;68;94 06:08:19 INFO - PROCESS | 2675 | |5;imaging-gaussian-blur;88;85;89;85;90;84;88;86;88;84 06:08:19 INFO - PROCESS | 2675 | |6;imaging-darkroom;97;98;98;98;98;97;97;113;168;103 06:08:19 INFO - PROCESS | 2675 | |7;imaging-desaturate;84;82;80;79;92;124;130;85;83;81 06:08:19 INFO - PROCESS | 2675 | |8;json-parse-financial;54;53;52;54;54;56;55;54;53;55 06:08:19 INFO - PROCESS | 2675 | |9;json-stringify-tinderbox;65;75;80;75;72;71;71;69;72;71 06:08:19 INFO - PROCESS | 2675 | |10;stanford-crypto-aes;87;86;86;88;81;86;85;90;86;88 06:08:19 INFO - PROCESS | 2675 | |11;stanford-crypto-ccm;148;160;161;156;148;145;154;164;209;156 06:08:19 INFO - PROCESS | 2675 | |12;stanford-crypto-pbkdf2;174;173;173;177;164;215;234;178;171;171 06:08:19 INFO - PROCESS | 2675 | |13;stanford-crypto-sha256-iterative;67;69;68;67;68;68;68;66;68;67 06:08:19 INFO - PROCESS | 2675 | __end_tp_report 06:08:19 INFO - PROCESS | 2675 | __start_cc_report 06:08:19 INFO - PROCESS | 2675 | _x_x_mozilla_cycle_collect,1970 06:08:19 INFO - PROCESS | 2675 | __end_cc_report 06:08:19 INFO - PROCESS | 2675 | __startTimestamp1479132499665__endTimestamp 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | ------- Summary: start ------- 06:08:19 INFO - PROCESS | 2675 | Number of tests: 14 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#0] ai-astar Cycles:10 Average:130.20 Median:118.00 stddev:24.58 (20.8%) stddev-sans-first:25.28 06:08:19 INFO - PROCESS | 2675 | Values: 113.0 112.0 113.0 113.0 118.0 166.0 166.0 118.0 118.0 165.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:127.00 stddev:21.41 (16.9%) stddev-sans-first:21.55 06:08:19 INFO - PROCESS | 2675 | Values: 148.0 112.0 112.0 145.0 116.0 112.0 115.0 138.0 174.0 116.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.50 stddev:1.49 (0.8%) stddev-sans-first:1.45 06:08:19 INFO - PROCESS | 2675 | Values: 186.0 183.0 184.0 185.0 187.0 184.0 185.0 184.0 183.0 182.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#3] audio-fft Cycles:10 Average:67.40 Median:68.00 stddev:12.29 (18.1%) stddev-sans-first:12.53 06:08:19 INFO - PROCESS | 2675 | Values: 77.0 58.0 58.0 88.0 63.0 57.0 58.0 57.0 85.0 73.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:78.50 stddev:12.00 (15.3%) stddev-sans-first:12.47 06:08:19 INFO - PROCESS | 2675 | Values: 85.0 72.0 98.0 71.0 68.0 70.0 89.0 67.0 68.0 94.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.16 (2.5%) stddev-sans-first:2.24 06:08:19 INFO - PROCESS | 2675 | Values: 88.0 85.0 89.0 85.0 90.0 84.0 88.0 86.0 88.0 84.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:97.50 stddev:22.10 (22.7%) stddev-sans-first:23.16 06:08:19 INFO - PROCESS | 2675 | Values: 97.0 98.0 98.0 98.0 98.0 97.0 97.0 113.0 168.0 103.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:82.50 stddev:18.84 (22.8%) stddev-sans-first:19.76 06:08:19 INFO - PROCESS | 2675 | Values: 84.0 82.0 80.0 79.0 92.0 124.0 130.0 85.0 83.0 81.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:1.15 (2.1%) stddev-sans-first:1.22 06:08:19 INFO - PROCESS | 2675 | Values: 54.0 53.0 52.0 54.0 54.0 56.0 55.0 54.0 53.0 55.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:3.98 (5.5%) stddev-sans-first:3.30 06:08:19 INFO - PROCESS | 2675 | Values: 65.0 75.0 80.0 75.0 72.0 71.0 71.0 69.0 72.0 71.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.50 stddev:2.36 (2.7%) stddev-sans-first:2.49 06:08:19 INFO - PROCESS | 2675 | Values: 87.0 86.0 86.0 88.0 81.0 86.0 85.0 90.0 86.0 88.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#11] stanford-crypto-ccm Cycles:10 Average:160.10 Median:158.00 stddev:18.25 (11.6%) stddev-sans-first:18.83 06:08:19 INFO - PROCESS | 2675 | Values: 148.0 160.0 161.0 156.0 148.0 145.0 154.0 164.0 209.0 156.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.00 Median:175.50 stddev:22.65 (12.9%) stddev-sans-first:23.79 06:08:19 INFO - PROCESS | 2675 | Values: 174.0 173.0 173.0 177.0 164.0 215.0 234.0 178.0 171.0 171.0 06:08:19 INFO - PROCESS | 2675 | 06:08:19 INFO - PROCESS | 2675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.84 (1.2%) stddev-sans-first:0.87 06:08:19 INFO - PROCESS | 2675 | Values: 67.0 69.0 68.0 67.0 68.0 68.0 68.0 66.0 68.0 67.0 06:08:19 INFO - PROCESS | 2675 | -------- Summary: end -------- 06:08:19 INFO - PROCESS | 2675 | 06:08:20 INFO - TEST-INFO | 2675: exit 0 06:08:20 INFO - TEST-OK | kraken | took 60199ms 06:08:20 INFO - SUITE-END | took 1179s 06:08:20 INFO - Completed test suite (00:19:39) 06:08:20 INFO - javascript benchmark 06:08:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.091374415163, 3275.312184441266, 3277.0, 3301.042697617691, 3343.9665603343974, 5510.531604813596, 5569.6658200508, 5629.634073785206, 5631.042722737135, 5660.3490598581175, 4634.606058485025, 5595.916061259074, 5678.0, 5686.2039314496005, 5714.371419143891, 4245.766482843443, 5012.9749351253195, 5028.472010438888, 5049.116404629193, 5078.085944529983, 5121.1038068338075, 5221.373435187783, 5228.398734145584, 5235.528802407802, 5290.470952904698, 5051.191809310492, 5167.173252279636, 5197.246399272084, 5205.869853253699, 5278.049951008817, 5089.6691715038405, 5110.923336149961, 5140.640155189139, 5145.871353216199, 5156.948430515685, 5040.621953353513, 5104.719240441774, 5133.666311689765, 5135.486451354872, 5170.819021334273, 5599.552035837123, 5617.297837770278, 5651.717414129318, 5655.066913959192, 5681.659100453945, 6004.5796794224425, 6054.334023257438, 6054.666993315366, 6073.817785466443, 6113.052476866093, 6154.815355539341, 6161.4146656067605, 6199.721012554446, 6207.131001659771, 6229.127922090911, 5717.828465146053, 5723.141528770718, 5723.399043100456, 5736.6558006519745, 5780.421957804228, 6154.292256390483, 6157.384261573897, 6226.968865155736, 6227.657478838707, 6254.655993920287, 5969.582129250997, 6027.276726792813, 6030.6080104793045, 6097.115918191837, 6118.4668989547035, 6200.503959683215, 6203.906941395882, 6207.720652570645, 6211.81364559064, 6212.4408803207025, 6070.575059745775, 6106.511479081662, 6131.0, 6135.630783975157, 6147.44672979434, 5997.400259974055, 6043.788467403708, 6047.667378294148, 6121.449069583669, 6124.418180272867, 4807.990322032342, 4838.879028024257, 4858.028394321079, 4861.805527778849, 4862.95137048625, 4297.892552686208, 4301.591348821857, 4426.667999900021, 4435.977820110878, 4436.556344365602, 5614.045612245928, 5641.012822756002, 5642.815008848188, 5645.068563687028, 5648.63283886546, 6094.199305899851, 6103.246756470893, 6115.94015414234, 6127.525510585588, 6136.641621527207, 5595.552355811526, 5643.576731745054, 5646.24524691084, 5653.575981801381, 5668.773249070074, 5283.973580132151, 5290.365156181206, 5306.336707911511, 5309.150535914235, 5348.893022139613, 6256.624602523863, 6267.6866156691985, 6267.780627678009, 6270.9372906270355, 6274.843128921813, 5968.313643931004, 6023.45788879003, 6113.938860611337, 6214.9067763982675, 6217.191765070512, 6165.691715414122, 6170.043643235305, 6180.443760061616, 6219.129321895029, 6260.593061450991, 6110.801781737137, 6113.5675242835405, 6120.386183820725, 6120.416658368064, 6140.412224875342, 6190.2571691397325, 6210.875782484415, 6211.378862113933, 6213.440790328892, 6218.81343559694, 5794.971025145016, 5850.765969361178, 5874.089516125007, 5933.762649493972, 5980.611260268069, 4354.56454354562, 5773.653580785166, 5924.081767326072, 5990.550708696778, 6044.15381846533, 5761.596688231783, 5802.912956305574, 5803.274590676166, 5815.94184058154, 5819.5635327351465, 5794.985877471341, 5961.4038596140035, 5978.611390259619, 5992.340842507234, 5994.220751302303, 5721.8569535761935, 5745.597808153556, 5751.626144300607, 5779.757352169304, 5782.045962416322], "value": 5594.547676102732, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.9321095046693, 487.3250547991034, 507.6979197377561, 525.5690333926179, 544.4201924949931, 30486.847565762197, 39074.41388379173, 40588.18823623533, 40711.38932916001, 40750.79624601873, 41262.0, 41770.582294177046, 41772.37341439873, 41840.37239441403, 41951.853610979146, 9316.627334906596, 9512.904870951272, 9518.571664275125, 9518.666846660368, 9526.523673816317, 9325.067493250655, 9425.670101546448, 9431.905680943206, 9433.0, 9434.009429009959, 9399.81200375992, 9420.434773913586, 9438.952805235964, 9439.292053096045, 9451.243900487978, 9365.95317023414, 9405.037413214815, 9409.529523523865, 9417.62329506819, 9420.152186303265, 9280.814383712355, 9316.580753866025, 9359.29805264601, 9361.531923403803, 9374.29692773038, 32112.839435802904, 38234.0, 38623.03442413934, 38697.03257418559, 38826.61173388258, 3278.622958359795, 3284.704376606093, 3335.216224187314, 3366.4108780963234, 3372.3255348930065, 29143.979960701487, 29569.96505122332, 30085.699143008507, 30663.38673226523, 30707.539386909215, 3125.1718294651937, 3163.604549431321, 3177.793443426193, 3193.2815116598813, 3277.8688852445875, 483539.0, 485526.0, 485588.0, 486204.0, 487094.12905870844, 55504.44495555074, 55671.0, 55683.443165568635, 55809.0, 55858.44141558532, 33963.15092122717, 34801.30397392038, 60020.0, 60131.0, 60269.397306027255, 15167.544973650809, 15606.0, 15682.921585392, 15736.449224277096, 15737.055776653437, 11892.286462812086, 11949.462274197682, 11977.0, 11980.820287695691, 11992.100592455601, 18612.255509779727, 18613.906930465262, 18644.110162924768, 18669.786463879806, 18693.532661683566, 6643.468522518187, 7643.0, 7735.6905723770415, 7743.051043967617, 7832.412569057344, 856.4433118473042, 976.7558110472382, 1008.3798463944768, 1053.1890444357803, 1071.3786004117594, 28004.43991120166, 29449.969251076538, 29645.555316670267, 30108.54837177444, 30159.0, 963.3882484622176, 1044.9738756531146, 1051.222095649214, 1056.1761825775907, 1085.56577369053, 14759.557213283619, 15499.5350139496, 15711.764323535157, 15958.521244362688, 16373.672526549402, 898437.0, 919124.0, 920317.9602549681, 923987.0, 925036.0, 54431.9176061797, 54811.72594137004, 54931.0, 55098.724506378014, 55113.44886551083, 1193.20651766576, 1222.1261797814427, 1322.2198902647485, 1349.0826238157952, 1373.3957058894055, 27761.444771104867, 29595.556066658588, 29641.407171856874, 30203.697963020968, 30237.48812559407, 48231.27653085277, 48835.511644883096, 48884.75557622189, 48901.5109848897, 49057.0, 561643.5753463783, 568909.0, 569169.0, 571089.0, 571285.0, 23922.760772392052, 24363.26910192697, 25587.872060639576, 25790.0, 26176.0, 43293.567064328956, 44210.336844948, 45357.86605334893, 45442.0, 45660.0, 33663.653453861574, 35904.82047589745, 35976.100597485274, 36870.63129368672, 37850.62149378471, 36465.81767091148, 36469.45295820618, 37341.626583733814, 37591.060223494635, 37924.43113353247, 35542.822285888404, 36036.0, 36789.0, 36859.447108293934, 37163.0, 31206.843965780936, 32868.55178066305, 33061.00816975495, 33177.83411082929, 33493.330133397685, 24266.0, 24539.631905521073, 24852.183198032304, 24912.626310604992, 24976.625350620117], "value": 19519.916847855802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.27700444045314, 369.6709928163934, 370.6867696796206, 383.1379396358194, 402.1253773043631, 14877.107373557581, 14914.477993270224, 15961.68076638468, 20385.0, 20467.795322046775, 19509.0, 19587.902060489712, 20145.899270503665, 20156.294529691462, 20343.59312813743, 5551.72241387931, 5799.73901174446, 5858.648481091127, 5861.974154522963, 5864.648121112726, 3588.856445742167, 6346.30190679025, 6427.453666438335, 6495.188101487314, 6522.249941256745, 5720.427957204288, 6149.2313460817395, 6348.0, 6443.0657554654545, 6445.4199122079235, 6310.211223597051, 6319.999999999977, 6356.364363563653, 6360.7137678804565, 6391.744330226832, 4779.904401911942, 5277.683338999634, 5461.262729531502, 5522.779088836442, 5533.391326954032, 20702.58594828095, 20758.0, 22191.334259972227, 25081.0, 25459.745402545923, 2383.07060246504, 2476.863772492512, 2800.2579316481147, 3008.8944351362575, 3130.89041883533, 11828.881711182865, 14789.482368117171, 15557.066576005363, 15751.212439377985, 15892.966957147864, 1572.1588949911857, 1755.2978808476585, 1774.299151835018, 1789.579448829524, 1859.1540848913712, 115151.0, 117260.41369793181, 119095.4045229777, 120659.0, 120822.99999999913, 9650.903490965, 10375.84436233457, 10548.630797922036, 10661.893381066246, 10699.572017119384, 13875.791863122062, 13882.791758123636, 13892.23592702411, 13913.0, 13917.652058698613, 6551.901721474182, 8025.759227223192, 8296.751097467086, 8323.0, 8431.40980131385, 7374.373178279799, 7738.761798112275, 8154.266116049584, 8167.060788009336, 8334.333253339719, 5581.860453488695, 6499.220093588705, 6609.900851487231, 6622.139121914121, 6646.933530664728, 1804.9819501804814, 1849.7317888906302, 1850.0657168130276, 1869.6541139889164, 1880.6238752249606, 684.4011489946297, 822.1737154160031, 863.935204859638, 871.7515508080294, 996.0437979539578, 20706.68939965902, 21775.128994840346, 21793.891030545063, 21807.563848723254, 21875.562488750136, 780.4029917113413, 893.3433926064338, 909.2044461096541, 942.688912658821, 1013.7465633591602, 9065.864012039825, 11300.547978080785, 11594.0, 11638.53445862156, 11698.649040528799, 465131.0, 471210.0, 472087.0, 521056.0, 586979.0, 116899.2465113024, 120527.0, 125106.0, 125794.63016977077, 137476.0, 1001.3140998416109, 1004.3271008424373, 1009.6668099527138, 1011.1001208923916, 1039.6984874386344, 18626.720599191292, 18944.905275473535, 19114.23543058262, 19222.903885480482, 19299.131539080496, 37659.0, 46867.0, 50608.240876386146, 52735.0, 52765.73617132043, 75994.0, 77280.61359693165, 78783.0, 81391.0, 81664.0, 7641.197674244347, 7688.269614386624, 7831.355415362837, 8018.799530011796, 8093.959530202312, 9113.544322783702, 12236.75526489483, 12626.179298345578, 13210.867891321348, 13341.599752007456, 10884.0, 11159.27464714791, 11326.093912487146, 11340.09279257674, 11520.0, 10933.56265749361, 10984.231103822656, 11212.775744485229, 11453.770924581295, 11518.0, 11053.502592383202, 11146.275492092987, 11301.943490282496, 11498.25261358009, 11588.536458541565, 7701.576413297323, 7878.015248093989, 7963.40274479428, 7992.40056995716, 8074.596270186585, 8638.913610864063, 10837.837432438362, 10965.671029869116, 11116.44417779124, 11226.550937962716], "value": 9542.662263779086, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.1452812935663, 1466.523379901532, 1479.6522817137975, 1647.36576418079, 1837.7243413487975, 7362.263773622635, 7764.456488045832, 8038.075621303552, 8301.792455188623, 8312.958435207831, 8154.429189956705, 8238.17618238176, 8241.381896357781, 8253.91746082539, 8257.110011495974, 1985.8609897307194, 2256.289268880306, 2278.1229226747737, 2289.6794448777187, 2292.002978704269, 2252.3805953362876, 2291.5302363015626, 2299.2642354446584, 2301.1600765720477, 2303.3665741921022, 2235.2065016919005, 2238.55228954209, 2265.8753768542724, 2267.1384873748034, 2269.2624896908487, 1651.28169246377, 2115.23851413491, 2173.3805865328304, 2177.052981952848, 2177.314146044007, 2271.716035495563, 2299.4136495193784, 2302.3668491164894, 2303.285981345788, 2305.319930620471, 6940.618265995368, 8266.13205613408, 8338.499690018558, 8369.539675317856, 8370.0, 4993.700377977333, 5172.741362931876, 5173.534381905646, 5179.326687530634, 5182.4299327073995, 3173.714365707074, 3175.634801997777, 3190.4735718606403, 3267.134209434502, 3272.590926134233, 896.8968568614628, 900.1538553759445, 906.8277027364779, 963.0802583532709, 971.1842052675715, 10797.784044319069, 10840.78318433627, 10863.348199108079, 10865.728356791064, 10874.130069594414, 4117.238310912491, 4347.695661303678, 4369.912601748011, 4394.824207031683, 4399.340098985178, 8654.35092368075, 8658.393912426196, 8667.653293868301, 8668.783280417914, 8721.171488708562, 5026.346574945307, 5031.496850314939, 5034.723090230073, 5037.4458809530925, 5040.294358789773, 3959.5842436544326, 4195.559466255999, 4398.164348773723, 4602.309653551994, 4717.976410117996, 3528.6471352865024, 3545.539079919594, 3633.1462106404974, 3647.9817600911824, 3653.214558869856, 1105.7788442311569, 1109.3288560420829, 1118.82658187981, 1133.7789131119519, 1144.0961640304067, 815.0, 833.691534132375, 850.7706364303646, 886.2865393358409, 890.3366991591306, 4969.676970996874, 5183.196604526229, 5201.1678131498775, 5390.784368625211, 5411.594130440154, 388.4134956216134, 409.4860949508348, 420.997895010523, 432.2245188280368, 440.4626355845863, 3462.5498685170764, 3470.479428085757, 3569.7679650822615, 3584.9641503585153, 3629.564452265745, 9634.373765705206, 9930.354526955725, 10065.295429320024, 10069.69790906274, 10091.848622270672, 8536.8719469208, 9912.603495860229, 9967.003299669976, 10018.048285412875, 10019.749506262257, 7656.583532046478, 7662.501937374052, 7713.8457230855065, 7752.108507521707, 7861.882071768928, 4568.383268258875, 5123.5910124716, 5294.100002999577, 5307.097793375213, 5320.600954928318, 9084.591193396445, 9143.314251431037, 9288.256939444693, 9395.43627382332, 9485.857712134462, 7258.0, 9393.530323483661, 9439.764005899908, 9472.0, 9563.282753793354, 6574.27682954865, 6668.06647069401, 6690.933090669226, 6795.966020169867, 7063.6821343038655, 7830.491018083806, 7932.682692692459, 7939.642716077675, 7945.20547945224, 7957.7612671619945, 6084.847878803065, 6740.056392105207, 7030.543014704028, 7135.108111486064, 7269.854602908018, 6003.82925329574, 6347.0, 6698.196216454056, 6975.581465112109, 7048.929510704827, 6340.4293613575, 6356.77751278703, 6367.87264254703, 6421.935780642134, 6443.871122577616, 4578.6108180805, 4588.816447342069, 4634.582887540138, 4652.069586082729, 4760.571548560646, 3616.6383361663625, 4633.559811817872, 4688.812447502061, 4697.694649847749, 4786.856394308176], "value": 4144.532190789391, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8361900954475, 307.58476057322616, 307.9399517094167, 317.0678206074142, 318.14419212318876, 4263.827447451952, 4563.498015218323, 4767.903382222086, 5140.820071297505, 5156.974215128928, 4294.754521188859, 4542.568455996675, 5166.689998600086, 5197.8440646780655, 5295.537575027612, 3257.96742032579, 3581.982090089559, 3732.2908647357053, 3855.903602409934, 3912.9043867717087, 3409.9147521311916, 3953.4500247340447, 3986.3422535281643, 4006.73956192848, 4041.0301527633305, 3509.31568344173, 3775.828898383427, 3911.882643520699, 3927.8232479538487, 3961.663258623006, 2765.9308517287027, 3758.586555478895, 3972.543157536891, 3989.9202015959663, 4003.619656132663, 2893.594896714462, 3469.8265086745814, 3472.461768425898, 3479.7216222702377, 3503.4744788281705, 5064.215046667736, 5074.8581569146645, 5078.720670363128, 5089.338386009795, 5089.796408143669, 3790.9620903790883, 4092.4679791627195, 4490.57339552742, 4516.1419330327135, 4531.297648864398, 4213.325867861127, 5255.9750848584545, 5374.48942350476, 5375.6774593524515, 5406.621536492447, 3140.733037691799, 3407.5910890693276, 3542.2030043240325, 3719.7210209234418, 3728.8508459661853, 5377.316538171287, 5380.354357477088, 5553.611247212697, 5616.354119276295, 5641.323041235037, 2899.1157696902224, 3726.720495962814, 3751.649406213761, 3751.955184424495, 3766.3785475396253, 3112.081935828936, 3468.531748213959, 3559.288142371536, 3664.358737220976, 3804.8668296609485, 4104.774237416911, 4426.911461770746, 4430.9808743988415, 4434.534373890695, 4439.023414848728, 5155.716435596003, 5393.83818485446, 5410.404855465896, 5431.619786614899, 5443.945560544423, 1087.3367245979946, 1109.2567979453625, 1199.5381778015353, 1236.9010479161645, 1249.2572860859232, 730.747891977267, 801.5511313664366, 827.3753316246316, 843.7890527368158, 853.3855623950745, 4309.612134907873, 4951.653384263139, 4988.55103040728, 4993.50064993505, 5146.948530514722, 4623.42207224097, 5352.849137435393, 5521.3926468088475, 5549.38956714761, 5549.861253468695, 4064.837406503766, 4243.321068629005, 4633.397658304466, 4887.144749668793, 5055.87360315995, 2472.5302192583354, 2525.2676723749905, 2568.9595713735966, 2745.6018877263086, 2753.8898444062015, 4418.513963464016, 4958.545519967639, 5268.762905669254, 5327.3074500314715, 5390.978383106335, 5082.822101226439, 5364.6512976656395, 5364.834747498994, 5368.570514358842, 5400.271912987885, 4639.420072490939, 5167.715775632376, 5379.973100134474, 5389.571763482681, 5426.414679595814, 4254.766247719972, 4462.866114016585, 5216.921746173734, 5221.686698798084, 5246.685198888079, 4847.975760121177, 4863.7324947128245, 5136.15098564509, 5180.6891586504935, 5191.870203244949, 4429.886756276318, 4667.509911459248, 4792.712437253636, 5402.515911793281, 5447.428020057995, 3318.9170270743425, 3507.4212754895157, 3518.7978939165237, 3531.9625122394204, 3533.5583052118486, 4273.487181538236, 4479.9328010079225, 4919.631027672867, 4943.77753001123, 5050.747462626928, 4159.646430053479, 4764.737344603614, 5002.849914502571, 5101.92347114801, 5158.498128407644, 4362.7818609070055, 4861.683990540603, 5054.620903432184, 5057.342545469065, 5063.417706963599, 4148.233666578694, 4747.525247475225, 4971.384300102409, 4985.6011519077665, 5000.849974500771, 3841.4250157435317, 4493.191225579297, 4781.900162962607, 4912.582430493448, 5289.894202116013, 4668.696534725232, 5132.948670513247, 5172.267290457738, 5249.4251724483265, 5276.129438642734], "value": 3793.4568373443894, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.448214187579, 718.5006420537725, 753.9886901696475, 756.8032311598982, 774.8411575626995, 13843.792343114861, 14584.197869117193, 14596.635084122901, 14626.48807291745, 14641.926790366046, 12988.155769874975, 13129.67175820605, 13137.67155821105, 13140.080194386397, 13145.211287322767, 647.3267801486448, 667.366002297819, 668.4952860590272, 795.4710117771675, 814.3566582399897, 818.8853560501534, 844.7507985144397, 844.9227235275013, 853.9701110461135, 854.5556310718423, 887.2103827593447, 894.463322006794, 894.8031433084743, 895.8924929008529, 896.0054339682947, 692.5809885019592, 890.5591732092655, 890.9955450222773, 891.0332289465937, 891.7948871759531, 531.6145794299141, 640.337250945274, 659.7295109005332, 661.1141070964931, 662.4170729757831, 13678.589642310746, 13703.657408564866, 13706.794398084037, 13706.862931370759, 13729.0, 12760.425780839885, 12932.224066555942, 13005.40653045452, 13038.217706937614, 13059.412326445332, 11829.822552661717, 11894.286342819372, 11960.401979901057, 11962.521499140112, 12285.69285767863, 4310.396544483775, 4329.978350108229, 4406.889827754299, 4510.774461276956, 4527.139843429738, 74810.87783683195, 77260.0, 85179.57410212971, 86927.0, 89309.0, 10395.792084158275, 10429.536703639078, 10500.684979450629, 10511.894881051245, 10701.143908487458, 19415.61168776645, 19417.417477475698, 19423.12595933158, 19426.611467770847, 19436.70844937327, 7308.963455182797, 7569.318761311509, 7624.237576242443, 7695.846083078307, 7705.537667739954, 3728.832202550892, 4012.9799351003057, 4183.184279065614, 4293.3130699088, 4321.222180007629, 3228.257500774788, 3379.543761592203, 3381.1716129548417, 3385.23832137772, 3385.23832137772, 556.5519756595899, 625.2684359299631, 628.1864984844638, 638.658317799984, 644.336333576417, 555.7276934302243, 584.7076461769116, 651.4560491634761, 661.6658587413325, 683.2381894187945, 8458.915410845935, 8489.151084891584, 8558.0, 9065.954670226607, 9185.586648600829, 593.4569868570296, 641.3842710997401, 649.1118201970165, 673.4208580620663, 679.7688785812848, 3766.227923275722, 3812.733108682421, 3981.203759248162, 4013.91972160561, 4392.560743925582, 109340.0, 110255.0, 112928.0, 114107.0, 117366.0, 64296.67851660805, 73789.0, 81564.59217703874, 85452.57273713591, 100451.0, 77310.6134469324, 79132.0, 83149.0, 84842.15157848466, 84936.35572798477, 9451.385659932082, 9594.42433453995, 9595.712128636153, 9614.759631009001, 9666.661666841626, 50120.74939625279, 51629.48370516247, 53583.0, 54625.0, 55468.61328466821, 69255.68931728225, 76432.0, 80715.0, 81010.18989810262, 82744.0, 11540.711482213012, 11885.16803823724, 11910.880891190976, 12098.213616114925, 12309.384530773605, 13674.589762307147, 13736.93131534336, 13737.175769453865, 13937.442502299795, 14433.0, 12529.0, 12835.93582032084, 12871.420786064462, 13027.0, 13423.127496712683, 12423.31671758062, 12730.427130779322, 12741.362931853555, 12949.172872207357, 13110.803337950128, 12484.188527745668, 12504.562340318045, 12573.685657858627, 12652.43064062101, 12931.870681293067, 8738.775632510105, 9913.603455861685, 10729.088027517748, 10829.837552436877, 11204.487394201678, 9676.0, 10607.94696026515, 11270.436478176221, 11276.830847537129, 11354.54581816718], "value": 7187.922773736609, "unit": "score"}], "name": "dromaeo_css", "value": 7001.3196732185415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 118.0, 166.0, 166.0, 118.0, 118.0, 165.0], "value": 130.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 112.0, 145.0, 116.0, 112.0, 115.0, 138.0, 174.0, 116.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 184.0, 185.0, 187.0, 184.0, 185.0, 184.0, 183.0, 182.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 58.0, 88.0, 63.0, 57.0, 58.0, 57.0, 85.0, 73.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 98.0, 71.0, 68.0, 70.0, 89.0, 67.0, 68.0, 94.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 90.0, 84.0, 88.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 98.0, 98.0, 97.0, 97.0, 113.0, 168.0, 103.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 80.0, 79.0, 92.0, 124.0, 130.0, 85.0, 83.0, 81.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 54.0, 54.0, 56.0, 55.0, 54.0, 53.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 80.0, 75.0, 72.0, 71.0, 71.0, 69.0, 72.0, 71.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 88.0, 81.0, 86.0, 85.0, 90.0, 86.0, 88.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 160.0, 161.0, 156.0, 148.0, 145.0, 154.0, 164.0, 209.0, 156.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 173.0, 177.0, 164.0, 215.0, 234.0, 178.0, 171.0, 171.0], "value": 183.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 68.0, 68.0, 68.0, 66.0, 68.0, 67.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1497.4, "alertThreshold": 2.0}]} 06:08:20 INFO - Return code: 0 06:08:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:20 INFO - # TBPL SUCCESS # 06:08:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:21 INFO - 2016-11-14 06:08:21.001 screenresolution[2679:21598] starting screenresolution argv=screenresolution get 06:08:21 INFO - 2016-11-14 06:08:21.004 screenresolution[2679:21598] Display 0: 1600x1200x32@60 06:08:21 INFO - 2016-11-14 06:08:21.010 screenresolution[2680:21600] starting screenresolution argv=screenresolution list 06:08:21 INFO - Available Modes on Display 0 06:08:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:21 INFO - Intel Iris: 06:08:21 INFO - Chipset Model: Intel Iris 06:08:21 INFO - Type: GPU 06:08:21 INFO - Bus: Built-In 06:08:21 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:21 INFO - Vendor: Intel (0x8086) 06:08:21 INFO - Device ID: 0x0a2e 06:08:21 INFO - Revision ID: 0x0009 06:08:21 INFO - Displays: 06:08:21 INFO - Display: 06:08:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:21 INFO - Display Serial Number: 1600x1200 60 06:08:21 INFO - Main Display: Yes 06:08:21 INFO - Mirror: Off 06:08:21 INFO - Online: Yes 06:08:21 INFO - Rotation: Supported 06:08:21 INFO - Return code: 0 06:08:21 INFO - Running post-action listener: _resource_record_post_action 06:08:21 INFO - [mozharness: 2016-11-14 14:08:21.247872Z] Finished run-tests step (success) 06:08:21 INFO - Running post-run listener: _resource_record_post_run 06:08:21 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 274432; Write bytes: 699401216; Read time: 4; Write time: 1954 06:08:21 INFO - TinderboxPrint: CPU usage
53.9% 06:08:21 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 4 06:08:21 INFO - TinderboxPrint: I/O write bytes / time
699,401,216 / 1,954 06:08:21 INFO - TinderboxPrint: CPU idle
2,241.2 (46.1%) 06:08:21 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 06:08:21 INFO - TinderboxPrint: CPU user
1,636.7 (33.7%) 06:08:21 INFO - TinderboxPrint: Swap in / out
398,213,120 / 0 06:08:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399499776; Write bytes: 401192448; Read time: 29790; Write time: 785 06:08:21 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 241664; Write bytes: 296852992; Read time: 3; Write time: 1162 06:08:21 INFO - Running post-run listener: _upload_blobber_files 06:08:21 INFO - Blob upload gear active. 06:08:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:21 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 06:08:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:22 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:08:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:22 INFO - (blobuploader) - INFO - Done attempting. 06:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:08:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:23 INFO - (blobuploader) - INFO - Done attempting. 06:08:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:08:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:26 INFO - (blobuploader) - INFO - Done attempting. 06:08:26 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:26 INFO - Return code: 0 06:08:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a2f3053b5a0842a5d10d2ac913810fa5b07932df40a276ee534a09614302d92e6c85e757b238fdc00b9c34c7a3044fe9bd3746bc716fc4da190124c81526bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a2789da1f876a5461af0ba2d0d47dffe140d31eb1544fc3762f9ce5b177407158b3f3d7983b8f3b5cbb696426334553df074e50124eb74554ae986c66180de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd6fddb3afc4602486894047b78282e4d7a6b614681f6f3d9b738e126b5b285479c001f6b635eb64a624403550ee2077134dea12c6b63bdc60bfec64aa3be23"} 06:08:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:26 INFO - Contents: 06:08:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a2f3053b5a0842a5d10d2ac913810fa5b07932df40a276ee534a09614302d92e6c85e757b238fdc00b9c34c7a3044fe9bd3746bc716fc4da190124c81526bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a2789da1f876a5461af0ba2d0d47dffe140d31eb1544fc3762f9ce5b177407158b3f3d7983b8f3b5cbb696426334553df074e50124eb74554ae986c66180de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd6fddb3afc4602486894047b78282e4d7a6b614681f6f3d9b738e126b5b285479c001f6b635eb64a624403550ee2077134dea12c6b63bdc60bfec64aa3be23"} 06:08:26 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:26 INFO - Copying logs to upload dir... 06:08:26 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.735266 ========= master_lag: 3.00 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-14 06:08:29.380483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 06:08:29.385373) ========= 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.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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/Firefox/sha512/a9a2f3053b5a0842a5d10d2ac913810fa5b07932df40a276ee534a09614302d92e6c85e757b238fdc00b9c34c7a3044fe9bd3746bc716fc4da190124c81526bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a2789da1f876a5461af0ba2d0d47dffe140d31eb1544fc3762f9ce5b177407158b3f3d7983b8f3b5cbb696426334553df074e50124eb74554ae986c66180de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd6fddb3afc4602486894047b78282e4d7a6b614681f6f3d9b738e126b5b285479c001f6b635eb64a624403550ee2077134dea12c6b63bdc60bfec64aa3be23"} build_url:https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/eakdcyz7QHOrNWxlamBbsg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a9a2f3053b5a0842a5d10d2ac913810fa5b07932df40a276ee534a09614302d92e6c85e757b238fdc00b9c34c7a3044fe9bd3746bc716fc4da190124c81526bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a2789da1f876a5461af0ba2d0d47dffe140d31eb1544fc3762f9ce5b177407158b3f3d7983b8f3b5cbb696426334553df074e50124eb74554ae986c66180de4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bd6fddb3afc4602486894047b78282e4d7a6b614681f6f3d9b738e126b5b285479c001f6b635eb64a624403550ee2077134dea12c6b63bdc60bfec64aa3be23"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 06:08:29.423520) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:08:29.423850) ========= 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.J3qjx1N4y5/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.jOJYQIeX19/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.005403 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 06:08:29.472666) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 06:08:29.472994) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 06:08:29.473286) ========= ========= Total master_lag: 3.15 =========