builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0082 starttime: 1478905985.54 results: success (0) buildid: 20161111113020 builduid: f4c1ab79ad424b8a8feba60fac305c46 revision: 1edd75c67df0fb5e03f4746ae238603f03129807 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.543239) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.543672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.543947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.570886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.571182) ========= 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.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-11 15:13:05-- 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% 40.1M=0s 2016-11-11 15:13:05 (40.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077096 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.670680) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.670991) ========= 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.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.025800 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:05.721569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-11-11 15:13:05.721879) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 1edd75c67df0fb5e03f4746ae238603f03129807 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 1edd75c67df0fb5e03f4746ae238603f03129807 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-11 15:13:05,804 truncating revision to first 12 chars 2016-11-11 15:13:05,804 Setting DEBUG logging. 2016-11-11 15:13:05,804 attempt 1/10 2016-11-11 15:13:05,804 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/1edd75c67df0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-11 15:13:07,919 unpacking tar archive at: larch-1edd75c67df0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.358080 ========= master_lag: 3.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-11-11 15:13:11.267759) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:11.268087) ========= script_repo_revision: 1edd75c67df0fb5e03f4746ae238603f03129807 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:11.268500) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:11.268764) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 15:13:11.292107) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-11 15:13:11.292387) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13:11 INFO - MultiFileLogger online at 20161111 15:13:11 in /builds/slave/test 15:13:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:13:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:13:11 INFO - {'append_to_log': False, 15:13:11 INFO - 'base_work_dir': '/builds/slave/test', 15:13:11 INFO - 'blob_upload_branch': 'Larch', 15:13:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:13:11 INFO - 'branch': 'Larch', 15:13:11 INFO - 'buildbot_json_path': 'buildprops.json', 15:13:11 INFO - 'config_files': ('talos/mac_config.py',), 15:13:11 INFO - 'default_actions': ('clobber', 15:13:11 INFO - 'read-buildbot-config', 15:13:11 INFO - 'download-and-extract', 15:13:11 INFO - 'populate-webroot', 15:13:11 INFO - 'create-virtualenv', 15:13:11 INFO - 'install', 15:13:11 INFO - 'run-tests'), 15:13:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:13:11 INFO - 'download_minidump_stackwalk': True, 15:13:11 INFO - 'download_symbols': 'ondemand', 15:13:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:13:11 INFO - 'tooltool.py': '/tools/tooltool.py', 15:13:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:13:11 INFO - '/tools/misc-python/virtualenv.py')}, 15:13:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:13:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:13:11 INFO - 'installer_path': 'installer.exe', 15:13:11 INFO - 'log_level': 'info', 15:13:11 INFO - 'log_name': 'talos', 15:13:11 INFO - 'log_to_console': True, 15:13:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:13:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:13:11 INFO - 'opt_config_files': (), 15:13:11 INFO - 'pip_index': False, 15:13:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:11 INFO - 'cmd': ('bash', 15:13:11 INFO - '-c', 15:13:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:11 INFO - 'enabled': True, 15:13:11 INFO - 'halt_on_failure': False, 15:13:11 INFO - 'name': 'check_screen_resolution'},), 15:13:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:13:11 INFO - 'cmd': ('bash', 15:13:11 INFO - '-c', 15:13:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:13:11 INFO - 'enabled': True, 15:13:11 INFO - 'halt_on_failure': False, 15:13:11 INFO - 'name': 'check_screen_resolution'},), 15:13:11 INFO - 'run_cmd_checks_enabled': True, 15:13:11 INFO - 'sps_profile': False, 15:13:11 INFO - 'sps_profile_interval': 0, 15:13:11 INFO - 'suite': 'dromaeojs', 15:13:11 INFO - 'system_bits': '32', 15:13:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:13:11 INFO - 'title': 't-yosemite-r7-0082', 15:13:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:13:11 INFO - 'use_talos_json': True, 15:13:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:13:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:13:11 INFO - 'work_dir': 'build'} 15:13:11 INFO - [mozharness: 2016-11-11 23:13:11.497249Z] Running clobber step. 15:13:11 INFO - Running pre-action listener: _resource_record_pre_action 15:13:11 INFO - Running main action method: clobber 15:13:11 INFO - rmtree: /builds/slave/test/build 15:13:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:13:13 INFO - Running post-action listener: _resource_record_post_action 15:13:13 INFO - [mozharness: 2016-11-11 23:13:13.995498Z] Finished clobber step (success) 15:13:13 INFO - [mozharness: 2016-11-11 23:13:13.995667Z] Running read-buildbot-config step. 15:13:13 INFO - Running pre-action listener: _resource_record_pre_action 15:13:13 INFO - Running main action method: read_buildbot_config 15:13:13 INFO - Using buildbot properties: 15:13:13 INFO - { 15:13:13 INFO - "project": "", 15:13:13 INFO - "product": "firefox", 15:13:13 INFO - "script_repo_revision": "production", 15:13:13 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 15:13:13 INFO - "repository": "", 15:13:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 15:13:13 INFO - "buildid": "20161111113020", 15:13:13 INFO - "pgo_build": "False", 15:13:13 INFO - "basedir": "/builds/slave/test", 15:13:13 INFO - "buildnumber": 1, 15:13:13 INFO - "slavename": "t-yosemite-r7-0082", 15:13:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:13:13 INFO - "platform": "yosemite_r7", 15:13:13 INFO - "scheduler": "tests-larch-macosx64-talos", 15:13:13 INFO - "branch": "larch", 15:13:13 INFO - "repo_path": "projects/larch", 15:13:13 INFO - "revision": "1edd75c67df0fb5e03f4746ae238603f03129807", 15:13:13 INFO - "stage_platform": "macosx64", 15:13:13 INFO - "builduid": "f4c1ab79ad424b8a8feba60fac305c46", 15:13:13 INFO - "slavebuilddir": "test" 15:13:13 INFO - } 15:13:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:13:13 INFO - Running post-action listener: _resource_record_post_action 15:13:13 INFO - [mozharness: 2016-11-11 23:13:13.998786Z] Finished read-buildbot-config step (success) 15:13:13 INFO - [mozharness: 2016-11-11 23:13:13.998918Z] Running download-and-extract step. 15:13:13 INFO - Running pre-action listener: _resource_record_pre_action 15:13:13 INFO - Running main action method: download_and_extract 15:13:13 INFO - mkdir: /builds/slave/test/build/tests 15:13:13 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')]} 15:13:13 INFO - trying https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:13:13 INFO - Downloading https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/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 15:13:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/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 15:13:15 INFO - Downloaded 1199 bytes. 15:13:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:13:15 INFO - Using the following test package requirements: 15:13:15 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:13:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:13:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:13:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'jsshell-mac.zip'], 15:13:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:13:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:13:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:13:15 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:13:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:13:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:13:15 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:13:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:13:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:13:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:13:15 INFO - Fetch https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:13:15 INFO - Http code: 200 15:13:15 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 15:13:15 INFO - x-amz-cf-id: teMdpFHVWJDLy0ATUneVK7Qla68etfNQbWJkVjnc_0plhPGsgd2ZIg== 15:13:15 INFO - x-amz-version-id: mUxTSUg9OSBFJ3ZZtkqS2oH9HhCFHxql 15:13:16 INFO - Expected file size: 16328496 15:13:16 INFO - Obtained file size: 16328496 15:13:22 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 15:13:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:13:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:13:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:13:23 INFO - Http code: 200 15:13:23 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 15:13:23 INFO - x-amz-cf-id: y6mkhE-LvAK-O3A-Ff8oUtVdIwG-_3RglM0nNMsFKvaP6-sZ3wpwVQ== 15:13:23 INFO - x-amz-version-id: mUxTSUg9OSBFJ3ZZtkqS2oH9HhCFHxql 15:13:23 INFO - Expected file size: 16328496 15:13:23 INFO - Obtained file size: 16328496 15:13:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:13:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:13:30 INFO - Fetch https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:13:30 INFO - Http code: 200 15:13:30 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 15:13:30 INFO - x-amz-cf-id: 3Wsbg9XWO1dvssCnppzs5GVmoBmA-akOQHJnwotFS6zyAnhNqxKeGA== 15:13:30 INFO - x-amz-version-id: w1RuXhOq2fqsMowfSxqb1iJX.E_mcKMc 15:13:31 INFO - Expected file size: 14409038 15:13:31 INFO - Obtained file size: 14409038 15:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:31 INFO - trying https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:13:31 INFO - Downloading https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/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 15:13:34 INFO - Downloaded 110981082 bytes. 15:13:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:13:34 INFO - mkdir: /builds/slave/test/properties 15:13:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:13:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:13:34 INFO - Contents: 15:13:34 INFO - build_url:https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:13:34 INFO - Running post-action listener: _resource_record_post_action 15:13:34 INFO - Running post-action listener: set_extra_try_arguments 15:13:34 INFO - [mozharness: 2016-11-11 23:13:34.836860Z] Finished download-and-extract step (success) 15:13:34 INFO - [mozharness: 2016-11-11 23:13:34.836967Z] Running populate-webroot step. 15:13:34 INFO - Running pre-action listener: _resource_record_pre_action 15:13:34 INFO - Running main action method: populate_webroot 15:13:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:13:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:13:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:13:34 INFO - '--tppagecycles', 15:13:34 INFO - '7'], 15:13:34 INFO - 'tests': ['tsvgm']}}, 15:13:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:13:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:13:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:13:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:13:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:13:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['damp', 'tps']}, 15:13:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'tests': ['damp', 'tps']}, 15:13:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['dromaeo_dom']}, 15:13:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:13:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['basic_compositor_video']}, 15:13:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:13:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['a11yr', 15:13:34 INFO - 'ts_paint', 15:13:34 INFO - 'tpaint', 15:13:34 INFO - 'sessionrestore', 15:13:34 INFO - 'sessionrestore_no_auto_restore', 15:13:34 INFO - 'tabpaint']}, 15:13:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['tsvgx', 15:13:34 INFO - 'tsvgr_opacity', 15:13:34 INFO - 'tart', 15:13:34 INFO - 'tscrollx', 15:13:34 INFO - 'cart']}, 15:13:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:13:34 INFO - 'tsvgr_opacity', 15:13:34 INFO - 'tart', 15:13:34 INFO - 'tscrollx', 15:13:34 INFO - 'cart']}, 15:13:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'talos_options': ['--disable-e10s'], 15:13:34 INFO - 'tests': ['tp5o']}, 15:13:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'tests': ['tp5o']}, 15:13:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'talos_options': ['--disable-e10s', 15:13:34 INFO - '--xperf_path', 15:13:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:34 INFO - 'tests': ['tp5n']}, 15:13:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:13:34 INFO - 'talos_options': ['--xperf_path', 15:13:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:13:34 INFO - 'tests': ['tp5n']}}, 15:13:34 INFO - 'talos.zip': {'path': '', 15:13:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:13:34 INFO - Running post-action listener: _resource_record_post_action 15:13:34 INFO - [mozharness: 2016-11-11 23:13:34.855883Z] Finished populate-webroot step (success) 15:13:34 INFO - [mozharness: 2016-11-11 23:13:34.856007Z] Running create-virtualenv step. 15:13:34 INFO - Running pre-action listener: _resource_record_pre_action 15:13:34 INFO - Running main action method: create_virtualenv 15:13:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:13: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 15:13:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:13:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:13:34 INFO - Using real prefix '/tools/python27' 15:13:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:13:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:13:38 INFO - Installing pip.................done. 15:13:38 INFO - Return code: 0 15:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:13:38 INFO - Reading from file tmpfile_stdout 15:13:38 INFO - Output received: 15:13: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) 15:13:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:38 INFO - 'HOME': '/Users/cltbld', 15:13:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:38 INFO - 'LOGNAME': 'cltbld', 15:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:38 INFO - 'MOZ_NO_REMOTE': '1', 15:13:38 INFO - 'NO_EM_RESTART': '1', 15:13:38 INFO - 'PAGER': '/bin/cat', 15:13:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:38 INFO - 'PWD': '/builds/slave/test', 15:13:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:38 INFO - 'SHELL': '/bin/bash', 15:13:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:38 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:38 INFO - 'USER': 'cltbld', 15:13:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:38 INFO - 'XPC_FLAGS': '0x0', 15:13:38 INFO - 'XPC_SERVICE_NAME': '0', 15:13:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13: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 15:13:39 INFO - Cleaning up... 15:13:39 INFO - Return code: 0 15:13:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:39 INFO - 'HOME': '/Users/cltbld', 15:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:39 INFO - 'LOGNAME': 'cltbld', 15:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:39 INFO - 'MOZ_NO_REMOTE': '1', 15:13:39 INFO - 'NO_EM_RESTART': '1', 15:13:39 INFO - 'PAGER': '/bin/cat', 15:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:39 INFO - 'PWD': '/builds/slave/test', 15:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:39 INFO - 'SHELL': '/bin/bash', 15:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:39 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:39 INFO - 'USER': 'cltbld', 15:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:39 INFO - 'XPC_FLAGS': '0x0', 15:13:39 INFO - 'XPC_SERVICE_NAME': '0', 15:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:39 INFO - Downloading/unpacking psutil>=3.1.1 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:13:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:42 INFO - Installing collected packages: psutil 15:13:42 INFO - Running setup.py install for psutil 15:13:42 INFO - building 'psutil._psutil_osx' extension 15:13: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 15:13: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 15:13: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 15:13: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 15:13:43 INFO - building 'psutil._psutil_posix' extension 15:13: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 15:13:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:13:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:13:43 INFO - ^ 15:13:43 INFO - 1 warning generated. 15:13: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 15:13:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:13:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:13:43 INFO - Successfully installed psutil 15:13:43 INFO - Cleaning up... 15:13:43 INFO - Return code: 0 15:13:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:43 INFO - 'HOME': '/Users/cltbld', 15:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:43 INFO - 'LOGNAME': 'cltbld', 15:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:43 INFO - 'MOZ_NO_REMOTE': '1', 15:13:43 INFO - 'NO_EM_RESTART': '1', 15:13:43 INFO - 'PAGER': '/bin/cat', 15:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:43 INFO - 'PWD': '/builds/slave/test', 15:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:43 INFO - 'SHELL': '/bin/bash', 15:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:43 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:43 INFO - 'USER': 'cltbld', 15:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:43 INFO - 'XPC_FLAGS': '0x0', 15:13:43 INFO - 'XPC_SERVICE_NAME': '0', 15:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:13:44 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 15:13:44 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 15:13:44 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 15:13:44 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 15:13:44 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 15:13:44 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 15:13:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:13:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:13:46 INFO - Installing collected packages: mozsystemmonitor 15:13:46 INFO - Running setup.py install for mozsystemmonitor 15:13:46 INFO - Successfully installed mozsystemmonitor 15:13:46 INFO - Cleaning up... 15:13:47 INFO - Return code: 0 15:13:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13:47 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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13:47 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 15:13:47 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 15:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:47 INFO - 'HOME': '/Users/cltbld', 15:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:47 INFO - 'LOGNAME': 'cltbld', 15:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:47 INFO - 'MOZ_NO_REMOTE': '1', 15:13:47 INFO - 'NO_EM_RESTART': '1', 15:13:47 INFO - 'PAGER': '/bin/cat', 15:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:47 INFO - 'PWD': '/builds/slave/test', 15:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:47 INFO - 'SHELL': '/bin/bash', 15:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:47 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:47 INFO - 'USER': 'cltbld', 15:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:47 INFO - 'XPC_FLAGS': '0x0', 15:13:47 INFO - 'XPC_SERVICE_NAME': '0', 15:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:47 INFO - Downloading/unpacking jsonschema==2.5.1 15:13:47 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 15:13:47 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 15:13:47 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 15:13:47 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 15:13:47 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 15:13:47 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 15:13:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:13:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13:50 INFO - Downloading functools32-3.2.3-2.tar.gz 15:13:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:13:50 INFO - warning: no files found matching '*.txt' 15:13:50 INFO - no previously-included directories found matching 'build' 15:13:50 INFO - no previously-included directories found matching 'dist' 15:13:50 INFO - no previously-included directories found matching '.git*' 15:13:50 INFO - Installing collected packages: jsonschema, functools32 15:13:50 INFO - Running setup.py install for functools32 15:13:50 INFO - warning: no files found matching '*.txt' 15:13:50 INFO - no previously-included directories found matching 'build' 15:13:50 INFO - no previously-included directories found matching 'dist' 15:13:50 INFO - no previously-included directories found matching '.git*' 15:13:50 INFO - Successfully installed jsonschema functools32 15:13:50 INFO - Cleaning up... 15:13:50 INFO - Return code: 0 15:13:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:50 INFO - 'HOME': '/Users/cltbld', 15:13:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:50 INFO - 'LOGNAME': 'cltbld', 15:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:50 INFO - 'MOZ_NO_REMOTE': '1', 15:13:50 INFO - 'NO_EM_RESTART': '1', 15:13:50 INFO - 'PAGER': '/bin/cat', 15:13:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:50 INFO - 'PWD': '/builds/slave/test', 15:13:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:50 INFO - 'SHELL': '/bin/bash', 15:13:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:50 INFO - 'USER': 'cltbld', 15:13:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:50 INFO - 'XPC_FLAGS': '0x0', 15:13:50 INFO - 'XPC_SERVICE_NAME': '0', 15:13:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:13:50 INFO - Cleaning up... 15:13:50 INFO - Return code: 0 15:13:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:50 INFO - 'HOME': '/Users/cltbld', 15:13:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:50 INFO - 'LOGNAME': 'cltbld', 15:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:50 INFO - 'MOZ_NO_REMOTE': '1', 15:13:50 INFO - 'NO_EM_RESTART': '1', 15:13:50 INFO - 'PAGER': '/bin/cat', 15:13:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:50 INFO - 'PWD': '/builds/slave/test', 15:13:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:50 INFO - 'SHELL': '/bin/bash', 15:13:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:50 INFO - 'USER': 'cltbld', 15:13:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:50 INFO - 'XPC_FLAGS': '0x0', 15:13:50 INFO - 'XPC_SERVICE_NAME': '0', 15:13:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:51 INFO - Downloading/unpacking blobuploader==1.2.4 15:13:51 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 15:13:51 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 15:13:51 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 15:13:51 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 15:13:51 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 15:13:51 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 15:13:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:13:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:13:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13: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 15:13:54 INFO - Downloading docopt-0.6.1.tar.gz 15:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:13:54 INFO - Installing collected packages: blobuploader, requests, docopt 15:13:54 INFO - Running setup.py install for blobuploader 15:13:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:13:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:13:55 INFO - Running setup.py install for requests 15:13:55 INFO - Running setup.py install for docopt 15:13:55 INFO - Successfully installed blobuploader requests docopt 15:13:55 INFO - Cleaning up... 15:13:55 INFO - Return code: 0 15:13:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:13: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')]} 15:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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')]} 15:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:13: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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:13: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 15:13: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 15:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:13:55 INFO - 'HOME': '/Users/cltbld', 15:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:13:55 INFO - 'LOGNAME': 'cltbld', 15:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:13:55 INFO - 'MOZ_NO_REMOTE': '1', 15:13:55 INFO - 'NO_EM_RESTART': '1', 15:13:55 INFO - 'PAGER': '/bin/cat', 15:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:13:55 INFO - 'PWD': '/builds/slave/test', 15:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:13:55 INFO - 'SHELL': '/bin/bash', 15:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:13:55 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:13:55 INFO - 'USER': 'cltbld', 15:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:13:55 INFO - 'XPC_FLAGS': '0x0', 15:13:55 INFO - 'XPC_SERVICE_NAME': '0', 15:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-97MPbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eLrqpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-LX5FjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-75Ossu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-8Tsh0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-W_VyKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-aham60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-KV2oLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-wXrj7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:13:56 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Ot6x1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-1owZNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-oB6e4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-k6dI4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-bN88DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-u13e2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-lT5ufR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:13:57 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-1cv0FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:13:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:13:57 INFO - Running setup.py install for manifestparser 15:13:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:13:57 INFO - Running setup.py install for mozcrash 15:13:58 INFO - Running setup.py install for mozdebug 15:13:58 INFO - Running setup.py install for mozdevice 15:13:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Running setup.py install for mozfile 15:13:58 INFO - Running setup.py install for mozhttpd 15:13:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Running setup.py install for mozinfo 15:13:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Running setup.py install for mozInstall 15:13:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:13:58 INFO - Running setup.py install for mozleak 15:13:59 INFO - Running setup.py install for mozlog 15:13:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Running setup.py install for moznetwork 15:13:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Running setup.py install for mozprocess 15:13:59 INFO - Running setup.py install for mozprofile 15:13:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Running setup.py install for mozrunner 15:13:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:13:59 INFO - Running setup.py install for mozscreenshot 15:14:00 INFO - Running setup.py install for moztest 15:14:00 INFO - Running setup.py install for mozversion 15:14:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:14:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:14:00 INFO - Cleaning up... 15:14:00 INFO - Return code: 0 15:14:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14: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')]} 15:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14: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')]} 15:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:01 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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:14:01 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 15:14:01 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 15:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:01 INFO - 'HOME': '/Users/cltbld', 15:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:01 INFO - 'LOGNAME': 'cltbld', 15:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:01 INFO - 'MOZ_NO_REMOTE': '1', 15:14:01 INFO - 'NO_EM_RESTART': '1', 15:14:01 INFO - 'PAGER': '/bin/cat', 15:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:01 INFO - 'PWD': '/builds/slave/test', 15:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:01 INFO - 'SHELL': '/bin/bash', 15:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:14:01 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:01 INFO - 'USER': 'cltbld', 15:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:01 INFO - 'XPC_FLAGS': '0x0', 15:14:01 INFO - 'XPC_SERVICE_NAME': '0', 15:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-DehTyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:14:01 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)) 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-049jzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:14:01 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)) 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cEiBKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:14:01 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)) 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-qHzMcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:14:01 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)) 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vRVDAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:14:01 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)) 15:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:14:01 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-piboSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-d9BlxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-MuJVMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-53YASn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eFWu_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zelkJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-n8hS6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-390uvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-uizYJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:14:02 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)) 15:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:02 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-GP87U8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:14:03 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)) 15:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:14:03 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-b7PAZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:14:03 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)) 15:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:14:03 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-layN1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:14:03 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)) 15:14:03 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)) 15:14:03 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)) 15:14:03 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)) 15:14:03 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)) 15:14:03 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)) 15:14:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:14:03 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 15:14:03 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 15:14:03 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 15:14:03 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 15:14:03 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 15:14:03 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 15:14:06 INFO - Downloading blessings-1.6.tar.gz 15:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:14:06 INFO - Installing collected packages: blessings 15:14:06 INFO - Running setup.py install for blessings 15:14:06 INFO - Successfully installed blessings 15:14:06 INFO - Cleaning up... 15:14:06 INFO - Return code: 0 15:14:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:14:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:06 INFO - Reading from file tmpfile_stdout 15:14:06 INFO - Current package versions: 15:14:06 INFO - blessings == 1.6 15:14:06 INFO - blobuploader == 1.2.4 15:14:06 INFO - docopt == 0.6.1 15:14:06 INFO - functools32 == 3.2.3-2 15:14:06 INFO - jsonschema == 2.5.1 15:14:06 INFO - manifestparser == 1.1 15:14:06 INFO - mozInstall == 1.12 15:14:06 INFO - mozcrash == 1.0 15:14:06 INFO - mozdebug == 0.1 15:14:06 INFO - mozdevice == 0.48 15:14:06 INFO - mozfile == 1.2 15:14:06 INFO - mozhttpd == 0.7 15:14:06 INFO - mozinfo == 0.9 15:14:06 INFO - mozleak == 0.1 15:14:06 INFO - mozlog == 3.4 15:14:06 INFO - moznetwork == 0.27 15:14:06 INFO - mozprocess == 0.23 15:14:06 INFO - mozprofile == 0.28 15:14:06 INFO - mozrunner == 6.13 15:14:06 INFO - mozscreenshot == 0.1 15:14:06 INFO - mozsystemmonitor == 0.3 15:14:06 INFO - moztest == 0.7 15:14:06 INFO - mozversion == 1.4 15:14:06 INFO - psutil == 3.1.1 15:14:06 INFO - requests == 1.2.3 15:14:06 INFO - wsgiref == 0.1.2 15:14:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:06 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 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:14:06 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 15:14:06 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 15:14:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:14:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:06 INFO - 'HOME': '/Users/cltbld', 15:14:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:06 INFO - 'LOGNAME': 'cltbld', 15:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:06 INFO - 'MOZ_NO_REMOTE': '1', 15:14:06 INFO - 'NO_EM_RESTART': '1', 15:14:06 INFO - 'PAGER': '/bin/cat', 15:14:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:06 INFO - 'PWD': '/builds/slave/test', 15:14:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:06 INFO - 'SHELL': '/bin/bash', 15:14:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:14:06 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:14:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:06 INFO - 'USER': 'cltbld', 15:14:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:06 INFO - 'XPC_FLAGS': '0x0', 15:14:06 INFO - 'XPC_SERVICE_NAME': '0', 15:14:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 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)) 15:14:07 INFO - Cleaning up... 15:14:07 INFO - Return code: 0 15:14:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:14:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100915490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10088b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8024c0370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, '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.LxTNY5d6FN/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.tK67Pad9J0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 15:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:07 INFO - 'HOME': '/Users/cltbld', 15:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:07 INFO - 'LOGNAME': 'cltbld', 15:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:07 INFO - 'MOZ_NO_REMOTE': '1', 15:14:07 INFO - 'NO_EM_RESTART': '1', 15:14:07 INFO - 'PAGER': '/bin/cat', 15:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:07 INFO - 'PWD': '/builds/slave/test', 15:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:07 INFO - 'SHELL': '/bin/bash', 15:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:14:07 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:07 INFO - 'USER': 'cltbld', 15:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:07 INFO - 'XPC_FLAGS': '0x0', 15:14:07 INFO - 'XPC_SERVICE_NAME': '0', 15:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:14:07 INFO - Cleaning up... 15:14:07 INFO - Return code: 0 15:14:07 INFO - Running post-action listener: _resource_record_post_action 15:14:07 INFO - Running post-action listener: _start_resource_monitoring 15:14:07 INFO - Starting resource monitoring. 15:14:07 INFO - [mozharness: 2016-11-11 23:14:07.351324Z] Finished create-virtualenv step (success) 15:14:07 INFO - [mozharness: 2016-11-11 23:14:07.352308Z] Running install step. 15:14:07 INFO - Running pre-action listener: _resource_record_pre_action 15:14:07 INFO - Running main action method: install 15:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:14:07 INFO - Reading from file tmpfile_stdout 15:14:07 INFO - Detecting whether we're running mozinstall >=1.0... 15:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:14:07 INFO - Reading from file tmpfile_stdout 15:14:07 INFO - Output received: 15:14:07 INFO - Usage: mozinstall [options] installer 15:14:07 INFO - Options: 15:14:07 INFO - -h, --help show this help message and exit 15:14:07 INFO - -d DEST, --destination=DEST 15:14:07 INFO - Directory to install application into. [default: 15:14:07 INFO - "/builds/slave/test"] 15:14:07 INFO - --app=APP Application being installed. [default: firefox] 15:14:07 INFO - mkdir: /builds/slave/test/build/application 15:14:07 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'] 15:14:07 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 15:14:41 INFO - Reading from file tmpfile_stdout 15:14:41 INFO - Output received: 15:14:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:14:41 INFO - Running post-action listener: _resource_record_post_action 15:14:41 INFO - [mozharness: 2016-11-11 23:14:41.338658Z] Finished install step (success) 15:14:41 INFO - [mozharness: 2016-11-11 23:14:41.338825Z] Running run-tests step. 15:14:41 INFO - Running pre-action listener: _resource_record_pre_action 15:14:41 INFO - Running main action method: run_tests 15:14:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:14:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:14:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:14:41 INFO - 2016-11-11 15:14:41.353 screenresolution[1646:7707] starting screenresolution argv=screenresolution get 15:14:41 INFO - 2016-11-11 15:14:41.356 screenresolution[1646:7707] Display 0: 1600x1200x32@60 15:14:41 INFO - 2016-11-11 15:14:41.361 screenresolution[1647:7709] starting screenresolution argv=screenresolution list 15:14:41 INFO - Available Modes on Display 0 15:14:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:14:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:14:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:14:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:14:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:14:41 INFO - Intel Iris: 15:14:41 INFO - Chipset Model: Intel Iris 15:14:41 INFO - Type: GPU 15:14:41 INFO - Bus: Built-In 15:14:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:14:41 INFO - Vendor: Intel (0x8086) 15:14:41 INFO - Device ID: 0x0a2e 15:14:41 INFO - Revision ID: 0x0009 15:14:41 INFO - Displays: 15:14:41 INFO - Display: 15:14:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:14:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:14:41 INFO - Display Serial Number: 1600x1200 60 15:14:41 INFO - Main Display: Yes 15:14:41 INFO - Mirror: Off 15:14:41 INFO - Online: Yes 15:14:41 INFO - Rotation: Supported 15:14:41 INFO - Return code: 0 15:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:14:41 INFO - Python 2.7.3 15:14:41 INFO - Return code: 0 15:14:41 INFO - grabbing minidump binary from tooltool 15:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:14:41 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 0x100729d30>, '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 0x10054fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100721b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:14:41 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 15:14:41 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 15:14:41 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 15:14:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:14:41 INFO - Return code: 0 15:14:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:14:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:14:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:14:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:14:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 15:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --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 15:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LxTNY5d6FN/Render', 15:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:14:41 INFO - 'HOME': '/Users/cltbld', 15:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:14:41 INFO - 'LOGNAME': 'cltbld', 15:14:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:14:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:14:41 INFO - 'MOZ_NO_REMOTE': '1', 15:14:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:14:41 INFO - 'NO_EM_RESTART': '1', 15:14:41 INFO - 'PAGER': '/bin/cat', 15:14:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:14:41 INFO - 'PWD': '/builds/slave/test', 15:14:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:14:41 INFO - 'SHELL': '/bin/bash', 15:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tK67Pad9J0/Listeners', 15:14:41 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 15:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:14:41 INFO - 'USER': 'cltbld', 15:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:14:41 INFO - 'XPC_FLAGS': '0x0', 15:14:41 INFO - 'XPC_SERVICE_NAME': '0', 15:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:14:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 15:14:41 INFO - mozversion application_buildid: 20161111113020 15:14:41 INFO - mozversion application_changeset: 1edd75c67df0fb5e03f4746ae238603f03129807 15:14:41 INFO - mozversion application_display_name: Nightly 15:14:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:14:41 INFO - mozversion application_name: Firefox 15:14:41 INFO - mozversion application_remotingname: firefox 15:14:41 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:14:41 INFO - mozversion application_vendor: Mozilla 15:14:41 INFO - mozversion application_version: 52.0a1 15:14:41 INFO - mozversion platform_buildid: 20161111113020 15:14:41 INFO - mozversion platform_changeset: 1edd75c67df0fb5e03f4746ae238603f03129807 15:14:41 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:14:41 INFO - mozversion platform_version: 52.0a1 15:14:41 INFO - using testdate: 1478906081 15:14:41 INFO - actual date: 1478906081 15:14:41 INFO - starting webserver on 'localhost:49274' 15:14:41 INFO - SUITE-START | Running 2 tests 15:14:41 INFO - TEST-START | dromaeo_css 15:14:41 INFO - operating with platform_type : mac_ 15:14:41 INFO - Initialising browser for dromaeo_css test... 15:14:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpN9A1vF/profile http://localhost:49274/getInfo.html) 15:14:44 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 15:14:44 INFO - PROCESS | 1657 | colorDepth:24 15:14:44 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 15:14:44 INFO - PROCESS | 1657 | __metrics 15:14:44 INFO - PROCESS | 1657 | 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" 15:14:44 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:14:45 INFO - TEST-INFO | 1657: exit 0 15:14:45 INFO - Browser initialized. 15:14:45 INFO - Running cycle 1/1 for dromaeo_css test... 15:14:45 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpN9A1vF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:14:57 INFO - PROCESS | 1659 | RSS: Main: 240660480 15:14:57 INFO - PROCESS | 1659 | 15:15:49 INFO - PROCESS | 1659 | 1478906149255 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] 15:18:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 15:18:04 INFO - PROCESS | 1659 | RSS: Main: 373248000 15:18:04 INFO - PROCESS | 1659 | 15:21:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 15:21:07 INFO - PROCESS | 1659 | RSS: Main: 373968896 15:21:07 INFO - PROCESS | 1659 | 15:24:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 15:24:10 INFO - PROCESS | 1659 | RSS: Main: 337661952 15:24:10 INFO - PROCESS | 1659 | 15:27:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 15:27:13 INFO - PROCESS | 1659 | RSS: Main: 339046400 15:27:13 INFO - PROCESS | 1659 | 15:30:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 15:30:17 INFO - PROCESS | 1659 | RSS: Main: 332288000 15:30:17 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 15:33:21 INFO - PROCESS | 1659 | RSS: Main: 389074944 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | __start_tp_report 15:33:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:33:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:33:21 INFO - PROCESS | 1659 | |i|pagename|runs| 15:33:21 INFO - PROCESS | 1659 | |0;dojo.html;3271.5583396241504;3273.7053665170138;3285.6714328567145;3326.617438994513;3339.0817525180573;5467.234587157791;5541.002619528494;5553.25031120799;5638.774449022039;5647.745851436685;5551.195076713874;5596.188552659862;5598.524125449342;5620.943790562093;5630.211770352154;5043.798248070073;5092.388913330406;5106.234064890259;5118;5171.793128274864;5128.897422051576;5205.958808238349;5209.531142197202;5209.921851172236;5212.791488340462;5070.518300761421;5121.5390614844655;5130.179171332587;5156.53591176794;5193.818216362428;5185.403678576974;5195.688258704452;5197.168453047494;5205.427402985669;5254.369475662907;5008.8998220035755;5014.623903207274;5126.333576635051;5145.099607568698;5157.226416037587;5506.972465137689;5526.226328314039;5565.777368905239;5576.163575463673;5676.063449530822;6115.602485838451;6120.5103591713005;6125.632462052291;6134.294556126013;6137.846553836145;6034.064719968412;6150.7232174552255;6201.596896201732;6239.469645080174;6254.687265636746;5597.832065038015;5656.406077361818;5666.291713535808;5667.1782591524;5690.800821971211;6291.748330066837;6301.243850737849;6322.304546499881;6326.746930122836;6332.398422149889;6006.789762358297;6027.397260274025;6027.939720602738;6028.125921741323;6029.638221706711;6047.3045599755715;6102.413372523176;6105.786297479567;6123.4488895999575;6179.196704428396;5480.451954804487;5775.653460792366;6121.387861213843;6137.328339575531;6157.630542167484;6126.356732543108;6153.230846144232;6168;6171.13604095427;6176.011838105882;4774.594159496412;4813.133635945494;4870.829520966749;4879.634027447955;4906.067847109038;4321.243782338078;4388.9122217556105;4410.5368936261875;4414.977925110418;4426.645868330525;4466.419365482531;5039.143345631252;5634.549236061105;5645.125005624135;5656.2929633795775;6102.277183976845;6115.483736156635;6131.110988906673;6139.447449729546;6139.956207444745;5555.388907220203;5615.803446879339;5618.831435056954;5621.381648018714;5622.044252477089;4366.934495982563;4995.376502636657;5265.6840589564745;5287.656302340336;5302.310699609026;6220.657863817533;6243.75024999004;6256.0303153011355;6260.780872669435;6283.277423096402;5265.947340526699;6146.5390095742105;6210.7515699373325;6219.689015549295;6219.937800622117;6303.180586523723;6341.8097457076365;6348.619082855043;6359.650219237987;6365.427111559797;5887.322957859901;5927.585069045125;5938.079597662369;6036.758529658764;6090.9390906090375;6113.5414843886;6171.228596425447;6194.225721784777;6199.16311297969;6205.689715514116;5834.358220595816;5863.970680146572;5866.970665146647;6005.309389420097;6043.214382130471;5960.850978725393;5980.073088671089;5983.521318294439;5983.970080149571;5988.341282458839;5449.3460784706085;5495.340559132929;5700.429957004267;5754.625949313281;5763.682997435181;5618.353889302783;5618.887622247614;5622.803201887914;5626.88746225065;5665.093585026376;5492.780288788564;5493.066178749542;5494.587905906993;5498;5499.9175012375645 15:33:21 INFO - PROCESS | 1659 | |1;ext.html;449.96625253106015;469.88252936765787;493.28227429090674;507.7638897912472;510.130227961326;30375;39180.824575262755;40652.98367540805;40742.98142546434;42882.7855860721;41437;41806;41833.95415114624;41852.37221441673;41863.58136418635;9266.212371948375;9454.81090378192;9462.1484066434;9472.242220622367;9479.81040379192;9302.511598144298;9398.530073496331;9420.905790942106;9430.858537121949;9442.10300021497;9385.718428447157;9422.623095076224;9448.433094014346;9457.007014263507;9484.430934143973;9368.110029547182;9436.716898493056;9438.00900905402;9444.622215111387;9491.477968711719;9317.347785655007;9386.765330866714;9401.858972115424;9413.670521531782;9457.66898158568;38760;39067.41398879019;39082.804585977174;39086;39143;2935.794494385394;3457.4640930655537;3488.9651103488895;3633.909152271188;3996.7402118862183;29536.409271814657;30319.048705259363;30606.693933060607;30812.8459357704;31007.534886976715;3165.667604901498;3185.5380969759253;3322.5846769153854;3360.4791257354905;3387.1532116970757;478734;480996.5950170262;481252;481800;481999;56934;57394.426055738906;57503.712481437324;57624.42375576274;57751.224877511915;60659.69670151621;60687.78624427575;60781.882717591834;60791.696041519506;60995.6950215255;15498.30257638409;15737.68524629501;15756.897017208914;15765.527034188992;15773.921130394274;11900;11934.88065119355;11959.342236176919;11966.82049769254;11967.521299147937;15095.39618415251;15747.763783543256;15792.68414631701;15801.051936883823;15815.762763558556;5170.708312100947;6128.387161283925;6137.662428566382;6168;6180.6909654517995;879.5997820991433;907.7276816954887;991.4398364923795;1040.469360626071;1089.7765957978595;29178.124656260345;29198.1822999207;29497.557536636967;30231.79072837106;30796.692033079384;843.6077224090827;983.8573406856109;1020.4642562654666;1035.1264211776709;1094.885037071112;14067.788983165261;14665.926670366793;14802.481913133204;15107.244637768288;15285.312160952784;622517;623712;627648;630394;630879;54581;54743.45256547463;54842.725786370815;54862;54961;1210.3827048205303;1251.8748125187408;1328.0504439325928;1353.1813252982001;1379.799929010288;28653.426931460835;30868.53697194499;31226;31277.06168814939;31436.685633143377;53826.461735382145;54180;54214.457855420944;54229.45770542244;54248.45751542434;608022;612437;614867;616546;616720;25154.993800247787;25195.74804251934;25691.614625780254;25863.741362586134;26850.462990740467;41125;44364.556354437336;44767.55232447634;45683.77158114188;45719;37759.244815103;37985.81007094947;38477.80761096289;39422.40866386949;40895.591044089175;34604.48093278653;36710;37536.624633753316;37950.62049379471;38651.61348386593;30170;36709.08227294339;37801.62198377981;37962.810185949995;38894.2221155581;31423.84288078545;32705.18237044093;32720.836395817867;32797.508037379936;32834;24478.51042979095;24633.014679412623;24718;24725.258242252763;25467.490650186523 15:33:21 INFO - PROCESS | 1659 | |2;jquery.html;369.3702237684747;371.4687996165482;377.47719408619065;388.5279385546564;398.46008658268045;18372.936930812833;19744.210231590754;20143.798562014377;20429.48926276842;20607.86656733879;19313.517162070955;19610.803891961077;20164.798352016474;20178.19287228509;20303;5068.645194836363;5697.803461273127;5836.854078648025;5907.025340818781;5981.431763982415;4051.4530538377235;5621.381648018714;5908.02517584598;5909;5916.142159386886;4267.061246525759;4311.784410779464;6119.847003824895;6374.458171055466;6434.485241180694;6202.627842329474;6445.83885402864;6473.158489396372;6493.480521558287;6512.6092434454085;5616.887662246773;5623.831285061455;5624.156376543551;5624.521915637176;5629.60592758507;20394.98025098739;20560.897195514077;20774.688379674317;20796.48008799777;22704.864756762097;2042.3974927396305;2091.362134548962;2128.435964469417;2139.7539282982502;2904.867101830288;9860.852087218698;9927.602895884156;9994.000599939947;10161.18710503158;10276.229282803744;1855.1929910488846;1896.6111947050822;1914.2056046740586;1927.0846347984595;1943.2129987355145;111376;117861.41069294684;118082;118317;118510;11332.830007549894;11341.773164536828;11513.53945842157;11519.942400287944;11654.70863228426;14377.928110359382;14394.352254148589;14395.712085758227;14404.351804168837;14425.42298308056;8002.8799568006525;8334.458260213067;8371.958140209383;8482.703105391281;8502.78743031429;7992.600369981477;8025.839483210303;8098.473599216032;8162.469439486533;8344.791380215422;3568.964310356915;4373.409589705413;4400.757958312323;4424.823007079745;4523.705959112713;1717.1414292853574;1723.1436185021905;1778.9644207115784;1780.7952085510074;1802.9008404537876;788.6017561131706;905.1717678324391;949.1362859797551;955.3742298794301;983.311681822727;18810.717839232424;19990.400287991382;20426.182952681727;20454.284100056426;20569.137795728144;861.1560670542904;908.1463424381193;914.6965574056942;914.8124634449922;944.4333399960107;10409.843852342221;10680.252382333318;10801.243912926177;10804.513796879159;10825.729356765987;568278.3172168225;571831;575822;588398;588430;130542;131353;133353.33323333322;136001;139476;925.042580928749;926.1294383279695;947.7772723409993;950.3442624589012;950.7528042708807;17535.649287013934;18156.27374904989;18293;18295.438638590873;18356.17397217102;42796;50418;51611.48388516217;52561.68595785136;53603.73198134141;69731.95402068872;75377.6231118841;75772.62113689397;76963.61518192373;78040;6894.517383783288;7239.131304243525;7305.817354566178;7317.524360916524;7480.4015678746655;9832;10669.946650266698;10896.94551527269;12645.178063425848;12747;10794.622188223375;11062.446877655924;11359.886401136213;11525.423728813694;11574.826377604511;10682.412467314372;10862.837057443987;10910.78178436411;11262.718432038937;11478.713032174262;9931.155851752683;10342.224333175192;10424.947875260576;10585.947070264907;11230.775384492428;7276.672549735381;7345.853082938418;7422.517536360119;7482.807418257609;7497.2877576630135;9538.85691714611;10979.231453798158;11113.333200008025;11153.83269250978;11347.602833900773 15:33:21 INFO - PROCESS | 1659 | |3;mootools.html;1470.4265336518756;1500.0399744163735;1650.199653168214;1803.9458816235517;1821.3352126473833;7998.800029999245;8393.622286997084;8406.369522285822;8416.579171041447;8422.747317580466;7010.964945175279;7011.3339232772805;7426.368758655521;7726.8454630907345;7988.840223195547;2292.3925159832615;2319.6288593824993;2328.5459335429596;2332.941676458085;2333.8599684018927;2304.331743794298;2333.708286464192;2352.9058837646476;2353.705786776653;2358.292512246328;1886.2549293029244;1961.7940116287798;1964.204497178645;2234.4637287051155;2320.060375547906;2297.954040919189;2328.5808554460277;2330.545543618995;2331.5220379822267;2333.07843401857;2318.084356679119;2354.5290941811704;2355.9646605300936;2356.7643235676296;2356.78788908999;8269.503829770232;8337.541435221061;8357.749267521984;8362.916370836274;8368.539730314831;5085.008423357447;5244.580433565306;5246.711430871301;5248.8687782805355;5255.237990491358;3229.9354012919844;3388.390089783838;3511.227529943434;3576.4635304704243;3578.910527236814;942.7501712046313;1047.0838016735356;1074.6185104288022;1078.9514471848786;1093.1801149138146;10556.683299501028;10858.782824343549;10882.61910833125;11023.61417350389;11037.668869933916;3912.9804350978634;4007.1785284016705;4165.91668166635;4177.603127702924;4301.677374196947;8737.475751454933;8740.606672699743;8747.300215982707;8751.737447876574;8757.47455152693;5115.667481613683;5127.717975511308;5130.87172820675;5133.050385678662;5135.563477104413;4541.6593755468475;4668.206404911173;4806.4712881583;4820.084184005028;4820.39745031871;3460.723142148622;3470.670286322795;3485.6165821759587;3533.0284171852945;3559.056849934769;1108.9741988660458;1116.179607988128;1117.586492997596;1126.1778901402022;1131.5813149134872;617.984550386244;725;759.9544027358265;791.3669064748293;870.4211699220075;4934.185859333242;5477.589180811376;5510.1459273812625;5551.833444996657;5649.350324712711;789.944703870735;866.8483015472267;908.9227415669742;912.598456679059;926.490430263366;3035.4536183487185;3781.4516895050065;3783.129880127586;3854.1713531590844;3920.3923391874305;9787.706368808947;10041.497925103715;10148.137408320375;10163.136133428594;10202.795944081077;9253.79700638926;9555.713328600154;10159;10187.847182292271;10193.592256309665;7464.290892365217;7709.691612335556;7785.883211751829;7790.5325680459355;7912.802179945433;4624.398828152319;4956.826511072095;5651.094606136515;5659.858503537444;5719.942800571941;9059.728208153503;9092.090790920854;9187.265018798615;9337.53312334367;9444.811103777749;8103;8676.479411235347;9555.665551705657;9586.520673966414;9649.710508684751;7527.736529221451;7676.53940763556;7800.1419843816;7870.212978702313;7947.046354437504;7929.881051784112;7948.682052717826;8019.318357939641;8026.959865200637;8031.518108913484;7080.079589653312;7103.502754807114;7146.178189508273;7198.49610527279;7273.3453989141235;6935.757248496278;7002.3697867192195;7008.159020917523;7051.576905385693;7081.716731330689;6563.048357987969;6754.425873800782;6763.628000459825;6764.2559318475915;6781.762638307636;4644.535546445463;4782.0914026334885;4853.466118727008;4920.114379411597;4945.060438516671;4195.454590903285;4760.571548560646;4816.4220293564995;5079.136546786981;5088.185890257689 15:33:21 INFO - PROCESS | 1659 | |4;prototype.html;276.8560348618718;306.8220432149352;309.20688434166357;312.9655737868834;316.98256595887216;5043.697378157313;5056.696598204106;5112.412072611651;5119.641625086246;5133.768980395882;4312.74123552587;4937.210476081021;5022.321986531817;5071.239314102886;5081.46644602317;3691.5754688210864;3780.281746468176;3805.904852378685;3840.4431357453154;3874.748141370816;3214.2161100589165;3771.264603402338;3859.9421008684894;3899.7660140391667;3945.526536815586;3310.668933106694;3851.6340947609933;3852.4782710829204;3856.5372155341397;3871.8644847430346;3166.335069635379;3303.8678452861855;3524.3259451760464;3868.8065596720194;3870.3807390817474;3361.226917808893;3386.4243078676686;3389.949115774093;3395.1172695099453;3395.1851555626718;4373.685971544589;4910.593809033584;4921.704697718148;4930.926036109461;4940.4318503372215;4058.6753059755147;4170;4263.744175349489;4397.516273209944;4407.228734971399;4550.0889626356675;5244.921326180111;5250.212468129773;5284.603654725873;5302.814401495968;3623.601403845575;3730.0674831292176;3737.9813100934593;3750.343689854292;3753.7935413552245;4811.735354555533;5081.339425874613;5305.867353316198;5505.476495043135;5563.36021357545;3292.3579901918897;3292.7280176678014;3558.217192217708;3613.602503724588;3776.981115094407;3478.756487045933;3814.6376094271;3839.5968423315708;3850.152966347399;3852.653261206505;3696.6303369663356;4158.251514727318;4401.493828209796;4415;4474.552544745565;4037.9798101009305;4484.417025786692;4887.826921538876;5314.73426328682;5349.946500535023;1058.6612284069179;1133.8979491845773;1190.9043679814592;1205.9318269168243;1224.9326287054118;810.4529442626203;832.5171400587644;837.9245867871799;842.9168518453757;843.7089204224533;4429.335599660077;4630.4443466784205;4722.220833562493;4850.320955066293;4869.172240719086;4682.859514214579;5161.509656582619;5317.54800841925;5371.4897084776885;5429.294191755125;4167.312393455107;4932.136876046678;4958.330625365529;4959.578435832923;4962.354893863775;2615.3592369869502;2630.278348885041;2675.100678518235;2695.8517281549703;2702.3919618085774;4720.88197795051;5305.31030965972;5313.760880760375;5333.57331413486;5336.065164159697;4258.87223383299;4486.509986957205;5256.6870576946785;5277.366715994105;5312.574994000471;5217.008988124088;5607.635503692247;5675.914861277084;5682.516986056232;5707.343655479673;4613.792379342871;4854.611631069577;4890.657653964188;5492.972535137299;5518.668879867221;5150.201718733602;5437.40188579248;5447.373552041566;5469.562435005269;5476.972615136899;4942.072591689172;5630.8029218977135;5631.436856314336;5688.943110569007;5695.544356451557;3252.6096868375;3707.9814600926825;3738.158914244273;3738.775184042015;3740.9625903740616;4578.427696537933;5286.12778891494;5456.781728730806;5493.395726470165;5504.284443022381;4395.472543294697;4571.908561828811;5145.897082058263;5335.306410166809;5342.519173274424;4390.560943905712;4414.713043652152;5302.3637163540625;5303;5328.4671532846405;4765.571098601143;4787.180871268895;4887.535684110004;4922.1140194765285;5227.477252274894;5133.922991155061;5195.493306940944;5286.312779338661;5313.654612450178;5362.383325917569;4107.330235347048;4431.872701103553;4622.6764126510825;5157.149070403341;5254.661675554342 15:33:21 INFO - PROCESS | 1659 | |5;yui.html;610.4993904997903;714.8070021094304;765.7779244019227;772.1159272632835;794.3486341200216;13500.18998860069;14252.643683907907;14272.072315299498;14305.070170438914;14312.570622881303;12781.616551503446;12888;12892.2909239992;12902.032347573922;12905.032122590796;653.6633633678659;675.966201689916;679.8606285711442;821.5317269156568;839.102160688064;678.5623272988919;678.8370791010146;680.8978653202009;802.1336956087441;841.2134654098437;867.3668222197799;874.6370256343641;880.6389380354057;882.8631562107852;883.4346018548134;820.0733171516179;820.5322965909434;840.3277378097498;877.8727084572765;882.6778225947547;652.670401447266;652.8433176037764;653.2911790707041;655.9540832141752;657.3623585122424;11056.613018544393;11550.422478876022;13137.34313284332;13592;13654.112482688593;13304.401301941436;13511.527096551574;13709.588712338646;13723.588292351245;13795.724085518234;9753.366031207948;9771.21830253578;9786.608535658565;9881.90118098817;9914.40513569181;3478.58257009158;3498.597661268961;3507.7369197310304;3532.3235323235376;3535.964640353615;68926;77046.84429733558;88577.55711221402;88755.66866497007;88994;10449.582016719398;10651.520681569347;10666.14670826332;10750.032497075144;10921.706441647184;20019.799802001795;20029.399118026482;20089.698654520194;20100;20108.798912010985;7490.588017658972;7718.807029824299;7789.610519474004;7873.330766884879;7873.685052597946;4119.732217405859;4291.871243862689;4300.634446072057;4394;4416.470023597189;3295.6045274567205;3432.6052503961864;3438.0889064397957;3442.208292092783;3442.8106454144763;602.9487493563096;606.5572132343326;629.1506466270498;641.9775307864203;664.9933500664931;541.8970395624818;610.9786157484467;626.746506986028;633.6926590603554;648.4391001783423;8702.956485217534;8872.467651940904;8876;9056.637734490678;9284.48935308551;563.5576072782927;621.6922623301494;627.2034516164446;651.9185101862267;671.9899201511981;4225.915481690349;4416.227160246944;4447.666425018072;4448.644108471314;4617;74234;74805.25194748092;75109.62445187739;109752.4512377433;117139;57085.85828283411;72788.63605681938;72888.63555682187;96278.51860740651;98579.50710246403;71873;73548;74537.62731186309;87445.56277218573;87644.56177719071;9001.504917229353;9489.810203796023;9525.952370238105;9585.424874507273;9758.414495130315;50919;52948.94102117859;54141.729291353295;54663.18005229845;56808.715956419954;67276;69487.3051269501;79998;80373;80770;12155.453004614637;12195.51217951272;12305.93847030759;12395.75208495843;12515.186512876633;13955;14441.422343106578;14726.263686815402;14854.85145148578;14889.106653600818;13021.609351719464;13795.448182072605;13892.097013694078;13917.58247252584;13925.651858703613;12942.482300707867;13277.203367797963;13504.594862154152;13572;13768.724625507233;12868.935655321664;13276.668083297609;13372.398242079324;13442.93278533601;13498.93250533741;11056.502457389597;11078.612248571262;11280.717982050515;11398.259113157617;11525.423728813357;11166.665000050012;11173.608923687632;11406.714832129263;11407.543698251977;11408.771824563628 15:33:21 INFO - PROCESS | 1659 | __end_tp_report 15:33:21 INFO - PROCESS | 1659 | __start_cc_report 15:33:21 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1364 15:33:21 INFO - PROCESS | 1659 | __end_cc_report 15:33:21 INFO - PROCESS | 1659 | __startTimestamp1478907201522__endTimestamp 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:33:21 INFO - PROCESS | 1659 | Number of tests: 6 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5606.12 Median:5656.29 stddev:642.53 (11.4%) stddev-sans-first:617.85 15:33:21 INFO - PROCESS | 1659 | Values: 3271.6 3273.7 3285.7 3326.6 3339.1 5467.2 5541.0 5553.3 5638.8 5647.7 5551.2 5596.2 5598.5 5620.9 5630.2 5043.8 5092.4 5106.2 5118.0 5171.8 5128.9 5206.0 5209.5 5209.9 5212.8 5070.5 5121.5 5130.2 5156.5 5193.8 5185.4 5195.7 5197.2 5205.4 5254.4 5008.9 5014.6 5126.3 5145.1 5157.2 5507.0 5526.2 5565.8 5576.2 5676.1 6115.6 6120.5 6125.6 6134.3 6137.8 6034.1 6150.7 6201.6 6239.5 6254.7 5597.8 5656.4 5666.3 5667.2 5690.8 6291.7 6301.2 6322.3 6326.7 6332.4 6006.8 6027.4 6027.9 6028.1 6029.6 6047.3 6102.4 6105.8 6123.4 6179.2 5480.5 5775.7 6121.4 6137.3 6157.6 6126.4 6153.2 6168.0 6171.1 6176.0 4774.6 4813.1 4870.8 4879.6 4906.1 4321.2 4388.9 4410.5 4415.0 4426.6 4466.4 5039.1 5634.5 5645.1 5656.3 6102.3 6115.5 6131.1 6139.4 6140.0 5555.4 5615.8 5618.8 5621.4 5622.0 4366.9 4995.4 5265.7 5287.7 5302.3 6220.7 6243.8 6256.0 6260.8 6283.3 5265.9 6146.5 6210.8 6219.7 6219.9 6303.2 6341.8 6348.6 6359.7 6365.4 5887.3 5927.6 5938.1 6036.8 6090.9 6113.5 6171.2 6194.2 6199.2 6205.7 5834.4 5864.0 5867.0 6005.3 6043.2 5960.9 5980.1 5983.5 5984.0 5988.3 5449.3 5495.3 5700.4 5754.6 5763.7 5618.4 5618.9 5622.8 5626.9 5665.1 5492.8 5493.1 5494.6 5498.0 5499.9 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70020.50 Median:22324.86 stddev:154541.60 (692.2%) stddev-sans-first:154886.85 15:33:21 INFO - PROCESS | 1659 | Values: 450.0 469.9 493.3 507.8 510.1 30375.0 39180.8 40653.0 40743.0 42882.8 41437.0 41806.0 41834.0 41852.4 41863.6 9266.2 9454.8 9462.1 9472.2 9479.8 9302.5 9398.5 9420.9 9430.9 9442.1 9385.7 9422.6 9448.4 9457.0 9484.4 9368.1 9436.7 9438.0 9444.6 9491.5 9317.3 9386.8 9401.9 9413.7 9457.7 38760.0 39067.4 39082.8 39086.0 39143.0 2935.8 3457.5 3489.0 3633.9 3996.7 29536.4 30319.0 30606.7 30812.8 31007.5 3165.7 3185.5 3322.6 3360.5 3387.2 478734.0 480996.6 481252.0 481800.0 481999.0 56934.0 57394.4 57503.7 57624.4 57751.2 60659.7 60687.8 60781.9 60791.7 60995.7 15498.3 15737.7 15756.9 15765.5 15773.9 11900.0 11934.9 11959.3 11966.8 11967.5 15095.4 15747.8 15792.7 15801.1 15815.8 5170.7 6128.4 6137.7 6168.0 6180.7 879.6 907.7 991.4 1040.5 1089.8 29178.1 29198.2 29497.6 30231.8 30796.7 843.6 983.9 1020.5 1035.1 1094.9 14067.8 14665.9 14802.5 15107.2 15285.3 622517.0 623712.0 627648.0 630394.0 630879.0 54581.0 54743.5 54842.7 54862.0 54961.0 1210.4 1251.9 1328.1 1353.2 1379.8 28653.4 30868.5 31226.0 31277.1 31436.7 53826.5 54180.0 54214.5 54229.5 54248.5 608022.0 612437.0 614867.0 616546.0 616720.0 25155.0 25195.7 25691.6 25863.7 26850.5 41125.0 44364.6 44767.6 45683.8 45719.0 37759.2 37985.8 38477.8 39422.4 40895.6 34604.5 36710.0 37536.6 37950.6 38651.6 30170.0 36709.1 37801.6 37962.8 38894.2 31423.8 32705.2 32720.8 32797.5 32834.0 24478.5 24633.0 24718.0 24725.3 25467.5 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34130.29 Median:370.42 stddev:96919.82 (26164.9%) stddev-sans-first:97158.55 15:33:21 INFO - PROCESS | 1659 | Values: 369.4 371.5 377.5 388.5 398.5 18372.9 19744.2 20143.8 20429.5 20607.9 19313.5 19610.8 20164.8 20178.2 20303.0 5068.6 5697.8 5836.9 5907.0 5981.4 4051.5 5621.4 5908.0 5909.0 5916.1 4267.1 4311.8 6119.8 6374.5 6434.5 6202.6 6445.8 6473.2 6493.5 6512.6 5616.9 5623.8 5624.2 5624.5 5629.6 20395.0 20560.9 20774.7 20796.5 22704.9 2042.4 2091.4 2128.4 2139.8 2904.9 9860.9 9927.6 9994.0 10161.2 10276.2 1855.2 1896.6 1914.2 1927.1 1943.2 111376.0 117861.4 118082.0 118317.0 118510.0 11332.8 11341.8 11513.5 11519.9 11654.7 14377.9 14394.4 14395.7 14404.4 14425.4 8002.9 8334.5 8372.0 8482.7 8502.8 7992.6 8025.8 8098.5 8162.5 8344.8 3569.0 4373.4 4400.8 4424.8 4523.7 1717.1 1723.1 1779.0 1780.8 1802.9 788.6 905.2 949.1 955.4 983.3 18810.7 19990.4 20426.2 20454.3 20569.1 861.2 908.1 914.7 914.8 944.4 10409.8 10680.3 10801.2 10804.5 10825.7 568278.3 571831.0 575822.0 588398.0 588430.0 130542.0 131353.0 133353.3 136001.0 139476.0 925.0 926.1 947.8 950.3 950.8 17535.6 18156.3 18293.0 18295.4 18356.2 42796.0 50418.0 51611.5 52561.7 53603.7 69732.0 75377.6 75772.6 76963.6 78040.0 6894.5 7239.1 7305.8 7317.5 7480.4 9832.0 10669.9 10896.9 12645.2 12747.0 10794.6 11062.4 11359.9 11525.4 11574.8 10682.4 10862.8 10910.8 11262.7 11478.7 9931.2 10342.2 10424.9 10585.9 11230.8 7276.7 7345.9 7422.5 7482.8 7497.3 9538.9 10979.2 11113.3 11153.8 11347.6 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5373.09 Median:5121.69 stddev:2964.51 (57.9%) stddev-sans-first:2958.32 15:33:21 INFO - PROCESS | 1659 | Values: 1470.4 1500.0 1650.2 1803.9 1821.3 7998.8 8393.6 8406.4 8416.6 8422.7 7011.0 7011.3 7426.4 7726.8 7988.8 2292.4 2319.6 2328.5 2332.9 2333.9 2304.3 2333.7 2352.9 2353.7 2358.3 1886.3 1961.8 1964.2 2234.5 2320.1 2298.0 2328.6 2330.5 2331.5 2333.1 2318.1 2354.5 2356.0 2356.8 2356.8 8269.5 8337.5 8357.7 8362.9 8368.5 5085.0 5244.6 5246.7 5248.9 5255.2 3229.9 3388.4 3511.2 3576.5 3578.9 942.8 1047.1 1074.6 1079.0 1093.2 10556.7 10858.8 10882.6 11023.6 11037.7 3913.0 4007.2 4165.9 4177.6 4301.7 8737.5 8740.6 8747.3 8751.7 8757.5 5115.7 5127.7 5130.9 5133.1 5135.6 4541.7 4668.2 4806.5 4820.1 4820.4 3460.7 3470.7 3485.6 3533.0 3559.1 1109.0 1116.2 1117.6 1126.2 1131.6 618.0 725.0 760.0 791.4 870.4 4934.2 5477.6 5510.1 5551.8 5649.4 789.9 866.8 908.9 912.6 926.5 3035.5 3781.5 3783.1 3854.2 3920.4 9787.7 10041.5 10148.1 10163.1 10202.8 9253.8 9555.7 10159.0 10187.8 10193.6 7464.3 7709.7 7785.9 7790.5 7912.8 4624.4 4956.8 5651.1 5659.9 5719.9 9059.7 9092.1 9187.3 9337.5 9444.8 8103.0 8676.5 9555.7 9586.5 9649.7 7527.7 7676.5 7800.1 7870.2 7947.0 7929.9 7948.7 8019.3 8027.0 8031.5 7080.1 7103.5 7146.2 7198.5 7273.3 6935.8 7002.4 7008.2 7051.6 7081.7 6563.0 6754.4 6763.6 6764.3 6781.8 4644.5 4782.1 4853.5 4920.1 4945.1 4195.5 4760.6 4816.4 5079.1 5088.2 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4242.36 Median:4721.55 stddev:1298.92 (27.5%) stddev-sans-first:1268.00 15:33:21 INFO - PROCESS | 1659 | Values: 276.9 306.8 309.2 313.0 317.0 5043.7 5056.7 5112.4 5119.6 5133.8 4312.7 4937.2 5022.3 5071.2 5081.5 3691.6 3780.3 3805.9 3840.4 3874.7 3214.2 3771.3 3859.9 3899.8 3945.5 3310.7 3851.6 3852.5 3856.5 3871.9 3166.3 3303.9 3524.3 3868.8 3870.4 3361.2 3386.4 3389.9 3395.1 3395.2 4373.7 4910.6 4921.7 4930.9 4940.4 4058.7 4170.0 4263.7 4397.5 4407.2 4550.1 5244.9 5250.2 5284.6 5302.8 3623.6 3730.1 3738.0 3750.3 3753.8 4811.7 5081.3 5305.9 5505.5 5563.4 3292.4 3292.7 3558.2 3613.6 3777.0 3478.8 3814.6 3839.6 3850.2 3852.7 3696.6 4158.3 4401.5 4415.0 4474.6 4038.0 4484.4 4887.8 5314.7 5349.9 1058.7 1133.9 1190.9 1205.9 1224.9 810.5 832.5 837.9 842.9 843.7 4429.3 4630.4 4722.2 4850.3 4869.2 4682.9 5161.5 5317.5 5371.5 5429.3 4167.3 4932.1 4958.3 4959.6 4962.4 2615.4 2630.3 2675.1 2695.9 2702.4 4720.9 5305.3 5313.8 5333.6 5336.1 4258.9 4486.5 5256.7 5277.4 5312.6 5217.0 5607.6 5675.9 5682.5 5707.3 4613.8 4854.6 4890.7 5493.0 5518.7 5150.2 5437.4 5447.4 5469.6 5477.0 4942.1 5630.8 5631.4 5688.9 5695.5 3252.6 3708.0 3738.2 3738.8 3741.0 4578.4 5286.1 5456.8 5493.4 5504.3 4395.5 4571.9 5145.9 5335.3 5342.5 4390.6 4414.7 5302.4 5303.0 5328.5 4765.6 4787.2 4887.5 4922.1 5227.5 5133.9 5195.5 5286.3 5313.7 5362.4 4107.3 4431.9 4622.7 5157.1 5254.7 15:33:21 INFO - PROCESS | 1659 | 15:33:21 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:19239.94 Median:54402.45 stddev:27128.54 (49.9%) stddev-sans-first:27168.58 15:33:21 INFO - PROCESS | 1659 | Values: 610.5 714.8 765.8 772.1 794.3 13500.2 14252.6 14272.1 14305.1 14312.6 12781.6 12888.0 12892.3 12902.0 12905.0 653.7 676.0 679.9 821.5 839.1 678.6 678.8 680.9 802.1 841.2 867.4 874.6 880.6 882.9 883.4 820.1 820.5 840.3 877.9 882.7 652.7 652.8 653.3 656.0 657.4 11056.6 11550.4 13137.3 13592.0 13654.1 13304.4 13511.5 13709.6 13723.6 13795.7 9753.4 9771.2 9786.6 9881.9 9914.4 3478.6 3498.6 3507.7 3532.3 3536.0 68926.0 77046.8 88577.6 88755.7 88994.0 10449.6 10651.5 10666.1 10750.0 10921.7 20019.8 20029.4 20089.7 20100.0 20108.8 7490.6 7718.8 7789.6 7873.3 7873.7 4119.7 4291.9 4300.6 4394.0 4416.5 3295.6 3432.6 3438.1 3442.2 3442.8 602.9 606.6 629.2 642.0 665.0 541.9 611.0 626.7 633.7 648.4 8703.0 8872.5 8876.0 9056.6 9284.5 563.6 621.7 627.2 651.9 672.0 4225.9 4416.2 4447.7 4448.6 4617.0 74234.0 74805.3 75109.6 109752.5 117139.0 57085.9 72788.6 72888.6 96278.5 98579.5 71873.0 73548.0 74537.6 87445.6 87644.6 9001.5 9489.8 9526.0 9585.4 9758.4 50919.0 52948.9 54141.7 54663.2 56808.7 67276.0 69487.3 79998.0 80373.0 80770.0 12155.5 12195.5 12305.9 12395.8 12515.2 13955.0 14441.4 14726.3 14854.9 14889.1 13021.6 13795.4 13892.1 13917.6 13925.7 12942.5 13277.2 13504.6 13572.0 13768.7 12868.9 13276.7 13372.4 13442.9 13498.9 11056.5 11078.6 11280.7 11398.3 11525.4 11166.7 11173.6 11406.7 11407.5 11408.8 15:33:21 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:33:21 INFO - PROCESS | 1659 | 15:33:22 INFO - TEST-INFO | 1659: exit 0 15:33:22 INFO - TEST-OK | dromaeo_css | took 1120261ms 15:33:22 INFO - TEST-START | kraken 15:33:22 INFO - operating with platform_type : mac_ 15:33:22 INFO - Initialising browser for kraken test... 15:33:22 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpwLicly/profile http://localhost:49274/getInfo.html) 15:33:24 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 15:33:24 INFO - PROCESS | 1670 | colorDepth:24 15:33:24 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 15:33:24 INFO - PROCESS | 1670 | __metrics 15:33:24 INFO - PROCESS | 1670 | 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" 15:33:24 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:33:25 INFO - TEST-INFO | 1670: exit 0 15:33:25 INFO - Browser initialized. 15:33:25 INFO - Running cycle 1/1 for kraken test... 15:33:25 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpwLicly/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:37 INFO - PROCESS | 1671 | RSS: Main: 240709632 15:33:37 INFO - PROCESS | 1671 | 15:33:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 15:33:41 INFO - PROCESS | 1671 | RSS: Main: 292306944 15:33:41 INFO - PROCESS | 1671 | 15:33:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 15:33:44 INFO - PROCESS | 1671 | RSS: Main: 294313984 15:33:44 INFO - PROCESS | 1671 | 15:33:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 15:33:48 INFO - PROCESS | 1671 | RSS: Main: 383131648 15:33:48 INFO - PROCESS | 1671 | 15:33:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 15:33:51 INFO - PROCESS | 1671 | RSS: Main: 287420416 15:33:51 INFO - PROCESS | 1671 | 15:33:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:33:54 INFO - PROCESS | 1671 | RSS: Main: 289390592 15:33:54 INFO - PROCESS | 1671 | 15:33:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 15:33:57 INFO - PROCESS | 1671 | RSS: Main: 325808128 15:33:57 INFO - PROCESS | 1671 | 15:34:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 15:34:00 INFO - PROCESS | 1671 | RSS: Main: 327737344 15:34:00 INFO - PROCESS | 1671 | 15:34:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 15:34:04 INFO - PROCESS | 1671 | RSS: Main: 326205440 15:34:04 INFO - PROCESS | 1671 | 15:34:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:34:06 INFO - PROCESS | 1671 | RSS: Main: 299069440 15:34:06 INFO - PROCESS | 1671 | 15:34:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:34:09 INFO - PROCESS | 1671 | RSS: Main: 302657536 15:34:09 INFO - PROCESS | 1671 | 15:34:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:34:11 INFO - PROCESS | 1671 | RSS: Main: 310038528 15:34:11 INFO - PROCESS | 1671 | 15:34:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:34:15 INFO - PROCESS | 1671 | RSS: Main: 322387968 15:34:15 INFO - PROCESS | 1671 | 15:34:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:34:19 INFO - PROCESS | 1671 | RSS: Main: 316219392 15:34:19 INFO - PROCESS | 1671 | 15:34:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 15:34:21 INFO - PROCESS | 1671 | RSS: Main: 359923712 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | __start_tp_report 15:34:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 15:34:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 15:34:21 INFO - PROCESS | 1671 | |i|pagename|runs| 15:34:21 INFO - PROCESS | 1671 | |0;ai-astar;116;115;124;170;152;116;115;115;113;114 15:34:21 INFO - PROCESS | 1671 | |1;audio-beat-detection;141;112;111;146;113;113;111;108;141;111 15:34:21 INFO - PROCESS | 1671 | |2;audio-dft;185;182;182;186;185;183;185;181;183;183 15:34:21 INFO - PROCESS | 1671 | |3;audio-fft;80;58;59;87;59;59;74;81;103;58 15:34:21 INFO - PROCESS | 1671 | |4;audio-oscillator;83;70;94;71;68;70;90;67;69;92 15:34:21 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;84;87;84;88;83;87;84;87;84 15:34:21 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;92;93;95;92;92;93;93;93 15:34:21 INFO - PROCESS | 1671 | |7;imaging-desaturate;81;84;80;74;93;119;85;84;81;81 15:34:21 INFO - PROCESS | 1671 | |8;json-parse-financial;58;57;56;55;53;54;55;54;53;55 15:34:21 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;74;74;70;69;69;69;70;68;70 15:34:21 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;88;87;91;90;111;107;83;87;91;92 15:34:21 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;146;149;137;148;156;151;149;149;184;147 15:34:21 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;174;170;172;171;172;172;199;240;170;174 15:34:21 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;66;67;68;66;66;66;66;66 15:34:21 INFO - PROCESS | 1671 | __end_tp_report 15:34:21 INFO - PROCESS | 1671 | __start_cc_report 15:34:21 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1979 15:34:21 INFO - PROCESS | 1671 | __end_cc_report 15:34:21 INFO - PROCESS | 1671 | __startTimestamp1478907261173__endTimestamp 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | ------- Summary: start ------- 15:34:21 INFO - PROCESS | 1671 | Number of tests: 14 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:125.00 Median:116.00 stddev:19.67 (17.0%) stddev-sans-first:20.59 15:34:21 INFO - PROCESS | 1671 | Values: 116.0 115.0 124.0 170.0 152.0 116.0 115.0 115.0 113.0 114.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:120.70 Median:113.00 stddev:15.28 (13.5%) stddev-sans-first:14.34 15:34:21 INFO - PROCESS | 1671 | Values: 141.0 112.0 111.0 146.0 113.0 113.0 111.0 108.0 141.0 111.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.66 15:34:21 INFO - PROCESS | 1671 | Values: 185.0 182.0 182.0 186.0 185.0 183.0 185.0 181.0 183.0 183.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:71.80 Median:66.50 stddev:15.75 (23.7%) stddev-sans-first:16.43 15:34:21 INFO - PROCESS | 1671 | Values: 80.0 58.0 59.0 87.0 59.0 59.0 74.0 81.0 103.0 58.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.00 stddev:11.04 (14.3%) stddev-sans-first:11.52 15:34:21 INFO - PROCESS | 1671 | Values: 83.0 70.0 94.0 71.0 68.0 70.0 90.0 67.0 69.0 92.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:1.84 (2.1%) stddev-sans-first:1.87 15:34:21 INFO - PROCESS | 1671 | Values: 87.0 84.0 87.0 84.0 88.0 83.0 87.0 84.0 87.0 84.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 15:34:21 INFO - PROCESS | 1671 | Values: 93.0 93.0 92.0 93.0 95.0 92.0 92.0 93.0 93.0 93.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:82.50 stddev:12.48 (15.1%) stddev-sans-first:13.09 15:34:21 INFO - PROCESS | 1671 | Values: 81.0 84.0 80.0 74.0 93.0 119.0 85.0 84.0 81.0 81.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:1.63 (3.0%) stddev-sans-first:1.32 15:34:21 INFO - PROCESS | 1671 | Values: 58.0 57.0 56.0 55.0 53.0 54.0 55.0 54.0 53.0 55.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:2.66 (3.8%) stddev-sans-first:2.18 15:34:21 INFO - PROCESS | 1671 | Values: 65.0 74.0 74.0 70.0 69.0 69.0 69.0 70.0 68.0 70.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:89.00 stddev:9.03 (10.1%) stddev-sans-first:9.42 15:34:21 INFO - PROCESS | 1671 | Values: 88.0 87.0 91.0 90.0 111.0 107.0 83.0 87.0 91.0 92.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:149.00 stddev:12.33 (8.3%) stddev-sans-first:12.91 15:34:21 INFO - PROCESS | 1671 | Values: 146.0 149.0 137.0 148.0 156.0 151.0 149.0 149.0 184.0 147.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:173.00 stddev:22.33 (12.9%) stddev-sans-first:23.52 15:34:21 INFO - PROCESS | 1671 | Values: 174.0 170.0 172.0 171.0 172.0 172.0 199.0 240.0 170.0 174.0 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.73 15:34:21 INFO - PROCESS | 1671 | Values: 68.0 67.0 66.0 67.0 68.0 66.0 66.0 66.0 66.0 66.0 15:34:21 INFO - PROCESS | 1671 | -------- Summary: end -------- 15:34:21 INFO - PROCESS | 1671 | 15:34:21 INFO - TEST-INFO | 1671: exit 0 15:34:22 INFO - TEST-OK | kraken | took 59832ms 15:34:22 INFO - SUITE-END | took 1180s 15:34:22 INFO - Completed test suite (00:19:40) 15:34:22 INFO - javascript benchmark 15:34:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.5583396241504, 3273.7053665170138, 3285.6714328567145, 3326.617438994513, 3339.0817525180573, 5467.234587157791, 5541.002619528494, 5553.25031120799, 5638.774449022039, 5647.745851436685, 5551.195076713874, 5596.188552659862, 5598.524125449342, 5620.943790562093, 5630.211770352154, 5043.798248070073, 5092.388913330406, 5106.234064890259, 5118.0, 5171.793128274864, 5128.897422051576, 5205.958808238349, 5209.531142197202, 5209.921851172236, 5212.791488340462, 5070.518300761421, 5121.5390614844655, 5130.179171332587, 5156.53591176794, 5193.818216362428, 5185.403678576974, 5195.688258704452, 5197.168453047494, 5205.427402985669, 5254.369475662907, 5008.8998220035755, 5014.623903207274, 5126.333576635051, 5145.099607568698, 5157.226416037587, 5506.972465137689, 5526.226328314039, 5565.777368905239, 5576.163575463673, 5676.063449530822, 6115.602485838451, 6120.5103591713005, 6125.632462052291, 6134.294556126013, 6137.846553836145, 6034.064719968412, 6150.7232174552255, 6201.596896201732, 6239.469645080174, 6254.687265636746, 5597.832065038015, 5656.406077361818, 5666.291713535808, 5667.1782591524, 5690.800821971211, 6291.748330066837, 6301.243850737849, 6322.304546499881, 6326.746930122836, 6332.398422149889, 6006.789762358297, 6027.397260274025, 6027.939720602738, 6028.125921741323, 6029.638221706711, 6047.3045599755715, 6102.413372523176, 6105.786297479567, 6123.4488895999575, 6179.196704428396, 5480.451954804487, 5775.653460792366, 6121.387861213843, 6137.328339575531, 6157.630542167484, 6126.356732543108, 6153.230846144232, 6168.0, 6171.13604095427, 6176.011838105882, 4774.594159496412, 4813.133635945494, 4870.829520966749, 4879.634027447955, 4906.067847109038, 4321.243782338078, 4388.9122217556105, 4410.5368936261875, 4414.977925110418, 4426.645868330525, 4466.419365482531, 5039.143345631252, 5634.549236061105, 5645.125005624135, 5656.2929633795775, 6102.277183976845, 6115.483736156635, 6131.110988906673, 6139.447449729546, 6139.956207444745, 5555.388907220203, 5615.803446879339, 5618.831435056954, 5621.381648018714, 5622.044252477089, 4366.934495982563, 4995.376502636657, 5265.6840589564745, 5287.656302340336, 5302.310699609026, 6220.657863817533, 6243.75024999004, 6256.0303153011355, 6260.780872669435, 6283.277423096402, 5265.947340526699, 6146.5390095742105, 6210.7515699373325, 6219.689015549295, 6219.937800622117, 6303.180586523723, 6341.8097457076365, 6348.619082855043, 6359.650219237987, 6365.427111559797, 5887.322957859901, 5927.585069045125, 5938.079597662369, 6036.758529658764, 6090.9390906090375, 6113.5414843886, 6171.228596425447, 6194.225721784777, 6199.16311297969, 6205.689715514116, 5834.358220595816, 5863.970680146572, 5866.970665146647, 6005.309389420097, 6043.214382130471, 5960.850978725393, 5980.073088671089, 5983.521318294439, 5983.970080149571, 5988.341282458839, 5449.3460784706085, 5495.340559132929, 5700.429957004267, 5754.625949313281, 5763.682997435181, 5618.353889302783, 5618.887622247614, 5622.803201887914, 5626.88746225065, 5665.093585026376, 5492.780288788564, 5493.066178749542, 5494.587905906993, 5498.0, 5499.9175012375645], "value": 5565.201511755744, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.96625253106015, 469.88252936765787, 493.28227429090674, 507.7638897912472, 510.130227961326, 30375.0, 39180.824575262755, 40652.98367540805, 40742.98142546434, 42882.7855860721, 41437.0, 41806.0, 41833.95415114624, 41852.37221441673, 41863.58136418635, 9266.212371948375, 9454.81090378192, 9462.1484066434, 9472.242220622367, 9479.81040379192, 9302.511598144298, 9398.530073496331, 9420.905790942106, 9430.858537121949, 9442.10300021497, 9385.718428447157, 9422.623095076224, 9448.433094014346, 9457.007014263507, 9484.430934143973, 9368.110029547182, 9436.716898493056, 9438.00900905402, 9444.622215111387, 9491.477968711719, 9317.347785655007, 9386.765330866714, 9401.858972115424, 9413.670521531782, 9457.66898158568, 38760.0, 39067.41398879019, 39082.804585977174, 39086.0, 39143.0, 2935.794494385394, 3457.4640930655537, 3488.9651103488895, 3633.909152271188, 3996.7402118862183, 29536.409271814657, 30319.048705259363, 30606.693933060607, 30812.8459357704, 31007.534886976715, 3165.667604901498, 3185.5380969759253, 3322.5846769153854, 3360.4791257354905, 3387.1532116970757, 478734.0, 480996.5950170262, 481252.0, 481800.0, 481999.0, 56934.0, 57394.426055738906, 57503.712481437324, 57624.42375576274, 57751.224877511915, 60659.69670151621, 60687.78624427575, 60781.882717591834, 60791.696041519506, 60995.6950215255, 15498.30257638409, 15737.68524629501, 15756.897017208914, 15765.527034188992, 15773.921130394274, 11900.0, 11934.88065119355, 11959.342236176919, 11966.82049769254, 11967.521299147937, 15095.39618415251, 15747.763783543256, 15792.68414631701, 15801.051936883823, 15815.762763558556, 5170.708312100947, 6128.387161283925, 6137.662428566382, 6168.0, 6180.6909654517995, 879.5997820991433, 907.7276816954887, 991.4398364923795, 1040.469360626071, 1089.7765957978595, 29178.124656260345, 29198.1822999207, 29497.557536636967, 30231.79072837106, 30796.692033079384, 843.6077224090827, 983.8573406856109, 1020.4642562654666, 1035.1264211776709, 1094.885037071112, 14067.788983165261, 14665.926670366793, 14802.481913133204, 15107.244637768288, 15285.312160952784, 622517.0, 623712.0, 627648.0, 630394.0, 630879.0, 54581.0, 54743.45256547463, 54842.725786370815, 54862.0, 54961.0, 1210.3827048205303, 1251.8748125187408, 1328.0504439325928, 1353.1813252982001, 1379.799929010288, 28653.426931460835, 30868.53697194499, 31226.0, 31277.06168814939, 31436.685633143377, 53826.461735382145, 54180.0, 54214.457855420944, 54229.45770542244, 54248.45751542434, 608022.0, 612437.0, 614867.0, 616546.0, 616720.0, 25154.993800247787, 25195.74804251934, 25691.614625780254, 25863.741362586134, 26850.462990740467, 41125.0, 44364.556354437336, 44767.55232447634, 45683.77158114188, 45719.0, 37759.244815103, 37985.81007094947, 38477.80761096289, 39422.40866386949, 40895.591044089175, 34604.48093278653, 36710.0, 37536.624633753316, 37950.62049379471, 38651.61348386593, 30170.0, 36709.08227294339, 37801.62198377981, 37962.810185949995, 38894.2221155581, 31423.84288078545, 32705.18237044093, 32720.836395817867, 32797.508037379936, 32834.0, 24478.51042979095, 24633.014679412623, 24718.0, 24725.258242252763, 25467.490650186523], "value": 19385.649340485434, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.3702237684747, 371.4687996165482, 377.47719408619065, 388.5279385546564, 398.46008658268045, 18372.936930812833, 19744.210231590754, 20143.798562014377, 20429.48926276842, 20607.86656733879, 19313.517162070955, 19610.803891961077, 20164.798352016474, 20178.19287228509, 20303.0, 5068.645194836363, 5697.803461273127, 5836.854078648025, 5907.025340818781, 5981.431763982415, 4051.4530538377235, 5621.381648018714, 5908.02517584598, 5909.0, 5916.142159386886, 4267.061246525759, 4311.784410779464, 6119.847003824895, 6374.458171055466, 6434.485241180694, 6202.627842329474, 6445.83885402864, 6473.158489396372, 6493.480521558287, 6512.6092434454085, 5616.887662246773, 5623.831285061455, 5624.156376543551, 5624.521915637176, 5629.60592758507, 20394.98025098739, 20560.897195514077, 20774.688379674317, 20796.48008799777, 22704.864756762097, 2042.3974927396305, 2091.362134548962, 2128.435964469417, 2139.7539282982502, 2904.867101830288, 9860.852087218698, 9927.602895884156, 9994.000599939947, 10161.18710503158, 10276.229282803744, 1855.1929910488846, 1896.6111947050822, 1914.2056046740586, 1927.0846347984595, 1943.2129987355145, 111376.0, 117861.41069294684, 118082.0, 118317.0, 118510.0, 11332.830007549894, 11341.773164536828, 11513.53945842157, 11519.942400287944, 11654.70863228426, 14377.928110359382, 14394.352254148589, 14395.712085758227, 14404.351804168837, 14425.42298308056, 8002.8799568006525, 8334.458260213067, 8371.958140209383, 8482.703105391281, 8502.78743031429, 7992.600369981477, 8025.839483210303, 8098.473599216032, 8162.469439486533, 8344.791380215422, 3568.964310356915, 4373.409589705413, 4400.757958312323, 4424.823007079745, 4523.705959112713, 1717.1414292853574, 1723.1436185021905, 1778.9644207115784, 1780.7952085510074, 1802.9008404537876, 788.6017561131706, 905.1717678324391, 949.1362859797551, 955.3742298794301, 983.311681822727, 18810.717839232424, 19990.400287991382, 20426.182952681727, 20454.284100056426, 20569.137795728144, 861.1560670542904, 908.1463424381193, 914.6965574056942, 914.8124634449922, 944.4333399960107, 10409.843852342221, 10680.252382333318, 10801.243912926177, 10804.513796879159, 10825.729356765987, 568278.3172168225, 571831.0, 575822.0, 588398.0, 588430.0, 130542.0, 131353.0, 133353.33323333322, 136001.0, 139476.0, 925.042580928749, 926.1294383279695, 947.7772723409993, 950.3442624589012, 950.7528042708807, 17535.649287013934, 18156.27374904989, 18293.0, 18295.438638590873, 18356.17397217102, 42796.0, 50418.0, 51611.48388516217, 52561.68595785136, 53603.73198134141, 69731.95402068872, 75377.6231118841, 75772.62113689397, 76963.61518192373, 78040.0, 6894.517383783288, 7239.131304243525, 7305.817354566178, 7317.524360916524, 7480.4015678746655, 9832.0, 10669.946650266698, 10896.94551527269, 12645.178063425848, 12747.0, 10794.622188223375, 11062.446877655924, 11359.886401136213, 11525.423728813694, 11574.826377604511, 10682.412467314372, 10862.837057443987, 10910.78178436411, 11262.718432038937, 11478.713032174262, 9931.155851752683, 10342.224333175192, 10424.947875260576, 10585.947070264907, 11230.775384492428, 7276.672549735381, 7345.853082938418, 7422.517536360119, 7482.807418257609, 7497.2877576630135, 9538.85691714611, 10979.231453798158, 11113.333200008025, 11153.83269250978, 11347.602833900773], "value": 9267.624922770932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1470.4265336518756, 1500.0399744163735, 1650.199653168214, 1803.9458816235517, 1821.3352126473833, 7998.800029999245, 8393.622286997084, 8406.369522285822, 8416.579171041447, 8422.747317580466, 7010.964945175279, 7011.3339232772805, 7426.368758655521, 7726.8454630907345, 7988.840223195547, 2292.3925159832615, 2319.6288593824993, 2328.5459335429596, 2332.941676458085, 2333.8599684018927, 2304.331743794298, 2333.708286464192, 2352.9058837646476, 2353.705786776653, 2358.292512246328, 1886.2549293029244, 1961.7940116287798, 1964.204497178645, 2234.4637287051155, 2320.060375547906, 2297.954040919189, 2328.5808554460277, 2330.545543618995, 2331.5220379822267, 2333.07843401857, 2318.084356679119, 2354.5290941811704, 2355.9646605300936, 2356.7643235676296, 2356.78788908999, 8269.503829770232, 8337.541435221061, 8357.749267521984, 8362.916370836274, 8368.539730314831, 5085.008423357447, 5244.580433565306, 5246.711430871301, 5248.8687782805355, 5255.237990491358, 3229.9354012919844, 3388.390089783838, 3511.227529943434, 3576.4635304704243, 3578.910527236814, 942.7501712046313, 1047.0838016735356, 1074.6185104288022, 1078.9514471848786, 1093.1801149138146, 10556.683299501028, 10858.782824343549, 10882.61910833125, 11023.61417350389, 11037.668869933916, 3912.9804350978634, 4007.1785284016705, 4165.91668166635, 4177.603127702924, 4301.677374196947, 8737.475751454933, 8740.606672699743, 8747.300215982707, 8751.737447876574, 8757.47455152693, 5115.667481613683, 5127.717975511308, 5130.87172820675, 5133.050385678662, 5135.563477104413, 4541.6593755468475, 4668.206404911173, 4806.4712881583, 4820.084184005028, 4820.39745031871, 3460.723142148622, 3470.670286322795, 3485.6165821759587, 3533.0284171852945, 3559.056849934769, 1108.9741988660458, 1116.179607988128, 1117.586492997596, 1126.1778901402022, 1131.5813149134872, 617.984550386244, 725.0, 759.9544027358265, 791.3669064748293, 870.4211699220075, 4934.185859333242, 5477.589180811376, 5510.1459273812625, 5551.833444996657, 5649.350324712711, 789.944703870735, 866.8483015472267, 908.9227415669742, 912.598456679059, 926.490430263366, 3035.4536183487185, 3781.4516895050065, 3783.129880127586, 3854.1713531590844, 3920.3923391874305, 9787.706368808947, 10041.497925103715, 10148.137408320375, 10163.136133428594, 10202.795944081077, 9253.79700638926, 9555.713328600154, 10159.0, 10187.847182292271, 10193.592256309665, 7464.290892365217, 7709.691612335556, 7785.883211751829, 7790.5325680459355, 7912.802179945433, 4624.398828152319, 4956.826511072095, 5651.094606136515, 5659.858503537444, 5719.942800571941, 9059.728208153503, 9092.090790920854, 9187.265018798615, 9337.53312334367, 9444.811103777749, 8103.0, 8676.479411235347, 9555.665551705657, 9586.520673966414, 9649.710508684751, 7527.736529221451, 7676.53940763556, 7800.1419843816, 7870.212978702313, 7947.046354437504, 7929.881051784112, 7948.682052717826, 8019.318357939641, 8026.959865200637, 8031.518108913484, 7080.079589653312, 7103.502754807114, 7146.178189508273, 7198.49610527279, 7273.3453989141235, 6935.757248496278, 7002.3697867192195, 7008.159020917523, 7051.576905385693, 7081.716731330689, 6563.048357987969, 6754.425873800782, 6763.628000459825, 6764.2559318475915, 6781.762638307636, 4644.535546445463, 4782.0914026334885, 4853.466118727008, 4920.114379411597, 4945.060438516671, 4195.454590903285, 4760.571548560646, 4816.4220293564995, 5079.136546786981, 5088.185890257689], "value": 4329.129083951422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.8560348618718, 306.8220432149352, 309.20688434166357, 312.9655737868834, 316.98256595887216, 5043.697378157313, 5056.696598204106, 5112.412072611651, 5119.641625086246, 5133.768980395882, 4312.74123552587, 4937.210476081021, 5022.321986531817, 5071.239314102886, 5081.46644602317, 3691.5754688210864, 3780.281746468176, 3805.904852378685, 3840.4431357453154, 3874.748141370816, 3214.2161100589165, 3771.264603402338, 3859.9421008684894, 3899.7660140391667, 3945.526536815586, 3310.668933106694, 3851.6340947609933, 3852.4782710829204, 3856.5372155341397, 3871.8644847430346, 3166.335069635379, 3303.8678452861855, 3524.3259451760464, 3868.8065596720194, 3870.3807390817474, 3361.226917808893, 3386.4243078676686, 3389.949115774093, 3395.1172695099453, 3395.1851555626718, 4373.685971544589, 4910.593809033584, 4921.704697718148, 4930.926036109461, 4940.4318503372215, 4058.6753059755147, 4170.0, 4263.744175349489, 4397.516273209944, 4407.228734971399, 4550.0889626356675, 5244.921326180111, 5250.212468129773, 5284.603654725873, 5302.814401495968, 3623.601403845575, 3730.0674831292176, 3737.9813100934593, 3750.343689854292, 3753.7935413552245, 4811.735354555533, 5081.339425874613, 5305.867353316198, 5505.476495043135, 5563.36021357545, 3292.3579901918897, 3292.7280176678014, 3558.217192217708, 3613.602503724588, 3776.981115094407, 3478.756487045933, 3814.6376094271, 3839.5968423315708, 3850.152966347399, 3852.653261206505, 3696.6303369663356, 4158.251514727318, 4401.493828209796, 4415.0, 4474.552544745565, 4037.9798101009305, 4484.417025786692, 4887.826921538876, 5314.73426328682, 5349.946500535023, 1058.6612284069179, 1133.8979491845773, 1190.9043679814592, 1205.9318269168243, 1224.9326287054118, 810.4529442626203, 832.5171400587644, 837.9245867871799, 842.9168518453757, 843.7089204224533, 4429.335599660077, 4630.4443466784205, 4722.220833562493, 4850.320955066293, 4869.172240719086, 4682.859514214579, 5161.509656582619, 5317.54800841925, 5371.4897084776885, 5429.294191755125, 4167.312393455107, 4932.136876046678, 4958.330625365529, 4959.578435832923, 4962.354893863775, 2615.3592369869502, 2630.278348885041, 2675.100678518235, 2695.8517281549703, 2702.3919618085774, 4720.88197795051, 5305.31030965972, 5313.760880760375, 5333.57331413486, 5336.065164159697, 4258.87223383299, 4486.509986957205, 5256.6870576946785, 5277.366715994105, 5312.574994000471, 5217.008988124088, 5607.635503692247, 5675.914861277084, 5682.516986056232, 5707.343655479673, 4613.792379342871, 4854.611631069577, 4890.657653964188, 5492.972535137299, 5518.668879867221, 5150.201718733602, 5437.40188579248, 5447.373552041566, 5469.562435005269, 5476.972615136899, 4942.072591689172, 5630.8029218977135, 5631.436856314336, 5688.943110569007, 5695.544356451557, 3252.6096868375, 3707.9814600926825, 3738.158914244273, 3738.775184042015, 3740.9625903740616, 4578.427696537933, 5286.12778891494, 5456.781728730806, 5493.395726470165, 5504.284443022381, 4395.472543294697, 4571.908561828811, 5145.897082058263, 5335.306410166809, 5342.519173274424, 4390.560943905712, 4414.713043652152, 5302.3637163540625, 5303.0, 5328.4671532846405, 4765.571098601143, 4787.180871268895, 4887.535684110004, 4922.1140194765285, 5227.477252274894, 5133.922991155061, 5195.493306940944, 5286.312779338661, 5313.654612450178, 5362.383325917569, 4107.330235347048, 4431.872701103553, 4622.6764126510825, 5157.149070403341, 5254.661675554342], "value": 3831.396267076961, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.4993904997903, 714.8070021094304, 765.7779244019227, 772.1159272632835, 794.3486341200216, 13500.18998860069, 14252.643683907907, 14272.072315299498, 14305.070170438914, 14312.570622881303, 12781.616551503446, 12888.0, 12892.2909239992, 12902.032347573922, 12905.032122590796, 653.6633633678659, 675.966201689916, 679.8606285711442, 821.5317269156568, 839.102160688064, 678.5623272988919, 678.8370791010146, 680.8978653202009, 802.1336956087441, 841.2134654098437, 867.3668222197799, 874.6370256343641, 880.6389380354057, 882.8631562107852, 883.4346018548134, 820.0733171516179, 820.5322965909434, 840.3277378097498, 877.8727084572765, 882.6778225947547, 652.670401447266, 652.8433176037764, 653.2911790707041, 655.9540832141752, 657.3623585122424, 11056.613018544393, 11550.422478876022, 13137.34313284332, 13592.0, 13654.112482688593, 13304.401301941436, 13511.527096551574, 13709.588712338646, 13723.588292351245, 13795.724085518234, 9753.366031207948, 9771.21830253578, 9786.608535658565, 9881.90118098817, 9914.40513569181, 3478.58257009158, 3498.597661268961, 3507.7369197310304, 3532.3235323235376, 3535.964640353615, 68926.0, 77046.84429733558, 88577.55711221402, 88755.66866497007, 88994.0, 10449.582016719398, 10651.520681569347, 10666.14670826332, 10750.032497075144, 10921.706441647184, 20019.799802001795, 20029.399118026482, 20089.698654520194, 20100.0, 20108.798912010985, 7490.588017658972, 7718.807029824299, 7789.610519474004, 7873.330766884879, 7873.685052597946, 4119.732217405859, 4291.871243862689, 4300.634446072057, 4394.0, 4416.470023597189, 3295.6045274567205, 3432.6052503961864, 3438.0889064397957, 3442.208292092783, 3442.8106454144763, 602.9487493563096, 606.5572132343326, 629.1506466270498, 641.9775307864203, 664.9933500664931, 541.8970395624818, 610.9786157484467, 626.746506986028, 633.6926590603554, 648.4391001783423, 8702.956485217534, 8872.467651940904, 8876.0, 9056.637734490678, 9284.48935308551, 563.5576072782927, 621.6922623301494, 627.2034516164446, 651.9185101862267, 671.9899201511981, 4225.915481690349, 4416.227160246944, 4447.666425018072, 4448.644108471314, 4617.0, 74234.0, 74805.25194748092, 75109.62445187739, 109752.4512377433, 117139.0, 57085.85828283411, 72788.63605681938, 72888.63555682187, 96278.51860740651, 98579.50710246403, 71873.0, 73548.0, 74537.62731186309, 87445.56277218573, 87644.56177719071, 9001.504917229353, 9489.810203796023, 9525.952370238105, 9585.424874507273, 9758.414495130315, 50919.0, 52948.94102117859, 54141.729291353295, 54663.18005229845, 56808.715956419954, 67276.0, 69487.3051269501, 79998.0, 80373.0, 80770.0, 12155.453004614637, 12195.51217951272, 12305.93847030759, 12395.75208495843, 12515.186512876633, 13955.0, 14441.422343106578, 14726.263686815402, 14854.85145148578, 14889.106653600818, 13021.609351719464, 13795.448182072605, 13892.097013694078, 13917.58247252584, 13925.651858703613, 12942.482300707867, 13277.203367797963, 13504.594862154152, 13572.0, 13768.724625507233, 12868.935655321664, 13276.668083297609, 13372.398242079324, 13442.93278533601, 13498.93250533741, 11056.502457389597, 11078.612248571262, 11280.717982050515, 11398.259113157617, 11525.423728813357, 11166.665000050012, 11173.608923687632, 11406.714832129263, 11407.543698251977, 11408.771824563628], "value": 7122.010695399214, "unit": "score"}], "name": "dromaeo_css", "value": 7004.707044835735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 124.0, 170.0, 152.0, 116.0, 115.0, 115.0, 113.0, 114.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 111.0, 146.0, 113.0, 113.0, 111.0, 108.0, 141.0, 111.0], "value": 120.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 182.0, 186.0, 185.0, 183.0, 185.0, 181.0, 183.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 59.0, 87.0, 59.0, 59.0, 74.0, 81.0, 103.0, 58.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 94.0, 71.0, 68.0, 70.0, 90.0, 67.0, 69.0, 92.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 88.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 95.0, 92.0, 92.0, 93.0, 93.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 80.0, 74.0, 93.0, 119.0, 85.0, 84.0, 81.0, 81.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 55.0, 53.0, 54.0, 55.0, 54.0, 53.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 74.0, 70.0, 69.0, 69.0, 69.0, 70.0, 68.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 91.0, 90.0, 111.0, 107.0, 83.0, 87.0, 91.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 137.0, 148.0, 156.0, 151.0, 149.0, 149.0, 184.0, 147.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 172.0, 171.0, 172.0, 172.0, 199.0, 240.0, 170.0, 174.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 68.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1460.1, "alertThreshold": 2.0}]} 15:34:22 INFO - Return code: 0 15:34:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:34:22 INFO - # TBPL SUCCESS # 15:34:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:34:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:34:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:34:22 INFO - 2016-11-11 15:34:22.510 screenresolution[1675:10238] starting screenresolution argv=screenresolution get 15:34:22 INFO - 2016-11-11 15:34:22.512 screenresolution[1675:10238] Display 0: 1600x1200x32@60 15:34:22 INFO - 2016-11-11 15:34:22.518 screenresolution[1676:10241] starting screenresolution argv=screenresolution list 15:34:22 INFO - Available Modes on Display 0 15:34:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:34:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:34:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:34:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:34:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:34:22 INFO - Intel Iris: 15:34:22 INFO - Chipset Model: Intel Iris 15:34:22 INFO - Type: GPU 15:34:22 INFO - Bus: Built-In 15:34:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:34:22 INFO - Vendor: Intel (0x8086) 15:34:22 INFO - Device ID: 0x0a2e 15:34:22 INFO - Revision ID: 0x0009 15:34:22 INFO - Displays: 15:34:22 INFO - Display: 15:34:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:34:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:34:22 INFO - Display Serial Number: 1600x1200 60 15:34:22 INFO - Main Display: Yes 15:34:22 INFO - Mirror: Off 15:34:22 INFO - Online: Yes 15:34:22 INFO - Rotation: Supported 15:34:22 INFO - Return code: 0 15:34:22 INFO - Running post-action listener: _resource_record_post_action 15:34:22 INFO - [mozharness: 2016-11-11 23:34:22.753003Z] Finished run-tests step (success) 15:34:22 INFO - Running post-run listener: _resource_record_post_run 15:34:22 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38540288; Write bytes: 699796480; Read time: 382; Write time: 2111 15:34:22 INFO - TinderboxPrint: CPU usage
53.7% 15:34:22 INFO - TinderboxPrint: I/O read bytes / time
38,540,288 / 382 15:34:22 INFO - TinderboxPrint: I/O write bytes / time
699,796,480 / 2,111 15:34:22 INFO - TinderboxPrint: CPU idle
2,250.9 (46.3%) 15:34:22 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 15:34:22 INFO - TinderboxPrint: CPU user
1,631.2 (33.6%) 15:34:22 INFO - TinderboxPrint: Swap in / out
436,625,408 / 0 15:34:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376797696; Write bytes: 372272640; Read time: 28032; Write time: 883 15:34:22 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37602304; Write bytes: 297565696; Read time: 365; Write time: 1172 15:34:23 INFO - Running post-run listener: _upload_blobber_files 15:34:23 INFO - Blob upload gear active. 15:34:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:34:23 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 15:34:23 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:34:23 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:34:23 INFO - (blobuploader) - INFO - Open directory for files ... 15:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:34:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:24 INFO - (blobuploader) - INFO - Done attempting. 15:34:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:34:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:34:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:25 INFO - (blobuploader) - INFO - Done attempting. 15:34:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:34:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:34:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:34:28 INFO - (blobuploader) - INFO - Done attempting. 15:34:28 INFO - (blobuploader) - INFO - Iteration through files over. 15:34:28 INFO - Return code: 0 15:34:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:34:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:34:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0926dac5a57dca6fa7cab01de8679116155da1813b4e9545d1ec6a9a2d2342262f08daa5e2f0a37b9224901d25bcdac938a77fda98d8cf7d7268bfa3e8de4a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25fcf167ec5b261c9a8235612d5d123368d29748f25ceed08086c12a3a966ceae61ff7c14f613becf78ae6b013a9edb4626aabe92a0fe93a99f04ba45db3dc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7402582d4cf03a54ba3aa5ca1d415be11e855661b38be361f42a16c49661283e4e41a46fc9a57548727708972e1e8ea03b0f3ac80d913a34210846f6b4716d38"} 15:34:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:34:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:34:28 INFO - Contents: 15:34:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0926dac5a57dca6fa7cab01de8679116155da1813b4e9545d1ec6a9a2d2342262f08daa5e2f0a37b9224901d25bcdac938a77fda98d8cf7d7268bfa3e8de4a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25fcf167ec5b261c9a8235612d5d123368d29748f25ceed08086c12a3a966ceae61ff7c14f613becf78ae6b013a9edb4626aabe92a0fe93a99f04ba45db3dc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7402582d4cf03a54ba3aa5ca1d415be11e855661b38be361f42a16c49661283e4e41a46fc9a57548727708972e1e8ea03b0f3ac80d913a34210846f6b4716d38"} 15:34:28 INFO - Running post-run listener: copy_logs_to_upload_dir 15:34:28 INFO - Copying logs to upload dir... 15:34:28 INFO - mkdir: /builds/slave/test/build/upload/logs 15:34:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.996300 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-11 15:34:28.343226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 15:34:28.347594) ========= 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.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/Larch/sha512/0926dac5a57dca6fa7cab01de8679116155da1813b4e9545d1ec6a9a2d2342262f08daa5e2f0a37b9224901d25bcdac938a77fda98d8cf7d7268bfa3e8de4a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25fcf167ec5b261c9a8235612d5d123368d29748f25ceed08086c12a3a966ceae61ff7c14f613becf78ae6b013a9edb4626aabe92a0fe93a99f04ba45db3dc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7402582d4cf03a54ba3aa5ca1d415be11e855661b38be361f42a16c49661283e4e41a46fc9a57548727708972e1e8ea03b0f3ac80d913a34210846f6b4716d38"} build_url:https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010739 build_url: 'https://queue.taskcluster.net/v1/task/HV5H7YUBRj6mzbn1MGe5vw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0926dac5a57dca6fa7cab01de8679116155da1813b4e9545d1ec6a9a2d2342262f08daa5e2f0a37b9224901d25bcdac938a77fda98d8cf7d7268bfa3e8de4a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25fcf167ec5b261c9a8235612d5d123368d29748f25ceed08086c12a3a966ceae61ff7c14f613becf78ae6b013a9edb4626aabe92a0fe93a99f04ba45db3dc3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7402582d4cf03a54ba3aa5ca1d415be11e855661b38be361f42a16c49661283e4e41a46fc9a57548727708972e1e8ea03b0f3ac80d913a34210846f6b4716d38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 15:34:28.382339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:34:28.382654) ========= 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.LxTNY5d6FN/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.tK67Pad9J0/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005117 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 15:34:28.430637) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 15:34:28.430930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 15:34:28.434222) ========= ========= Total master_lag: 3.36 =========