builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0112 starttime: 1472906139.28 results: success (0) buildid: 20160903030202 builduid: 477c217fa5694c43b708706287c3f8d5 revision: d0830980ffdb36a10855d02a588b4869cad6707e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 05:35:39.276482) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 05:35:39.276878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 17 secs) (at 2016-09-03 05:35:39.277617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 17 secs) (at 2016-09-03 05:35:56.823092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-03 05:35:56.823405) ========= 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.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-09-03 05:35:57-- 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% 14.0M=0.001s 2016-09-03 05:35:57 (14.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168231 ========= master_lag: 1.86 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-03 05:35:58.851687) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 05:35:58.854682) ========= 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.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.028172 ========= master_lag: 0.14 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 05:35:59.020679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-03 05:35:59.021032) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0830980ffdb36a10855d02a588b4869cad6707e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d0830980ffdb36a10855d02a588b4869cad6707e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-09-03 05:35:59,105 truncating revision to first 12 chars 2016-09-03 05:35:59,105 Setting DEBUG logging. 2016-09-03 05:35:59,105 attempt 1/10 2016-09-03 05:35:59,105 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0830980ffdb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 05:36:00,141 unpacking tar archive at: mozilla-central-d0830980ffdb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317893 ========= master_lag: 2.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-03 05:36:02.558374) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 05:36:02.558702) ========= script_repo_revision: d0830980ffdb36a10855d02a588b4869cad6707e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 05:36:02.559077) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-09-03 05:36:02.559342) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-09-03 05:36:04.798802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-03 05:36:04.799087) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:36:04 INFO - MultiFileLogger online at 20160903 05:36:04 in /builds/slave/test 05:36:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:36:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:36:04 INFO - {'append_to_log': False, 05:36:04 INFO - 'base_work_dir': '/builds/slave/test', 05:36:04 INFO - 'blob_upload_branch': 'Firefox', 05:36:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:36:04 INFO - 'branch': 'Firefox', 05:36:04 INFO - 'buildbot_json_path': 'buildprops.json', 05:36:04 INFO - 'config_files': ('talos/mac_config.py',), 05:36:04 INFO - 'default_actions': ('clobber', 05:36:04 INFO - 'read-buildbot-config', 05:36:04 INFO - 'download-and-extract', 05:36:04 INFO - 'populate-webroot', 05:36:04 INFO - 'create-virtualenv', 05:36:04 INFO - 'install', 05:36:04 INFO - 'run-tests'), 05:36:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:36:04 INFO - 'download_minidump_stackwalk': True, 05:36:04 INFO - 'download_symbols': 'ondemand', 05:36:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:36:04 INFO - 'tooltool.py': '/tools/tooltool.py', 05:36:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:36:04 INFO - '/tools/misc-python/virtualenv.py')}, 05:36:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:36:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:36:04 INFO - 'installer_path': 'installer.exe', 05:36:04 INFO - 'log_level': 'info', 05:36:04 INFO - 'log_name': 'talos', 05:36:04 INFO - 'log_to_console': True, 05:36:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:36:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:36:04 INFO - 'opt_config_files': (), 05:36:04 INFO - 'pip_index': False, 05:36:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:04 INFO - 'cmd': ('bash', 05:36:04 INFO - '-c', 05:36:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:04 INFO - 'enabled': True, 05:36:04 INFO - 'halt_on_failure': False, 05:36:04 INFO - 'name': 'check_screen_resolution'},), 05:36:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:36:04 INFO - 'cmd': ('bash', 05:36:04 INFO - '-c', 05:36:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:36:04 INFO - 'enabled': True, 05:36:04 INFO - 'halt_on_failure': False, 05:36:04 INFO - 'name': 'check_screen_resolution'},), 05:36:04 INFO - 'run_cmd_checks_enabled': True, 05:36:04 INFO - 'sps_profile': False, 05:36:04 INFO - 'sps_profile_interval': 0, 05:36:04 INFO - 'suite': 'dromaeojs', 05:36:04 INFO - 'system_bits': '32', 05:36:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:36:04 INFO - 'title': 't-yosemite-r7-0112', 05:36:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:36:04 INFO - 'use_talos_json': True, 05:36:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:36:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:36:04 INFO - 'work_dir': 'build'} 05:36:04 INFO - [mozharness: 2016-09-03 12:36:04.997386Z] Running clobber step. 05:36:04 INFO - Running pre-action listener: _resource_record_pre_action 05:36:04 INFO - Running main action method: clobber 05:36:04 INFO - rmtree: /builds/slave/test/build 05:36:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:36:06 INFO - Running post-action listener: _resource_record_post_action 05:36:06 INFO - [mozharness: 2016-09-03 12:36:06.603378Z] Finished clobber step (success) 05:36:06 INFO - [mozharness: 2016-09-03 12:36:06.603554Z] Running read-buildbot-config step. 05:36:06 INFO - Running pre-action listener: _resource_record_pre_action 05:36:06 INFO - Running main action method: read_buildbot_config 05:36:06 INFO - Using buildbot properties: 05:36:06 INFO - { 05:36:06 INFO - "project": "", 05:36:06 INFO - "product": "firefox", 05:36:06 INFO - "script_repo_revision": "production", 05:36:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:36:06 INFO - "repository": "", 05:36:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:36:06 INFO - "buildid": "20160903030202", 05:36:06 INFO - "stage_platform": "macosx64", 05:36:06 INFO - "basedir": "/builds/slave/test", 05:36:06 INFO - "nightly_build": "True", 05:36:06 INFO - "buildnumber": 38, 05:36:06 INFO - "slavename": "t-yosemite-r7-0112", 05:36:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:36:06 INFO - "platform": "yosemite_r7", 05:36:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:36:06 INFO - "branch": "mozilla-central", 05:36:06 INFO - "repo_path": "mozilla-central", 05:36:06 INFO - "revision": "d0830980ffdb36a10855d02a588b4869cad6707e", 05:36:06 INFO - "pgo_build": "False", 05:36:06 INFO - "builduid": "477c217fa5694c43b708706287c3f8d5", 05:36:06 INFO - "slavebuilddir": "test" 05:36:06 INFO - } 05:36:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:36:06 INFO - Running post-action listener: _resource_record_post_action 05:36:06 INFO - [mozharness: 2016-09-03 12:36:06.606716Z] Finished read-buildbot-config step (success) 05:36:06 INFO - [mozharness: 2016-09-03 12:36:06.606824Z] Running download-and-extract step. 05:36:06 INFO - Running pre-action listener: _resource_record_pre_action 05:36:06 INFO - Running main action method: download_and_extract 05:36:06 INFO - mkdir: /builds/slave/test/build/tests 05:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:06 INFO - trying https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:36:06 INFO - Downloading https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:07 INFO - Downloaded 1199 bytes. 05:36:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:36:07 INFO - Using the following test package requirements: 05:36:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:36:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:36:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:36:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'jsshell-mac.zip'], 05:36:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:36:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:36:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:36:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:36:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:36:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:36:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:36:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:07 INFO - trying https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:36:07 INFO - Downloading https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:08 INFO - Downloaded 16400025 bytes. 05:36:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:36:15 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 05:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:15 INFO - trying https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:36:15 INFO - Downloading https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:16 INFO - Downloaded 16400025 bytes. 05:36:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:23 INFO - trying https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:36:23 INFO - Downloading https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:24 INFO - Downloaded 14413998 bytes. 05:36:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:25 INFO - trying https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:36:25 INFO - Downloading https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:36:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/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 05:36:27 INFO - Downloaded 111631322 bytes. 05:36:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:36:27 INFO - mkdir: /builds/slave/test/properties 05:36:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:36:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:36:27 INFO - Contents: 05:36:27 INFO - build_url:https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:36:28 INFO - Running post-action listener: _resource_record_post_action 05:36:28 INFO - Running post-action listener: set_extra_try_arguments 05:36:28 INFO - [mozharness: 2016-09-03 12:36:28.423813Z] Finished download-and-extract step (success) 05:36:28 INFO - [mozharness: 2016-09-03 12:36:28.423977Z] Running populate-webroot step. 05:36:28 INFO - Running pre-action listener: _resource_record_pre_action 05:36:28 INFO - Running main action method: populate_webroot 05:36:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:36:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:36:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:36:28 INFO - '--tppagecycles', 05:36:28 INFO - '7'], 05:36:28 INFO - 'tests': ['tsvgm']}}, 05:36:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:36:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:36:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:36:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:36:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['damp', 'tps']}, 05:36:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'tests': ['damp', 'tps']}, 05:36:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['dromaeo_dom']}, 05:36:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:36:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['basic_compositor_video']}, 05:36:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:36:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['a11yr', 05:36:28 INFO - 'ts_paint', 05:36:28 INFO - 'tpaint', 05:36:28 INFO - 'sessionrestore', 05:36:28 INFO - 'sessionrestore_no_auto_restore', 05:36:28 INFO - 'tabpaint']}, 05:36:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['tsvgx', 05:36:28 INFO - 'tsvgr_opacity', 05:36:28 INFO - 'tart', 05:36:28 INFO - 'tscrollx', 05:36:28 INFO - 'cart']}, 05:36:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:36:28 INFO - 'tsvgr_opacity', 05:36:28 INFO - 'tart', 05:36:28 INFO - 'tscrollx', 05:36:28 INFO - 'cart']}, 05:36:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'talos_options': ['--disable-e10s'], 05:36:28 INFO - 'tests': ['tp5o']}, 05:36:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'tests': ['tp5o']}, 05:36:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'talos_options': ['--disable-e10s', 05:36:28 INFO - '--xperf_path', 05:36:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:28 INFO - 'tests': ['tp5n']}, 05:36:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:28 INFO - 'talos_options': ['--xperf_path', 05:36:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:28 INFO - 'tests': ['tp5n']}}, 05:36:28 INFO - 'talos.zip': {'path': '', 05:36:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:36:28 INFO - Running post-action listener: _resource_record_post_action 05:36:28 INFO - [mozharness: 2016-09-03 12:36:28.442473Z] Finished populate-webroot step (success) 05:36:28 INFO - [mozharness: 2016-09-03 12:36:28.442604Z] Running create-virtualenv step. 05:36:28 INFO - Running pre-action listener: _resource_record_pre_action 05:36:28 INFO - Running main action method: create_virtualenv 05:36:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:36:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:36:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:36:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:36:28 INFO - Using real prefix '/tools/python27' 05:36:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:36:29 INFO - Installing distribute.............................................................................................................................................................................................done. 05:36:32 INFO - Installing pip.................done. 05:36:32 INFO - Return code: 0 05:36:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:32 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:32 INFO - 'HOME': '/Users/cltbld', 05:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:32 INFO - 'LOGNAME': 'cltbld', 05:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:32 INFO - 'MOZ_NO_REMOTE': '1', 05:36:32 INFO - 'NO_EM_RESTART': '1', 05:36:32 INFO - 'PAGER': '/bin/cat', 05:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:32 INFO - 'PWD': '/builds/slave/test', 05:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:32 INFO - 'SHELL': '/bin/bash', 05:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:32 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:32 INFO - 'USER': 'cltbld', 05:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:32 INFO - 'XPC_FLAGS': '0x0', 05:36:32 INFO - 'XPC_SERVICE_NAME': '0', 05:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:36:32 INFO - Cleaning up... 05:36:32 INFO - Return code: 0 05:36:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:32 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:32 INFO - 'HOME': '/Users/cltbld', 05:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:32 INFO - 'LOGNAME': 'cltbld', 05:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:32 INFO - 'MOZ_NO_REMOTE': '1', 05:36:32 INFO - 'NO_EM_RESTART': '1', 05:36:32 INFO - 'PAGER': '/bin/cat', 05:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:32 INFO - 'PWD': '/builds/slave/test', 05:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:32 INFO - 'SHELL': '/bin/bash', 05:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:32 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:32 INFO - 'USER': 'cltbld', 05:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:32 INFO - 'XPC_FLAGS': '0x0', 05:36:32 INFO - 'XPC_SERVICE_NAME': '0', 05:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:32 INFO - Downloading/unpacking psutil>=3.1.1 05:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:36:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:36 INFO - Installing collected packages: psutil 05:36:36 INFO - Running setup.py install for psutil 05:36:36 INFO - building 'psutil._psutil_osx' extension 05:36:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:36:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:36:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:36:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:36:37 INFO - building 'psutil._psutil_posix' extension 05:36:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:36:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:36:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:36:37 INFO - ^ 05:36:37 INFO - 1 warning generated. 05:36:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:36:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:37 INFO - Successfully installed psutil 05:36:37 INFO - Cleaning up... 05:36:37 INFO - Return code: 0 05:36:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:36: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')]} 05:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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')]} 05:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:36: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 mozsystemmonitor==0.3 05:36:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:37 INFO - 'HOME': '/Users/cltbld', 05:36:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:37 INFO - 'LOGNAME': 'cltbld', 05:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:37 INFO - 'MOZ_NO_REMOTE': '1', 05:36:37 INFO - 'NO_EM_RESTART': '1', 05:36:37 INFO - 'PAGER': '/bin/cat', 05:36:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:37 INFO - 'PWD': '/builds/slave/test', 05:36:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:37 INFO - 'SHELL': '/bin/bash', 05:36:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:37 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:37 INFO - 'USER': 'cltbld', 05:36:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:37 INFO - 'XPC_FLAGS': '0x0', 05:36:37 INFO - 'XPC_SERVICE_NAME': '0', 05:36:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:36:40 INFO - Installing collected packages: mozsystemmonitor 05:36:40 INFO - Running setup.py install for mozsystemmonitor 05:36:40 INFO - Successfully installed mozsystemmonitor 05:36:40 INFO - Cleaning up... 05:36:40 INFO - Return code: 0 05:36:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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', '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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:36: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 jsonschema==2.5.1 05:36:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:40 INFO - 'HOME': '/Users/cltbld', 05:36:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:40 INFO - 'LOGNAME': 'cltbld', 05:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:40 INFO - 'MOZ_NO_REMOTE': '1', 05:36:40 INFO - 'NO_EM_RESTART': '1', 05:36:40 INFO - 'PAGER': '/bin/cat', 05:36:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:40 INFO - 'PWD': '/builds/slave/test', 05:36:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:40 INFO - 'SHELL': '/bin/bash', 05:36:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:40 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:40 INFO - 'USER': 'cltbld', 05:36:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:40 INFO - 'XPC_FLAGS': '0x0', 05:36:40 INFO - 'XPC_SERVICE_NAME': '0', 05:36:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:36: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 05:36: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 05:36: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 05:36: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 05:36: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 05:36: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 05:36:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:36:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:44 INFO - Downloading functools32-3.2.3-2.tar.gz 05:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:36:44 INFO - warning: no files found matching '*.txt' 05:36:44 INFO - no previously-included directories found matching 'build' 05:36:44 INFO - no previously-included directories found matching 'dist' 05:36:44 INFO - no previously-included directories found matching '.git*' 05:36:44 INFO - Installing collected packages: jsonschema, functools32 05:36:44 INFO - Running setup.py install for functools32 05:36:44 INFO - warning: no files found matching '*.txt' 05:36:44 INFO - no previously-included directories found matching 'build' 05:36:44 INFO - no previously-included directories found matching 'dist' 05:36:44 INFO - no previously-included directories found matching '.git*' 05:36:44 INFO - Successfully installed jsonschema functools32 05:36:44 INFO - Cleaning up... 05:36:44 INFO - Return code: 0 05:36:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:44 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:44 INFO - 'HOME': '/Users/cltbld', 05:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:44 INFO - 'LOGNAME': 'cltbld', 05:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:44 INFO - 'MOZ_NO_REMOTE': '1', 05:36:44 INFO - 'NO_EM_RESTART': '1', 05:36:44 INFO - 'PAGER': '/bin/cat', 05:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:44 INFO - 'PWD': '/builds/slave/test', 05:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:44 INFO - 'SHELL': '/bin/bash', 05:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:44 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:44 INFO - 'USER': 'cltbld', 05:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:44 INFO - 'XPC_FLAGS': '0x0', 05:36:44 INFO - 'XPC_SERVICE_NAME': '0', 05:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:36:44 INFO - Cleaning up... 05:36:44 INFO - Return code: 0 05:36:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:44 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:44 INFO - 'HOME': '/Users/cltbld', 05:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:44 INFO - 'LOGNAME': 'cltbld', 05:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:44 INFO - 'MOZ_NO_REMOTE': '1', 05:36:44 INFO - 'NO_EM_RESTART': '1', 05:36:44 INFO - 'PAGER': '/bin/cat', 05:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:44 INFO - 'PWD': '/builds/slave/test', 05:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:44 INFO - 'SHELL': '/bin/bash', 05:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:44 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:44 INFO - 'USER': 'cltbld', 05:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:44 INFO - 'XPC_FLAGS': '0x0', 05:36:44 INFO - 'XPC_SERVICE_NAME': '0', 05:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:36:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:36:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:48 INFO - Downloading docopt-0.6.1.tar.gz 05:36:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:36:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:36:48 INFO - Running setup.py install for blobuploader 05:36:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:36:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:36:49 INFO - Running setup.py install for requests 05:36:49 INFO - Running setup.py install for docopt 05:36:49 INFO - Successfully installed blobuploader requests docopt 05:36:49 INFO - Cleaning up... 05:36:49 INFO - Return code: 0 05:36:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:49 INFO - 'HOME': '/Users/cltbld', 05:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:49 INFO - 'LOGNAME': 'cltbld', 05:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:49 INFO - 'MOZ_NO_REMOTE': '1', 05:36:49 INFO - 'NO_EM_RESTART': '1', 05:36:49 INFO - 'PAGER': '/bin/cat', 05:36:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:49 INFO - 'PWD': '/builds/slave/test', 05:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:49 INFO - 'SHELL': '/bin/bash', 05:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:49 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:49 INFO - 'USER': 'cltbld', 05:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:49 INFO - 'XPC_FLAGS': '0x0', 05:36:49 INFO - 'XPC_SERVICE_NAME': '0', 05:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Jbe4iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kfwPM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-cgj3R4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-O5gwtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-6s5cOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-pStVr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-bnZd6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-u7Xz0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-K7R4ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:36:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:36:50 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-tVgPuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-aVwHzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-hx7KTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-MMxdkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3zq22l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-F900WC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-eeAnZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:36:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:36:51 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nbKosO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:36:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:36:51 INFO - Running setup.py install for manifestparser 05:36:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:36:51 INFO - Running setup.py install for mozcrash 05:36:52 INFO - Running setup.py install for mozdebug 05:36:52 INFO - Running setup.py install for mozdevice 05:36:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Running setup.py install for mozfile 05:36:52 INFO - Running setup.py install for mozhttpd 05:36:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Running setup.py install for mozinfo 05:36:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Running setup.py install for mozInstall 05:36:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:36:52 INFO - Running setup.py install for mozleak 05:36:53 INFO - Running setup.py install for mozlog 05:36:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Running setup.py install for moznetwork 05:36:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Running setup.py install for mozprocess 05:36:53 INFO - Running setup.py install for mozprofile 05:36:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Running setup.py install for mozrunner 05:36:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:36:53 INFO - Running setup.py install for mozscreenshot 05:36:54 INFO - Running setup.py install for moztest 05:36:54 INFO - Running setup.py install for mozversion 05:36:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:36:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:36:54 INFO - Cleaning up... 05:36:54 INFO - Return code: 0 05:36:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:54 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:36:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:54 INFO - 'HOME': '/Users/cltbld', 05:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:54 INFO - 'LOGNAME': 'cltbld', 05:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:54 INFO - 'MOZ_NO_REMOTE': '1', 05:36:54 INFO - 'NO_EM_RESTART': '1', 05:36:54 INFO - 'PAGER': '/bin/cat', 05:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:54 INFO - 'PWD': '/builds/slave/test', 05:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:54 INFO - 'SHELL': '/bin/bash', 05:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:54 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:54 INFO - 'USER': 'cltbld', 05:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:54 INFO - 'XPC_FLAGS': '0x0', 05:36:54 INFO - 'XPC_SERVICE_NAME': '0', 05:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:54 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-SlHeWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:54 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Fze1_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:36:54 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)) 05:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:36:54 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-aJIADS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-YZUE9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-j5dkuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-rAUDQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-N7IUsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-XwHvj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-dh5tN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jJn7DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:36:55 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)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-fFKupt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kShaVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:36:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:36:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:36:55 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-AX4eeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:36:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-ZytiZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:36:56 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)) 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-jn1Fxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:36:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-_vwQal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:36:56 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-YzdXfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:36:56 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)) 05:36:56 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)) 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:58 INFO - Downloading blessings-1.6.tar.gz 05:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:36:59 INFO - Installing collected packages: blessings 05:36:59 INFO - Running setup.py install for blessings 05:36:59 INFO - Successfully installed blessings 05:36:59 INFO - Cleaning up... 05:36:59 INFO - Return code: 0 05:36:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:36:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:36:59 INFO - Reading from file tmpfile_stdout 05:36:59 INFO - Current package versions: 05:36:59 INFO - blessings == 1.6 05:36:59 INFO - blobuploader == 1.2.4 05:36:59 INFO - docopt == 0.6.1 05:36:59 INFO - functools32 == 3.2.3-2 05:36:59 INFO - jsonschema == 2.5.1 05:36:59 INFO - manifestparser == 1.1 05:36:59 INFO - mozInstall == 1.12 05:36:59 INFO - mozcrash == 0.17 05:36:59 INFO - mozdebug == 0.1 05:36:59 INFO - mozdevice == 0.48 05:36:59 INFO - mozfile == 1.2 05:36:59 INFO - mozhttpd == 0.7 05:36:59 INFO - mozinfo == 0.9 05:36:59 INFO - mozleak == 0.1 05:36:59 INFO - mozlog == 3.3 05:36:59 INFO - moznetwork == 0.27 05:36:59 INFO - mozprocess == 0.23 05:36:59 INFO - mozprofile == 0.28 05:36:59 INFO - mozrunner == 6.12 05:36:59 INFO - mozscreenshot == 0.1 05:36:59 INFO - mozsystemmonitor == 0.3 05:36:59 INFO - moztest == 0.7 05:36:59 INFO - mozversion == 1.4 05:36:59 INFO - psutil == 3.1.1 05:36:59 INFO - requests == 1.2.3 05:36:59 INFO - wsgiref == 0.1.2 05:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:59 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:59 INFO - 'HOME': '/Users/cltbld', 05:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:59 INFO - 'LOGNAME': 'cltbld', 05:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:59 INFO - 'MOZ_NO_REMOTE': '1', 05:36:59 INFO - 'NO_EM_RESTART': '1', 05:36:59 INFO - 'PAGER': '/bin/cat', 05:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:59 INFO - 'PWD': '/builds/slave/test', 05:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:59 INFO - 'SHELL': '/bin/bash', 05:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:59 INFO - 'USER': 'cltbld', 05:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:59 INFO - 'XPC_FLAGS': '0x0', 05:36:59 INFO - 'XPC_SERVICE_NAME': '0', 05:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:36:59 INFO - Cleaning up... 05:36:59 INFO - Return code: 0 05:36:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:59 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 0x1042e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104375618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4b1593e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, '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.Je8y41UYNX/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.DEE2zXNMG3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:59 INFO - 'HOME': '/Users/cltbld', 05:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:59 INFO - 'LOGNAME': 'cltbld', 05:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:59 INFO - 'MOZ_NO_REMOTE': '1', 05:36:59 INFO - 'NO_EM_RESTART': '1', 05:36:59 INFO - 'PAGER': '/bin/cat', 05:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:59 INFO - 'PWD': '/builds/slave/test', 05:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:59 INFO - 'SHELL': '/bin/bash', 05:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:36:59 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:59 INFO - 'USER': 'cltbld', 05:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:59 INFO - 'XPC_FLAGS': '0x0', 05:36:59 INFO - 'XPC_SERVICE_NAME': '0', 05:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:37:00 INFO - Cleaning up... 05:37:00 INFO - Return code: 0 05:37:00 INFO - Running post-action listener: _resource_record_post_action 05:37:00 INFO - Running post-action listener: _start_resource_monitoring 05:37:00 INFO - Starting resource monitoring. 05:37:00 INFO - [mozharness: 2016-09-03 12:37:00.233296Z] Finished create-virtualenv step (success) 05:37:00 INFO - [mozharness: 2016-09-03 12:37:00.234296Z] Running install step. 05:37:00 INFO - Running pre-action listener: _resource_record_pre_action 05:37:00 INFO - Running main action method: install 05:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:37:00 INFO - Reading from file tmpfile_stdout 05:37:00 INFO - Detecting whether we're running mozinstall >=1.0... 05:37:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:37:00 INFO - Reading from file tmpfile_stdout 05:37:00 INFO - Output received: 05:37:00 INFO - Usage: mozinstall [options] installer 05:37:00 INFO - Options: 05:37:00 INFO - -h, --help show this help message and exit 05:37:00 INFO - -d DEST, --destination=DEST 05:37:00 INFO - Directory to install application into. [default: 05:37:00 INFO - "/builds/slave/test"] 05:37:00 INFO - --app=APP Application being installed. [default: firefox] 05:37:00 INFO - mkdir: /builds/slave/test/build/application 05:37:00 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'] 05:37:00 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 05:37:35 INFO - Reading from file tmpfile_stdout 05:37:35 INFO - Output received: 05:37:35 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:37:35 INFO - Running post-action listener: _resource_record_post_action 05:37:35 INFO - [mozharness: 2016-09-03 12:37:35.106586Z] Finished install step (success) 05:37:35 INFO - [mozharness: 2016-09-03 12:37:35.106733Z] Running run-tests step. 05:37:35 INFO - Running pre-action listener: _resource_record_pre_action 05:37:35 INFO - Running main action method: run_tests 05:37:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:35 INFO - 2016-09-03 05:37:35.121 screenresolution[1640:7658] starting screenresolution argv=screenresolution get 05:37:35 INFO - 2016-09-03 05:37:35.123 screenresolution[1640:7658] Display 0: 1600x1200x32@60 05:37:35 INFO - 2016-09-03 05:37:35.129 screenresolution[1641:7660] starting screenresolution argv=screenresolution list 05:37:35 INFO - Available Modes on Display 0 05:37:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:35 INFO - Intel Iris: 05:37:35 INFO - Chipset Model: Intel Iris 05:37:35 INFO - Type: GPU 05:37:35 INFO - Bus: Built-In 05:37:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:35 INFO - Vendor: Intel (0x8086) 05:37:35 INFO - Device ID: 0x0a2e 05:37:35 INFO - Revision ID: 0x0009 05:37:35 INFO - Displays: 05:37:35 INFO - Display: 05:37:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:35 INFO - Display Serial Number: 1600x1200 60 05:37:35 INFO - Main Display: Yes 05:37:35 INFO - Mirror: Off 05:37:35 INFO - Online: Yes 05:37:35 INFO - Rotation: Supported 05:37:35 INFO - Return code: 0 05:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:37:35 INFO - Python 2.7.3 05:37:35 INFO - Return code: 0 05:37:35 INFO - grabbing minidump binary from tooltool 05:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:35 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 0x104244d30>, '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 0x103fa9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10423cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:37:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:37:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:37:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:37:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:37:35 INFO - Return code: 0 05:37:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:37:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:37:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:37:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:37:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Je8y41UYNX/Render', 05:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:37:35 INFO - 'HOME': '/Users/cltbld', 05:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:37:35 INFO - 'LOGNAME': 'cltbld', 05:37:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:37:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:35 INFO - 'MOZ_NO_REMOTE': '1', 05:37:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:37:35 INFO - 'NO_EM_RESTART': '1', 05:37:35 INFO - 'PAGER': '/bin/cat', 05:37:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:35 INFO - 'PWD': '/builds/slave/test', 05:37:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:37:35 INFO - 'SHELL': '/bin/bash', 05:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DEE2zXNMG3/Listeners', 05:37:35 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 05:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:37:35 INFO - 'USER': 'cltbld', 05:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:35 INFO - 'XPC_FLAGS': '0x0', 05:37:35 INFO - 'XPC_SERVICE_NAME': '0', 05:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:37:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:37:35 INFO - mozversion application_buildid: 20160903030202 05:37:35 INFO - mozversion application_changeset: d0830980ffdb36a10855d02a588b4869cad6707e 05:37:35 INFO - mozversion application_display_name: Nightly 05:37:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:37:35 INFO - mozversion application_name: Firefox 05:37:35 INFO - mozversion application_remotingname: firefox 05:37:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:37:35 INFO - mozversion application_vendor: Mozilla 05:37:35 INFO - mozversion application_version: 51.0a1 05:37:35 INFO - mozversion platform_buildid: 20160903030202 05:37:35 INFO - mozversion platform_changeset: d0830980ffdb36a10855d02a588b4869cad6707e 05:37:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:37:35 INFO - mozversion platform_version: 51.0a1 05:37:35 INFO - using testdate: 1472906255 05:37:35 INFO - actual date: 1472906255 05:37:35 INFO - starting webserver on 'localhost:49266' 05:37:35 INFO - SUITE-START | Running 2 tests 05:37:35 INFO - TEST-START | dromaeo_css 05:37:35 INFO - operating with platform_type : mac_ 05:37:35 INFO - Initialising browser for dromaeo_css test... 05:37:35 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpS1kPep/profile http://localhost:49266/getInfo.html) 05:37:38 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 05:37:38 INFO - PROCESS | 1651 | colorDepth:24 05:37:38 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 05:37:38 INFO - PROCESS | 1651 | __metrics 05:37:38 INFO - PROCESS | 1651 | 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" 05:37:38 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:38 INFO - TEST-INFO | 1651: exit 0 05:37:38 INFO - Browser initialized. 05:37:38 INFO - Running cycle 1/1 for dromaeo_css test... 05:37:38 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpS1kPep/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:50 INFO - PROCESS | 1654 | RSS: Main: 233734144 05:37:50 INFO - PROCESS | 1654 | 05:38:42 INFO - PROCESS | 1654 | 1472906322383 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:40:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:40:55 INFO - PROCESS | 1654 | RSS: Main: 373170176 05:40:55 INFO - PROCESS | 1654 | 05:43:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:43:58 INFO - PROCESS | 1654 | RSS: Main: 368861184 05:43:58 INFO - PROCESS | 1654 | 05:47:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:47:01 INFO - PROCESS | 1654 | RSS: Main: 334176256 05:47:01 INFO - PROCESS | 1654 | 05:50:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:50:04 INFO - PROCESS | 1654 | RSS: Main: 338153472 05:50:04 INFO - PROCESS | 1654 | 05:53:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:53:09 INFO - PROCESS | 1654 | RSS: Main: 331202560 05:53:09 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:56:12 INFO - PROCESS | 1654 | RSS: Main: 485720064 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | __start_tp_report 05:56:12 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:56:12 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:56:12 INFO - PROCESS | 1654 | |i|pagename|runs| 05:56:12 INFO - PROCESS | 1654 | |0;dojo.html;2821.322882508198;3329.683680050395;3358.2611825398403;3398.694117529422;3433.296174284272;5579.358373787016;5740.655560666363;5761.395053519383;5770.134479828028;5809.9999999999945;5735.1970724098655;5749.223854779603;5797.1594118852745;5866.6480011199255;5867.88264234715;5191.351081114861;5219.921701174486;5244.527992480676;5260.92108618371;5304.946950530484;5341.0920143575495;5348.839534813962;5353.384360798519;5380.166074258476;5429.755660995264;5187.3775146982225;5206.323177986837;5218.556422704075;5318.414974352838;5336.4930331618625;5261.500157485033;5265.420803711589;5266.052110620087;5270.525652691276;5285.365756109253;4448.510663826976;5200.29795977542;5230.738463076869;5232.633715639907;5278.841634750964;5718.628289161191;5721.914171287434;5764.654120752726;5868.03177475716;5877.441643043904;6183;6226.221722284715;6243.843903902393;6244.843878903019;6251.812445626639;6139.614800607363;6140.048292514667;6180.227471566054;6189.38106189382;6217.96891015542;5866.413358664099;5889.852753681106;5939.792107276267;5962.731677074542;5974.3726908674835;6212.234706616729;6269.279032911182;6270.9372906271265;6273.905891411632;6277.058441233851;6056.242969628796;6110.908336374958;6116.3053375860445;6125.050617154348;6209;6246.3441338659695;6269.592476489013;6270.059491076375;6271.6550589718;6276.027215781562;6236.469900058546;6244.687765611701;6257.468115210167;6264.624122552661;6272.749090036347;5959;6020.638761674313;6091.239754606059;6183.783567575203;6267.9373206267355;4842.975785121052;4851.514848515191;4858.02839432115;4860.927086093711;4864.002879409783;4356.629686476685;4357.43353364067;4366.672499562545;4399.890002749893;4401.6478681705385;5493.752781124859;5533.750981205855;5556.472135147154;5613.354464236583;5672.943270567324;5251.448597897166;6228.968855155696;6235.314115447297;6248.937510624926;6252.093446450239;5771.336296325976;5781.653100813881;5804.796832110856;5828.300603927556;5834.154047663065;5364.490373414519;5410.621256512007;5467.097928841697;5481.369642491085;5488.615796894179;6162.907556386658;6177.536684748572;6195.380461953769;6212.409821066992;6225.719842607003;6224.408681175281;6231.40801623845;6233.376662333731;6237.064440333987;6239.812805615838;6144.139820425234;6169.143323984716;6207.16203312547;6211.720472578654;6223.066540019034;6091.299500557493;6108.554560543837;6128.900367973482;6143.266246520256;6247.781327653511;6062.999605065292;6243.656598887105;6274.905876411949;6275.964465863264;6279.058141278845;4598.770061496845;5391.757370918396;6164.137020816999;6165.568410211242;6166.691665416801;6029.125776762433;6095.207623008981;6096.012695893777;6132.938670613236;6161.353057929032;5082.2122571001555;6002.459778619946;6057.545684073801;6072.696365181811;6108.633481990917;6146.815595532141;6160.045192995093;6184.319724830355;6191.721372538156;6194.721237544231;5749.080147176432;5758.395368486416;5780.479756821906;5839.649621022752;5879.353271140086 05:56:12 INFO - PROCESS | 1654 | |1;ext.html;528.5744975294888;530.4695304695302;531.5986430245164;534.6765207049734;556.3324011186572;30210.546841797364;39928.80035599822;41270.17459650813;41406.58593414061;43673.563264367345;43184.56815431845;43334;43470;43477.7826110869;43583.78208108963;8300.70947516837;9885.308028438012;9890.258230632695;9893.159081478083;9913.206943444507;8852.8672069919;9075.137861903142;11946;12231.08266879983;12261.264324140535;12032.037437005063;12104.213226140317;12105.697357566043;12115.454804533772;12122;12146.210496317755;12243.632691019284;12248.265104093784;12254.81617775734;12263.877361226363;11901.107416943763;11959.40202989847;11962.282263064157;11974.461149248305;11980.28118312904;34558.48162277568;40441.59558404437;40445.191096178205;40667.389989150186;40696.59303406958;3233.466478031122;3347.44767113426;3587.5336206293277;3821.102041020358;4192.496900371944;30334.393312133634;30844;31564.895228667116;31827.204319891956;32071.51892721611;3243.8378081095852;3278.6885245901603;3371.039253812676;3383.272596391763;3392.6776956189124;461471;466329;466950;469941;472716;32726.836365818017;33501.49747753786;59111.704441477516;59411.81176376448;59457.40542594605;34546.48180277298;34619.13452163716;34758.82620586881;34913.47629785555;34935.825320873235;14535.709285814224;14687;14694.55916322512;14701.044432112092;14707.92646036763;11333.943330283295;11567.305961642329;12445.75108497825;12539.811902821464;12567.748645027232;7308.744193953293;7363.484556081023;7363.926360736431;7521.0974683038385;7701.576413297211;3245.6105267367593;3254.6745325467264;3259.9837000814846;3280.6391296957795;3294.7364210863075;899.2670973156856;904.452806052342;940.3840484482538;1019.6380284998867;1045.7594753206804;29396.853015734785;29781.255468613024;29905.551416728766;30503.694963050086;30685.709429566887;1016.5425558498705;1048.9527971241312;1054.298891237334;1061.8938106189319;1127.9774404511863;12838.743225135631;13783.724325513434;15388.538343849703;15460.536183914275;15669.713583183875;642875;649520;652062;655127;656008;54345.45654543404;54623.45376546184;54746.63133421617;54843.725781370835;54934;1339.3303348325837;1364.3860262881544;1376.1536654957329;1378.400395827838;1378.8690074442911;24760.771530562335;28473.43053138967;29215.561766573945;29858.701412986462;30171;49306;49546.752266238436;49593.25610115923;49773.75113124411;49792;486073;487115;488073;489616;490971;25656.102036429384;26465.544395058456;26500.73499264983;26507.602385964612;26526.204213873614;46441.07117857556;46819.76590117028;46941.591752247485;47541.52458475371;47558;37275;39194.804025980826;39779;40234.79882600568;40605;38136.809315953244;38953.22093558056;39600.80199598984;39718.2056358877;40158;37388.06529836776;37651.81174094112;38454.6154538451;38687.806560967016;38938.6106138935;28172.43655126927;32552;34806.20618228159;36358.27283454369;36503.45244821383;24993.75006249987;25522.23433297004;25741.227763167135;25763.72527668354;26216.213513594623 05:56:12 INFO - PROCESS | 1654 | |2;jquery.html;352.8976596786932;369.65806628868296;373.48086160237256;381.69655124176285;404.72478714474164;15316.693666126685;16019;19767.5058123547;20383.694244586324;20410.183592656274;20141.798582014177;20445.693314600292;20805;21116.472088197803;21147.682784758243;5316.415194328621;5965.999999999989;6143.815685529441;6233.501319894398;6242.063690446424;6123.081537769348;6144.96927515364;6173.938260617404;6196.256449226099;6197.907031394533;5883.29400471944;6002.309734380537;6080.0575910733905;6085.574009819315;6195.597286176384;5887.941120588782;6002.699865006754;6064.332923378425;6066.39336066392;6067.575269731121;5202.843914682566;5260.184671375943;5263.421023687391;5265.6577322474295;5268.209768534713;24998.750012499822;25443.61834572483;25600.103996360223;25710.742892571023;25810;2262.592733308004;2328.918487852934;2354.2902727227265;2417.915372961938;2480.7147178074392;11790.483933333988;15067.17130557819;15388.846111538853;15436.536903892902;15746.685066298724;1742.259539695637;1787.9821201788077;1835.3392778599693;1898.6107847891149;1940.670086085369;99744;121208;124119.75880241263;125114.12328815075;125816.99999999908;11244.289384503094;11247.318571165482;11504.194706370474;11652.067834573214;11699;14388.928055359656;14404.279786010658;14408.495702650356;14419.279036048156;14490.130592164503;8311.418200725891;8446.155384461505;8476.905929335748;8480.448770829877;8615.224629783224;8389.874151887727;8400.873986890201;8410.621522031523;8569.400141990001;8594.226519613265;4476.194285028726;4494.887627809331;4501.617362524222;4517.638588912879;4590.081983603226;1627.2839950421776;1739.1217435195144;1788.8181484093366;1837.5038739540516;1881.5954569767566;748.4731148457196;808.3106307816691;854.8760429737778;872.7120050383359;896.4576431259125;20624.17503299851;20990.580188396452;21056.841873697093;21072.367828965154;21540.892295538422;896.462122726359;939.7744541310036;975.9707208783748;976.6239997600833;994.9850752238722;9158.954205229065;10835.891641083488;10949.83575246372;11021.614243501439;11023.944880275547;560678.9404564247;561746;571717;577180;588270;77859.61070194613;128130.85316182139;130076;130153;134865;949.7720547068653;969.539468752337;988.5106872098357;989.0356902020616;992.369845148336;18688.532786679898;19445.222191112764;19494.31769888047;19509.122089505723;19551.511212219808;43118.35322470103;48222;50348;54579;56199;78633;78931.81602276085;80911.59544202242;82707;83262;5640.548756099584;6841.692123854339;7048.964755176191;7069.363757261871;7280.126384833854;10035.849462258218;12225.144239903122;12271.447784849881;12478.126531142732;12661.78418776692;9112.453252804853;10662.57349706034;10988.340699558052;11016.339019658846;11021.283616564897;9938.503074846085;10482.318649287772;10684.198685098494;10966.451677416257;11092.667219983414;9807.411555306704;10819.350838949687;10869.402182880018;11164;11451.370174640142;6931.549449285781;7047.894281585883;7155.212926578176;7225.710971561079;7351.338379545867;8800.33997450181;10539.473026348805;10963.945180274048;11037.11703063737;11164.051055660035 05:56:12 INFO - PROCESS | 1654 | |3;mootools.html;1381.3231516556887;1516.5450364890532;1691.8561922236609;1873.7938826729066;1902.9904850475746;7581.962090189555;8904.866427003592;8905.376623636348;8918.955405222981;8930.910690893097;7800.765977020685;8049.637766300516;8496.91503084969;8738.95630521848;8750.3874728769;2361.551305252005;2393.6648869158244;2394.928152155438;2397.244867866625;2406.68713067301;2342.8594284342907;2375.643653451979;2394.4851856850773;2396;2398.616221404567;2383.618621020637;2387.236084452976;2399.6520504526834;2399.796017338528;2401.7838394544574;2345.870977096259;2370.383700237942;2371.4071482129466;2378.7621237876247;2381.5951288281035;2333.3583264602203;2349.706286714161;2355.328731311585;2358.8348815582917;2361.9409514762096;8825.867611985825;8834.514101724402;8877.778055548599;8886.60010299538;8888.244499217575;5166.457521960216;5345.358556973188;5345.3852806926925;5354.143337066051;5355.732213389354;3614.5301110855685;3657.3416784978695;3669.94495082574;3711.6845068169237;3722.2927643747603;1075.4515197249382;1077.3320541264388;1079.2984560035961;1094.179365475893;1096.0354887698854;11393.829092563617;11482;11548.53805847765;11579.189456738031;11587.246828956091;4518.909621807611;4593.19619066662;4777.020710754287;4795.304680821261;4816.253480710496;9946.900530994742;9957.30298879086;9959.352642078242;9969.950150249348;9993.900060999296;5367.114426119647;5367.302250707461;5368.818859850827;5373.7581808818695;5392.352917649907;4210.6841986851105;4392.231359512072;4543.5002149763495;4906.386701662293;4922.261660750916;3851.306764782366;3885.3977633466857;4008.5390180129075;4027.637512623878;4038.919221615551;1116.6817457024708;1248.6441364211157;1272.465564462928;1276.4957841652495;1296.3453456004656;790.3874497264666;866.8916385451819;888.1250527758829;932.5372246859774;951.357833462424;5677.205191273225;5800.4489573490455;5912.17229587858;5968.880622387528;6133.325334213233;937.7796217888927;943.7970836270235;946.8437707778139;959.3764053365369;984.6947446291673;3949.980250098731;3964.900877478086;4019.718619696593;4089.1003979124544;4092.9181416371507;10263.897361026444;10702.250842440955;10706.304090234109;10757.515911783988;10804.837927431096;10027.348222365523;10516.421596812248;10524.631637892637;10631.893681063246;10675.199360048027;7859.567723775247;8105.27052565272;8112.18878112184;8126.83746325082;8130.715424960098;5780.393058728773;5873.911891321548;5914.911276330772;6082.60463069899;6276.278228003655;9537.189338906272;9664.661736839176;10069.899301006895;10073.748156296151;10105.444200568745;10097.646582369582;10226.181905447695;10246.077852993267;10267.640632577824;10278.33190842593;6815.93184068153;7312.5246858954;7420.406367490696;7571.545707257582;7578.545287282781;8233.012038555411;8312.6674933002;8456.281216096455;8458.788530286545;8480.575971201539;7337;7440.590767507839;7475.439342049477;7552.848943020999;7610.6194690266375;7333;7364.447666424932;7441;7513.361364283878;7535.24647535264;6921.515493915377;7071.82320441993;7076.504644674823;7078.256783037707;7081.964590177222;4906.141425250638;4975.378077740283;5061.089003979319;5082.593392528663;5142.434332223377;4950.282209079592;5037.622178336566;5087.592992560512;5133.512316329943;5243.790248390022 05:56:12 INFO - PROCESS | 1654 | |4;prototype.html;299.3817766312563;299.57759559021775;307.944569977404;313.64871344094615;315.5944611174641;4633.6988095773795;5165.974170129153;5232.058229518685;5286.180642000487;5336.733163341837;4477.865664030084;4966.304717339575;5041.6974981501135;5042.369703787027;5125.66683165594;3546.8049257290904;4054.7161698681102;4064.471618689566;4066.207089617511;4074.633283004529;3748.6626203641667;4063.349864021757;4084.4077608746716;4092.7953602319913;4128.50457945047;3183.4588120019653;3288.8653414571936;3664.798436086014;3735.3276410246144;4033.657139143176;3443.517907492953;3960.4059391091423;3969.642732154091;3976.6818654507715;3989.341758609826;3087.9691203087905;3456.3260164267977;3461.6019157796923;3471.2536804587135;3483.0421634050585;4637.582617564435;4921.729304888229;5119.312361010462;5124.846909445382;5146.845594632167;3863.744283108001;4253.128108737732;4496.168208881368;4505.234110201273;4516.725374103781;4378.912421751579;4752.97810970643;4884.923907339366;5239.921401178947;5459.918101228484;3278.4754439289836;3502.982485087584;3520.6479352064844;3803.3075281499746;3803.9239215215816;4809.615230781529;5199.440167949638;5302.549283310923;5701.600887937804;5773.769049238025;3855.070146688537;3856.8650097247023;3875.9031024224055;3878.379459286557;3885.184111336651;3908.768737847569;3932.3708206686792;3936.193080418507;3941;3966.5835087315995;4243.917800960797;4536;4554.130897534598;4574.108048930427;4577.198990176706;4664;5320.255164277004;5350.79607088402;5437.564994800407;5517.944820551824;1177.587844254504;1315.4606611289516;1353.4383230959238;1358.796180572922;1362.5639795265618;758.5410826450029;781.5076501803827;785.8939043229205;863.3309185381254;890.5725251879135;4576.954230457719;4786.936360729136;4881.902361952741;5087.8982420351385;5133.920762091359;4295.379818310828;5098.490150984871;5454.945450545444;5611.9719401403545;5622.57830662702;4336.718113322624;4809.374781278411;5065.265536497261;5142.2286657001005;5213.895722085537;2759.3377589378406;2966.9912229841957;3004.9248768780953;3010.5333673280675;3051.4812481878134;5232.895342093213;5812.709364531756;5831.6468030882415;5848.356680765112;5955.52355811534;4830.154722923475;5413.052715774724;5621.747021384048;5824.718561916372;5854.4533128089415;5889.293284805851;5896.754336877441;5918.496927761188;5922.733476993546;5937.079752638348;4884.65807393479;5243.217306115939;5698.287714035746;5701.600887937804;5713;4971.776270067928;5186.8329625833885;5194.64939115825;5328.573714102785;5674.858128546819;4989.052080104769;5650.547956163415;5834.30805066543;5862.794802181903;5872.5301975842685;3022.652394974606;3114.423831591163;3778.4521244419957;3779.1307999160895;3799.8100094995693;5123.743812809419;5305.1776974569;5611;5712.400197979318;5769.624974376652;4822.927656085091;4835.975820121018;5573.804916827891;5580.38615752275;5596.272484577142;5146.022255771391;5427.728613569227;5567.66594004361;5673.971630141823;5679.801206957736;5123.462961111577;5468.781248750005;5520.723963801875;5525.834224973257;5559.138333558305;4646.488886222445;5470;5529.198266251451;5534;5573.749181286771;5078.873028174326;5330;5381.954857154413;5541.6145963509125;5553.389127196086 05:56:12 INFO - PROCESS | 1654 | |5;yui.html;641.5861769158893;658.6772481484073;763.9197884222153;765.7587859824155;796.4902462424046;14961.17713525757;15199.924000379997;15739.527814165593;16044.358225670989;16080.115593642346;14356.712865742678;14437.927810360934;14457.710845783078;14491.927540362283;14854.851451485481;849.3757088539934;875.382855087162;876.1632640828026;877.6971944679076;878.4948654523643;693.4487082769185;716.4161208614988;716.7849645106447;721.6247551273335;723.4031923662973;888.2183678363032;912.4981260306838;920.59953920045;920.8066306075699;924.5700749151644;904.1139683110556;904.5567671840817;905.5336501701563;907.9364444488856;909.0000998901222;530.3609150973068;545.3183520599251;670.9731610735565;674.1876039372544;674.4503229867692;12269;12375.876241237562;14078.2960851957;14652;14659.340329685083;14053.945954053384;14545.127292362386;14565.563033109023;14631;14733.705325893528;11743.530258789639;11790.469428875635;11810.822837657443;11849.822252666218;12250.81623775644;3767.05823544114;3813.771173729585;3850.6534411903062;3868.3423817951016;3894.6300101490315;113099.43450282777;113234;113391;113677.43161284224;113693.43153284263;10647;10649.361038337725;10887.618933337295;10936.398498082683;11225.550977960791;22047.00788464523;22223.888880555493;22345.55308893813;22361.552768944854;22415.43961400978;7830.491018083806;7874.803129921684;7937.722179723682;8026.117127116013;8048.356131509582;4329.610335069859;4403.4495688039;4405.537418571068;4591.977040114778;4602.516735742765;3532.8056956867617;3636.818159092035;3646.4348026056005;3647.3222317733607;3648.3797754381817;630.6539672418595;640.4108220437209;651.4495251512465;653.2716021635937;674.0596867369991;643.9678016099176;665.1994117821251;673.7709178879204;679.6499802601664;713.1905287498727;8663.176998185163;9254.814903701888;9315.021922698019;9444.386114902509;9466;628.5034822490181;671.8689855478233;674.5716470041559;680.5440354962187;682.931706829323;4253.425787518708;4567.2920697291975;4664.650151238603;4723.811047558059;4937.530934561211;137371.62628373588;140946;143248.28375858054;151883;155209;124267.37866310611;125407.37296313643;129378;130626;131806.34096829453;107403;111045;111486;111832;113513.99999999834;9952.751181220237;9968.028774103364;10066.396016239049;10082.546285417027;10124.898751012395;53485.73257133845;55236.72381638066;56945;62989.68505157445;66748;71749.64125179341;81341;96330;97673;100410;13078.738425231633;13127.081104322988;13136.802947955597;13454.798178027533;13796.517121900686;14591.90149467056;14777.630559236106;15023.399064037316;15052.54842354731;15828;13780.517681881487;13837.515686950908;14072.859271407155;14093.718125637224;14692.045017073857;13903.026788124735;13954.302284885513;13975.301234938415;14048.438062477386;14061.507847225297;12187.817182742088;13573.864261357656;13631.591052268448;13664.111832730841;13672.453101875813;10820.134389248999;11221.71945701364;11507.482163302839;11580.594679186188;11689.142582947778;9877.506124693593;11227.438628068401;11503.597374091867;11517.308961462339;11533.942330288295 05:56:12 INFO - PROCESS | 1654 | __end_tp_report 05:56:12 INFO - PROCESS | 1654 | __start_cc_report 05:56:12 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1304 05:56:12 INFO - PROCESS | 1654 | __end_cc_report 05:56:12 INFO - PROCESS | 1654 | __startTimestamp1472907372451__endTimestamp 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:56:12 INFO - PROCESS | 1654 | Number of tests: 6 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5723.61 Median:5959.00 stddev:655.86 (11.0%) stddev-sans-first:617.09 05:56:12 INFO - PROCESS | 1654 | Values: 2821.3 3329.7 3358.3 3398.7 3433.3 5579.4 5740.7 5761.4 5770.1 5810.0 5735.2 5749.2 5797.2 5866.6 5867.9 5191.4 5219.9 5244.5 5260.9 5304.9 5341.1 5348.8 5353.4 5380.2 5429.8 5187.4 5206.3 5218.6 5318.4 5336.5 5261.5 5265.4 5266.1 5270.5 5285.4 4448.5 5200.3 5230.7 5232.6 5278.8 5718.6 5721.9 5764.7 5868.0 5877.4 6183.0 6226.2 6243.8 6244.8 6251.8 6139.6 6140.0 6180.2 6189.4 6218.0 5866.4 5889.9 5939.8 5962.7 5974.4 6212.2 6269.3 6270.9 6273.9 6277.1 6056.2 6110.9 6116.3 6125.1 6209.0 6246.3 6269.6 6270.1 6271.7 6276.0 6236.5 6244.7 6257.5 6264.6 6272.7 5959.0 6020.6 6091.2 6183.8 6267.9 4843.0 4851.5 4858.0 4860.9 4864.0 4356.6 4357.4 4366.7 4399.9 4401.6 5493.8 5533.8 5556.5 5613.4 5672.9 5251.4 6229.0 6235.3 6248.9 6252.1 5771.3 5781.7 5804.8 5828.3 5834.2 5364.5 5410.6 5467.1 5481.4 5488.6 6162.9 6177.5 6195.4 6212.4 6225.7 6224.4 6231.4 6233.4 6237.1 6239.8 6144.1 6169.1 6207.2 6211.7 6223.1 6091.3 6108.6 6128.9 6143.3 6247.8 6063.0 6243.7 6274.9 6276.0 6279.1 4598.8 5391.8 6164.1 6165.6 6166.7 6029.1 6095.2 6096.0 6132.9 6161.4 5082.2 6002.5 6057.5 6072.7 6108.6 6146.8 6160.0 6184.3 6191.7 6194.7 5749.1 5758.4 5780.5 5839.6 5879.4 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:66006.18 Median:34782.52 stddev:144904.83 (416.6%) stddev-sans-first:145227.93 05:56:12 INFO - PROCESS | 1654 | Values: 528.6 530.5 531.6 534.7 556.3 30210.5 39928.8 41270.2 41406.6 43673.6 43184.6 43334.0 43470.0 43477.8 43583.8 8300.7 9885.3 9890.3 9893.2 9913.2 8852.9 9075.1 11946.0 12231.1 12261.3 12032.0 12104.2 12105.7 12115.5 12122.0 12146.2 12243.6 12248.3 12254.8 12263.9 11901.1 11959.4 11962.3 11974.5 11980.3 34558.5 40441.6 40445.2 40667.4 40696.6 3233.5 3347.4 3587.5 3821.1 4192.5 30334.4 30844.0 31564.9 31827.2 32071.5 3243.8 3278.7 3371.0 3383.3 3392.7 461471.0 466329.0 466950.0 469941.0 472716.0 32726.8 33501.5 59111.7 59411.8 59457.4 34546.5 34619.1 34758.8 34913.5 34935.8 14535.7 14687.0 14694.6 14701.0 14707.9 11333.9 11567.3 12445.8 12539.8 12567.7 7308.7 7363.5 7363.9 7521.1 7701.6 3245.6 3254.7 3260.0 3280.6 3294.7 899.3 904.5 940.4 1019.6 1045.8 29396.9 29781.3 29905.6 30503.7 30685.7 1016.5 1049.0 1054.3 1061.9 1128.0 12838.7 13783.7 15388.5 15460.5 15669.7 642875.0 649520.0 652062.0 655127.0 656008.0 54345.5 54623.5 54746.6 54843.7 54934.0 1339.3 1364.4 1376.2 1378.4 1378.9 24760.8 28473.4 29215.6 29858.7 30171.0 49306.0 49546.8 49593.3 49773.8 49792.0 486073.0 487115.0 488073.0 489616.0 490971.0 25656.1 26465.5 26500.7 26507.6 26526.2 46441.1 46819.8 46941.6 47541.5 47558.0 37275.0 39194.8 39779.0 40234.8 40605.0 38136.8 38953.2 39600.8 39718.2 40158.0 37388.1 37651.8 38454.6 38687.8 38938.6 28172.4 32552.0 34806.2 36358.3 36503.5 24993.8 25522.2 25741.2 25763.7 26216.2 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34092.64 Median:377.59 stddev:95665.99 (25336.0%) stddev-sans-first:95900.81 05:56:12 INFO - PROCESS | 1654 | Values: 352.9 369.7 373.5 381.7 404.7 15316.7 16019.0 19767.5 20383.7 20410.2 20141.8 20445.7 20805.0 21116.5 21147.7 5316.4 5966.0 6143.8 6233.5 6242.1 6123.1 6145.0 6173.9 6196.3 6197.9 5883.3 6002.3 6080.1 6085.6 6195.6 5887.9 6002.7 6064.3 6066.4 6067.6 5202.8 5260.2 5263.4 5265.7 5268.2 24998.8 25443.6 25600.1 25710.7 25810.0 2262.6 2328.9 2354.3 2417.9 2480.7 11790.5 15067.2 15388.8 15436.5 15746.7 1742.3 1788.0 1835.3 1898.6 1940.7 99744.0 121208.0 124119.8 125114.1 125817.0 11244.3 11247.3 11504.2 11652.1 11699.0 14388.9 14404.3 14408.5 14419.3 14490.1 8311.4 8446.2 8476.9 8480.4 8615.2 8389.9 8400.9 8410.6 8569.4 8594.2 4476.2 4494.9 4501.6 4517.6 4590.1 1627.3 1739.1 1788.8 1837.5 1881.6 748.5 808.3 854.9 872.7 896.5 20624.2 20990.6 21056.8 21072.4 21540.9 896.5 939.8 976.0 976.6 995.0 9159.0 10835.9 10949.8 11021.6 11023.9 560678.9 561746.0 571717.0 577180.0 588270.0 77859.6 128130.9 130076.0 130153.0 134865.0 949.8 969.5 988.5 989.0 992.4 18688.5 19445.2 19494.3 19509.1 19551.5 43118.4 48222.0 50348.0 54579.0 56199.0 78633.0 78931.8 80911.6 82707.0 83262.0 5640.5 6841.7 7049.0 7069.4 7280.1 10035.8 12225.1 12271.4 12478.1 12661.8 9112.5 10662.6 10988.3 11016.3 11021.3 9938.5 10482.3 10684.2 10966.5 11092.7 9807.4 10819.4 10869.4 11164.0 11451.4 6931.5 7047.9 7155.2 7225.7 7351.3 8800.3 10539.5 10963.9 11037.1 11164.1 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5714.97 Median:5006.50 stddev:3167.68 (63.3%) stddev-sans-first:3159.83 05:56:12 INFO - PROCESS | 1654 | Values: 1381.3 1516.5 1691.9 1873.8 1903.0 7582.0 8904.9 8905.4 8919.0 8930.9 7800.8 8049.6 8496.9 8739.0 8750.4 2361.6 2393.7 2394.9 2397.2 2406.7 2342.9 2375.6 2394.5 2396.0 2398.6 2383.6 2387.2 2399.7 2399.8 2401.8 2345.9 2370.4 2371.4 2378.8 2381.6 2333.4 2349.7 2355.3 2358.8 2361.9 8825.9 8834.5 8877.8 8886.6 8888.2 5166.5 5345.4 5345.4 5354.1 5355.7 3614.5 3657.3 3669.9 3711.7 3722.3 1075.5 1077.3 1079.3 1094.2 1096.0 11393.8 11482.0 11548.5 11579.2 11587.2 4518.9 4593.2 4777.0 4795.3 4816.3 9946.9 9957.3 9959.4 9970.0 9993.9 5367.1 5367.3 5368.8 5373.8 5392.4 4210.7 4392.2 4543.5 4906.4 4922.3 3851.3 3885.4 4008.5 4027.6 4038.9 1116.7 1248.6 1272.5 1276.5 1296.3 790.4 866.9 888.1 932.5 951.4 5677.2 5800.4 5912.2 5968.9 6133.3 937.8 943.8 946.8 959.4 984.7 3950.0 3964.9 4019.7 4089.1 4092.9 10263.9 10702.3 10706.3 10757.5 10804.8 10027.3 10516.4 10524.6 10631.9 10675.2 7859.6 8105.3 8112.2 8126.8 8130.7 5780.4 5873.9 5914.9 6082.6 6276.3 9537.2 9664.7 10069.9 10073.7 10105.4 10097.6 10226.2 10246.1 10267.6 10278.3 6815.9 7312.5 7420.4 7571.5 7578.5 8233.0 8312.7 8456.3 8458.8 8480.6 7337.0 7440.6 7475.4 7552.8 7610.6 7333.0 7364.4 7441.0 7513.4 7535.2 6921.5 7071.8 7076.5 7078.3 7082.0 4906.1 4975.4 5061.1 5082.6 5142.4 4950.3 5037.6 5087.6 5133.5 5243.8 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4431.66 Median:4969.04 stddev:1366.94 (27.5%) stddev-sans-first:1335.12 05:56:12 INFO - PROCESS | 1654 | Values: 299.4 299.6 307.9 313.6 315.6 4633.7 5166.0 5232.1 5286.2 5336.7 4477.9 4966.3 5041.7 5042.4 5125.7 3546.8 4054.7 4064.5 4066.2 4074.6 3748.7 4063.3 4084.4 4092.8 4128.5 3183.5 3288.9 3664.8 3735.3 4033.7 3443.5 3960.4 3969.6 3976.7 3989.3 3088.0 3456.3 3461.6 3471.3 3483.0 4637.6 4921.7 5119.3 5124.8 5146.8 3863.7 4253.1 4496.2 4505.2 4516.7 4378.9 4753.0 4884.9 5239.9 5459.9 3278.5 3503.0 3520.6 3803.3 3803.9 4809.6 5199.4 5302.5 5701.6 5773.8 3855.1 3856.9 3875.9 3878.4 3885.2 3908.8 3932.4 3936.2 3941.0 3966.6 4243.9 4536.0 4554.1 4574.1 4577.2 4664.0 5320.3 5350.8 5437.6 5517.9 1177.6 1315.5 1353.4 1358.8 1362.6 758.5 781.5 785.9 863.3 890.6 4577.0 4786.9 4881.9 5087.9 5133.9 4295.4 5098.5 5454.9 5612.0 5622.6 4336.7 4809.4 5065.3 5142.2 5213.9 2759.3 2967.0 3004.9 3010.5 3051.5 5232.9 5812.7 5831.6 5848.4 5955.5 4830.2 5413.1 5621.7 5824.7 5854.5 5889.3 5896.8 5918.5 5922.7 5937.1 4884.7 5243.2 5698.3 5701.6 5713.0 4971.8 5186.8 5194.6 5328.6 5674.9 4989.1 5650.5 5834.3 5862.8 5872.5 3022.7 3114.4 3778.5 3779.1 3799.8 5123.7 5305.2 5611.0 5712.4 5769.6 4822.9 4836.0 5573.8 5580.4 5596.3 5146.0 5427.7 5567.7 5674.0 5679.8 5123.5 5468.8 5520.7 5525.8 5559.1 4646.5 5470.0 5529.2 5534.0 5573.7 5078.9 5330.0 5382.0 5541.6 5553.4 05:56:12 INFO - PROCESS | 1654 | 05:56:12 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24810.27 Median:22353.55 stddev:39410.40 (176.3%) stddev-sans-first:39479.18 05:56:12 INFO - PROCESS | 1654 | Values: 641.6 658.7 763.9 765.8 796.5 14961.2 15199.9 15739.5 16044.4 16080.1 14356.7 14437.9 14457.7 14491.9 14854.9 849.4 875.4 876.2 877.7 878.5 693.4 716.4 716.8 721.6 723.4 888.2 912.5 920.6 920.8 924.6 904.1 904.6 905.5 907.9 909.0 530.4 545.3 671.0 674.2 674.5 12269.0 12375.9 14078.3 14652.0 14659.3 14053.9 14545.1 14565.6 14631.0 14733.7 11743.5 11790.5 11810.8 11849.8 12250.8 3767.1 3813.8 3850.7 3868.3 3894.6 113099.4 113234.0 113391.0 113677.4 113693.4 10647.0 10649.4 10887.6 10936.4 11225.6 22047.0 22223.9 22345.6 22361.6 22415.4 7830.5 7874.8 7937.7 8026.1 8048.4 4329.6 4403.4 4405.5 4592.0 4602.5 3532.8 3636.8 3646.4 3647.3 3648.4 630.7 640.4 651.4 653.3 674.1 644.0 665.2 673.8 679.6 713.2 8663.2 9254.8 9315.0 9444.4 9466.0 628.5 671.9 674.6 680.5 682.9 4253.4 4567.3 4664.7 4723.8 4937.5 137371.6 140946.0 143248.3 151883.0 155209.0 124267.4 125407.4 129378.0 130626.0 131806.3 107403.0 111045.0 111486.0 111832.0 113514.0 9952.8 9968.0 10066.4 10082.5 10124.9 53485.7 55236.7 56945.0 62989.7 66748.0 71749.6 81341.0 96330.0 97673.0 100410.0 13078.7 13127.1 13136.8 13454.8 13796.5 14591.9 14777.6 15023.4 15052.5 15828.0 13780.5 13837.5 14072.9 14093.7 14692.0 13903.0 13954.3 13975.3 14048.4 14061.5 12187.8 13573.9 13631.6 13664.1 13672.5 10820.1 11221.7 11507.5 11580.6 11689.1 9877.5 11227.4 11503.6 11517.3 11533.9 05:56:12 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:56:12 INFO - PROCESS | 1654 | 05:56:13 INFO - TEST-INFO | 1654: exit 0 05:56:13 INFO - TEST-OK | dromaeo_css | took 1117360ms 05:56:13 INFO - TEST-START | kraken 05:56:13 INFO - operating with platform_type : mac_ 05:56:13 INFO - Initialising browser for kraken test... 05:56:13 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpIHud9H/profile http://localhost:49266/getInfo.html) 05:56:15 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:56:15 INFO - PROCESS | 1660 | colorDepth:24 05:56:15 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:56:15 INFO - PROCESS | 1660 | __metrics 05:56:15 INFO - PROCESS | 1660 | 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" 05:56:15 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:56:16 INFO - TEST-INFO | 1660: exit 0 05:56:16 INFO - Browser initialized. 05:56:16 INFO - Running cycle 1/1 for kraken test... 05:56:16 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpIHud9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:28 INFO - PROCESS | 1662 | RSS: Main: 233402368 05:56:28 INFO - PROCESS | 1662 | 05:56:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:56:31 INFO - PROCESS | 1662 | RSS: Main: 292761600 05:56:31 INFO - PROCESS | 1662 | 05:56:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:56:35 INFO - PROCESS | 1662 | RSS: Main: 288534528 05:56:35 INFO - PROCESS | 1662 | 05:56:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:56:39 INFO - PROCESS | 1662 | RSS: Main: 380112896 05:56:39 INFO - PROCESS | 1662 | 05:56:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:56:42 INFO - PROCESS | 1662 | RSS: Main: 282312704 05:56:42 INFO - PROCESS | 1662 | 05:56:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:56:44 INFO - PROCESS | 1662 | RSS: Main: 286347264 05:56:44 INFO - PROCESS | 1662 | 05:56:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:56:48 INFO - PROCESS | 1662 | RSS: Main: 323723264 05:56:48 INFO - PROCESS | 1662 | 05:56:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:56:51 INFO - PROCESS | 1662 | RSS: Main: 324812800 05:56:51 INFO - PROCESS | 1662 | 05:56:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:56:55 INFO - PROCESS | 1662 | RSS: Main: 323362816 05:56:55 INFO - PROCESS | 1662 | 05:56:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:56:57 INFO - PROCESS | 1662 | RSS: Main: 296878080 05:56:57 INFO - PROCESS | 1662 | 05:56:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:56:59 INFO - PROCESS | 1662 | RSS: Main: 302632960 05:56:59 INFO - PROCESS | 1662 | 05:57:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:03 INFO - PROCESS | 1662 | RSS: Main: 303579136 05:57:03 INFO - PROCESS | 1662 | 05:57:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:06 INFO - PROCESS | 1662 | RSS: Main: 319000576 05:57:06 INFO - PROCESS | 1662 | 05:57:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:57:10 INFO - PROCESS | 1662 | RSS: Main: 315084800 05:57:10 INFO - PROCESS | 1662 | 05:57:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:57:12 INFO - PROCESS | 1662 | RSS: Main: 358854656 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | __start_tp_report 05:57:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:57:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:57:12 INFO - PROCESS | 1662 | |i|pagename|runs| 05:57:12 INFO - PROCESS | 1662 | |0;ai-astar;113;112;115;112;113;114;112;113;112;122 05:57:12 INFO - PROCESS | 1662 | |1;audio-beat-detection;173;139;136;162;108;131;129;107;147;127 05:57:12 INFO - PROCESS | 1662 | |2;audio-dft;187;185;198;254;197;184;182;183;181;184 05:57:12 INFO - PROCESS | 1662 | |3;audio-fft;77;72;76;153;59;58;58;66;140;57 05:57:12 INFO - PROCESS | 1662 | |4;audio-oscillator;82;82;135;70;77;77;122;68;70;116 05:57:12 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;124;95;86;89;88;113;131;87;88;86 05:57:12 INFO - PROCESS | 1662 | |6;imaging-darkroom;98;97;99;97;99;96;98;99;97;97 05:57:12 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;86;84;84;85;85;83;92;126;85 05:57:12 INFO - PROCESS | 1662 | |8;json-parse-financial;56;56;54;55;79;78;79;55;55;55 05:57:12 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;69;65;69;73;72;76;73;70;70;68 05:57:12 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;89;85;103;103;86;85;99;101;75 05:57:12 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;148;147;138;140;139;137;141;146;177;140 05:57:12 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;168;171;169;168;169;170;169;169;170;170 05:57:12 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;67;69;68;69;81;85;82;63;59 05:57:12 INFO - PROCESS | 1662 | __end_tp_report 05:57:12 INFO - PROCESS | 1662 | __start_cc_report 05:57:12 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2070 05:57:12 INFO - PROCESS | 1662 | __end_cc_report 05:57:12 INFO - PROCESS | 1662 | __startTimestamp1472907432055__endTimestamp 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:57:12 INFO - PROCESS | 1662 | Number of tests: 14 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:113.80 Median:113.00 stddev:3.05 (2.7%) stddev-sans-first:3.22 05:57:12 INFO - PROCESS | 1662 | Values: 113.0 112.0 115.0 112.0 113.0 114.0 112.0 113.0 112.0 122.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:137.50 stddev:20.96 (15.2%) stddev-sans-first:17.41 05:57:12 INFO - PROCESS | 1662 | Values: 173.0 139.0 136.0 162.0 108.0 131.0 129.0 107.0 147.0 127.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:193.50 Median:186.00 stddev:22.08 (11.9%) stddev-sans-first:23.29 05:57:12 INFO - PROCESS | 1662 | Values: 187.0 185.0 198.0 254.0 197.0 184.0 182.0 183.0 181.0 184.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:81.60 Median:62.50 stddev:35.16 (56.3%) stddev-sans-first:37.25 05:57:12 INFO - PROCESS | 1662 | Values: 77.0 72.0 76.0 153.0 59.0 58.0 58.0 66.0 140.0 57.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:73.50 stddev:24.66 (33.6%) stddev-sans-first:25.99 05:57:12 INFO - PROCESS | 1662 | Values: 82.0 82.0 135.0 70.0 77.0 77.0 122.0 68.0 70.0 116.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:87.50 stddev:17.27 (19.7%) stddev-sans-first:15.70 05:57:12 INFO - PROCESS | 1662 | Values: 124.0 95.0 86.0 89.0 88.0 113.0 131.0 87.0 88.0 86.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:98.00 stddev:1.06 (1.1%) stddev-sans-first:1.12 05:57:12 INFO - PROCESS | 1662 | Values: 98.0 97.0 99.0 97.0 99.0 96.0 98.0 99.0 97.0 97.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:89.60 Median:85.00 stddev:13.02 (15.3%) stddev-sans-first:13.75 05:57:12 INFO - PROCESS | 1662 | Values: 86.0 86.0 84.0 84.0 85.0 85.0 83.0 92.0 126.0 85.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:56.00 stddev:11.38 (20.3%) stddev-sans-first:11.85 05:57:12 INFO - PROCESS | 1662 | Values: 56.0 56.0 54.0 55.0 79.0 78.0 79.0 55.0 55.0 55.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.00 stddev:3.10 (4.4%) stddev-sans-first:3.24 05:57:12 INFO - PROCESS | 1662 | Values: 69.0 65.0 69.0 73.0 72.0 76.0 73.0 70.0 70.0 68.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:85.00 stddev:9.69 (11.4%) stddev-sans-first:10.02 05:57:12 INFO - PROCESS | 1662 | Values: 85.0 89.0 85.0 103.0 103.0 86.0 85.0 99.0 101.0 75.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:143.50 stddev:11.80 (8.2%) stddev-sans-first:12.47 05:57:12 INFO - PROCESS | 1662 | Values: 148.0 147.0 138.0 140.0 139.0 137.0 141.0 146.0 177.0 140.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:169.50 stddev:0.95 (0.6%) stddev-sans-first:0.88 05:57:12 INFO - PROCESS | 1662 | Values: 168.0 171.0 169.0 168.0 169.0 170.0 169.0 169.0 170.0 170.0 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:69.00 stddev:8.61 (12.5%) stddev-sans-first:9.06 05:57:12 INFO - PROCESS | 1662 | Values: 68.0 67.0 69.0 68.0 69.0 81.0 85.0 82.0 63.0 59.0 05:57:12 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:57:12 INFO - PROCESS | 1662 | 05:57:12 INFO - TEST-INFO | 1662: exit 0 05:57:12 INFO - TEST-OK | kraken | took 59904ms 05:57:12 INFO - SUITE-END | took 1177s 05:57:13 INFO - Completed test suite (00:19:37) 05:57:13 INFO - javascript benchmark 05:57:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.322882508198, 3329.683680050395, 3358.2611825398403, 3398.694117529422, 3433.296174284272, 5579.358373787016, 5740.655560666363, 5761.395053519383, 5770.134479828028, 5809.9999999999945, 5735.1970724098655, 5749.223854779603, 5797.1594118852745, 5866.6480011199255, 5867.88264234715, 5191.351081114861, 5219.921701174486, 5244.527992480676, 5260.92108618371, 5304.946950530484, 5341.0920143575495, 5348.839534813962, 5353.384360798519, 5380.166074258476, 5429.755660995264, 5187.3775146982225, 5206.323177986837, 5218.556422704075, 5318.414974352838, 5336.4930331618625, 5261.500157485033, 5265.420803711589, 5266.052110620087, 5270.525652691276, 5285.365756109253, 4448.510663826976, 5200.29795977542, 5230.738463076869, 5232.633715639907, 5278.841634750964, 5718.628289161191, 5721.914171287434, 5764.654120752726, 5868.03177475716, 5877.441643043904, 6183.0, 6226.221722284715, 6243.843903902393, 6244.843878903019, 6251.812445626639, 6139.614800607363, 6140.048292514667, 6180.227471566054, 6189.38106189382, 6217.96891015542, 5866.413358664099, 5889.852753681106, 5939.792107276267, 5962.731677074542, 5974.3726908674835, 6212.234706616729, 6269.279032911182, 6270.9372906271265, 6273.905891411632, 6277.058441233851, 6056.242969628796, 6110.908336374958, 6116.3053375860445, 6125.050617154348, 6209.0, 6246.3441338659695, 6269.592476489013, 6270.059491076375, 6271.6550589718, 6276.027215781562, 6236.469900058546, 6244.687765611701, 6257.468115210167, 6264.624122552661, 6272.749090036347, 5959.0, 6020.638761674313, 6091.239754606059, 6183.783567575203, 6267.9373206267355, 4842.975785121052, 4851.514848515191, 4858.02839432115, 4860.927086093711, 4864.002879409783, 4356.629686476685, 4357.43353364067, 4366.672499562545, 4399.890002749893, 4401.6478681705385, 5493.752781124859, 5533.750981205855, 5556.472135147154, 5613.354464236583, 5672.943270567324, 5251.448597897166, 6228.968855155696, 6235.314115447297, 6248.937510624926, 6252.093446450239, 5771.336296325976, 5781.653100813881, 5804.796832110856, 5828.300603927556, 5834.154047663065, 5364.490373414519, 5410.621256512007, 5467.097928841697, 5481.369642491085, 5488.615796894179, 6162.907556386658, 6177.536684748572, 6195.380461953769, 6212.409821066992, 6225.719842607003, 6224.408681175281, 6231.40801623845, 6233.376662333731, 6237.064440333987, 6239.812805615838, 6144.139820425234, 6169.143323984716, 6207.16203312547, 6211.720472578654, 6223.066540019034, 6091.299500557493, 6108.554560543837, 6128.900367973482, 6143.266246520256, 6247.781327653511, 6062.999605065292, 6243.656598887105, 6274.905876411949, 6275.964465863264, 6279.058141278845, 4598.770061496845, 5391.757370918396, 6164.137020816999, 6165.568410211242, 6166.691665416801, 6029.125776762433, 6095.207623008981, 6096.012695893777, 6132.938670613236, 6161.353057929032, 5082.2122571001555, 6002.459778619946, 6057.545684073801, 6072.696365181811, 6108.633481990917, 6146.815595532141, 6160.045192995093, 6184.319724830355, 6191.721372538156, 6194.721237544231, 5749.080147176432, 5758.395368486416, 5780.479756821906, 5839.649621022752, 5879.353271140086], "value": 5681.116532061992, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.5744975294888, 530.4695304695302, 531.5986430245164, 534.6765207049734, 556.3324011186572, 30210.546841797364, 39928.80035599822, 41270.17459650813, 41406.58593414061, 43673.563264367345, 43184.56815431845, 43334.0, 43470.0, 43477.7826110869, 43583.78208108963, 8300.70947516837, 9885.308028438012, 9890.258230632695, 9893.159081478083, 9913.206943444507, 8852.8672069919, 9075.137861903142, 11946.0, 12231.08266879983, 12261.264324140535, 12032.037437005063, 12104.213226140317, 12105.697357566043, 12115.454804533772, 12122.0, 12146.210496317755, 12243.632691019284, 12248.265104093784, 12254.81617775734, 12263.877361226363, 11901.107416943763, 11959.40202989847, 11962.282263064157, 11974.461149248305, 11980.28118312904, 34558.48162277568, 40441.59558404437, 40445.191096178205, 40667.389989150186, 40696.59303406958, 3233.466478031122, 3347.44767113426, 3587.5336206293277, 3821.102041020358, 4192.496900371944, 30334.393312133634, 30844.0, 31564.895228667116, 31827.204319891956, 32071.51892721611, 3243.8378081095852, 3278.6885245901603, 3371.039253812676, 3383.272596391763, 3392.6776956189124, 461471.0, 466329.0, 466950.0, 469941.0, 472716.0, 32726.836365818017, 33501.49747753786, 59111.704441477516, 59411.81176376448, 59457.40542594605, 34546.48180277298, 34619.13452163716, 34758.82620586881, 34913.47629785555, 34935.825320873235, 14535.709285814224, 14687.0, 14694.55916322512, 14701.044432112092, 14707.92646036763, 11333.943330283295, 11567.305961642329, 12445.75108497825, 12539.811902821464, 12567.748645027232, 7308.744193953293, 7363.484556081023, 7363.926360736431, 7521.0974683038385, 7701.576413297211, 3245.6105267367593, 3254.6745325467264, 3259.9837000814846, 3280.6391296957795, 3294.7364210863075, 899.2670973156856, 904.452806052342, 940.3840484482538, 1019.6380284998867, 1045.7594753206804, 29396.853015734785, 29781.255468613024, 29905.551416728766, 30503.694963050086, 30685.709429566887, 1016.5425558498705, 1048.9527971241312, 1054.298891237334, 1061.8938106189319, 1127.9774404511863, 12838.743225135631, 13783.724325513434, 15388.538343849703, 15460.536183914275, 15669.713583183875, 642875.0, 649520.0, 652062.0, 655127.0, 656008.0, 54345.45654543404, 54623.45376546184, 54746.63133421617, 54843.725781370835, 54934.0, 1339.3303348325837, 1364.3860262881544, 1376.1536654957329, 1378.400395827838, 1378.8690074442911, 24760.771530562335, 28473.43053138967, 29215.561766573945, 29858.701412986462, 30171.0, 49306.0, 49546.752266238436, 49593.25610115923, 49773.75113124411, 49792.0, 486073.0, 487115.0, 488073.0, 489616.0, 490971.0, 25656.102036429384, 26465.544395058456, 26500.73499264983, 26507.602385964612, 26526.204213873614, 46441.07117857556, 46819.76590117028, 46941.591752247485, 47541.52458475371, 47558.0, 37275.0, 39194.804025980826, 39779.0, 40234.79882600568, 40605.0, 38136.809315953244, 38953.22093558056, 39600.80199598984, 39718.2056358877, 40158.0, 37388.06529836776, 37651.81174094112, 38454.6154538451, 38687.806560967016, 38938.6106138935, 28172.43655126927, 32552.0, 34806.20618228159, 36358.27283454369, 36503.45244821383, 24993.75006249987, 25522.23433297004, 25741.227763167135, 25763.72527668354, 26216.213513594623], "value": 18859.53452440298, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8976596786932, 369.65806628868296, 373.48086160237256, 381.69655124176285, 404.72478714474164, 15316.693666126685, 16019.0, 19767.5058123547, 20383.694244586324, 20410.183592656274, 20141.798582014177, 20445.693314600292, 20805.0, 21116.472088197803, 21147.682784758243, 5316.415194328621, 5965.999999999989, 6143.815685529441, 6233.501319894398, 6242.063690446424, 6123.081537769348, 6144.96927515364, 6173.938260617404, 6196.256449226099, 6197.907031394533, 5883.29400471944, 6002.309734380537, 6080.0575910733905, 6085.574009819315, 6195.597286176384, 5887.941120588782, 6002.699865006754, 6064.332923378425, 6066.39336066392, 6067.575269731121, 5202.843914682566, 5260.184671375943, 5263.421023687391, 5265.6577322474295, 5268.209768534713, 24998.750012499822, 25443.61834572483, 25600.103996360223, 25710.742892571023, 25810.0, 2262.592733308004, 2328.918487852934, 2354.2902727227265, 2417.915372961938, 2480.7147178074392, 11790.483933333988, 15067.17130557819, 15388.846111538853, 15436.536903892902, 15746.685066298724, 1742.259539695637, 1787.9821201788077, 1835.3392778599693, 1898.6107847891149, 1940.670086085369, 99744.0, 121208.0, 124119.75880241263, 125114.12328815075, 125816.99999999908, 11244.289384503094, 11247.318571165482, 11504.194706370474, 11652.067834573214, 11699.0, 14388.928055359656, 14404.279786010658, 14408.495702650356, 14419.279036048156, 14490.130592164503, 8311.418200725891, 8446.155384461505, 8476.905929335748, 8480.448770829877, 8615.224629783224, 8389.874151887727, 8400.873986890201, 8410.621522031523, 8569.400141990001, 8594.226519613265, 4476.194285028726, 4494.887627809331, 4501.617362524222, 4517.638588912879, 4590.081983603226, 1627.2839950421776, 1739.1217435195144, 1788.8181484093366, 1837.5038739540516, 1881.5954569767566, 748.4731148457196, 808.3106307816691, 854.8760429737778, 872.7120050383359, 896.4576431259125, 20624.17503299851, 20990.580188396452, 21056.841873697093, 21072.367828965154, 21540.892295538422, 896.462122726359, 939.7744541310036, 975.9707208783748, 976.6239997600833, 994.9850752238722, 9158.954205229065, 10835.891641083488, 10949.83575246372, 11021.614243501439, 11023.944880275547, 560678.9404564247, 561746.0, 571717.0, 577180.0, 588270.0, 77859.61070194613, 128130.85316182139, 130076.0, 130153.0, 134865.0, 949.7720547068653, 969.539468752337, 988.5106872098357, 989.0356902020616, 992.369845148336, 18688.532786679898, 19445.222191112764, 19494.31769888047, 19509.122089505723, 19551.511212219808, 43118.35322470103, 48222.0, 50348.0, 54579.0, 56199.0, 78633.0, 78931.81602276085, 80911.59544202242, 82707.0, 83262.0, 5640.548756099584, 6841.692123854339, 7048.964755176191, 7069.363757261871, 7280.126384833854, 10035.849462258218, 12225.144239903122, 12271.447784849881, 12478.126531142732, 12661.78418776692, 9112.453252804853, 10662.57349706034, 10988.340699558052, 11016.339019658846, 11021.283616564897, 9938.503074846085, 10482.318649287772, 10684.198685098494, 10966.451677416257, 11092.667219983414, 9807.411555306704, 10819.350838949687, 10869.402182880018, 11164.0, 11451.370174640142, 6931.549449285781, 7047.894281585883, 7155.212926578176, 7225.710971561079, 7351.338379545867, 8800.33997450181, 10539.473026348805, 10963.945180274048, 11037.11703063737, 11164.051055660035], "value": 9452.573946089467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.3231516556887, 1516.5450364890532, 1691.8561922236609, 1873.7938826729066, 1902.9904850475746, 7581.962090189555, 8904.866427003592, 8905.376623636348, 8918.955405222981, 8930.910690893097, 7800.765977020685, 8049.637766300516, 8496.91503084969, 8738.95630521848, 8750.3874728769, 2361.551305252005, 2393.6648869158244, 2394.928152155438, 2397.244867866625, 2406.68713067301, 2342.8594284342907, 2375.643653451979, 2394.4851856850773, 2396.0, 2398.616221404567, 2383.618621020637, 2387.236084452976, 2399.6520504526834, 2399.796017338528, 2401.7838394544574, 2345.870977096259, 2370.383700237942, 2371.4071482129466, 2378.7621237876247, 2381.5951288281035, 2333.3583264602203, 2349.706286714161, 2355.328731311585, 2358.8348815582917, 2361.9409514762096, 8825.867611985825, 8834.514101724402, 8877.778055548599, 8886.60010299538, 8888.244499217575, 5166.457521960216, 5345.358556973188, 5345.3852806926925, 5354.143337066051, 5355.732213389354, 3614.5301110855685, 3657.3416784978695, 3669.94495082574, 3711.6845068169237, 3722.2927643747603, 1075.4515197249382, 1077.3320541264388, 1079.2984560035961, 1094.179365475893, 1096.0354887698854, 11393.829092563617, 11482.0, 11548.53805847765, 11579.189456738031, 11587.246828956091, 4518.909621807611, 4593.19619066662, 4777.020710754287, 4795.304680821261, 4816.253480710496, 9946.900530994742, 9957.30298879086, 9959.352642078242, 9969.950150249348, 9993.900060999296, 5367.114426119647, 5367.302250707461, 5368.818859850827, 5373.7581808818695, 5392.352917649907, 4210.6841986851105, 4392.231359512072, 4543.5002149763495, 4906.386701662293, 4922.261660750916, 3851.306764782366, 3885.3977633466857, 4008.5390180129075, 4027.637512623878, 4038.919221615551, 1116.6817457024708, 1248.6441364211157, 1272.465564462928, 1276.4957841652495, 1296.3453456004656, 790.3874497264666, 866.8916385451819, 888.1250527758829, 932.5372246859774, 951.357833462424, 5677.205191273225, 5800.4489573490455, 5912.17229587858, 5968.880622387528, 6133.325334213233, 937.7796217888927, 943.7970836270235, 946.8437707778139, 959.3764053365369, 984.6947446291673, 3949.980250098731, 3964.900877478086, 4019.718619696593, 4089.1003979124544, 4092.9181416371507, 10263.897361026444, 10702.250842440955, 10706.304090234109, 10757.515911783988, 10804.837927431096, 10027.348222365523, 10516.421596812248, 10524.631637892637, 10631.893681063246, 10675.199360048027, 7859.567723775247, 8105.27052565272, 8112.18878112184, 8126.83746325082, 8130.715424960098, 5780.393058728773, 5873.911891321548, 5914.911276330772, 6082.60463069899, 6276.278228003655, 9537.189338906272, 9664.661736839176, 10069.899301006895, 10073.748156296151, 10105.444200568745, 10097.646582369582, 10226.181905447695, 10246.077852993267, 10267.640632577824, 10278.33190842593, 6815.93184068153, 7312.5246858954, 7420.406367490696, 7571.545707257582, 7578.545287282781, 8233.012038555411, 8312.6674933002, 8456.281216096455, 8458.788530286545, 8480.575971201539, 7337.0, 7440.590767507839, 7475.439342049477, 7552.848943020999, 7610.6194690266375, 7333.0, 7364.447666424932, 7441.0, 7513.361364283878, 7535.24647535264, 6921.515493915377, 7071.82320441993, 7076.504644674823, 7078.256783037707, 7081.964590177222, 4906.141425250638, 4975.378077740283, 5061.089003979319, 5082.593392528663, 5142.434332223377, 4950.282209079592, 5037.622178336566, 5087.592992560512, 5133.512316329943, 5243.790248390022], "value": 4607.57714330282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.3817766312563, 299.57759559021775, 307.944569977404, 313.64871344094615, 315.5944611174641, 4633.6988095773795, 5165.974170129153, 5232.058229518685, 5286.180642000487, 5336.733163341837, 4477.865664030084, 4966.304717339575, 5041.6974981501135, 5042.369703787027, 5125.66683165594, 3546.8049257290904, 4054.7161698681102, 4064.471618689566, 4066.207089617511, 4074.633283004529, 3748.6626203641667, 4063.349864021757, 4084.4077608746716, 4092.7953602319913, 4128.50457945047, 3183.4588120019653, 3288.8653414571936, 3664.798436086014, 3735.3276410246144, 4033.657139143176, 3443.517907492953, 3960.4059391091423, 3969.642732154091, 3976.6818654507715, 3989.341758609826, 3087.9691203087905, 3456.3260164267977, 3461.6019157796923, 3471.2536804587135, 3483.0421634050585, 4637.582617564435, 4921.729304888229, 5119.312361010462, 5124.846909445382, 5146.845594632167, 3863.744283108001, 4253.128108737732, 4496.168208881368, 4505.234110201273, 4516.725374103781, 4378.912421751579, 4752.97810970643, 4884.923907339366, 5239.921401178947, 5459.918101228484, 3278.4754439289836, 3502.982485087584, 3520.6479352064844, 3803.3075281499746, 3803.9239215215816, 4809.615230781529, 5199.440167949638, 5302.549283310923, 5701.600887937804, 5773.769049238025, 3855.070146688537, 3856.8650097247023, 3875.9031024224055, 3878.379459286557, 3885.184111336651, 3908.768737847569, 3932.3708206686792, 3936.193080418507, 3941.0, 3966.5835087315995, 4243.917800960797, 4536.0, 4554.130897534598, 4574.108048930427, 4577.198990176706, 4664.0, 5320.255164277004, 5350.79607088402, 5437.564994800407, 5517.944820551824, 1177.587844254504, 1315.4606611289516, 1353.4383230959238, 1358.796180572922, 1362.5639795265618, 758.5410826450029, 781.5076501803827, 785.8939043229205, 863.3309185381254, 890.5725251879135, 4576.954230457719, 4786.936360729136, 4881.902361952741, 5087.8982420351385, 5133.920762091359, 4295.379818310828, 5098.490150984871, 5454.945450545444, 5611.9719401403545, 5622.57830662702, 4336.718113322624, 4809.374781278411, 5065.265536497261, 5142.2286657001005, 5213.895722085537, 2759.3377589378406, 2966.9912229841957, 3004.9248768780953, 3010.5333673280675, 3051.4812481878134, 5232.895342093213, 5812.709364531756, 5831.6468030882415, 5848.356680765112, 5955.52355811534, 4830.154722923475, 5413.052715774724, 5621.747021384048, 5824.718561916372, 5854.4533128089415, 5889.293284805851, 5896.754336877441, 5918.496927761188, 5922.733476993546, 5937.079752638348, 4884.65807393479, 5243.217306115939, 5698.287714035746, 5701.600887937804, 5713.0, 4971.776270067928, 5186.8329625833885, 5194.64939115825, 5328.573714102785, 5674.858128546819, 4989.052080104769, 5650.547956163415, 5834.30805066543, 5862.794802181903, 5872.5301975842685, 3022.652394974606, 3114.423831591163, 3778.4521244419957, 3779.1307999160895, 3799.8100094995693, 5123.743812809419, 5305.1776974569, 5611.0, 5712.400197979318, 5769.624974376652, 4822.927656085091, 4835.975820121018, 5573.804916827891, 5580.38615752275, 5596.272484577142, 5146.022255771391, 5427.728613569227, 5567.66594004361, 5673.971630141823, 5679.801206957736, 5123.462961111577, 5468.781248750005, 5520.723963801875, 5525.834224973257, 5559.138333558305, 4646.488886222445, 5470.0, 5529.198266251451, 5534.0, 5573.749181286771, 5078.873028174326, 5330.0, 5381.954857154413, 5541.6145963509125, 5553.389127196086], "value": 3995.7290059278016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.5861769158893, 658.6772481484073, 763.9197884222153, 765.7587859824155, 796.4902462424046, 14961.17713525757, 15199.924000379997, 15739.527814165593, 16044.358225670989, 16080.115593642346, 14356.712865742678, 14437.927810360934, 14457.710845783078, 14491.927540362283, 14854.851451485481, 849.3757088539934, 875.382855087162, 876.1632640828026, 877.6971944679076, 878.4948654523643, 693.4487082769185, 716.4161208614988, 716.7849645106447, 721.6247551273335, 723.4031923662973, 888.2183678363032, 912.4981260306838, 920.59953920045, 920.8066306075699, 924.5700749151644, 904.1139683110556, 904.5567671840817, 905.5336501701563, 907.9364444488856, 909.0000998901222, 530.3609150973068, 545.3183520599251, 670.9731610735565, 674.1876039372544, 674.4503229867692, 12269.0, 12375.876241237562, 14078.2960851957, 14652.0, 14659.340329685083, 14053.945954053384, 14545.127292362386, 14565.563033109023, 14631.0, 14733.705325893528, 11743.530258789639, 11790.469428875635, 11810.822837657443, 11849.822252666218, 12250.81623775644, 3767.05823544114, 3813.771173729585, 3850.6534411903062, 3868.3423817951016, 3894.6300101490315, 113099.43450282777, 113234.0, 113391.0, 113677.43161284224, 113693.43153284263, 10647.0, 10649.361038337725, 10887.618933337295, 10936.398498082683, 11225.550977960791, 22047.00788464523, 22223.888880555493, 22345.55308893813, 22361.552768944854, 22415.43961400978, 7830.491018083806, 7874.803129921684, 7937.722179723682, 8026.117127116013, 8048.356131509582, 4329.610335069859, 4403.4495688039, 4405.537418571068, 4591.977040114778, 4602.516735742765, 3532.8056956867617, 3636.818159092035, 3646.4348026056005, 3647.3222317733607, 3648.3797754381817, 630.6539672418595, 640.4108220437209, 651.4495251512465, 653.2716021635937, 674.0596867369991, 643.9678016099176, 665.1994117821251, 673.7709178879204, 679.6499802601664, 713.1905287498727, 8663.176998185163, 9254.814903701888, 9315.021922698019, 9444.386114902509, 9466.0, 628.5034822490181, 671.8689855478233, 674.5716470041559, 680.5440354962187, 682.931706829323, 4253.425787518708, 4567.2920697291975, 4664.650151238603, 4723.811047558059, 4937.530934561211, 137371.62628373588, 140946.0, 143248.28375858054, 151883.0, 155209.0, 124267.37866310611, 125407.37296313643, 129378.0, 130626.0, 131806.34096829453, 107403.0, 111045.0, 111486.0, 111832.0, 113513.99999999834, 9952.751181220237, 9968.028774103364, 10066.396016239049, 10082.546285417027, 10124.898751012395, 53485.73257133845, 55236.72381638066, 56945.0, 62989.68505157445, 66748.0, 71749.64125179341, 81341.0, 96330.0, 97673.0, 100410.0, 13078.738425231633, 13127.081104322988, 13136.802947955597, 13454.798178027533, 13796.517121900686, 14591.90149467056, 14777.630559236106, 15023.399064037316, 15052.54842354731, 15828.0, 13780.517681881487, 13837.515686950908, 14072.859271407155, 14093.718125637224, 14692.045017073857, 13903.026788124735, 13954.302284885513, 13975.301234938415, 14048.438062477386, 14061.507847225297, 12187.817182742088, 13573.864261357656, 13631.591052268448, 13664.111832730841, 13672.453101875813, 10820.134389248999, 11221.71945701364, 11507.482163302839, 11580.594679186188, 11689.142582947778, 9877.506124693593, 11227.438628068401, 11503.597374091867, 11517.308961462339, 11533.942330288295], "value": 7837.542865640685, "unit": "score"}], "name": "dromaeo_css", "value": 7257.738930034522, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 115.0, 112.0, 113.0, 114.0, 112.0, 113.0, 112.0, 122.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 139.0, 136.0, 162.0, 108.0, 131.0, 129.0, 107.0, 147.0, 127.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 198.0, 254.0, 197.0, 184.0, 182.0, 183.0, 181.0, 184.0], "value": 193.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 76.0, 153.0, 59.0, 58.0, 58.0, 66.0, 140.0, 57.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 135.0, 70.0, 77.0, 77.0, 122.0, 68.0, 70.0, 116.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 95.0, 86.0, 89.0, 88.0, 113.0, 131.0, 87.0, 88.0, 86.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 99.0, 97.0, 99.0, 96.0, 98.0, 99.0, 97.0, 97.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 84.0, 85.0, 85.0, 83.0, 92.0, 126.0, 85.0], "value": 89.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 55.0, 79.0, 78.0, 79.0, 55.0, 55.0, 55.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 69.0, 73.0, 72.0, 76.0, 73.0, 70.0, 70.0, 68.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 85.0, 103.0, 103.0, 86.0, 85.0, 99.0, 101.0, 75.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 138.0, 140.0, 139.0, 137.0, 141.0, 146.0, 177.0, 140.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 169.0, 168.0, 169.0, 170.0, 169.0, 169.0, 170.0, 170.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 68.0, 69.0, 81.0, 85.0, 82.0, 63.0, 59.0], "value": 71.1, "unit": "score"}], "name": "kraken", "value": 1510.1999999999998, "alertThreshold": 2.0}]} 05:57:13 INFO - Return code: 0 05:57:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:57:13 INFO - # TBPL SUCCESS # 05:57:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:57:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:57:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:57:13 INFO - 2016-09-03 05:57:13.371 screenresolution[1666:9861] starting screenresolution argv=screenresolution get 05:57:13 INFO - 2016-09-03 05:57:13.373 screenresolution[1666:9861] Display 0: 1600x1200x32@60 05:57:13 INFO - 2016-09-03 05:57:13.379 screenresolution[1667:9864] starting screenresolution argv=screenresolution list 05:57:13 INFO - Available Modes on Display 0 05:57:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:57:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:57:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:57:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:57:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:57:13 INFO - Intel Iris: 05:57:13 INFO - Chipset Model: Intel Iris 05:57:13 INFO - Type: GPU 05:57:13 INFO - Bus: Built-In 05:57:13 INFO - VRAM (Dynamic, Max): 1536 MB 05:57:13 INFO - Vendor: Intel (0x8086) 05:57:13 INFO - Device ID: 0x0a2e 05:57:13 INFO - Revision ID: 0x0009 05:57:13 INFO - Displays: 05:57:13 INFO - Display: 05:57:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:57:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:57:13 INFO - Display Serial Number: 1600x1200 60 05:57:13 INFO - Main Display: Yes 05:57:13 INFO - Mirror: Off 05:57:13 INFO - Online: Yes 05:57:13 INFO - Rotation: Supported 05:57:13 INFO - Return code: 0 05:57:13 INFO - Running post-action listener: _resource_record_post_action 05:57:13 INFO - [mozharness: 2016-09-03 12:57:13.616269Z] Finished run-tests step (success) 05:57:13 INFO - Running post-run listener: _resource_record_post_run 05:57:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33229824; Write bytes: 703720448; Read time: 312; Write time: 1995 05:57:13 INFO - TinderboxPrint: CPU usage
53.7% 05:57:13 INFO - TinderboxPrint: I/O read bytes / time
33,229,824 / 312 05:57:13 INFO - TinderboxPrint: I/O write bytes / time
703,720,448 / 1,995 05:57:13 INFO - TinderboxPrint: CPU idle
2,246.5 (46.3%) 05:57:13 INFO - TinderboxPrint: CPU system
971.1 (20.0%) 05:57:13 INFO - TinderboxPrint: CPU user
1,632.2 (33.7%) 05:57:13 INFO - TinderboxPrint: Swap in / out
434,274,304 / 0 05:57:13 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387684864; Write bytes: 381687296; Read time: 29140; Write time: 720 05:57:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32304128; Write bytes: 295785984; Read time: 298; Write time: 1223 05:57:14 INFO - Running post-run listener: _upload_blobber_files 05:57:14 INFO - Blob upload gear active. 05:57:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:57:14 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 05:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:57:14 INFO - (blobuploader) - INFO - Open directory for files ... 05:57:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:57:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:57:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:15 INFO - (blobuploader) - INFO - Done attempting. 05:57:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:57:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:57:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:16 INFO - (blobuploader) - INFO - Done attempting. 05:57:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:57:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:57:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:18 INFO - (blobuploader) - INFO - Done attempting. 05:57:18 INFO - (blobuploader) - INFO - Iteration through files over. 05:57:18 INFO - Return code: 0 05:57:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:57:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:57:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1bcfdec578f047f1d2c129017a0986ded18495f0e6cdf43129a9b1af25dad1b307de8708e72493ac04666fa562a2a97a6e0d401de2dc868316449a2c6bb48e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20c7630c71c7a964cd1e0ede42bd120d49a21f803f8f15a35441b82659c214a177c1db0ab59ccf1f591797da50276b508f160d83047a727e8b52be5a1cdea47c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ac71ac1e50702495085760b4ff0a3cf681deca6156501cab34ffa961c291fa0c7fe100c3f8b3107f5391fb2072636836d76c4b57c73e68daea9e4673c334f8"} 05:57:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:57:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:57:18 INFO - Contents: 05:57:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1bcfdec578f047f1d2c129017a0986ded18495f0e6cdf43129a9b1af25dad1b307de8708e72493ac04666fa562a2a97a6e0d401de2dc868316449a2c6bb48e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20c7630c71c7a964cd1e0ede42bd120d49a21f803f8f15a35441b82659c214a177c1db0ab59ccf1f591797da50276b508f160d83047a727e8b52be5a1cdea47c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ac71ac1e50702495085760b4ff0a3cf681deca6156501cab34ffa961c291fa0c7fe100c3f8b3107f5391fb2072636836d76c4b57c73e68daea9e4673c334f8"} 05:57:18 INFO - Running post-run listener: copy_logs_to_upload_dir 05:57:18 INFO - Copying logs to upload dir... 05:57:18 INFO - mkdir: /builds/slave/test/build/upload/logs 05:57:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.205632 ========= master_lag: -0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-03 05:57:18.919103) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 05:57:18.923045) ========= 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.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1bcfdec578f047f1d2c129017a0986ded18495f0e6cdf43129a9b1af25dad1b307de8708e72493ac04666fa562a2a97a6e0d401de2dc868316449a2c6bb48e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20c7630c71c7a964cd1e0ede42bd120d49a21f803f8f15a35441b82659c214a177c1db0ab59ccf1f591797da50276b508f160d83047a727e8b52be5a1cdea47c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ac71ac1e50702495085760b4ff0a3cf681deca6156501cab34ffa961c291fa0c7fe100c3f8b3107f5391fb2072636836d76c4b57c73e68daea9e4673c334f8"} build_url:https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010786 build_url: 'https://queue.taskcluster.net/v1/task/DS-9E979RfynWXsA3sSRIw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1bcfdec578f047f1d2c129017a0986ded18495f0e6cdf43129a9b1af25dad1b307de8708e72493ac04666fa562a2a97a6e0d401de2dc868316449a2c6bb48e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20c7630c71c7a964cd1e0ede42bd120d49a21f803f8f15a35441b82659c214a177c1db0ab59ccf1f591797da50276b508f160d83047a727e8b52be5a1cdea47c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3ac71ac1e50702495085760b4ff0a3cf681deca6156501cab34ffa961c291fa0c7fe100c3f8b3107f5391fb2072636836d76c4b57c73e68daea9e4673c334f8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 05:57:18.962280) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 05:57:18.963022) ========= 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.Je8y41UYNX/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.DEE2zXNMG3/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005213 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 05:57:19.014115) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 05:57:19.014567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 05:57:19.015094) ========= ========= Total master_lag: 4.21 =========