builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0304 starttime: 1471892934.81 results: success (0) buildid: 20160822094614 builduid: 40e2b971be7a4833a9a3347246093863 revision: 74f16e8aed180aaf153827a6e5eedfb818c0cb11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.808642) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.809084) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.809355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.834146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.834409) ========= 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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-08-22 12:08:54-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-08-22 12:08:54 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.954657) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:54.954967) ========= 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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.026151 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:55.004138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-22 12:08:55.004627) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 74f16e8aed180aaf153827a6e5eedfb818c0cb11 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 74f16e8aed180aaf153827a6e5eedfb818c0cb11 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-08-22 12:08:55,085 truncating revision to first 12 chars 2016-08-22 12:08:55,085 Setting DEBUG logging. 2016-08-22 12:08:55,085 attempt 1/10 2016-08-22 12:08:55,085 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/74f16e8aed18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 12:08:56,943 unpacking tar archive at: fx-team-74f16e8aed18/testing/mozharness/ program finished with exit code 0 elapsedTime=2.125630 ========= master_lag: 2.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-22 12:08:59.676028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:59.676350) ========= script_repo_revision: 74f16e8aed180aaf153827a6e5eedfb818c0cb11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 12:08:59.676750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-08-22 12:08:59.677023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-08-22 12:09:01.263569) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-22 12:09:01.263973) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:01 INFO - MultiFileLogger online at 20160822 12:09:01 in /builds/slave/test 12:09:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:09:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:09:01 INFO - {'append_to_log': False, 12:09:01 INFO - 'base_work_dir': '/builds/slave/test', 12:09:01 INFO - 'blob_upload_branch': 'Fx-Team', 12:09:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:09:01 INFO - 'branch': 'Fx-Team', 12:09:01 INFO - 'buildbot_json_path': 'buildprops.json', 12:09:01 INFO - 'config_files': ('talos/mac_config.py',), 12:09:01 INFO - 'default_actions': ('clobber', 12:09:01 INFO - 'read-buildbot-config', 12:09:01 INFO - 'download-and-extract', 12:09:01 INFO - 'populate-webroot', 12:09:01 INFO - 'create-virtualenv', 12:09:01 INFO - 'install', 12:09:01 INFO - 'run-tests'), 12:09:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:09:01 INFO - 'download_minidump_stackwalk': True, 12:09:01 INFO - 'download_symbols': 'ondemand', 12:09:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:09:01 INFO - 'tooltool.py': '/tools/tooltool.py', 12:09:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:09:01 INFO - '/tools/misc-python/virtualenv.py')}, 12:09:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:09:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:09:01 INFO - 'installer_path': 'installer.exe', 12:09:01 INFO - 'log_level': 'info', 12:09:01 INFO - 'log_name': 'talos', 12:09:01 INFO - 'log_to_console': True, 12:09:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:09:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:09:01 INFO - 'opt_config_files': (), 12:09:01 INFO - 'pip_index': False, 12:09:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:01 INFO - 'cmd': ('bash', 12:09:01 INFO - '-c', 12:09:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:01 INFO - 'enabled': True, 12:09:01 INFO - 'halt_on_failure': False, 12:09:01 INFO - 'name': 'check_screen_resolution'},), 12:09:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:01 INFO - 'cmd': ('bash', 12:09:01 INFO - '-c', 12:09:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:01 INFO - 'enabled': True, 12:09:01 INFO - 'halt_on_failure': False, 12:09:01 INFO - 'name': 'check_screen_resolution'},), 12:09:01 INFO - 'run_cmd_checks_enabled': True, 12:09:01 INFO - 'sps_profile': False, 12:09:01 INFO - 'sps_profile_interval': 0, 12:09:01 INFO - 'suite': 'dromaeojs', 12:09:01 INFO - 'system_bits': '32', 12:09:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:09:01 INFO - 'title': 't-yosemite-r7-0304', 12:09:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:09:01 INFO - 'use_talos_json': True, 12:09:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:09:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:09:01 INFO - 'work_dir': 'build'} 12:09:01 INFO - [mozharness: 2016-08-22 19:09:01.457261Z] Running clobber step. 12:09:01 INFO - Running pre-action listener: _resource_record_pre_action 12:09:01 INFO - Running main action method: clobber 12:09:01 INFO - rmtree: /builds/slave/test/build 12:09:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:09:03 INFO - Running post-action listener: _resource_record_post_action 12:09:03 INFO - [mozharness: 2016-08-22 19:09:03.497181Z] Finished clobber step (success) 12:09:03 INFO - [mozharness: 2016-08-22 19:09:03.497322Z] Running read-buildbot-config step. 12:09:03 INFO - Running pre-action listener: _resource_record_pre_action 12:09:03 INFO - Running main action method: read_buildbot_config 12:09:03 INFO - Using buildbot properties: 12:09:03 INFO - { 12:09:03 INFO - "project": "", 12:09:03 INFO - "product": "firefox", 12:09:03 INFO - "script_repo_revision": "production", 12:09:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:09:03 INFO - "repository": "", 12:09:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:09:03 INFO - "buildid": "20160822094614", 12:09:03 INFO - "pgo_build": "False", 12:09:03 INFO - "basedir": "/builds/slave/test", 12:09:03 INFO - "buildnumber": 924, 12:09:03 INFO - "slavename": "t-yosemite-r7-0304", 12:09:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:09:03 INFO - "platform": "yosemite_r7", 12:09:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:09:03 INFO - "branch": "fx-team", 12:09:03 INFO - "repo_path": "integration/fx-team", 12:09:03 INFO - "revision": "74f16e8aed180aaf153827a6e5eedfb818c0cb11", 12:09:03 INFO - "stage_platform": "macosx64", 12:09:03 INFO - "builduid": "40e2b971be7a4833a9a3347246093863", 12:09:03 INFO - "slavebuilddir": "test" 12:09:03 INFO - } 12:09:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:09:03 INFO - Running post-action listener: _resource_record_post_action 12:09:03 INFO - [mozharness: 2016-08-22 19:09:03.501338Z] Finished read-buildbot-config step (success) 12:09:03 INFO - [mozharness: 2016-08-22 19:09:03.501565Z] Running download-and-extract step. 12:09:03 INFO - Running pre-action listener: _resource_record_pre_action 12:09:03 INFO - Running main action method: download_and_extract 12:09:03 INFO - mkdir: /builds/slave/test/build/tests 12:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:03 INFO - trying https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:09:03 INFO - Downloading https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 12:09:04 INFO - Downloaded 1199 bytes. 12:09:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:09:04 INFO - Using the following test package requirements: 12:09:04 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:09:04 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:09:04 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:09:04 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'jsshell-mac.zip'], 12:09:04 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:09:04 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:09:04 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:09:04 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:09:04 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:09:04 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:09:04 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:09:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:09:04 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')]} 12:09:04 INFO - trying https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:09:04 INFO - Downloading https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:09:05 INFO - Downloaded 16352479 bytes. 12:09:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:09:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:12 INFO - trying https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:09:12 INFO - Downloading https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:09:13 INFO - Downloaded 16352479 bytes. 12:09:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:09:20 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')]} 12:09:20 INFO - trying https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:09:20 INFO - Downloading https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:09:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:09:21 INFO - Downloaded 14413981 bytes. 12:09:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:22 INFO - trying https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:09:22 INFO - Downloading https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 12:09:25 INFO - Downloaded 111476417 bytes. 12:09:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:09:25 INFO - mkdir: /builds/slave/test/properties 12:09:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:25 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:25 INFO - Contents: 12:09:25 INFO - build_url:https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:09:25 INFO - Running post-action listener: _resource_record_post_action 12:09:25 INFO - Running post-action listener: set_extra_try_arguments 12:09:25 INFO - [mozharness: 2016-08-22 19:09:25.753958Z] Finished download-and-extract step (success) 12:09:25 INFO - [mozharness: 2016-08-22 19:09:25.754083Z] Running populate-webroot step. 12:09:25 INFO - Running pre-action listener: _resource_record_pre_action 12:09:25 INFO - Running main action method: populate_webroot 12:09:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:25 INFO - '--tppagecycles', 12:09:25 INFO - '7'], 12:09:25 INFO - 'tests': ['tsvgm']}}, 12:09:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:09:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['damp', 'tps']}, 12:09:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'tests': ['damp', 'tps']}, 12:09:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['dromaeo_dom']}, 12:09:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['basic_compositor_video']}, 12:09:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:09:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['a11yr', 12:09:25 INFO - 'ts_paint', 12:09:25 INFO - 'tpaint', 12:09:25 INFO - 'sessionrestore', 12:09:25 INFO - 'sessionrestore_no_auto_restore', 12:09:25 INFO - 'tabpaint']}, 12:09:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['tsvgx', 12:09:25 INFO - 'tsvgr_opacity', 12:09:25 INFO - 'tart', 12:09:25 INFO - 'tscrollx', 12:09:25 INFO - 'cart']}, 12:09:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:25 INFO - 'tsvgr_opacity', 12:09:25 INFO - 'tart', 12:09:25 INFO - 'tscrollx', 12:09:25 INFO - 'cart']}, 12:09:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'talos_options': ['--disable-e10s'], 12:09:25 INFO - 'tests': ['tp5o']}, 12:09:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'tests': ['tp5o']}, 12:09:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'talos_options': ['--disable-e10s', 12:09:25 INFO - '--xperf_path', 12:09:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:25 INFO - 'tests': ['tp5n']}, 12:09:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:25 INFO - 'talos_options': ['--xperf_path', 12:09:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:25 INFO - 'tests': ['tp5n']}}, 12:09:25 INFO - 'talos.zip': {'path': '', 12:09:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:25 INFO - Running post-action listener: _resource_record_post_action 12:09:25 INFO - [mozharness: 2016-08-22 19:09:25.773091Z] Finished populate-webroot step (success) 12:09:25 INFO - [mozharness: 2016-08-22 19:09:25.773173Z] Running create-virtualenv step. 12:09:25 INFO - Running pre-action listener: _resource_record_pre_action 12:09:25 INFO - Running main action method: create_virtualenv 12:09:25 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:25 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 12:09:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:25 INFO - Using real prefix '/tools/python27' 12:09:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:26 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:29 INFO - Installing pip.................done. 12:09:29 INFO - Return code: 0 12:09:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:29 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 12:09:29 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 12:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:29 INFO - 'HOME': '/Users/cltbld', 12:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:29 INFO - 'LOGNAME': 'cltbld', 12:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:29 INFO - 'MOZ_NO_REMOTE': '1', 12:09:29 INFO - 'NO_EM_RESTART': '1', 12:09:29 INFO - 'PAGER': '/bin/cat', 12:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:29 INFO - 'PWD': '/builds/slave/test', 12:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:29 INFO - 'SHELL': '/bin/bash', 12:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:29 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:29 INFO - 'USER': 'cltbld', 12:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:29 INFO - 'XPC_FLAGS': '0x0', 12:09:29 INFO - 'XPC_SERVICE_NAME': '0', 12:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:29 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 12:09:29 INFO - Cleaning up... 12:09:29 INFO - Return code: 0 12:09:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:29 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:29 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 12:09:29 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 12:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:29 INFO - 'HOME': '/Users/cltbld', 12:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:29 INFO - 'LOGNAME': 'cltbld', 12:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:29 INFO - 'MOZ_NO_REMOTE': '1', 12:09:29 INFO - 'NO_EM_RESTART': '1', 12:09:29 INFO - 'PAGER': '/bin/cat', 12:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:29 INFO - 'PWD': '/builds/slave/test', 12:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:29 INFO - 'SHELL': '/bin/bash', 12:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:29 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:29 INFO - 'USER': 'cltbld', 12:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:29 INFO - 'XPC_FLAGS': '0x0', 12:09:29 INFO - 'XPC_SERVICE_NAME': '0', 12:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:30 INFO - Downloading/unpacking psutil>=3.1.1 12:09:30 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 12:09:30 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 12:09:30 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 12:09:30 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 12:09:30 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 12:09:30 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 12:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:33 INFO - Installing collected packages: psutil 12:09:33 INFO - Running setup.py install for psutil 12:09:33 INFO - building 'psutil._psutil_osx' extension 12:09:33 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 12:09:33 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 12:09:33 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 12:09:33 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 12:09:33 INFO - building 'psutil._psutil_posix' extension 12:09:33 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 12:09:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:33 INFO - ^ 12:09:33 INFO - 1 warning generated. 12:09:33 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 12:09:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:34 INFO - Successfully installed psutil 12:09:34 INFO - Cleaning up... 12:09:34 INFO - Return code: 0 12:09:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:34 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')]} 12:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:34 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')]} 12:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:34 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:34 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 12:09:34 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 12:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:34 INFO - 'HOME': '/Users/cltbld', 12:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:34 INFO - 'LOGNAME': 'cltbld', 12:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:34 INFO - 'MOZ_NO_REMOTE': '1', 12:09:34 INFO - 'NO_EM_RESTART': '1', 12:09:34 INFO - 'PAGER': '/bin/cat', 12:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:34 INFO - 'PWD': '/builds/slave/test', 12:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:34 INFO - 'SHELL': '/bin/bash', 12:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:34 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:34 INFO - 'USER': 'cltbld', 12:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:34 INFO - 'XPC_FLAGS': '0x0', 12:09:34 INFO - 'XPC_SERVICE_NAME': '0', 12:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:34 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 12:09:34 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 12:09:34 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 12:09:34 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 12:09:34 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 12:09:34 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 12:09:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:36 INFO - Installing collected packages: mozsystemmonitor 12:09:36 INFO - Running setup.py install for mozsystemmonitor 12:09:37 INFO - Successfully installed mozsystemmonitor 12:09:37 INFO - Cleaning up... 12:09:37 INFO - Return code: 0 12:09:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:37 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')]} 12:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:37 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')]} 12:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:37 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:37 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 12:09:37 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 12:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:37 INFO - 'HOME': '/Users/cltbld', 12:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:37 INFO - 'LOGNAME': 'cltbld', 12:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:37 INFO - 'MOZ_NO_REMOTE': '1', 12:09:37 INFO - 'NO_EM_RESTART': '1', 12:09:37 INFO - 'PAGER': '/bin/cat', 12:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:37 INFO - 'PWD': '/builds/slave/test', 12:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:37 INFO - 'SHELL': '/bin/bash', 12:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:37 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:37 INFO - 'USER': 'cltbld', 12:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:37 INFO - 'XPC_FLAGS': '0x0', 12:09:37 INFO - 'XPC_SERVICE_NAME': '0', 12:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:37 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:37 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 12:09:37 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 12:09:37 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 12:09:37 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 12:09:37 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 12:09:37 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 12:09:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:40 INFO - warning: no files found matching '*.txt' 12:09:40 INFO - no previously-included directories found matching 'build' 12:09:40 INFO - no previously-included directories found matching 'dist' 12:09:40 INFO - no previously-included directories found matching '.git*' 12:09:40 INFO - Installing collected packages: jsonschema, functools32 12:09:40 INFO - Running setup.py install for functools32 12:09:40 INFO - warning: no files found matching '*.txt' 12:09:40 INFO - no previously-included directories found matching 'build' 12:09:40 INFO - no previously-included directories found matching 'dist' 12:09:40 INFO - no previously-included directories found matching '.git*' 12:09:40 INFO - Successfully installed jsonschema functools32 12:09:40 INFO - Cleaning up... 12:09:40 INFO - Return code: 0 12:09:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:40 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:40 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 12:09:40 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 12:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:40 INFO - 'HOME': '/Users/cltbld', 12:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:40 INFO - 'LOGNAME': 'cltbld', 12:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:40 INFO - 'MOZ_NO_REMOTE': '1', 12:09:40 INFO - 'NO_EM_RESTART': '1', 12:09:40 INFO - 'PAGER': '/bin/cat', 12:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:40 INFO - 'PWD': '/builds/slave/test', 12:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:40 INFO - 'SHELL': '/bin/bash', 12:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:40 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:40 INFO - 'USER': 'cltbld', 12:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:40 INFO - 'XPC_FLAGS': '0x0', 12:09:40 INFO - 'XPC_SERVICE_NAME': '0', 12:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:40 INFO - Cleaning up... 12:09:40 INFO - Return code: 0 12:09:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:40 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:40 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 12:09:40 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 12:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:40 INFO - 'HOME': '/Users/cltbld', 12:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:40 INFO - 'LOGNAME': 'cltbld', 12:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:40 INFO - 'MOZ_NO_REMOTE': '1', 12:09:40 INFO - 'NO_EM_RESTART': '1', 12:09:40 INFO - 'PAGER': '/bin/cat', 12:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:40 INFO - 'PWD': '/builds/slave/test', 12:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:40 INFO - 'SHELL': '/bin/bash', 12:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:40 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:40 INFO - 'USER': 'cltbld', 12:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:40 INFO - 'XPC_FLAGS': '0x0', 12:09:40 INFO - 'XPC_SERVICE_NAME': '0', 12:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:41 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 12:09:41 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 12:09:41 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 12:09:41 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 12:09:41 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 12:09:41 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 12:09:43 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:44 INFO - Downloading docopt-0.6.1.tar.gz 12:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:44 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:44 INFO - Running setup.py install for blobuploader 12:09:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:45 INFO - Running setup.py install for requests 12:09:45 INFO - Running setup.py install for docopt 12:09:45 INFO - Successfully installed blobuploader requests docopt 12:09:45 INFO - Cleaning up... 12:09:45 INFO - Return code: 0 12:09:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:45 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')]} 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:45 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')]} 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:45 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:45 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 12:09:45 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 12:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:45 INFO - 'HOME': '/Users/cltbld', 12:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:45 INFO - 'LOGNAME': 'cltbld', 12:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:45 INFO - 'MOZ_NO_REMOTE': '1', 12:09:45 INFO - 'NO_EM_RESTART': '1', 12:09:45 INFO - 'PAGER': '/bin/cat', 12:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:45 INFO - 'PWD': '/builds/slave/test', 12:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:45 INFO - 'SHELL': '/bin/bash', 12:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:45 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:45 INFO - 'USER': 'cltbld', 12:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:45 INFO - 'XPC_FLAGS': '0x0', 12:09:45 INFO - 'XPC_SERVICE_NAME': '0', 12:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-la86E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Hqe9Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-AQfaY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-faY0w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-kyIe3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-e1CsHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Add8F5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-4IBGbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-U6FgvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:46 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-WAHsva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-_yVapo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-t8gOkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-2AEM0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-LXvv2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-F5PNPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-8S8_Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:47 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-k5JBPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:47 INFO - Running setup.py install for manifestparser 12:09:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:47 INFO - Running setup.py install for mozcrash 12:09:48 INFO - Running setup.py install for mozdebug 12:09:48 INFO - Running setup.py install for mozdevice 12:09:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozfile 12:09:48 INFO - Running setup.py install for mozhttpd 12:09:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozinfo 12:09:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozInstall 12:09:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozleak 12:09:49 INFO - Running setup.py install for mozlog 12:09:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for moznetwork 12:09:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozprocess 12:09:49 INFO - Running setup.py install for mozprofile 12:09:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozrunner 12:09:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozscreenshot 12:09:50 INFO - Running setup.py install for moztest 12:09:50 INFO - Running setup.py install for mozversion 12:09:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:50 INFO - Cleaning up... 12:09:50 INFO - Return code: 0 12:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:50 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:50 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 12:09:50 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 12:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:50 INFO - 'HOME': '/Users/cltbld', 12:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:50 INFO - 'LOGNAME': 'cltbld', 12:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:50 INFO - 'MOZ_NO_REMOTE': '1', 12:09:50 INFO - 'NO_EM_RESTART': '1', 12:09:50 INFO - 'PAGER': '/bin/cat', 12:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:50 INFO - 'PWD': '/builds/slave/test', 12:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:50 INFO - 'SHELL': '/bin/bash', 12:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:50 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:50 INFO - 'USER': 'cltbld', 12:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:50 INFO - 'XPC_FLAGS': '0x0', 12:09:50 INFO - 'XPC_SERVICE_NAME': '0', 12:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-MKIgXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-8awZRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-tiajxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-dvOUjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-pPa5lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-A7Bhu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-D3x4Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Rj22Qz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:51 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-pWv8b9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-X5ZacD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Y6036_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-NZsqjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-tRvzba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-WYpYJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-QsO9ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-xN1B3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:52 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-3dAZGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:52 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)) 12:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:52 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)) 12:09:52 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)) 12:09:52 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)) 12:09:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:52 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 12:09:52 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 12:09:52 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 12:09:52 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 12:09:52 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 12:09:52 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 12:09:55 INFO - Downloading blessings-1.6.tar.gz 12:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:55 INFO - Installing collected packages: blessings 12:09:55 INFO - Running setup.py install for blessings 12:09:55 INFO - Successfully installed blessings 12:09:55 INFO - Cleaning up... 12:09:55 INFO - Return code: 0 12:09:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:55 INFO - Reading from file tmpfile_stdout 12:09:55 INFO - Current package versions: 12:09:55 INFO - blessings == 1.6 12:09:55 INFO - blobuploader == 1.2.4 12:09:55 INFO - docopt == 0.6.1 12:09:55 INFO - functools32 == 3.2.3-2 12:09:55 INFO - jsonschema == 2.5.1 12:09:55 INFO - manifestparser == 1.1 12:09:55 INFO - mozInstall == 1.12 12:09:55 INFO - mozcrash == 0.17 12:09:55 INFO - mozdebug == 0.1 12:09:55 INFO - mozdevice == 0.48 12:09:55 INFO - mozfile == 1.2 12:09:55 INFO - mozhttpd == 0.7 12:09:55 INFO - mozinfo == 0.9 12:09:55 INFO - mozleak == 0.1 12:09:55 INFO - mozlog == 3.3 12:09:55 INFO - moznetwork == 0.27 12:09:55 INFO - mozprocess == 0.23 12:09:55 INFO - mozprofile == 0.28 12:09:55 INFO - mozrunner == 6.12 12:09:55 INFO - mozscreenshot == 0.1 12:09:55 INFO - mozsystemmonitor == 0.3 12:09:55 INFO - moztest == 0.7 12:09:55 INFO - mozversion == 1.4 12:09:55 INFO - psutil == 3.1.1 12:09:55 INFO - requests == 1.2.3 12:09:55 INFO - wsgiref == 0.1.2 12:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:55 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:55 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 12:09:55 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 12:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:55 INFO - 'HOME': '/Users/cltbld', 12:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:55 INFO - 'LOGNAME': 'cltbld', 12:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:55 INFO - 'MOZ_NO_REMOTE': '1', 12:09:55 INFO - 'NO_EM_RESTART': '1', 12:09:55 INFO - 'PAGER': '/bin/cat', 12:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:55 INFO - 'PWD': '/builds/slave/test', 12:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:55 INFO - 'SHELL': '/bin/bash', 12:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:55 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:55 INFO - 'USER': 'cltbld', 12:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:55 INFO - 'XPC_FLAGS': '0x0', 12:09:55 INFO - 'XPC_SERVICE_NAME': '0', 12:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 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)) 12:09:56 INFO - Cleaning up... 12:09:56 INFO - Return code: 0 12:09:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:56 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')]} 12:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:56 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')]} 12:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:56 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 0x101079a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10110e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2bad97dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, '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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 12:09:56 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 12:09:56 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 12:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:56 INFO - 'HOME': '/Users/cltbld', 12:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:56 INFO - 'LOGNAME': 'cltbld', 12:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:56 INFO - 'MOZ_NO_REMOTE': '1', 12:09:56 INFO - 'NO_EM_RESTART': '1', 12:09:56 INFO - 'PAGER': '/bin/cat', 12:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:56 INFO - 'PWD': '/builds/slave/test', 12:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:56 INFO - 'SHELL': '/bin/bash', 12:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:09:56 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:56 INFO - 'USER': 'cltbld', 12:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:56 INFO - 'XPC_FLAGS': '0x0', 12:09:56 INFO - 'XPC_SERVICE_NAME': '0', 12:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:56 INFO - Cleaning up... 12:09:56 INFO - Return code: 0 12:09:56 INFO - Running post-action listener: _resource_record_post_action 12:09:56 INFO - Running post-action listener: _start_resource_monitoring 12:09:56 INFO - Starting resource monitoring. 12:09:56 INFO - [mozharness: 2016-08-22 19:09:56.491680Z] Finished create-virtualenv step (success) 12:09:56 INFO - [mozharness: 2016-08-22 19:09:56.492695Z] Running install step. 12:09:56 INFO - Running pre-action listener: _resource_record_pre_action 12:09:56 INFO - Running main action method: install 12:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:56 INFO - Reading from file tmpfile_stdout 12:09:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:56 INFO - Reading from file tmpfile_stdout 12:09:56 INFO - Output received: 12:09:56 INFO - Usage: mozinstall [options] installer 12:09:56 INFO - Options: 12:09:56 INFO - -h, --help show this help message and exit 12:09:56 INFO - -d DEST, --destination=DEST 12:09:56 INFO - Directory to install application into. [default: 12:09:56 INFO - "/builds/slave/test"] 12:09:56 INFO - --app=APP Application being installed. [default: firefox] 12:09:56 INFO - mkdir: /builds/slave/test/build/application 12:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:10:31 INFO - Reading from file tmpfile_stdout 12:10:31 INFO - Output received: 12:10:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:10:31 INFO - Running post-action listener: _resource_record_post_action 12:10:31 INFO - [mozharness: 2016-08-22 19:10:31.321376Z] Finished install step (success) 12:10:31 INFO - [mozharness: 2016-08-22 19:10:31.321561Z] Running run-tests step. 12:10:31 INFO - Running pre-action listener: _resource_record_pre_action 12:10:31 INFO - Running main action method: run_tests 12:10:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:31 INFO - 2016-08-22 12:10:31.336 screenresolution[1634:7503] starting screenresolution argv=screenresolution get 12:10:31 INFO - 2016-08-22 12:10:31.338 screenresolution[1634:7503] Display 0: 1600x1200x32@60 12:10:31 INFO - 2016-08-22 12:10:31.344 screenresolution[1635:7505] starting screenresolution argv=screenresolution list 12:10:31 INFO - Available Modes on Display 0 12:10:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:31 INFO - Intel Iris: 12:10:31 INFO - Chipset Model: Intel Iris 12:10:31 INFO - Type: GPU 12:10:31 INFO - Bus: Built-In 12:10:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:31 INFO - Vendor: Intel (0x8086) 12:10:31 INFO - Device ID: 0x0a2e 12:10:31 INFO - Revision ID: 0x0009 12:10:31 INFO - Displays: 12:10:31 INFO - Display: 12:10:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:31 INFO - Display Serial Number: 1600x1200 60 12:10:31 INFO - Main Display: Yes 12:10:31 INFO - Mirror: Off 12:10:31 INFO - Online: Yes 12:10:31 INFO - Rotation: Supported 12:10:31 INFO - Return code: 0 12:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:31 INFO - Python 2.7.3 12:10:31 INFO - Return code: 0 12:10:31 INFO - grabbing minidump binary from tooltool 12:10: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')]} 12:10:31 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 0x100fddd30>, '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 0x100d42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100fd5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:31 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 12:10:31 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 12:10:31 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 12:10:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:31 INFO - Return code: 0 12:10:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 12:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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 12:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cdUYDv8CGN/Render', 12:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:31 INFO - 'HOME': '/Users/cltbld', 12:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:31 INFO - 'LOGNAME': 'cltbld', 12:10:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:31 INFO - 'MOZ_NO_REMOTE': '1', 12:10:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:31 INFO - 'NO_EM_RESTART': '1', 12:10:31 INFO - 'PAGER': '/bin/cat', 12:10:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:31 INFO - 'PWD': '/builds/slave/test', 12:10:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:31 INFO - 'SHELL': '/bin/bash', 12:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Qzm9uLmQoE/Listeners', 12:10:31 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 12:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:31 INFO - 'USER': 'cltbld', 12:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:31 INFO - 'XPC_FLAGS': '0x0', 12:10:31 INFO - 'XPC_SERVICE_NAME': '0', 12:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 12:10:31 INFO - mozversion application_buildid: 20160822094614 12:10:31 INFO - mozversion application_changeset: 74f16e8aed180aaf153827a6e5eedfb818c0cb11 12:10:31 INFO - mozversion application_display_name: Nightly 12:10:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:31 INFO - mozversion application_name: Firefox 12:10:31 INFO - mozversion application_remotingname: firefox 12:10:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:10:31 INFO - mozversion application_vendor: Mozilla 12:10:31 INFO - mozversion application_version: 51.0a1 12:10:31 INFO - mozversion platform_buildid: 20160822094614 12:10:31 INFO - mozversion platform_changeset: 74f16e8aed180aaf153827a6e5eedfb818c0cb11 12:10:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:10:31 INFO - mozversion platform_version: 51.0a1 12:10:31 INFO - using testdate: 1471893031 12:10:31 INFO - actual date: 1471893031 12:10:31 INFO - starting webserver on 'localhost:49267' 12:10:31 INFO - SUITE-START | Running 2 tests 12:10:31 INFO - TEST-START | dromaeo_css 12:10:31 INFO - operating with platform_type : mac_ 12:10:31 INFO - Initialising browser for dromaeo_css test... 12:10:31 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp0vNx7f/profile http://localhost:49267/getInfo.html) 12:10:34 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 12:10:34 INFO - PROCESS | 1645 | colorDepth:24 12:10:34 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 12:10:34 INFO - PROCESS | 1645 | __metrics 12:10:34 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:10:34 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:10:35 INFO - TEST-INFO | 1645: exit 0 12:10:35 INFO - Browser initialized. 12:10:35 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:35 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp0vNx7f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:47 INFO - PROCESS | 1647 | RSS: Main: 228483072 12:10:47 INFO - PROCESS | 1647 | 12:11:38 INFO - PROCESS | 1647 | 1471893098632 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] 12:13:50 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 12:13:52 INFO - PROCESS | 1647 | RSS: Main: 362532864 12:13:52 INFO - PROCESS | 1647 | 12:16:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 12:16:56 INFO - PROCESS | 1647 | RSS: Main: 372383744 12:16:56 INFO - PROCESS | 1647 | 12:19:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 12:19:59 INFO - PROCESS | 1647 | RSS: Main: 343990272 12:19:59 INFO - PROCESS | 1647 | 12:23:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 12:23:01 INFO - PROCESS | 1647 | RSS: Main: 338706432 12:23:01 INFO - PROCESS | 1647 | 12:26:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 12:26:06 INFO - PROCESS | 1647 | RSS: Main: 331812864 12:26:06 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 12:29:09 INFO - PROCESS | 1647 | RSS: Main: 465596416 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | __start_tp_report 12:29:09 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 12:29:09 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 12:29:09 INFO - PROCESS | 1647 | |i|pagename|runs| 12:29:09 INFO - PROCESS | 1647 | |0;dojo.html;3336.5662463879694;3361.1933136047346;3394.3550725362165;3405.4381027130507;3422.229998250394;5525.392206857253;5609.971950140243;5691.3739488656265;5751.769929202832;5785.913211301834;5568.805091821787;5645.802396916099;5649.661020338783;5652.123920792284;5682.090865461527;5002.12462819007;5034.874128146807;5122.871928201787;5150.3047088642925;5227.843164705046;5249.606279529051;5292.735363231843;5305.071612467822;5305.814296499604;5328.733563321838;5147.613928955325;5311.840644780644;5314.867128321804;5322.414534401213;5324.840254792343;5039.596832253411;5260.132078207092;5268.446813084647;5298.258243845884;5323.813666521692;5082.23766435034;5100.132977393852;5159.948400516022;5171.276021357013;5180.326557547494;5597.216389705403;5600.411956744565;5638.689872057035;5638.943610563882;5662.49037586615;6233.781817636406;6236.407541283571;6242.344553821874;6261.624302541862;6272.65500397478;6117.755289788403;6196.915539780521;6244.281907580641;6250;6275.905861412082;5874.412558744091;5901.970490147522;5904.2029326040865;5922.230110085661;5928.67392293428;6289.528285378615;6304.243490781136;6327.58870673414;6338.01760727088;6355.428011478989;5958.433948774859;6092.360302168208;6159.876802463926;6185.690715464209;6245.531585131133;6314.210723659543;6317.778877739257;6323.557350985387;6325.683715814191;6327.145835312174;6182.536309776785;6239.844003899939;6248.843778905563;6263.154474145932;6279.309275979638;6033.637981721111;6110.29731580865;6123.295820980555;6181.258249010148;6188.845278868064;4911.901761964812;4935.062338155739;4951.084049450791;5014.573761230264;5023.6985780853265;4448.132614140211;4455.153520831096;4459.5986361227;4475.172093162775;4497.595216430537;5556.472135147154;5703.144528320702;5711.228984087095;5727.31272247333;5735.283089613798;6201.0388389799655;6277.843053923689;6300.527460440485;6332.746690132343;6343.397377249154;5318.494742999409;5694.886102277931;5720.628159169724;5741.311042674905;5746.540276777848;5273.551748101378;5273.683578985273;5465.316835395575;5474.069408200616;5492.368377636623;5708.771649133988;6254.6872656366995;6281.748730050746;6307.3377295383325;6326.841828954313;5957.821265362046;6308.432241098323;6322.778702745381;6332.525060620564;6334.144890439732;6277.2153480814895;6289.339619340087;6351.460125889166;6352.396522330371;6358.078078678658;6127.234095738033;6168.969155154375;6183.81448556544;6262.812115636538;6270.529710271657;6269.310375858743;6308.905366419599;6313.463355614824;6321.020241862519;6350.237971443272;5860;6163.414475624988;6165.136880836776;6195.504359651127;6196.194494715838;5996.130561068708;6045.21412216409;6097.725602347994;6157.6613286269685;6164.506839452923;4894.241392584155;5919.319278283053;5933.406659334032;5941.287045554561;5955.225820643289;5898.498627616701;6108.816735497942;6127;6152.323244443018;6155.045967874987;5803.158542011469;5840.123981402654;5871;5941.22764040672;5960.6125601837475 12:29:09 INFO - PROCESS | 1647 | |1;ext.html;519.7713006277238;524.6904326447395;527.7282199667171;528.8202011316149;551.1484756051899;30646.8547201696;39961.60038399615;41130.17739645209;41318.38022429666;43338;42810.57189428105;43190.35214471786;43336;43387.78306108473;43656.69029929107;8698.173673501007;9670.70987870365;9830.361026533288;9846.163076138537;9847.852282215737;8684.913150868473;8852;8857.822843543125;8902.109789021079;11797.941010294937;11802.05583553318;12095.576654817083;12195.512179512853;12209.877901220963;12222.022238220921;12070.396480176;12118.757624847454;12128.332941688204;12146.45340959659;12159.756804863853;8628.439151455135;11627.244229125165;11762.411879405996;11842.23025503348;11881.287122772575;40898.386524202164;41378.96552586209;41450.79274603638;41455.7927210365;41509.79245103785;3528.8059156746367;3570.517980072683;3809.466674665549;3819.9427008594894;4144.772037537934;31078.844605776827;32074.679253207636;32191.83904080488;32351;32366.838165809255;3265.2489927316615;3359.378514974713;3377.1219482934366;3426.691597756189;3437.415639341293;486039;488627.5568622134;488819;489073;489262;55773.44226557682;56065.43934560602;56129;56169.15746263809;56170;51602.74198629058;59834.20497385086;60590;60757.69621151866;60879.39120608826;15872.52382428529;16058.116803575682;16100.19499025044;16106.033637981523;16134.031958082553;12584.937075314565;12640.810387844005;12641.557545485864;12650.493980240688;12654.493820247271;12760.07401745175;13671.589852304447;13951.930240348733;14008.299585020708;14318.57044288673;4915.5330243626795;5278.709670968057;5928.79249226275;6204.534659900525;6217.595856269328;848.1476116502998;940.5532372123187;966.6471737815788;1050.3225419604312;1087.3475914451267;27798.861005694842;27874.02440914599;30229.941952031575;30491.69508304933;31188.532172016985;991.3407583956732;1014.6905193915779;1068.6793961811425;1069.4599227390274;1105.474899422784;15212.02243708333;15218.771718424232;15259.465918693015;15589.142597157039;15622.687546249012;664936;708516;708912;708962;914469;58848;59455.702721486115;59504.70247648821;59527.702361487914;59591.40408595859;1308.3719814489098;1362.6865820861258;1402.2217669193492;1404.5224623628017;1404.6628809085746;30084.398312034074;30441;31641;31830.363392731553;32675.183120422178;55824.883502328914;55839.44160558505;55994.44005560055;56036.719816400655;56193;554148;574898;576847.1157644185;581622;585028;25815.870920645277;26050.86974565115;26246.86876565605;26287.60568591511;26720;28598.571021435113;32955;40009.79995100122;48507;48967.26549101695;39189.41215881707;39299;39314;41079.38380924229;41218.1756364877;39241.018974524726;39369.80315098406;40582.1883562333;40612;40669.593304066584;30555.763744197568;37635.05912352213;38299;38615.6138438612;38956.02609934774;33048.50427243545;34471.96584102481;34814.12964675904;34912;35268.64731352653;25400.110996115047;25457;25665;26118.73881261163;26197.869010654824 12:29:09 INFO - PROCESS | 1647 | |2;jquery.html;348.23388545200567;369.2485791414471;372.06982543640896;376.6290204148913;404.094827586207;15279.083255004707;16086.51740447787;20412.387628371136;20571.177152913904;20623.896880515596;16887;19973.700394494095;21203.83378914159;21303.786962130373;21338.35984920451;5561.360443549003;5964.7912323068595;6203.1625730526475;6223.190985171944;6237.875242495147;6078.392160783908;6244.656543890084;6246.906296405557;6249.750009999594;6256.812295631138;5141.871453213681;5949.018411962021;6123.050927106284;6129.387061293857;6219;6067.848303792419;6132.356102609207;6133.570650054498;6138.202033735608;6139.109829074804;5184.870378240536;5444.292242008553;5488.039593071197;5492.917606235909;5496.395396506381;13407.731845363038;13436.395362208725;13453.461861525528;13633.045686801928;13695.931520342434;2131.158192513964;2289.3589794857467;2322.198841399714;2352.541254455382;2716.3888125171875;9931.255155863268;10146.767155890506;10331.845022324671;10515.369077855277;12889.90435812957;1793.7668103146507;1799.595091104504;1877.1271358818078;1886.5943822078318;1899.772027356726;120768;126983.3016698332;127243.36378318141;128684.35657821744;128865;11276.661700149009;11437.37094459796;11492.310461372344;11565.19043666952;11741;14387;14404.42382304696;14424.639384015483;14428.639284017983;14438.350274237684;8367.707130250534;8538.957305213435;8543;8548.957255213809;8662.047174810767;8453.788655283666;8457.112003239694;8491;8669.523176225242;8677.132286771272;4472.284434490466;4477.171723231213;4486.7083639563325;4487.41663583732;4558.316252562142;1835.6695794757072;1889.4803928919382;1890.7731072271415;1892.8391086757779;1895.3745264062827;827.210014436212;873.5021038008401;878.3004885546488;905.4702998745676;907.6641642592283;20486.97565121738;21496.518387811007;21529.031193596013;21542.030608622335;21597.46006349854;920.1810388754014;922.4373132389237;927.5501381829807;938.5025936253796;947.507296205969;10461.483084952792;11291.402977315263;11390.974812266935;11397;11408.543658253577;592393;593933;596921.0153949203;609273;610359;87990.36149396854;123662;132891;137368.31315843356;141871;953.7758626722715;958.2621381536318;970.8737864077625;973.3867663372031;985.5663508056432;15973;19261.711074334176;20121.798782011992;20137.79862201418;20165;54182.01998890099;57223.713881430325;57253.42746572481;57353.13970290365;57733;85969.57015214884;86472.56763716393;86612.56693716491;87148.12851871653;87814.56092719495;7079.929200707927;7874.606269686378;7963.641636126504;8177.141400152898;8250.257476827115;11777.587784427504;13046.869531304945;13053.324068566111;13294.667633309246;13388.665283367995;9609.663661772083;11096.00135987765;11191.496382662637;11395.715107122389;11500.367479788692;10837.241393102408;10943.507542160782;11025.83461248098;11217.214794964275;11262.267952583055;9186.58660360272;11274.379909105079;11627.941860290644;11642.301461912313;11836.881631183578;6558.44253238461;8049.436539442183;8060.919390806252;8115;8223.342132629496;9795.657151999645;10741.946290268812;10954.452277385939;11147.665570032912;11171.994520493196 12:29:09 INFO - PROCESS | 1647 | |3;mootools.html;1395.5045958684668;1482.021865568725;1634.5259874636356;1837.2008176443248;1839.475749411418;8338.666453341866;8846.911530884698;8854.734357969262;8855.867161992559;8886.91113088869;7760.495567788104;7988.081370642378;8025.117237103914;8147.511149331044;8660.696875609356;2114.6616541353387;2135.6903249028883;2295.6556516522487;2374.3351861478814;2376.976230237701;2371.5256948610263;2374.4538756086026;2377.512609914972;2378.7264464586533;2379.1435083369975;1959.9020048997565;2341.4029422497324;2368.0527788884497;2368.1829768729763;2374.5013547155118;2316.9073237070497;2338.3218866528687;2342.273895092518;2355.434695673026;2362.681038059857;2301.1255722825385;2323.047550504285;2328.848624839397;2330.766923307656;2359.7640235976437;8682.43564168327;8718.607662655195;8848.2478989286;8851.380403371766;8880.73357799267;5129.923051154198;5284.07528682479;5296.629235953485;5299.178627312773;5301.655392399521;3449.1549570355173;3593.6047034826147;3653.5433070866143;3656.012876523324;3668.1013151777984;1065.4512925843196;1099.3623698254994;1106.8118419868642;1124.9268797528216;1127.780082883838;11343;11418.31490110596;11422.200445968703;11425.771484570345;11428.314301141958;3705.55533335996;4378.956210437918;4553.317002449659;4689.413823272091;4719.952800471951;9719.708408747607;9734.269929755297;9744.366616169926;9752.512374381253;9759.414435133915;5284.339457567804;5287.947120528823;5292.52367286946;5295.920561191585;5298.761555730001;4705.364775755298;4807.591354734816;4847.466778654345;4850.878728031827;4860.295257187688;3647.7811331320204;3733.9439908401396;3754.831032603485;3777.395616701315;3857.0550215197454;1194.970125746846;1208;1229.9200551964093;1269.7143142792982;1270.8919741821865;889.5952341684647;899.3839220134229;916.9724908252763;939.3245761915778;963.1379914976197;4593.563611456906;5729;5824.138550250377;5852.063669812809;6025.578209525291;912.1790388650268;913.4199783137756;925.1303774451993;943.2595412601075;957.5308099031564;3843.577206507282;4023.5775243599;4024.873035549992;4103.199876024147;4258.1057977825;10388.168946484264;10413.479326033668;10544.736381590368;10545.84181237282;10587.25889187765;10488.10851077667;10488.370697758159;10496.370217786956;10510.159187265;10520.842187367196;7899.960500197462;8112.675492980215;8114.8782768258525;8124.781282807626;8147.144549822302;5353.892922141613;5666.546676265972;5842.795502157404;6093.29927058394;6106.72519736604;9694.175995040501;9840.901590984286;9916.355436896578;9919.057689519484;9929.900700993187;9525.666601668909;9891.208703303862;9919.504024798876;10081.042300981395;10091.293609447268;7404.629768511661;7543.509671871311;7544.471886968079;7554.093508778982;7556.168821429748;6738.764143254963;7183.604901730455;7754.34088102495;8152.796180095308;8237.970253718286;5902.763889444374;7347.55914645123;7394.334509894135;7461.664225109992;7478.140013898471;7215.747448839265;7236.312550307713;7287.12554493443;7360.595167265852;7450.0687414073245;5913.91129133072;6640.767573134917;6817.93182068173;6895.5173137879865;6896.551724137988;4902.2156454967035;4991.650584459053;5023.2214006829;5061.240813877947;5191.974040129775;4911.950880491149;5003.799848006039;5056.241563765465;5056.342675452168;5192.091384007708 12:29:09 INFO - PROCESS | 1647 | |4;prototype.html;287.09851067647577;296.4234563773461;304.9740772034377;305.9908202753917;307.6631088957591;4380.080183161536;4578.702384345015;5371.435999220084;5385.976664433755;5493.7802487900435;5064.290999260106;5091.872703182422;5205.869853253671;5348.572114230862;5349.331333583302;4107.527634322054;4127.607877251657;4140.8964775880695;4156.854510092147;4166.708330416872;3491.5635545556806;3707.6292370762976;4067.8983025424454;4076.2458945095254;4117.505899292074;3410.7380269300393;3587.3542762302905;3787.772733635991;4090.836366545335;4109.6506796922295;4024.4566983457153;4053.7770422626745;4058.310087285139;4066.2274167908004;4085.9387109193235;3480.5033835450745;3487.7907325560545;3489.4297566095106;3512.5071844466;3522.2603253316834;4193.421046056684;5244.370675518962;5276.7889284428575;5281.207818827168;5297.788088476456;4598;4620.283856002325;4644.303354496818;4658.510856360067;4679.040796636681;4917.754112294371;5622.465865742789;5626.690532020737;5699.68651724155;5701.657900525982;3720.4120306585846;3825.8660946867003;3837.808109594537;3878.119112230594;3885.339492286318;5435.402105768362;5584.441555844383;5967.37342579023;5986.760529578812;6023.759049638009;3853.672437842815;3865.2849222893856;3866.516685414323;3887.2031233597045;3929.155231625214;3950.940735888964;3965.900852478711;3967.861124860674;3969.4442778011103;3982.3628219484744;3884.669803066771;4369.902446825541;4570.680052396367;4635.536446355338;4650.790714417859;5627.409122042127;5690.5163061140265;5726.599138060295;5731.254936858182;5737.3688894221605;1262.7790136726219;1323.2920387592592;1333.386642144605;1355.6746380868522;1363.5636596289291;684.5721424109931;811.0753740735566;851.9393355272601;858.8454078265972;868.2619773192862;4659.6505262105475;5263.315768950089;5266.605004624592;5342.652727572696;5358.5981051421295;5297.788088476418;5818.738156782955;5820.650760954356;5824.301083869963;5847.56143289255;4396.296277789137;4993.775280112403;5173.663711858717;5245.606579506552;5289.418164001957;2757.903992965361;2950.8081974671577;3008.6540047894773;3047;3049.9542506862413;5181.481851814789;5939.821805345847;5977.940220597825;5979.206238128544;6007.639541627516;5668.858278543069;5901.232839730807;5921.3190483094995;5937.643741375531;5955.702214889325;5192.584593232532;5199.516324062812;5893.469587737039;5930.7627694893445;5958.553108516879;4388.670849686222;5402.324709411324;5764.884702305846;5803.767849285981;5821.505172060253;4975.975120124376;5412.431694672003;5654.886902261849;5671.631343962629;5733.655980641175;5713.342965559014;5914.526837853047;5954.672493012926;5965.522758179422;6003.949308870843;3626.1172367967;3798.905027374338;3827.483289755847;3827.61723827615;3836.8848934532007;5202.401723801811;5232.659877107976;5627.803026894039;5760.855978600569;5761.5390768739235;5066.795943764474;5425.810096646598;5650.689212093374;5652.236948011966;5704.80033198836;5556.694381809039;5620.824234307766;5633.492985631313;5706.600537962303;5775.393583673654;5221.485769126911;5472;5518.144687573433;5620.915686264628;5630.268065151504;4754.572088512051;5744.856378590569;5750.338711048283;5757.683327416865;5760.798372056958;5156.968606278834;5206.979951627971;5605.80379686709;5618;5633.084751184617 12:29:09 INFO - PROCESS | 1647 | |5;yui.html;714.2143641993806;762.249184553215;780.7267456390264;810.2707018213732;836.7782537627528;15292;16059.357625694958;16489.010659360447;16511.752323715154;16534.83465165348;14935.775963360531;15065.397384104623;15232.543023709279;15235.46675866342;15274.236288185573;659.3604203922212;813.243683374463;824.983500329993;825.5253229393093;825.6697321071559;665.5540787672271;819.5983967855767;845.0873057098319;852.0159216105408;854.3592305770673;886.8935727712684;888.1030159538848;892.5715656484891;894.0969620683092;894.1371576428745;682.8088135322117;885.942413743111;887;887.7913690282811;889.253027456936;646.8221239159269;670.2225418514548;671.228087699145;671.5936858200815;674.1236392689484;15072.54782356531;15117.773233401507;15122.621934451618;15138.167400792952;15147;13968.161910285315;14063.578092657237;14320.57038288853;14370.425182992667;14593.343299551545;13058.2817945013;13083.738325233442;13193.472261109544;13334.666633334147;13623;4520.276755719102;4603.447586289667;4615.607673347769;4661.113930600734;4715.104130215248;119202;119626;120743.79256207413;120881;121143.78856211502;9738.415695058318;10788.730281743019;11173.162012848907;11212.775744485065;11713.121515886343;22795.544089118124;22801.429964251027;22805.88597057004;22811.771882281297;22871.542569148525;7938.761837144895;8080.555569443737;8096.311813495801;8109.43233973628;8310.418270721113;4455.821767129343;4494.752788596593;4515.390422292949;4566.474855391606;4583.679142460062;3436.896893093211;3549.254656522159;3550.840212190406;3551.3785087609567;3554.4668299754726;639.8944174211296;652.4486808646781;663.9004149377536;664.3489380407228;665.7137430904758;593.3532449629924;619.1641284266294;622.3372108704216;646.1180488632982;675.7195763758084;8314.127016663284;8964.731058068268;9197.402168859002;9268.95365523168;9304.441733496013;527.6939375162397;589.9498891972296;605.1527860994555;633.7876811268185;652.7715299645181;4269.978650106729;4456.9331460028125;4494.325851122292;4543.775765863731;4569.451665800125;95082.52458737801;142197;151143.15283200832;157262;159206.20396897942;84779.57610212032;85559.14440855636;120005;131967.34016329856;136065.319673401;113168;114220.42889785497;114284;115511;116171.83828161779;8368.41421100517;8652.740417787476;8743.994440639408;8955.686550970684;8956.641734330815;56010.71994640164;56449.71775141098;61718.76562468815;65038.02442963265;67252;64601.35398645954;71642;75109;84068.57965710132;91553;13238.801417978546;13362.198268103944;13460.596182114552;13727.725445490834;13846.515371961934;14894.180820055;14933;15231;15499.845001550291;15956.281967311266;13155.210687358789;13315.810776372267;13576;13798.793018104536;14011;11487.597934072268;13331.866681333062;13332.800007999695;14495.985281030227;14533.200673963034;12611.108333750011;13003.804942926054;13090.280034598189;13313.933430332787;13389.66525836862;10407.84388234162;11580.594679186188;11678.00736937336;11848.466818992994;11916.642500724993;8529.530875801891;8759.686047092988;9421.046871424482;10663.680089597325;11702.239354441934 12:29:09 INFO - PROCESS | 1647 | __end_tp_report 12:29:09 INFO - PROCESS | 1647 | __start_cc_report 12:29:09 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1297 12:29:09 INFO - PROCESS | 1647 | __end_cc_report 12:29:09 INFO - PROCESS | 1647 | __startTimestamp1471894149291__endTimestamp 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | ------- Summary: start ------- 12:29:09 INFO - PROCESS | 1647 | Number of tests: 6 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5743.45 Median:5928.67 stddev:642.19 (10.8%) stddev-sans-first:615.78 12:29:09 INFO - PROCESS | 1647 | Values: 3336.6 3361.2 3394.4 3405.4 3422.2 5525.4 5610.0 5691.4 5751.8 5785.9 5568.8 5645.8 5649.7 5652.1 5682.1 5002.1 5034.9 5122.9 5150.3 5227.8 5249.6 5292.7 5305.1 5305.8 5328.7 5147.6 5311.8 5314.9 5322.4 5324.8 5039.6 5260.1 5268.4 5298.3 5323.8 5082.2 5100.1 5159.9 5171.3 5180.3 5597.2 5600.4 5638.7 5638.9 5662.5 6233.8 6236.4 6242.3 6261.6 6272.7 6117.8 6196.9 6244.3 6250.0 6275.9 5874.4 5902.0 5904.2 5922.2 5928.7 6289.5 6304.2 6327.6 6338.0 6355.4 5958.4 6092.4 6159.9 6185.7 6245.5 6314.2 6317.8 6323.6 6325.7 6327.1 6182.5 6239.8 6248.8 6263.2 6279.3 6033.6 6110.3 6123.3 6181.3 6188.8 4911.9 4935.1 4951.1 5014.6 5023.7 4448.1 4455.2 4459.6 4475.2 4497.6 5556.5 5703.1 5711.2 5727.3 5735.3 6201.0 6277.8 6300.5 6332.7 6343.4 5318.5 5694.9 5720.6 5741.3 5746.5 5273.6 5273.7 5465.3 5474.1 5492.4 5708.8 6254.7 6281.7 6307.3 6326.8 5957.8 6308.4 6322.8 6332.5 6334.1 6277.2 6289.3 6351.5 6352.4 6358.1 6127.2 6169.0 6183.8 6262.8 6270.5 6269.3 6308.9 6313.5 6321.0 6350.2 5860.0 6163.4 6165.1 6195.5 6196.2 5996.1 6045.2 6097.7 6157.7 6164.5 4894.2 5919.3 5933.4 5941.3 5955.2 5898.5 6108.8 6127.0 6152.3 6155.0 5803.2 5840.1 5871.0 5941.2 5960.6 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:72865.44 Median:38457.31 stddev:164284.66 (427.2%) stddev-sans-first:164655.73 12:29:09 INFO - PROCESS | 1647 | Values: 519.8 524.7 527.7 528.8 551.1 30646.9 39961.6 41130.2 41318.4 43338.0 42810.6 43190.4 43336.0 43387.8 43656.7 8698.2 9670.7 9830.4 9846.2 9847.9 8684.9 8852.0 8857.8 8902.1 11797.9 11802.1 12095.6 12195.5 12209.9 12222.0 12070.4 12118.8 12128.3 12146.5 12159.8 8628.4 11627.2 11762.4 11842.2 11881.3 40898.4 41379.0 41450.8 41455.8 41509.8 3528.8 3570.5 3809.5 3819.9 4144.8 31078.8 32074.7 32191.8 32351.0 32366.8 3265.2 3359.4 3377.1 3426.7 3437.4 486039.0 488627.6 488819.0 489073.0 489262.0 55773.4 56065.4 56129.0 56169.2 56170.0 51602.7 59834.2 60590.0 60757.7 60879.4 15872.5 16058.1 16100.2 16106.0 16134.0 12584.9 12640.8 12641.6 12650.5 12654.5 12760.1 13671.6 13951.9 14008.3 14318.6 4915.5 5278.7 5928.8 6204.5 6217.6 848.1 940.6 966.6 1050.3 1087.3 27798.9 27874.0 30229.9 30491.7 31188.5 991.3 1014.7 1068.7 1069.5 1105.5 15212.0 15218.8 15259.5 15589.1 15622.7 664936.0 708516.0 708912.0 708962.0 914469.0 58848.0 59455.7 59504.7 59527.7 59591.4 1308.4 1362.7 1402.2 1404.5 1404.7 30084.4 30441.0 31641.0 31830.4 32675.2 55824.9 55839.4 55994.4 56036.7 56193.0 554148.0 574898.0 576847.1 581622.0 585028.0 25815.9 26050.9 26246.9 26287.6 26720.0 28598.6 32955.0 40009.8 48507.0 48967.3 39189.4 39299.0 39314.0 41079.4 41218.2 39241.0 39369.8 40582.2 40612.0 40669.6 30555.8 37635.1 38299.0 38615.6 38956.0 33048.5 34472.0 34814.1 34912.0 35268.6 25400.1 25457.0 25665.0 26118.7 26197.9 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35249.95 Median:358.74 stddev:100586.16 (28038.6%) stddev-sans-first:100834.19 12:29:09 INFO - PROCESS | 1647 | Values: 348.2 369.2 372.1 376.6 404.1 15279.1 16086.5 20412.4 20571.2 20623.9 16887.0 19973.7 21203.8 21303.8 21338.4 5561.4 5964.8 6203.2 6223.2 6237.9 6078.4 6244.7 6246.9 6249.8 6256.8 5141.9 5949.0 6123.1 6129.4 6219.0 6067.8 6132.4 6133.6 6138.2 6139.1 5184.9 5444.3 5488.0 5492.9 5496.4 13407.7 13436.4 13453.5 13633.0 13695.9 2131.2 2289.4 2322.2 2352.5 2716.4 9931.3 10146.8 10331.8 10515.4 12889.9 1793.8 1799.6 1877.1 1886.6 1899.8 120768.0 126983.3 127243.4 128684.4 128865.0 11276.7 11437.4 11492.3 11565.2 11741.0 14387.0 14404.4 14424.6 14428.6 14438.4 8367.7 8539.0 8543.0 8549.0 8662.0 8453.8 8457.1 8491.0 8669.5 8677.1 4472.3 4477.2 4486.7 4487.4 4558.3 1835.7 1889.5 1890.8 1892.8 1895.4 827.2 873.5 878.3 905.5 907.7 20487.0 21496.5 21529.0 21542.0 21597.5 920.2 922.4 927.6 938.5 947.5 10461.5 11291.4 11391.0 11397.0 11408.5 592393.0 593933.0 596921.0 609273.0 610359.0 87990.4 123662.0 132891.0 137368.3 141871.0 953.8 958.3 970.9 973.4 985.6 15973.0 19261.7 20121.8 20137.8 20165.0 54182.0 57223.7 57253.4 57353.1 57733.0 85969.6 86472.6 86612.6 87148.1 87814.6 7079.9 7874.6 7963.6 8177.1 8250.3 11777.6 13046.9 13053.3 13294.7 13388.7 9609.7 11096.0 11191.5 11395.7 11500.4 10837.2 10943.5 11025.8 11217.2 11262.3 9186.6 11274.4 11627.9 11642.3 11836.9 6558.4 8049.4 8060.9 8115.0 8223.3 9795.7 10741.9 10954.5 11147.7 11172.0 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5609.80 Median:5095.58 stddev:3122.79 (61.3%) stddev-sans-first:3115.49 12:29:09 INFO - PROCESS | 1647 | Values: 1395.5 1482.0 1634.5 1837.2 1839.5 8338.7 8846.9 8854.7 8855.9 8886.9 7760.5 7988.1 8025.1 8147.5 8660.7 2114.7 2135.7 2295.7 2374.3 2377.0 2371.5 2374.5 2377.5 2378.7 2379.1 1959.9 2341.4 2368.1 2368.2 2374.5 2316.9 2338.3 2342.3 2355.4 2362.7 2301.1 2323.0 2328.8 2330.8 2359.8 8682.4 8718.6 8848.2 8851.4 8880.7 5129.9 5284.1 5296.6 5299.2 5301.7 3449.2 3593.6 3653.5 3656.0 3668.1 1065.5 1099.4 1106.8 1124.9 1127.8 11343.0 11418.3 11422.2 11425.8 11428.3 3705.6 4379.0 4553.3 4689.4 4720.0 9719.7 9734.3 9744.4 9752.5 9759.4 5284.3 5287.9 5292.5 5295.9 5298.8 4705.4 4807.6 4847.5 4850.9 4860.3 3647.8 3733.9 3754.8 3777.4 3857.1 1195.0 1208.0 1229.9 1269.7 1270.9 889.6 899.4 917.0 939.3 963.1 4593.6 5729.0 5824.1 5852.1 6025.6 912.2 913.4 925.1 943.3 957.5 3843.6 4023.6 4024.9 4103.2 4258.1 10388.2 10413.5 10544.7 10545.8 10587.3 10488.1 10488.4 10496.4 10510.2 10520.8 7900.0 8112.7 8114.9 8124.8 8147.1 5353.9 5666.5 5842.8 6093.3 6106.7 9694.2 9840.9 9916.4 9919.1 9929.9 9525.7 9891.2 9919.5 10081.0 10091.3 7404.6 7543.5 7544.5 7554.1 7556.2 6738.8 7183.6 7754.3 8152.8 8238.0 5902.8 7347.6 7394.3 7461.7 7478.1 7215.7 7236.3 7287.1 7360.6 7450.1 5913.9 6640.8 6817.9 6895.5 6896.6 4902.2 4991.7 5023.2 5061.2 5192.0 4912.0 5003.8 5056.2 5056.3 5192.1 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4580.40 Median:5214.23 stddev:1417.21 (27.2%) stddev-sans-first:1384.07 12:29:09 INFO - PROCESS | 1647 | Values: 287.1 296.4 305.0 306.0 307.7 4380.1 4578.7 5371.4 5386.0 5493.8 5064.3 5091.9 5205.9 5348.6 5349.3 4107.5 4127.6 4140.9 4156.9 4166.7 3491.6 3707.6 4067.9 4076.2 4117.5 3410.7 3587.4 3787.8 4090.8 4109.7 4024.5 4053.8 4058.3 4066.2 4085.9 3480.5 3487.8 3489.4 3512.5 3522.3 4193.4 5244.4 5276.8 5281.2 5297.8 4598.0 4620.3 4644.3 4658.5 4679.0 4917.8 5622.5 5626.7 5699.7 5701.7 3720.4 3825.9 3837.8 3878.1 3885.3 5435.4 5584.4 5967.4 5986.8 6023.8 3853.7 3865.3 3866.5 3887.2 3929.2 3950.9 3965.9 3967.9 3969.4 3982.4 3884.7 4369.9 4570.7 4635.5 4650.8 5627.4 5690.5 5726.6 5731.3 5737.4 1262.8 1323.3 1333.4 1355.7 1363.6 684.6 811.1 851.9 858.8 868.3 4659.7 5263.3 5266.6 5342.7 5358.6 5297.8 5818.7 5820.7 5824.3 5847.6 4396.3 4993.8 5173.7 5245.6 5289.4 2757.9 2950.8 3008.7 3047.0 3050.0 5181.5 5939.8 5977.9 5979.2 6007.6 5668.9 5901.2 5921.3 5937.6 5955.7 5192.6 5199.5 5893.5 5930.8 5958.6 4388.7 5402.3 5764.9 5803.8 5821.5 4976.0 5412.4 5654.9 5671.6 5733.7 5713.3 5914.5 5954.7 5965.5 6003.9 3626.1 3798.9 3827.5 3827.6 3836.9 5202.4 5232.7 5627.8 5760.9 5761.5 5066.8 5425.8 5650.7 5652.2 5704.8 5556.7 5620.8 5633.5 5706.6 5775.4 5221.5 5472.0 5518.1 5620.9 5630.3 4754.6 5744.9 5750.3 5757.7 5760.8 5157.0 5207.0 5605.8 5618.0 5633.1 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24275.85 Median:4475.63 stddev:38440.39 (858.9%) stddev-sans-first:38507.51 12:29:09 INFO - PROCESS | 1647 | Values: 714.2 762.2 780.7 810.3 836.8 15292.0 16059.4 16489.0 16511.8 16534.8 14935.8 15065.4 15232.5 15235.5 15274.2 659.4 813.2 825.0 825.5 825.7 665.6 819.6 845.1 852.0 854.4 886.9 888.1 892.6 894.1 894.1 682.8 885.9 887.0 887.8 889.3 646.8 670.2 671.2 671.6 674.1 15072.5 15117.8 15122.6 15138.2 15147.0 13968.2 14063.6 14320.6 14370.4 14593.3 13058.3 13083.7 13193.5 13334.7 13623.0 4520.3 4603.4 4615.6 4661.1 4715.1 119202.0 119626.0 120743.8 120881.0 121143.8 9738.4 10788.7 11173.2 11212.8 11713.1 22795.5 22801.4 22805.9 22811.8 22871.5 7938.8 8080.6 8096.3 8109.4 8310.4 4455.8 4494.8 4515.4 4566.5 4583.7 3436.9 3549.3 3550.8 3551.4 3554.5 639.9 652.4 663.9 664.3 665.7 593.4 619.2 622.3 646.1 675.7 8314.1 8964.7 9197.4 9269.0 9304.4 527.7 589.9 605.2 633.8 652.8 4270.0 4456.9 4494.3 4543.8 4569.5 95082.5 142197.0 151143.2 157262.0 159206.2 84779.6 85559.1 120005.0 131967.3 136065.3 113168.0 114220.4 114284.0 115511.0 116171.8 8368.4 8652.7 8744.0 8955.7 8956.6 56010.7 56449.7 61718.8 65038.0 67252.0 64601.4 71642.0 75109.0 84068.6 91553.0 13238.8 13362.2 13460.6 13727.7 13846.5 14894.2 14933.0 15231.0 15499.8 15956.3 13155.2 13315.8 13576.0 13798.8 14011.0 11487.6 13331.9 13332.8 14496.0 14533.2 12611.1 13003.8 13090.3 13313.9 13389.7 10407.8 11580.6 11678.0 11848.5 11916.6 8529.5 8759.7 9421.0 10663.7 11702.2 12:29:09 INFO - PROCESS | 1647 | -------- Summary: end -------- 12:29:09 INFO - PROCESS | 1647 | 12:29:09 INFO - TEST-INFO | 1647: exit 0 12:29:09 INFO - TEST-OK | dromaeo_css | took 1117970ms 12:29:09 INFO - TEST-START | kraken 12:29:09 INFO - operating with platform_type : mac_ 12:29:09 INFO - Initialising browser for kraken test... 12:29:09 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpA9gIgS/profile http://localhost:49267/getInfo.html) 12:29:11 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 12:29:11 INFO - PROCESS | 1653 | colorDepth:24 12:29:11 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 12:29:11 INFO - PROCESS | 1653 | __metrics 12:29:12 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:29:12 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:29:12 INFO - TEST-INFO | 1653: exit 0 12:29:12 INFO - Browser initialized. 12:29:12 INFO - Running cycle 1/1 for kraken test... 12:29:12 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpA9gIgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:24 INFO - PROCESS | 1654 | RSS: Main: 228917248 12:29:24 INFO - PROCESS | 1654 | 12:29:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 12:29:28 INFO - PROCESS | 1654 | RSS: Main: 294199296 12:29:28 INFO - PROCESS | 1654 | 12:29:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 12:29:31 INFO - PROCESS | 1654 | RSS: Main: 294936576 12:29:31 INFO - PROCESS | 1654 | 12:29:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 12:29:35 INFO - PROCESS | 1654 | RSS: Main: 379691008 12:29:35 INFO - PROCESS | 1654 | 12:29:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 12:29:38 INFO - PROCESS | 1654 | RSS: Main: 281735168 12:29:38 INFO - PROCESS | 1654 | 12:29:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:41 INFO - PROCESS | 1654 | RSS: Main: 285597696 12:29:41 INFO - PROCESS | 1654 | 12:29:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:44 INFO - PROCESS | 1654 | RSS: Main: 325849088 12:29:44 INFO - PROCESS | 1654 | 12:29:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:48 INFO - PROCESS | 1654 | RSS: Main: 326299648 12:29:48 INFO - PROCESS | 1654 | 12:29:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 12:29:51 INFO - PROCESS | 1654 | RSS: Main: 325550080 12:29:51 INFO - PROCESS | 1654 | 12:29:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:54 INFO - PROCESS | 1654 | RSS: Main: 302067712 12:29:54 INFO - PROCESS | 1654 | 12:29:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:56 INFO - PROCESS | 1654 | RSS: Main: 303546368 12:29:56 INFO - PROCESS | 1654 | 12:29:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:59 INFO - PROCESS | 1654 | RSS: Main: 305520640 12:29:59 INFO - PROCESS | 1654 | 12:30:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:03 INFO - PROCESS | 1654 | RSS: Main: 313339904 12:30:03 INFO - PROCESS | 1654 | 12:30:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:06 INFO - PROCESS | 1654 | RSS: Main: 314744832 12:30:06 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 12:30:08 INFO - PROCESS | 1654 | RSS: Main: 358633472 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | __start_tp_report 12:30:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 12:30:08 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 12:30:08 INFO - PROCESS | 1654 | |i|pagename|runs| 12:30:08 INFO - PROCESS | 1654 | |0;ai-astar;96;113;107;112;113;112;116;113;113;112 12:30:08 INFO - PROCESS | 1654 | |1;audio-beat-detection;142;110;132;188;138;135;127;107;157;107 12:30:08 INFO - PROCESS | 1654 | |2;audio-dft;187;183;183;183;183;182;183;185;180;178 12:30:08 INFO - PROCESS | 1654 | |3;audio-fft;76;78;104;94;57;64;82;111;106;57 12:30:08 INFO - PROCESS | 1654 | |4;audio-oscillator;83;82;131;74;100;114;135;72;69;114 12:30:08 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;87;84;87;87;90;84;87;87;86;84 12:30:08 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;93;94;93;93;93;93;93;93;93 12:30:08 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;85;84;87;97;133;105;127;142;135 12:30:08 INFO - PROCESS | 1654 | |8;json-parse-financial;56;55;52;62;77;84;84;83;55;55 12:30:08 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;66;66;72;68;74;77;71;75;104;60 12:30:08 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;83;84;84;87;101;81;84;89;90 12:30:08 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;140;136;137;138;137;137;138;139;150;140 12:30:08 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;168;166;169;167;166;166;168;169;167;168 12:30:08 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;68;69;67;65;67;66;66;67;66 12:30:08 INFO - PROCESS | 1654 | __end_tp_report 12:30:08 INFO - PROCESS | 1654 | __start_cc_report 12:30:08 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2165 12:30:08 INFO - PROCESS | 1654 | __end_cc_report 12:30:08 INFO - PROCESS | 1654 | __startTimestamp1471894208577__endTimestamp 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | ------- Summary: start ------- 12:30:08 INFO - PROCESS | 1654 | Number of tests: 14 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:110.70 Median:113.00 stddev:5.62 (5.0%) stddev-sans-first:2.35 12:30:08 INFO - PROCESS | 1654 | Values: 96.0 113.0 107.0 112.0 113.0 112.0 116.0 113.0 113.0 112.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:136.50 stddev:24.97 (18.3%) stddev-sans-first:26.33 12:30:08 INFO - PROCESS | 1654 | Values: 142.0 110.0 132.0 188.0 138.0 135.0 127.0 107.0 157.0 107.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:2.45 (1.3%) stddev-sans-first:2.05 12:30:08 INFO - PROCESS | 1654 | Values: 187.0 183.0 183.0 183.0 183.0 182.0 183.0 185.0 180.0 178.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:82.90 Median:70.00 stddev:20.17 (28.8%) stddev-sans-first:21.24 12:30:08 INFO - PROCESS | 1654 | Values: 76.0 78.0 104.0 94.0 57.0 64.0 82.0 111.0 106.0 57.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:70.50 stddev:24.82 (35.2%) stddev-sans-first:25.77 12:30:08 INFO - PROCESS | 1654 | Values: 83.0 82.0 131.0 74.0 100.0 114.0 135.0 72.0 69.0 114.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 12:30:08 INFO - PROCESS | 1654 | Values: 87.0 84.0 87.0 87.0 90.0 84.0 87.0 87.0 86.0 84.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:30:08 INFO - PROCESS | 1654 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 93.0 93.0 93.0 93.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:108.10 Median:84.50 stddev:23.64 (28.0%) stddev-sans-first:23.69 12:30:08 INFO - PROCESS | 1654 | Values: 86.0 85.0 84.0 87.0 97.0 133.0 105.0 127.0 142.0 135.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:69.50 stddev:13.87 (20.0%) stddev-sans-first:14.20 12:30:08 INFO - PROCESS | 1654 | Values: 56.0 55.0 52.0 62.0 77.0 84.0 84.0 83.0 55.0 55.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:71.50 stddev:11.92 (16.7%) stddev-sans-first:12.34 12:30:08 INFO - PROCESS | 1654 | Values: 66.0 66.0 72.0 68.0 74.0 77.0 71.0 75.0 104.0 60.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.50 stddev:5.69 (6.7%) stddev-sans-first:6.00 12:30:08 INFO - PROCESS | 1654 | Values: 85.0 83.0 84.0 84.0 87.0 101.0 81.0 84.0 89.0 90.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:138.50 stddev:4.02 (2.9%) stddev-sans-first:4.26 12:30:08 INFO - PROCESS | 1654 | Values: 140.0 136.0 137.0 138.0 137.0 137.0 138.0 139.0 150.0 140.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.00 stddev:1.17 (0.7%) stddev-sans-first:1.22 12:30:08 INFO - PROCESS | 1654 | Values: 168.0 166.0 169.0 167.0 166.0 166.0 168.0 169.0 167.0 168.0 12:30:08 INFO - PROCESS | 1654 | 12:30:08 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 12:30:08 INFO - PROCESS | 1654 | Values: 67.0 68.0 69.0 67.0 65.0 67.0 66.0 66.0 67.0 66.0 12:30:08 INFO - PROCESS | 1654 | -------- Summary: end -------- 12:30:08 INFO - PROCESS | 1654 | 12:30:09 INFO - TEST-INFO | 1654: exit 0 12:30:09 INFO - TEST-OK | kraken | took 59537ms 12:30:09 INFO - SUITE-END | took 1177s 12:30:09 INFO - Completed test suite (00:19:37) 12:30:09 INFO - javascript benchmark 12:30:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3336.5662463879694, 3361.1933136047346, 3394.3550725362165, 3405.4381027130507, 3422.229998250394, 5525.392206857253, 5609.971950140243, 5691.3739488656265, 5751.769929202832, 5785.913211301834, 5568.805091821787, 5645.802396916099, 5649.661020338783, 5652.123920792284, 5682.090865461527, 5002.12462819007, 5034.874128146807, 5122.871928201787, 5150.3047088642925, 5227.843164705046, 5249.606279529051, 5292.735363231843, 5305.071612467822, 5305.814296499604, 5328.733563321838, 5147.613928955325, 5311.840644780644, 5314.867128321804, 5322.414534401213, 5324.840254792343, 5039.596832253411, 5260.132078207092, 5268.446813084647, 5298.258243845884, 5323.813666521692, 5082.23766435034, 5100.132977393852, 5159.948400516022, 5171.276021357013, 5180.326557547494, 5597.216389705403, 5600.411956744565, 5638.689872057035, 5638.943610563882, 5662.49037586615, 6233.781817636406, 6236.407541283571, 6242.344553821874, 6261.624302541862, 6272.65500397478, 6117.755289788403, 6196.915539780521, 6244.281907580641, 6250.0, 6275.905861412082, 5874.412558744091, 5901.970490147522, 5904.2029326040865, 5922.230110085661, 5928.67392293428, 6289.528285378615, 6304.243490781136, 6327.58870673414, 6338.01760727088, 6355.428011478989, 5958.433948774859, 6092.360302168208, 6159.876802463926, 6185.690715464209, 6245.531585131133, 6314.210723659543, 6317.778877739257, 6323.557350985387, 6325.683715814191, 6327.145835312174, 6182.536309776785, 6239.844003899939, 6248.843778905563, 6263.154474145932, 6279.309275979638, 6033.637981721111, 6110.29731580865, 6123.295820980555, 6181.258249010148, 6188.845278868064, 4911.901761964812, 4935.062338155739, 4951.084049450791, 5014.573761230264, 5023.6985780853265, 4448.132614140211, 4455.153520831096, 4459.5986361227, 4475.172093162775, 4497.595216430537, 5556.472135147154, 5703.144528320702, 5711.228984087095, 5727.31272247333, 5735.283089613798, 6201.0388389799655, 6277.843053923689, 6300.527460440485, 6332.746690132343, 6343.397377249154, 5318.494742999409, 5694.886102277931, 5720.628159169724, 5741.311042674905, 5746.540276777848, 5273.551748101378, 5273.683578985273, 5465.316835395575, 5474.069408200616, 5492.368377636623, 5708.771649133988, 6254.6872656366995, 6281.748730050746, 6307.3377295383325, 6326.841828954313, 5957.821265362046, 6308.432241098323, 6322.778702745381, 6332.525060620564, 6334.144890439732, 6277.2153480814895, 6289.339619340087, 6351.460125889166, 6352.396522330371, 6358.078078678658, 6127.234095738033, 6168.969155154375, 6183.81448556544, 6262.812115636538, 6270.529710271657, 6269.310375858743, 6308.905366419599, 6313.463355614824, 6321.020241862519, 6350.237971443272, 5860.0, 6163.414475624988, 6165.136880836776, 6195.504359651127, 6196.194494715838, 5996.130561068708, 6045.21412216409, 6097.725602347994, 6157.6613286269685, 6164.506839452923, 4894.241392584155, 5919.319278283053, 5933.406659334032, 5941.287045554561, 5955.225820643289, 5898.498627616701, 6108.816735497942, 6127.0, 6152.323244443018, 6155.045967874987, 5803.158542011469, 5840.123981402654, 5871.0, 5941.22764040672, 5960.6125601837475], "value": 5702.399145943112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.7713006277238, 524.6904326447395, 527.7282199667171, 528.8202011316149, 551.1484756051899, 30646.8547201696, 39961.60038399615, 41130.17739645209, 41318.38022429666, 43338.0, 42810.57189428105, 43190.35214471786, 43336.0, 43387.78306108473, 43656.69029929107, 8698.173673501007, 9670.70987870365, 9830.361026533288, 9846.163076138537, 9847.852282215737, 8684.913150868473, 8852.0, 8857.822843543125, 8902.109789021079, 11797.941010294937, 11802.05583553318, 12095.576654817083, 12195.512179512853, 12209.877901220963, 12222.022238220921, 12070.396480176, 12118.757624847454, 12128.332941688204, 12146.45340959659, 12159.756804863853, 8628.439151455135, 11627.244229125165, 11762.411879405996, 11842.23025503348, 11881.287122772575, 40898.386524202164, 41378.96552586209, 41450.79274603638, 41455.7927210365, 41509.79245103785, 3528.8059156746367, 3570.517980072683, 3809.466674665549, 3819.9427008594894, 4144.772037537934, 31078.844605776827, 32074.679253207636, 32191.83904080488, 32351.0, 32366.838165809255, 3265.2489927316615, 3359.378514974713, 3377.1219482934366, 3426.691597756189, 3437.415639341293, 486039.0, 488627.5568622134, 488819.0, 489073.0, 489262.0, 55773.44226557682, 56065.43934560602, 56129.0, 56169.15746263809, 56170.0, 51602.74198629058, 59834.20497385086, 60590.0, 60757.69621151866, 60879.39120608826, 15872.52382428529, 16058.116803575682, 16100.19499025044, 16106.033637981523, 16134.031958082553, 12584.937075314565, 12640.810387844005, 12641.557545485864, 12650.493980240688, 12654.493820247271, 12760.07401745175, 13671.589852304447, 13951.930240348733, 14008.299585020708, 14318.57044288673, 4915.5330243626795, 5278.709670968057, 5928.79249226275, 6204.534659900525, 6217.595856269328, 848.1476116502998, 940.5532372123187, 966.6471737815788, 1050.3225419604312, 1087.3475914451267, 27798.861005694842, 27874.02440914599, 30229.941952031575, 30491.69508304933, 31188.532172016985, 991.3407583956732, 1014.6905193915779, 1068.6793961811425, 1069.4599227390274, 1105.474899422784, 15212.02243708333, 15218.771718424232, 15259.465918693015, 15589.142597157039, 15622.687546249012, 664936.0, 708516.0, 708912.0, 708962.0, 914469.0, 58848.0, 59455.702721486115, 59504.70247648821, 59527.702361487914, 59591.40408595859, 1308.3719814489098, 1362.6865820861258, 1402.2217669193492, 1404.5224623628017, 1404.6628809085746, 30084.398312034074, 30441.0, 31641.0, 31830.363392731553, 32675.183120422178, 55824.883502328914, 55839.44160558505, 55994.44005560055, 56036.719816400655, 56193.0, 554148.0, 574898.0, 576847.1157644185, 581622.0, 585028.0, 25815.870920645277, 26050.86974565115, 26246.86876565605, 26287.60568591511, 26720.0, 28598.571021435113, 32955.0, 40009.79995100122, 48507.0, 48967.26549101695, 39189.41215881707, 39299.0, 39314.0, 41079.38380924229, 41218.1756364877, 39241.018974524726, 39369.80315098406, 40582.1883562333, 40612.0, 40669.593304066584, 30555.763744197568, 37635.05912352213, 38299.0, 38615.6138438612, 38956.02609934774, 33048.50427243545, 34471.96584102481, 34814.12964675904, 34912.0, 35268.64731352653, 25400.110996115047, 25457.0, 25665.0, 26118.73881261163, 26197.869010654824], "value": 20150.588290259533, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.23388545200567, 369.2485791414471, 372.06982543640896, 376.6290204148913, 404.094827586207, 15279.083255004707, 16086.51740447787, 20412.387628371136, 20571.177152913904, 20623.896880515596, 16887.0, 19973.700394494095, 21203.83378914159, 21303.786962130373, 21338.35984920451, 5561.360443549003, 5964.7912323068595, 6203.1625730526475, 6223.190985171944, 6237.875242495147, 6078.392160783908, 6244.656543890084, 6246.906296405557, 6249.750009999594, 6256.812295631138, 5141.871453213681, 5949.018411962021, 6123.050927106284, 6129.387061293857, 6219.0, 6067.848303792419, 6132.356102609207, 6133.570650054498, 6138.202033735608, 6139.109829074804, 5184.870378240536, 5444.292242008553, 5488.039593071197, 5492.917606235909, 5496.395396506381, 13407.731845363038, 13436.395362208725, 13453.461861525528, 13633.045686801928, 13695.931520342434, 2131.158192513964, 2289.3589794857467, 2322.198841399714, 2352.541254455382, 2716.3888125171875, 9931.255155863268, 10146.767155890506, 10331.845022324671, 10515.369077855277, 12889.90435812957, 1793.7668103146507, 1799.595091104504, 1877.1271358818078, 1886.5943822078318, 1899.772027356726, 120768.0, 126983.3016698332, 127243.36378318141, 128684.35657821744, 128865.0, 11276.661700149009, 11437.37094459796, 11492.310461372344, 11565.19043666952, 11741.0, 14387.0, 14404.42382304696, 14424.639384015483, 14428.639284017983, 14438.350274237684, 8367.707130250534, 8538.957305213435, 8543.0, 8548.957255213809, 8662.047174810767, 8453.788655283666, 8457.112003239694, 8491.0, 8669.523176225242, 8677.132286771272, 4472.284434490466, 4477.171723231213, 4486.7083639563325, 4487.41663583732, 4558.316252562142, 1835.6695794757072, 1889.4803928919382, 1890.7731072271415, 1892.8391086757779, 1895.3745264062827, 827.210014436212, 873.5021038008401, 878.3004885546488, 905.4702998745676, 907.6641642592283, 20486.97565121738, 21496.518387811007, 21529.031193596013, 21542.030608622335, 21597.46006349854, 920.1810388754014, 922.4373132389237, 927.5501381829807, 938.5025936253796, 947.507296205969, 10461.483084952792, 11291.402977315263, 11390.974812266935, 11397.0, 11408.543658253577, 592393.0, 593933.0, 596921.0153949203, 609273.0, 610359.0, 87990.36149396854, 123662.0, 132891.0, 137368.31315843356, 141871.0, 953.7758626722715, 958.2621381536318, 970.8737864077625, 973.3867663372031, 985.5663508056432, 15973.0, 19261.711074334176, 20121.798782011992, 20137.79862201418, 20165.0, 54182.01998890099, 57223.713881430325, 57253.42746572481, 57353.13970290365, 57733.0, 85969.57015214884, 86472.56763716393, 86612.56693716491, 87148.12851871653, 87814.56092719495, 7079.929200707927, 7874.606269686378, 7963.641636126504, 8177.141400152898, 8250.257476827115, 11777.587784427504, 13046.869531304945, 13053.324068566111, 13294.667633309246, 13388.665283367995, 9609.663661772083, 11096.00135987765, 11191.496382662637, 11395.715107122389, 11500.367479788692, 10837.241393102408, 10943.507542160782, 11025.83461248098, 11217.214794964275, 11262.267952583055, 9186.58660360272, 11274.379909105079, 11627.941860290644, 11642.301461912313, 11836.881631183578, 6558.44253238461, 8049.436539442183, 8060.919390806252, 8115.0, 8223.342132629496, 9795.657151999645, 10741.946290268812, 10954.452277385939, 11147.665570032912, 11171.994520493196], "value": 9451.464514706919, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.5045958684668, 1482.021865568725, 1634.5259874636356, 1837.2008176443248, 1839.475749411418, 8338.666453341866, 8846.911530884698, 8854.734357969262, 8855.867161992559, 8886.91113088869, 7760.495567788104, 7988.081370642378, 8025.117237103914, 8147.511149331044, 8660.696875609356, 2114.6616541353387, 2135.6903249028883, 2295.6556516522487, 2374.3351861478814, 2376.976230237701, 2371.5256948610263, 2374.4538756086026, 2377.512609914972, 2378.7264464586533, 2379.1435083369975, 1959.9020048997565, 2341.4029422497324, 2368.0527788884497, 2368.1829768729763, 2374.5013547155118, 2316.9073237070497, 2338.3218866528687, 2342.273895092518, 2355.434695673026, 2362.681038059857, 2301.1255722825385, 2323.047550504285, 2328.848624839397, 2330.766923307656, 2359.7640235976437, 8682.43564168327, 8718.607662655195, 8848.2478989286, 8851.380403371766, 8880.73357799267, 5129.923051154198, 5284.07528682479, 5296.629235953485, 5299.178627312773, 5301.655392399521, 3449.1549570355173, 3593.6047034826147, 3653.5433070866143, 3656.012876523324, 3668.1013151777984, 1065.4512925843196, 1099.3623698254994, 1106.8118419868642, 1124.9268797528216, 1127.780082883838, 11343.0, 11418.31490110596, 11422.200445968703, 11425.771484570345, 11428.314301141958, 3705.55533335996, 4378.956210437918, 4553.317002449659, 4689.413823272091, 4719.952800471951, 9719.708408747607, 9734.269929755297, 9744.366616169926, 9752.512374381253, 9759.414435133915, 5284.339457567804, 5287.947120528823, 5292.52367286946, 5295.920561191585, 5298.761555730001, 4705.364775755298, 4807.591354734816, 4847.466778654345, 4850.878728031827, 4860.295257187688, 3647.7811331320204, 3733.9439908401396, 3754.831032603485, 3777.395616701315, 3857.0550215197454, 1194.970125746846, 1208.0, 1229.9200551964093, 1269.7143142792982, 1270.8919741821865, 889.5952341684647, 899.3839220134229, 916.9724908252763, 939.3245761915778, 963.1379914976197, 4593.563611456906, 5729.0, 5824.138550250377, 5852.063669812809, 6025.578209525291, 912.1790388650268, 913.4199783137756, 925.1303774451993, 943.2595412601075, 957.5308099031564, 3843.577206507282, 4023.5775243599, 4024.873035549992, 4103.199876024147, 4258.1057977825, 10388.168946484264, 10413.479326033668, 10544.736381590368, 10545.84181237282, 10587.25889187765, 10488.10851077667, 10488.370697758159, 10496.370217786956, 10510.159187265, 10520.842187367196, 7899.960500197462, 8112.675492980215, 8114.8782768258525, 8124.781282807626, 8147.144549822302, 5353.892922141613, 5666.546676265972, 5842.795502157404, 6093.29927058394, 6106.72519736604, 9694.175995040501, 9840.901590984286, 9916.355436896578, 9919.057689519484, 9929.900700993187, 9525.666601668909, 9891.208703303862, 9919.504024798876, 10081.042300981395, 10091.293609447268, 7404.629768511661, 7543.509671871311, 7544.471886968079, 7554.093508778982, 7556.168821429748, 6738.764143254963, 7183.604901730455, 7754.34088102495, 8152.796180095308, 8237.970253718286, 5902.763889444374, 7347.55914645123, 7394.334509894135, 7461.664225109992, 7478.140013898471, 7215.747448839265, 7236.312550307713, 7287.12554493443, 7360.595167265852, 7450.0687414073245, 5913.91129133072, 6640.767573134917, 6817.93182068173, 6895.5173137879865, 6896.551724137988, 4902.2156454967035, 4991.650584459053, 5023.2214006829, 5061.240813877947, 5191.974040129775, 4911.950880491149, 5003.799848006039, 5056.241563765465, 5056.342675452168, 5192.091384007708], "value": 4528.324534424383, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.09851067647577, 296.4234563773461, 304.9740772034377, 305.9908202753917, 307.6631088957591, 4380.080183161536, 4578.702384345015, 5371.435999220084, 5385.976664433755, 5493.7802487900435, 5064.290999260106, 5091.872703182422, 5205.869853253671, 5348.572114230862, 5349.331333583302, 4107.527634322054, 4127.607877251657, 4140.8964775880695, 4156.854510092147, 4166.708330416872, 3491.5635545556806, 3707.6292370762976, 4067.8983025424454, 4076.2458945095254, 4117.505899292074, 3410.7380269300393, 3587.3542762302905, 3787.772733635991, 4090.836366545335, 4109.6506796922295, 4024.4566983457153, 4053.7770422626745, 4058.310087285139, 4066.2274167908004, 4085.9387109193235, 3480.5033835450745, 3487.7907325560545, 3489.4297566095106, 3512.5071844466, 3522.2603253316834, 4193.421046056684, 5244.370675518962, 5276.7889284428575, 5281.207818827168, 5297.788088476456, 4598.0, 4620.283856002325, 4644.303354496818, 4658.510856360067, 4679.040796636681, 4917.754112294371, 5622.465865742789, 5626.690532020737, 5699.68651724155, 5701.657900525982, 3720.4120306585846, 3825.8660946867003, 3837.808109594537, 3878.119112230594, 3885.339492286318, 5435.402105768362, 5584.441555844383, 5967.37342579023, 5986.760529578812, 6023.759049638009, 3853.672437842815, 3865.2849222893856, 3866.516685414323, 3887.2031233597045, 3929.155231625214, 3950.940735888964, 3965.900852478711, 3967.861124860674, 3969.4442778011103, 3982.3628219484744, 3884.669803066771, 4369.902446825541, 4570.680052396367, 4635.536446355338, 4650.790714417859, 5627.409122042127, 5690.5163061140265, 5726.599138060295, 5731.254936858182, 5737.3688894221605, 1262.7790136726219, 1323.2920387592592, 1333.386642144605, 1355.6746380868522, 1363.5636596289291, 684.5721424109931, 811.0753740735566, 851.9393355272601, 858.8454078265972, 868.2619773192862, 4659.6505262105475, 5263.315768950089, 5266.605004624592, 5342.652727572696, 5358.5981051421295, 5297.788088476418, 5818.738156782955, 5820.650760954356, 5824.301083869963, 5847.56143289255, 4396.296277789137, 4993.775280112403, 5173.663711858717, 5245.606579506552, 5289.418164001957, 2757.903992965361, 2950.8081974671577, 3008.6540047894773, 3047.0, 3049.9542506862413, 5181.481851814789, 5939.821805345847, 5977.940220597825, 5979.206238128544, 6007.639541627516, 5668.858278543069, 5901.232839730807, 5921.3190483094995, 5937.643741375531, 5955.702214889325, 5192.584593232532, 5199.516324062812, 5893.469587737039, 5930.7627694893445, 5958.553108516879, 4388.670849686222, 5402.324709411324, 5764.884702305846, 5803.767849285981, 5821.505172060253, 4975.975120124376, 5412.431694672003, 5654.886902261849, 5671.631343962629, 5733.655980641175, 5713.342965559014, 5914.526837853047, 5954.672493012926, 5965.522758179422, 6003.949308870843, 3626.1172367967, 3798.905027374338, 3827.483289755847, 3827.61723827615, 3836.8848934532007, 5202.401723801811, 5232.659877107976, 5627.803026894039, 5760.855978600569, 5761.5390768739235, 5066.795943764474, 5425.810096646598, 5650.689212093374, 5652.236948011966, 5704.80033198836, 5556.694381809039, 5620.824234307766, 5633.492985631313, 5706.600537962303, 5775.393583673654, 5221.485769126911, 5472.0, 5518.144687573433, 5620.915686264628, 5630.268065151504, 4754.572088512051, 5744.856378590569, 5750.338711048283, 5757.683327416865, 5760.798372056958, 5156.968606278834, 5206.979951627971, 5605.80379686709, 5618.0, 5633.084751184617], "value": 4117.7697804993095, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.2143641993806, 762.249184553215, 780.7267456390264, 810.2707018213732, 836.7782537627528, 15292.0, 16059.357625694958, 16489.010659360447, 16511.752323715154, 16534.83465165348, 14935.775963360531, 15065.397384104623, 15232.543023709279, 15235.46675866342, 15274.236288185573, 659.3604203922212, 813.243683374463, 824.983500329993, 825.5253229393093, 825.6697321071559, 665.5540787672271, 819.5983967855767, 845.0873057098319, 852.0159216105408, 854.3592305770673, 886.8935727712684, 888.1030159538848, 892.5715656484891, 894.0969620683092, 894.1371576428745, 682.8088135322117, 885.942413743111, 887.0, 887.7913690282811, 889.253027456936, 646.8221239159269, 670.2225418514548, 671.228087699145, 671.5936858200815, 674.1236392689484, 15072.54782356531, 15117.773233401507, 15122.621934451618, 15138.167400792952, 15147.0, 13968.161910285315, 14063.578092657237, 14320.57038288853, 14370.425182992667, 14593.343299551545, 13058.2817945013, 13083.738325233442, 13193.472261109544, 13334.666633334147, 13623.0, 4520.276755719102, 4603.447586289667, 4615.607673347769, 4661.113930600734, 4715.104130215248, 119202.0, 119626.0, 120743.79256207413, 120881.0, 121143.78856211502, 9738.415695058318, 10788.730281743019, 11173.162012848907, 11212.775744485065, 11713.121515886343, 22795.544089118124, 22801.429964251027, 22805.88597057004, 22811.771882281297, 22871.542569148525, 7938.761837144895, 8080.555569443737, 8096.311813495801, 8109.43233973628, 8310.418270721113, 4455.821767129343, 4494.752788596593, 4515.390422292949, 4566.474855391606, 4583.679142460062, 3436.896893093211, 3549.254656522159, 3550.840212190406, 3551.3785087609567, 3554.4668299754726, 639.8944174211296, 652.4486808646781, 663.9004149377536, 664.3489380407228, 665.7137430904758, 593.3532449629924, 619.1641284266294, 622.3372108704216, 646.1180488632982, 675.7195763758084, 8314.127016663284, 8964.731058068268, 9197.402168859002, 9268.95365523168, 9304.441733496013, 527.6939375162397, 589.9498891972296, 605.1527860994555, 633.7876811268185, 652.7715299645181, 4269.978650106729, 4456.9331460028125, 4494.325851122292, 4543.775765863731, 4569.451665800125, 95082.52458737801, 142197.0, 151143.15283200832, 157262.0, 159206.20396897942, 84779.57610212032, 85559.14440855636, 120005.0, 131967.34016329856, 136065.319673401, 113168.0, 114220.42889785497, 114284.0, 115511.0, 116171.83828161779, 8368.41421100517, 8652.740417787476, 8743.994440639408, 8955.686550970684, 8956.641734330815, 56010.71994640164, 56449.71775141098, 61718.76562468815, 65038.02442963265, 67252.0, 64601.35398645954, 71642.0, 75109.0, 84068.57965710132, 91553.0, 13238.801417978546, 13362.198268103944, 13460.596182114552, 13727.725445490834, 13846.515371961934, 14894.180820055, 14933.0, 15231.0, 15499.845001550291, 15956.281967311266, 13155.210687358789, 13315.810776372267, 13576.0, 13798.793018104536, 14011.0, 11487.597934072268, 13331.866681333062, 13332.800007999695, 14495.985281030227, 14533.200673963034, 12611.108333750011, 13003.804942926054, 13090.280034598189, 13313.933430332787, 13389.66525836862, 10407.84388234162, 11580.594679186188, 11678.00736937336, 11848.466818992994, 11916.642500724993, 8529.530875801891, 8759.686047092988, 9421.046871424482, 10663.680089597325, 11702.239354441934], "value": 7804.470476374062, "unit": "score"}], "name": "dromaeo_css", "value": 7353.13093219033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 113.0, 107.0, 112.0, 113.0, 112.0, 116.0, 113.0, 113.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 132.0, 188.0, 138.0, 135.0, 127.0, 107.0, 157.0, 107.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 183.0, 183.0, 183.0, 182.0, 183.0, 185.0, 180.0, 178.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 104.0, 94.0, 57.0, 64.0, 82.0, 111.0, 106.0, 57.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 131.0, 74.0, 100.0, 114.0, 135.0, 72.0, 69.0, 114.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 87.0, 90.0, 84.0, 87.0, 87.0, 86.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 87.0, 97.0, 133.0, 105.0, 127.0, 142.0, 135.0], "value": 108.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 52.0, 62.0, 77.0, 84.0, 84.0, 83.0, 55.0, 55.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 72.0, 68.0, 74.0, 77.0, 71.0, 75.0, 104.0, 60.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 84.0, 87.0, 101.0, 81.0, 84.0, 89.0, 90.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 137.0, 138.0, 137.0, 137.0, 138.0, 139.0, 150.0, 140.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 169.0, 167.0, 166.0, 166.0, 168.0, 169.0, 167.0, 168.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 67.0, 65.0, 67.0, 66.0, 66.0, 67.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1495.3, "alertThreshold": 2.0}]} 12:30:09 INFO - Return code: 0 12:30:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:30:09 INFO - # TBPL SUCCESS # 12:30:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:30:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:30:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:30:09 INFO - 2016-08-22 12:30:09.894 screenresolution[1659:9710] starting screenresolution argv=screenresolution get 12:30:09 INFO - 2016-08-22 12:30:09.897 screenresolution[1659:9710] Display 0: 1600x1200x32@60 12:30:09 INFO - 2016-08-22 12:30:09.903 screenresolution[1660:9713] starting screenresolution argv=screenresolution list 12:30:09 INFO - Available Modes on Display 0 12:30:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:30:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:30:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:30:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:30:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:30:10 INFO - Intel Iris: 12:30:10 INFO - Chipset Model: Intel Iris 12:30:10 INFO - Type: GPU 12:30:10 INFO - Bus: Built-In 12:30:10 INFO - VRAM (Dynamic, Max): 1536 MB 12:30:10 INFO - Vendor: Intel (0x8086) 12:30:10 INFO - Device ID: 0x0a2e 12:30:10 INFO - Revision ID: 0x0009 12:30:10 INFO - Displays: 12:30:10 INFO - Display: 12:30:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:30:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:30:10 INFO - Display Serial Number: 1600x1200 60 12:30:10 INFO - Main Display: Yes 12:30:10 INFO - Mirror: Off 12:30:10 INFO - Online: Yes 12:30:10 INFO - Rotation: Supported 12:30:10 INFO - Return code: 0 12:30:10 INFO - Running post-action listener: _resource_record_post_action 12:30:10 INFO - [mozharness: 2016-08-22 19:30:10.137695Z] Finished run-tests step (success) 12:30:10 INFO - Running post-run listener: _resource_record_post_run 12:30:10 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37196800; Write bytes: 706546176; Read time: 239; Write time: 1996 12:30:10 INFO - TinderboxPrint: CPU usage
53.4% 12:30:10 INFO - TinderboxPrint: I/O read bytes / time
37,196,800 / 239 12:30:10 INFO - TinderboxPrint: I/O write bytes / time
706,546,176 / 1,996 12:30:10 INFO - TinderboxPrint: CPU idle
2,260.7 (46.6%) 12:30:10 INFO - TinderboxPrint: CPU system
965.9 (19.9%) 12:30:10 INFO - TinderboxPrint: CPU user
1,627.1 (33.5%) 12:30:10 INFO - TinderboxPrint: Swap in / out
443,150,336 / 0 12:30:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398825984; Write bytes: 387823616; Read time: 29533; Write time: 821 12:30:10 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30944256; Write bytes: 294709760; Read time: 183; Write time: 1111 12:30:10 INFO - Running post-run listener: _upload_blobber_files 12:30:10 INFO - Blob upload gear active. 12:30:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:30:10 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 12:30:10 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:30:10 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:30:11 INFO - (blobuploader) - INFO - Open directory for files ... 12:30:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:30:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:30:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:12 INFO - (blobuploader) - INFO - Done attempting. 12:30:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:30:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:30:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:13 INFO - (blobuploader) - INFO - Done attempting. 12:30:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:30:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:30:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:15 INFO - (blobuploader) - INFO - Done attempting. 12:30:15 INFO - (blobuploader) - INFO - Iteration through files over. 12:30:15 INFO - Return code: 0 12:30:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:30:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:30:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9b99bb8f4a4d2b4affb754de7a73d55d694a186d923ebfac6545a7558f8126ff0e4fffcc30acbd72b3e36d9df1c61208d5bc65afd6f0601dad39d2ed2f90c93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ef1874c17c5582350b43672d2d849096909ad96aac12caeba9264160c168ed66f46d958d1f27ce0aabe933b288efeadfcf011d0ab2c3dd8c6d311bcd4df52b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad11b3e1ea6ed438799ecd5e382999039681744b3cae5cb57951720b5efcf385e098e5d8e08fa16587d31495ea29663a2bd5fbe4f864a2a685b5d6191b8e2076"} 12:30:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:30:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:30:15 INFO - Contents: 12:30:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9b99bb8f4a4d2b4affb754de7a73d55d694a186d923ebfac6545a7558f8126ff0e4fffcc30acbd72b3e36d9df1c61208d5bc65afd6f0601dad39d2ed2f90c93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ef1874c17c5582350b43672d2d849096909ad96aac12caeba9264160c168ed66f46d958d1f27ce0aabe933b288efeadfcf011d0ab2c3dd8c6d311bcd4df52b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad11b3e1ea6ed438799ecd5e382999039681744b3cae5cb57951720b5efcf385e098e5d8e08fa16587d31495ea29663a2bd5fbe4f864a2a685b5d6191b8e2076"} 12:30:15 INFO - Running post-run listener: copy_logs_to_upload_dir 12:30:15 INFO - Copying logs to upload dir... 12:30:15 INFO - mkdir: /builds/slave/test/build/upload/logs 12:30:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.658280 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-22 12:30:15.966915) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 12:30:15.970939) ========= 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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/Fx-Team/sha512/d9b99bb8f4a4d2b4affb754de7a73d55d694a186d923ebfac6545a7558f8126ff0e4fffcc30acbd72b3e36d9df1c61208d5bc65afd6f0601dad39d2ed2f90c93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ef1874c17c5582350b43672d2d849096909ad96aac12caeba9264160c168ed66f46d958d1f27ce0aabe933b288efeadfcf011d0ab2c3dd8c6d311bcd4df52b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad11b3e1ea6ed438799ecd5e382999039681744b3cae5cb57951720b5efcf385e098e5d8e08fa16587d31495ea29663a2bd5fbe4f864a2a685b5d6191b8e2076"} build_url:https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/NZH8bvEjSCSKSsFT826zpA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9b99bb8f4a4d2b4affb754de7a73d55d694a186d923ebfac6545a7558f8126ff0e4fffcc30acbd72b3e36d9df1c61208d5bc65afd6f0601dad39d2ed2f90c93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ef1874c17c5582350b43672d2d849096909ad96aac12caeba9264160c168ed66f46d958d1f27ce0aabe933b288efeadfcf011d0ab2c3dd8c6d311bcd4df52b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ad11b3e1ea6ed438799ecd5e382999039681744b3cae5cb57951720b5efcf385e098e5d8e08fa16587d31495ea29663a2bd5fbe4f864a2a685b5d6191b8e2076"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 12:30:16.004706) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:30:16.005180) ========= 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.cdUYDv8CGN/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.Qzm9uLmQoE/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 12:30:16.054471) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 12:30:16.054796) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 12:30:16.055117) ========= ========= Total master_lag: 2.70 =========