builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0274 starttime: 1470807924.99 results: success (0) buildid: 20160809210458 builduid: c0cce048ef7b4e618e942baafc15adc6 revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:24.995165) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:24.995590) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:24.995861) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:25.019529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:25.019797) ========= 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.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-09 22:45:25-- 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% 127M=0s 2016-08-09 22:45:25 (127 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.198417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:25.240707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:25.241067) ========= 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.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.025621 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:25.288997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-09 22:45:25.289343) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c9bbdb627b7804fee47aa6a6708647e6e589d09c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c9bbdb627b7804fee47aa6a6708647e6e589d09c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-09 22:45:25,374 truncating revision to first 12 chars 2016-08-09 22:45:25,374 Setting DEBUG logging. 2016-08-09 22:45:25,374 attempt 1/10 2016-08-09 22:45:25,374 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c9bbdb627b78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 22:45:27,712 unpacking tar archive at: fx-team-c9bbdb627b78/testing/mozharness/ program finished with exit code 0 elapsedTime=2.606067 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-09 22:45:27.917038) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:27.917374) ========= script_repo_revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:27.917754) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:27.918023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:45:27.937008) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-09 22:45:27.937354) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45:28 INFO - MultiFileLogger online at 20160809 22:45:28 in /builds/slave/test 22:45:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:45:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:45:28 INFO - {'append_to_log': False, 22:45:28 INFO - 'base_work_dir': '/builds/slave/test', 22:45:28 INFO - 'blob_upload_branch': 'Fx-Team', 22:45:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:45:28 INFO - 'branch': 'Fx-Team', 22:45:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:45:28 INFO - 'config_files': ('talos/mac_config.py',), 22:45:28 INFO - 'default_actions': ('clobber', 22:45:28 INFO - 'read-buildbot-config', 22:45:28 INFO - 'download-and-extract', 22:45:28 INFO - 'populate-webroot', 22:45:28 INFO - 'create-virtualenv', 22:45:28 INFO - 'install', 22:45:28 INFO - 'run-tests'), 22:45:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:45:28 INFO - 'download_minidump_stackwalk': True, 22:45:28 INFO - 'download_symbols': 'ondemand', 22:45:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:45:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:45:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:45:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:45:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:45:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:45:28 INFO - 'installer_path': 'installer.exe', 22:45:28 INFO - 'log_level': 'info', 22:45:28 INFO - 'log_name': 'talos', 22:45:28 INFO - 'log_to_console': True, 22:45:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:45:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:45:28 INFO - 'opt_config_files': (), 22:45:28 INFO - 'pip_index': False, 22:45:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:45:28 INFO - 'cmd': ('bash', 22:45:28 INFO - '-c', 22:45:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:45:28 INFO - 'enabled': True, 22:45:28 INFO - 'halt_on_failure': False, 22:45:28 INFO - 'name': 'check_screen_resolution'},), 22:45:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:45:28 INFO - 'cmd': ('bash', 22:45:28 INFO - '-c', 22:45:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:45:28 INFO - 'enabled': True, 22:45:28 INFO - 'halt_on_failure': False, 22:45:28 INFO - 'name': 'check_screen_resolution'},), 22:45:28 INFO - 'run_cmd_checks_enabled': True, 22:45:28 INFO - 'sps_profile': False, 22:45:28 INFO - 'sps_profile_interval': 0, 22:45:28 INFO - 'suite': 'dromaeojs', 22:45:28 INFO - 'system_bits': '32', 22:45:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:45:28 INFO - 'title': 't-yosemite-r7-0274', 22:45:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:45:28 INFO - 'use_talos_json': True, 22:45:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:45:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:45:28 INFO - 'work_dir': 'build'} 22:45:28 INFO - [mozharness: 2016-08-10 05:45:28.151035Z] Running clobber step. 22:45:28 INFO - Running pre-action listener: _resource_record_pre_action 22:45:28 INFO - Running main action method: clobber 22:45:28 INFO - rmtree: /builds/slave/test/build 22:45:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:45:30 INFO - Running post-action listener: _resource_record_post_action 22:45:30 INFO - [mozharness: 2016-08-10 05:45:30.427712Z] Finished clobber step (success) 22:45:30 INFO - [mozharness: 2016-08-10 05:45:30.427852Z] Running read-buildbot-config step. 22:45:30 INFO - Running pre-action listener: _resource_record_pre_action 22:45:30 INFO - Running main action method: read_buildbot_config 22:45:30 INFO - Using buildbot properties: 22:45:30 INFO - { 22:45:30 INFO - "project": "", 22:45:30 INFO - "product": "firefox", 22:45:30 INFO - "script_repo_revision": "production", 22:45:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:45:30 INFO - "repository": "", 22:45:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:45:30 INFO - "buildid": "20160809210458", 22:45:30 INFO - "pgo_build": "False", 22:45:30 INFO - "basedir": "/builds/slave/test", 22:45:30 INFO - "buildnumber": 114, 22:45:30 INFO - "slavename": "t-yosemite-r7-0274", 22:45:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:45:30 INFO - "platform": "yosemite_r7", 22:45:30 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:45:30 INFO - "branch": "fx-team", 22:45:30 INFO - "repo_path": "integration/fx-team", 22:45:30 INFO - "revision": "c9bbdb627b7804fee47aa6a6708647e6e589d09c", 22:45:30 INFO - "stage_platform": "macosx64", 22:45:30 INFO - "builduid": "c0cce048ef7b4e618e942baafc15adc6", 22:45:30 INFO - "slavebuilddir": "test" 22:45:30 INFO - } 22:45:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:45:30 INFO - Running post-action listener: _resource_record_post_action 22:45:30 INFO - [mozharness: 2016-08-10 05:45:30.431468Z] Finished read-buildbot-config step (success) 22:45:30 INFO - [mozharness: 2016-08-10 05:45:30.431585Z] Running download-and-extract step. 22:45:30 INFO - Running pre-action listener: _resource_record_pre_action 22:45:30 INFO - Running main action method: download_and_extract 22:45:30 INFO - mkdir: /builds/slave/test/build/tests 22:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:30 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:45:30 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:31 INFO - Downloaded 1199 bytes. 22:45:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:45:31 INFO - Using the following test package requirements: 22:45:31 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:45:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:45:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:45:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'jsshell-mac.zip'], 22:45:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:45:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:45:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:45:31 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:45:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:45:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:45:31 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:45:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:31 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:45:31 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:32 INFO - Downloaded 16325980 bytes. 22:45:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:45:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:45:35 INFO - Return code: 0 22:45:35 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 22:45: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')]} 22:45:35 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:45:35 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:36 INFO - Downloaded 16325980 bytes. 22:45:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:45:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:45:39 INFO - Return code: 0 22:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:39 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:45:39 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:40 INFO - Downloaded 14413723 bytes. 22:45:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:45:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:45:40 INFO - Return code: 0 22:45: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')]} 22:45:40 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:45:40 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:45:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/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 22:45:44 INFO - Downloaded 110879628 bytes. 22:45:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:45:44 INFO - mkdir: /builds/slave/test/properties 22:45:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:45:44 INFO - Writing to file /builds/slave/test/properties/build_url 22:45:44 INFO - Contents: 22:45:44 INFO - build_url:https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:45:44 INFO - Running post-action listener: _resource_record_post_action 22:45:44 INFO - Running post-action listener: set_extra_try_arguments 22:45:44 INFO - [mozharness: 2016-08-10 05:45:44.797614Z] Finished download-and-extract step (success) 22:45:44 INFO - [mozharness: 2016-08-10 05:45:44.797764Z] Running populate-webroot step. 22:45:44 INFO - Running pre-action listener: _resource_record_pre_action 22:45:44 INFO - Running main action method: populate_webroot 22:45:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:45:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:45:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:45:44 INFO - '--tppagecycles', 22:45:44 INFO - '7'], 22:45:44 INFO - 'tests': ['tsvgm']}}, 22:45:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:45:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:45:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:45:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:45:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['damp', 'tps']}, 22:45:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'tests': ['damp', 'tps']}, 22:45:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['dromaeo_dom']}, 22:45:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:45:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['basic_compositor_video']}, 22:45:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:45:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'other-e10s': {'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['a11yr', 22:45:44 INFO - 'ts_paint', 22:45:44 INFO - 'tpaint', 22:45:44 INFO - 'sessionrestore', 22:45:44 INFO - 'sessionrestore_no_auto_restore', 22:45:44 INFO - 'tabpaint']}, 22:45:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['tsvgx', 22:45:44 INFO - 'tsvgr_opacity', 22:45:44 INFO - 'tart', 22:45:44 INFO - 'tscrollx', 22:45:44 INFO - 'cart']}, 22:45:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:45:44 INFO - 'tsvgr_opacity', 22:45:44 INFO - 'tart', 22:45:44 INFO - 'tscrollx', 22:45:44 INFO - 'cart']}, 22:45:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'talos_options': ['--disable-e10s'], 22:45:44 INFO - 'tests': ['tp5o']}, 22:45:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'tests': ['tp5o']}, 22:45:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'talos_options': ['--disable-e10s', 22:45:44 INFO - '--xperf_path', 22:45:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:44 INFO - 'tests': ['tp5n']}, 22:45:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:44 INFO - 'talos_options': ['--xperf_path', 22:45:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:44 INFO - 'tests': ['tp5n']}}, 22:45:44 INFO - 'talos.zip': {'path': '', 22:45:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:45:44 INFO - Running post-action listener: _resource_record_post_action 22:45:44 INFO - [mozharness: 2016-08-10 05:45:44.817659Z] Finished populate-webroot step (success) 22:45:44 INFO - [mozharness: 2016-08-10 05:45:44.817800Z] Running create-virtualenv step. 22:45:44 INFO - Running pre-action listener: _resource_record_pre_action 22:45:44 INFO - Running main action method: create_virtualenv 22:45:44 INFO - Creating virtualenv /builds/slave/test/build/venv 22:45:44 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 22:45:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:45:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:45:44 INFO - Using real prefix '/tools/python27' 22:45:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:45:45 INFO - Installing distribute.............................................................................................................................................................................................done. 22:45:48 INFO - Installing pip.................done. 22:45:48 INFO - Return code: 0 22:45:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:45:48 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')]} 22:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:48 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')]} 22:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:48 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45:48 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 22:45:48 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 22:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:48 INFO - 'HOME': '/Users/cltbld', 22:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:48 INFO - 'LOGNAME': 'cltbld', 22:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:48 INFO - 'MOZ_NO_REMOTE': '1', 22:45:48 INFO - 'NO_EM_RESTART': '1', 22:45:48 INFO - 'PAGER': '/bin/cat', 22:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:48 INFO - 'PWD': '/builds/slave/test', 22:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:48 INFO - 'SHELL': '/bin/bash', 22:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:48 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:48 INFO - 'USER': 'cltbld', 22:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:48 INFO - 'XPC_FLAGS': '0x0', 22:45:48 INFO - 'XPC_SERVICE_NAME': '0', 22:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:48 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 22:45:48 INFO - Cleaning up... 22:45:48 INFO - Return code: 0 22:45:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:45:48 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')]} 22:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:48 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')]} 22:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:48 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45:48 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 22:45:48 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 22:45:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:48 INFO - 'HOME': '/Users/cltbld', 22:45:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:48 INFO - 'LOGNAME': 'cltbld', 22:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:48 INFO - 'MOZ_NO_REMOTE': '1', 22:45:48 INFO - 'NO_EM_RESTART': '1', 22:45:48 INFO - 'PAGER': '/bin/cat', 22:45:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:48 INFO - 'PWD': '/builds/slave/test', 22:45:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:48 INFO - 'SHELL': '/bin/bash', 22:45:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:48 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:48 INFO - 'USER': 'cltbld', 22:45:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:48 INFO - 'XPC_FLAGS': '0x0', 22:45:48 INFO - 'XPC_SERVICE_NAME': '0', 22:45:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:49 INFO - Downloading/unpacking psutil>=3.1.1 22:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:45:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:51 INFO - Installing collected packages: psutil 22:45:51 INFO - Running setup.py install for psutil 22:45:51 INFO - building 'psutil._psutil_osx' extension 22:45:51 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 22:45:52 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 22:45:52 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 22:45:52 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 22:45:52 INFO - building 'psutil._psutil_posix' extension 22:45:52 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 22:45:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:45:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:45:52 INFO - ^ 22:45:52 INFO - 1 warning generated. 22:45:52 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 22:45:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:52 INFO - Successfully installed psutil 22:45:52 INFO - Cleaning up... 22:45:52 INFO - Return code: 0 22:45:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:45:52 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')]} 22:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:52 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')]} 22:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:52 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45:52 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 22:45:52 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 22:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:52 INFO - 'HOME': '/Users/cltbld', 22:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:52 INFO - 'LOGNAME': 'cltbld', 22:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:52 INFO - 'MOZ_NO_REMOTE': '1', 22:45:52 INFO - 'NO_EM_RESTART': '1', 22:45:52 INFO - 'PAGER': '/bin/cat', 22:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:52 INFO - 'PWD': '/builds/slave/test', 22:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:52 INFO - 'SHELL': '/bin/bash', 22:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:52 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:52 INFO - 'USER': 'cltbld', 22:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:52 INFO - 'XPC_FLAGS': '0x0', 22:45:52 INFO - 'XPC_SERVICE_NAME': '0', 22:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:45:55 INFO - Installing collected packages: mozsystemmonitor 22:45:55 INFO - Running setup.py install for mozsystemmonitor 22:45:55 INFO - Successfully installed mozsystemmonitor 22:45:55 INFO - Cleaning up... 22:45:55 INFO - Return code: 0 22:45:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:55 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45:55 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 22:45:55 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 22:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:55 INFO - 'HOME': '/Users/cltbld', 22:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:55 INFO - 'LOGNAME': 'cltbld', 22:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:55 INFO - 'MOZ_NO_REMOTE': '1', 22:45:55 INFO - 'NO_EM_RESTART': '1', 22:45:55 INFO - 'PAGER': '/bin/cat', 22:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:55 INFO - 'PWD': '/builds/slave/test', 22:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:55 INFO - 'SHELL': '/bin/bash', 22:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:55 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:55 INFO - 'USER': 'cltbld', 22:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:55 INFO - 'XPC_FLAGS': '0x0', 22:45:55 INFO - 'XPC_SERVICE_NAME': '0', 22:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:56 INFO - Downloading/unpacking jsonschema==2.5.1 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45: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 22:45:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:45:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:45:58 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 22:45:58 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 22:45:58 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 22:45:58 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 22:45:58 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 22:45:58 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 22:45:59 INFO - Downloading functools32-3.2.3-2.tar.gz 22:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:45:59 INFO - warning: no files found matching '*.txt' 22:45:59 INFO - no previously-included directories found matching 'build' 22:45:59 INFO - no previously-included directories found matching 'dist' 22:45:59 INFO - no previously-included directories found matching '.git*' 22:45:59 INFO - Installing collected packages: jsonschema, functools32 22:45:59 INFO - Running setup.py install for functools32 22:45:59 INFO - warning: no files found matching '*.txt' 22:45:59 INFO - no previously-included directories found matching 'build' 22:45:59 INFO - no previously-included directories found matching 'dist' 22:45:59 INFO - no previously-included directories found matching '.git*' 22:45:59 INFO - Successfully installed jsonschema functools32 22:45:59 INFO - Cleaning up... 22:45:59 INFO - Return code: 0 22:45:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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', '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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:45: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 functools32==3.2.3-2 22:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:59 INFO - 'HOME': '/Users/cltbld', 22:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:59 INFO - 'LOGNAME': 'cltbld', 22:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:59 INFO - 'MOZ_NO_REMOTE': '1', 22:45:59 INFO - 'NO_EM_RESTART': '1', 22:45:59 INFO - 'PAGER': '/bin/cat', 22:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:59 INFO - 'PWD': '/builds/slave/test', 22:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:59 INFO - 'SHELL': '/bin/bash', 22:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:59 INFO - 'USER': 'cltbld', 22:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:59 INFO - 'XPC_FLAGS': '0x0', 22:45:59 INFO - 'XPC_SERVICE_NAME': '0', 22:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:45:59 INFO - Cleaning up... 22:45:59 INFO - Return code: 0 22:45:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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', '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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:45: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:45: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 blobuploader==1.2.4 22:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:59 INFO - 'HOME': '/Users/cltbld', 22:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:59 INFO - 'LOGNAME': 'cltbld', 22:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:59 INFO - 'MOZ_NO_REMOTE': '1', 22:45:59 INFO - 'NO_EM_RESTART': '1', 22:45:59 INFO - 'PAGER': '/bin/cat', 22:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:59 INFO - 'PWD': '/builds/slave/test', 22:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:59 INFO - 'SHELL': '/bin/bash', 22:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:45:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:59 INFO - 'USER': 'cltbld', 22:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:59 INFO - 'XPC_FLAGS': '0x0', 22:45:59 INFO - 'XPC_SERVICE_NAME': '0', 22:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:59 INFO - Downloading/unpacking blobuploader==1.2.4 22:45:59 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 22:45:59 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 22:45:59 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 22:45:59 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 22:45:59 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 22:45:59 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 22:46:02 INFO - Downloading blobuploader-1.2.4.tar.gz 22:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:46:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:46:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:03 INFO - Downloading docopt-0.6.1.tar.gz 22:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:46:03 INFO - Installing collected packages: blobuploader, requests, docopt 22:46:03 INFO - Running setup.py install for blobuploader 22:46:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:46:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:46:03 INFO - Running setup.py install for requests 22:46:04 INFO - Running setup.py install for docopt 22:46:04 INFO - Successfully installed blobuploader requests docopt 22:46:04 INFO - Cleaning up... 22:46:04 INFO - Return code: 0 22:46:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:04 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:46:04 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 22:46:04 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 22:46:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:46:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:04 INFO - 'HOME': '/Users/cltbld', 22:46:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:04 INFO - 'LOGNAME': 'cltbld', 22:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:04 INFO - 'MOZ_NO_REMOTE': '1', 22:46:04 INFO - 'NO_EM_RESTART': '1', 22:46:04 INFO - 'PAGER': '/bin/cat', 22:46:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:04 INFO - 'PWD': '/builds/slave/test', 22:46:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:04 INFO - 'SHELL': '/bin/bash', 22:46:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:46:04 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:46:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:04 INFO - 'USER': 'cltbld', 22:46:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:04 INFO - 'XPC_FLAGS': '0x0', 22:46:04 INFO - 'XPC_SERVICE_NAME': '0', 22:46:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-K_hgm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-4fJ7jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-KqP1dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-CIZswN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-jpA2O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-wEOOXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-88vY1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-xln0yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-nZJyEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-W_oyj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-py6QLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-ve6al9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:06 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-44aPSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:06 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Mm6zzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:06 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-u77nHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:06 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-djmSOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:06 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-cQbwhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:46:06 INFO - Running setup.py install for manifestparser 22:46:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:46:06 INFO - Running setup.py install for mozcrash 22:46:06 INFO - Running setup.py install for mozdebug 22:46:06 INFO - Running setup.py install for mozdevice 22:46:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Running setup.py install for mozfile 22:46:07 INFO - Running setup.py install for mozhttpd 22:46:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Running setup.py install for mozinfo 22:46:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Running setup.py install for mozInstall 22:46:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:46:07 INFO - Running setup.py install for mozleak 22:46:07 INFO - Running setup.py install for mozlog 22:46:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Running setup.py install for moznetwork 22:46:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Running setup.py install for mozprocess 22:46:08 INFO - Running setup.py install for mozprofile 22:46:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Running setup.py install for mozrunner 22:46:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:46:08 INFO - Running setup.py install for mozscreenshot 22:46:08 INFO - Running setup.py install for moztest 22:46:08 INFO - Running setup.py install for mozversion 22:46:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:46:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:46:09 INFO - Cleaning up... 22:46:09 INFO - Return code: 0 22:46:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:09 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')]} 22:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:09 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')]} 22:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:09 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:46:09 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 22:46:09 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 22:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:09 INFO - 'HOME': '/Users/cltbld', 22:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:09 INFO - 'LOGNAME': 'cltbld', 22:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:09 INFO - 'MOZ_NO_REMOTE': '1', 22:46:09 INFO - 'NO_EM_RESTART': '1', 22:46:09 INFO - 'PAGER': '/bin/cat', 22:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:09 INFO - 'PWD': '/builds/slave/test', 22:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:09 INFO - 'SHELL': '/bin/bash', 22:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:46:09 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:09 INFO - 'USER': 'cltbld', 22:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:09 INFO - 'XPC_FLAGS': '0x0', 22:46:09 INFO - 'XPC_SERVICE_NAME': '0', 22:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7qFcC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:09 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)) 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-m6CT9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:09 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)) 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-41gKxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:09 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)) 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-zjTl6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:09 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)) 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-Ds7OQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:09 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)) 22:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:09 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-iCkwux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-CBacYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-3POsBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-NCtERS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yF8XJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-HiGOvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-6Pd07l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-pWrsVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-SY81Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-vEFHCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:10 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)) 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:10 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-pI9Ji5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:11 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)) 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:11 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-kcWobD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:11 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)) 22:46:11 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)) 22:46:11 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)) 22:46:11 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)) 22:46:11 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)) 22:46:11 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)) 22:46:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:46:11 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 22:46:11 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 22:46:11 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 22:46:11 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 22:46:11 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 22:46:11 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 22:46:13 INFO - Downloading blessings-1.6.tar.gz 22:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:46:13 INFO - Installing collected packages: blessings 22:46:13 INFO - Running setup.py install for blessings 22:46:13 INFO - Successfully installed blessings 22:46:13 INFO - Cleaning up... 22:46:13 INFO - Return code: 0 22:46:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:46:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:14 INFO - Reading from file tmpfile_stdout 22:46:14 INFO - Current package versions: 22:46:14 INFO - blessings == 1.6 22:46:14 INFO - blobuploader == 1.2.4 22:46:14 INFO - docopt == 0.6.1 22:46:14 INFO - functools32 == 3.2.3-2 22:46:14 INFO - jsonschema == 2.5.1 22:46:14 INFO - manifestparser == 1.1 22:46:14 INFO - mozInstall == 1.12 22:46:14 INFO - mozcrash == 0.17 22:46:14 INFO - mozdebug == 0.1 22:46:14 INFO - mozdevice == 0.48 22:46:14 INFO - mozfile == 1.2 22:46:14 INFO - mozhttpd == 0.7 22:46:14 INFO - mozinfo == 0.9 22:46:14 INFO - mozleak == 0.1 22:46:14 INFO - mozlog == 3.3 22:46:14 INFO - moznetwork == 0.27 22:46:14 INFO - mozprocess == 0.23 22:46:14 INFO - mozprofile == 0.28 22:46:14 INFO - mozrunner == 6.12 22:46:14 INFO - mozscreenshot == 0.1 22:46:14 INFO - mozsystemmonitor == 0.3 22:46:14 INFO - moztest == 0.7 22:46:14 INFO - mozversion == 1.4 22:46:14 INFO - psutil == 3.1.1 22:46:14 INFO - requests == 1.2.3 22:46:14 INFO - wsgiref == 0.1.2 22:46:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:14 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 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:46:14 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 22:46:14 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 22:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:14 INFO - 'HOME': '/Users/cltbld', 22:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:14 INFO - 'LOGNAME': 'cltbld', 22:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:14 INFO - 'MOZ_NO_REMOTE': '1', 22:46:14 INFO - 'NO_EM_RESTART': '1', 22:46:14 INFO - 'PAGER': '/bin/cat', 22:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:14 INFO - 'PWD': '/builds/slave/test', 22:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:14 INFO - 'SHELL': '/bin/bash', 22:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:46:14 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:14 INFO - 'USER': 'cltbld', 22:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:14 INFO - 'XPC_FLAGS': '0x0', 22:46:14 INFO - 'XPC_SERVICE_NAME': '0', 22:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 INFO - Cleaning up... 22:46:14 INFO - Return code: 0 22:46:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105aaea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b43618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed98491ea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, '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.r3J94guRqe/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.7tN1BdCOIH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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 22:46:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:14 INFO - 'HOME': '/Users/cltbld', 22:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:14 INFO - 'LOGNAME': 'cltbld', 22:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:14 INFO - 'MOZ_NO_REMOTE': '1', 22:46:14 INFO - 'NO_EM_RESTART': '1', 22:46:14 INFO - 'PAGER': '/bin/cat', 22:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:14 INFO - 'PWD': '/builds/slave/test', 22:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:14 INFO - 'SHELL': '/bin/bash', 22:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:46:14 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:14 INFO - 'USER': 'cltbld', 22:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:14 INFO - 'XPC_FLAGS': '0x0', 22:46:14 INFO - 'XPC_SERVICE_NAME': '0', 22:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:46:14 INFO - Cleaning up... 22:46:14 INFO - Return code: 0 22:46:14 INFO - Running post-action listener: _resource_record_post_action 22:46:14 INFO - Running post-action listener: _start_resource_monitoring 22:46:14 INFO - Starting resource monitoring. 22:46:14 INFO - [mozharness: 2016-08-10 05:46:14.862715Z] Finished create-virtualenv step (success) 22:46:14 INFO - [mozharness: 2016-08-10 05:46:14.863653Z] Running install step. 22:46:14 INFO - Running pre-action listener: _resource_record_pre_action 22:46:14 INFO - Running main action method: install 22:46:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:15 INFO - Reading from file tmpfile_stdout 22:46:15 INFO - Detecting whether we're running mozinstall >=1.0... 22:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:46:15 INFO - Reading from file tmpfile_stdout 22:46:15 INFO - Output received: 22:46:15 INFO - Usage: mozinstall [options] installer 22:46:15 INFO - Options: 22:46:15 INFO - -h, --help show this help message and exit 22:46:15 INFO - -d DEST, --destination=DEST 22:46:15 INFO - Directory to install application into. [default: 22:46:15 INFO - "/builds/slave/test"] 22:46:15 INFO - --app=APP Application being installed. [default: firefox] 22:46:15 INFO - mkdir: /builds/slave/test/build/application 22:46:15 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'] 22:46:15 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 22:46:49 INFO - Reading from file tmpfile_stdout 22:46:49 INFO - Output received: 22:46:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:46:49 INFO - Running post-action listener: _resource_record_post_action 22:46:49 INFO - [mozharness: 2016-08-10 05:46:49.167539Z] Finished install step (success) 22:46:49 INFO - [mozharness: 2016-08-10 05:46:49.167654Z] Running run-tests step. 22:46:49 INFO - Running pre-action listener: _resource_record_pre_action 22:46:49 INFO - Running main action method: run_tests 22:46:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:46:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:46:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:46:49 INFO - 2016-08-09 22:46:49.181 screenresolution[1645:7620] starting screenresolution argv=screenresolution get 22:46:49 INFO - 2016-08-09 22:46:49.184 screenresolution[1645:7620] Display 0: 1600x1200x32@60 22:46:49 INFO - 2016-08-09 22:46:49.190 screenresolution[1646:7622] starting screenresolution argv=screenresolution list 22:46:49 INFO - Available Modes on Display 0 22:46:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:46:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:46:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:46:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:46:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:46:49 INFO - Intel Iris: 22:46:49 INFO - Chipset Model: Intel Iris 22:46:49 INFO - Type: GPU 22:46:49 INFO - Bus: Built-In 22:46:49 INFO - VRAM (Dynamic, Max): 1536 MB 22:46:49 INFO - Vendor: Intel (0x8086) 22:46:49 INFO - Device ID: 0x0a2e 22:46:49 INFO - Revision ID: 0x0009 22:46:49 INFO - Displays: 22:46:49 INFO - Display: 22:46:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:46:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:46:49 INFO - Display Serial Number: 1600x1200 60 22:46:49 INFO - Main Display: Yes 22:46:49 INFO - Mirror: Off 22:46:49 INFO - Online: Yes 22:46:49 INFO - Rotation: Supported 22:46:49 INFO - Return code: 0 22:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:46:49 INFO - Python 2.7.3 22:46:49 INFO - Return code: 0 22:46:49 INFO - grabbing minidump binary from tooltool 22:46: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')]} 22:46:49 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 0x105a12d30>, '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 0x105777db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:46:49 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 22:46:49 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 22:46:49 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 22:46:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:46:49 INFO - Return code: 0 22:46:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:46:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:46:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:46:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:46:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 22:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 22:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r3J94guRqe/Render', 22:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:49 INFO - 'HOME': '/Users/cltbld', 22:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:49 INFO - 'LOGNAME': 'cltbld', 22:46:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:49 INFO - 'MOZ_NO_REMOTE': '1', 22:46:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:49 INFO - 'NO_EM_RESTART': '1', 22:46:49 INFO - 'PAGER': '/bin/cat', 22:46:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:49 INFO - 'PWD': '/builds/slave/test', 22:46:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:49 INFO - 'SHELL': '/bin/bash', 22:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7tN1BdCOIH/Listeners', 22:46:49 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 22:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:49 INFO - 'USER': 'cltbld', 22:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:49 INFO - 'XPC_FLAGS': '0x0', 22:46:49 INFO - 'XPC_SERVICE_NAME': '0', 22:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 22:46:49 INFO - mozversion application_buildid: 20160809210458 22:46:49 INFO - mozversion application_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:46:49 INFO - mozversion application_display_name: Nightly 22:46:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:49 INFO - mozversion application_name: Firefox 22:46:49 INFO - mozversion application_remotingname: firefox 22:46:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:46:49 INFO - mozversion application_vendor: Mozilla 22:46:49 INFO - mozversion application_version: 51.0a1 22:46:49 INFO - mozversion platform_buildid: 20160809210458 22:46:49 INFO - mozversion platform_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:46:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:46:49 INFO - mozversion platform_version: 51.0a1 22:46:49 INFO - using testdate: 1470808009 22:46:49 INFO - actual date: 1470808009 22:46:49 INFO - starting webserver on 'localhost:49276' 22:46:49 INFO - SUITE-START | Running 2 tests 22:46:49 INFO - TEST-START | dromaeo_css 22:46:49 INFO - operating with platform_type : mac_ 22:46:49 INFO - Initialising browser for dromaeo_css test... 22:46:49 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmphNvuqd/profile http://localhost:49276/getInfo.html) 22:46:52 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 22:46:52 INFO - PROCESS | 1656 | colorDepth:24 22:46:52 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 22:46:52 INFO - PROCESS | 1656 | __metrics 22:46:52 INFO - PROCESS | 1656 | 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" 22:46:52 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:46:52 INFO - TEST-INFO | 1656: exit 0 22:46:52 INFO - Browser initialized. 22:46:52 INFO - Running cycle 1/1 for dromaeo_css test... 22:46:53 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmphNvuqd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:47:04 INFO - PROCESS | 1658 | RSS: Main: 243707904 22:47:04 INFO - PROCESS | 1658 | 22:47:56 INFO - PROCESS | 1658 | 1470808076717 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] 22:50:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 22:50:10 INFO - PROCESS | 1658 | RSS: Main: 358490112 22:50:10 INFO - PROCESS | 1658 | 22:53:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 22:53:13 INFO - PROCESS | 1658 | RSS: Main: 386592768 22:53:13 INFO - PROCESS | 1658 | 22:56:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 22:56:16 INFO - PROCESS | 1658 | RSS: Main: 380284928 22:56:16 INFO - PROCESS | 1658 | 22:59:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 22:59:19 INFO - PROCESS | 1658 | RSS: Main: 351068160 22:59:19 INFO - PROCESS | 1658 | 23:02:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 23:02:23 INFO - PROCESS | 1658 | RSS: Main: 347189248 23:02:23 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 23:05:26 INFO - PROCESS | 1658 | RSS: Main: 458616832 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | __start_tp_report 23:05:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 23:05:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 23:05:26 INFO - PROCESS | 1658 | |i|pagename|runs| 23:05:26 INFO - PROCESS | 1658 | |0;dojo.html;3386.898393048208;3391.3217356528685;3406.148462884279;3422.3839708852397;3466.705330046946;4668.719876807394;5277.3156127643915;5718.027935251007;5735.397783232763;5757.827265182046;5740.655560666363;5794.82615521534;5795.15970184323;5804.59367844251;5815.592908496407;5210.088234558956;5238.266642670029;5266.92099618506;5278.894422111555;5330.813421530247;5255.5795536357;5296.973515132419;5314.893702125955;5321.707306098182;5324.334458192726;5241.685498870061;5244.947550524503;5256.579473642099;5277.815276465324;5279.63042587019;5337.172738225581;5343.385510666284;5347.893042139174;5352.116900711417;5398.973005134989;5128.2564028216075;5160.896782064376;5179.533841954205;5236.345456817898;5254.395744489394;5681.062624666925;5715.571332150105;5741.942580574183;5812.244408226946;5830.912536311958;6237.719302631347;6240.968795155995;6260.655663938482;6265.373462653743;6276.497880169575;5249.2126181073145;6154.599951003215;6164.013757798775;6234.064890266451;6261.186045814015;5961.105834124916;5976.880462390728;5994.880102397928;6005;6007.008843540854;5424.213489044067;6306.3378345274;6337.207849018872;6340.714667839959;6341.0805433212845;6078.118672792509;6090.888650161123;6145.678679084019;6177.69111544421;6212.844678883064;6248.781292654735;6252.187215661935;6255.843603909939;6259.061140828912;6273.309935907047;6195.287541932736;6197.380261973766;6223.315435302113;6249.187605611241;6253.155823963798;6132.936844521141;6165.0444181152;6201.720922558496;6234.220722409699;6235.844103897439;4923.483034281349;4935.383077115361;4944.307796908436;4946.554810067111;4966.776495057658;4445.26653102241;4456.197884380777;4457.754823484739;4490.057088011489;4498.550144985541;5735.1684005818925;5758.596898217084;5782.277215348082;5884.735186916599;5887.705614719247;6163.075538669146;6175.073738939195;6241.375862413723;6257.342978987232;6260.373962603703;5878.559108066826;5896.321922978912;5920.940790592125;5924;5943.732532036069;5445.618806683573;5482.890342193134;5485.17722341644;5493;5510.173473978935;5234.97382513085;5783.566232532493;5921.023031199804;6333.20834895638;6363.490920726424;5687.232223649754;6199.535034872493;6219.657918814509;6227.844303892258;6261.530385221217;6099.725512352044;6235.949175269595;6245.7189426476825;6315.368463153648;6316.368363163647;6253.218347706536;6268.749250029948;6292.905606415999;6294.748210071546;6314.557980941474;5008.77460514285;6272.560920735687;6290.24517057956;6319.6208227506495;6362.904556431565;6194.690265486617;6196.225471816023;6200.81397558074;6213.285472170758;6213.658248796179;6145.754169833157;6197.845053873509;6201.596896201732;6201.96899015502;6214.471769899609;5792.420757924174;5947.583669143291;5974.40255974399;6020.307664618625;6084.209052823105;6146.016637338004;6164.250919870711;6208.130861679459;6210.627362358273;6213.720382582885;5876.41235876409;6051.304100028553;6077.314953551058;6087.634741915499;6130.693465326805 23:05:26 INFO - PROCESS | 1658 | |1;ext.html;462.78480506564443;472.7588929645881;475.9524047595241;492.35501493044114;529.1665626638041;30227.942022029205;32055.83972080142;41453.99999999996;42053;42246;43137;43642.56357436425;43657.56342436575;43758.34362484558;43880.12239755203;9591.568379422924;9760.951195244013;9763.755906102333;9770.755731106708;9780.364276322016;8682.392232543725;9550.80898382032;9674.613015479408;9675.709728708114;9705.805883882318;9661.661841835537;9699.75750606237;9702.981186975372;9706;9711.708648740549;9623.085806848338;9648.421094734338;9680.225581953462;9684.322097453181;9704.61181552737;9613.855792163124;9731.659391921248;9733.659321923698;9739.853902191473;9740.269479788973;33837.83081084603;35988.92033239007;38656.420153697436;41175.38236926449;41185.588144118476;3374.983125084359;3385.8137802420856;3438.398280300937;3452.102453362119;3453.8791142309897;27662;31718.365632687448;31944.840275798706;32267.51598726021;33054.50418243728;3222.4682927316962;3226.516022596606;3257.16942179745;3284.8850290239966;3339.1652086978256;495310;501191;502814.485927568;503949.99999999633;506911;61307;61669;61692;61798.69100654468;61984;34091.659083409344;34666.65333346685;65074.67462662656;65084.42035687988;65276.020859687145;16250.024998500128;16773.90969586973;16785.496435106965;16797.748033779502;16815.91592042032;12791.912687421487;12852.807207891889;12862.614121576367;12896.161749486253;12911.289879056554;19412.692646465963;19444.902775486033;19462.688341406854;19648.017599119987;19680.60638787216;6543.051257567626;7593.620318984029;7644.6942122315595;7668.469405096304;7716.2669546393;879.7624641346929;973.8782652168479;980.8970058143935;1007.9647212347533;1038.678009816959;23727.7627223729;30921.226969325948;31159.53260701091;31465.528017079763;31617.05148845538;736.867363874497;922.9492377919145;1108.3817626265582;1125.9943700281447;1126.9549218031186;14805.407783688532;15227.695446091016;15356.769648455282;15476.976706077572;15644.452444164628;689521;689592;692917;694126.5293673499;694736;59668;60317.095243571384;60374.49063773353;60392.772505924164;60497.39502604918;1230.5262473947416;1266.2465832829453;1352.0462931064715;1378.3383975691725;1402.8246469191351;31160.065198044096;31617.683823162395;32482.350352992336;32694.673053270115;32823.67176328302;56891.14663279971;57413.712931435075;57419.425805741404;57450.712746437675;57529.13706294325;607073;610492;610572;611467;612754;26955;27336.316592085357;27421.90312387482;28006.159815205578;28108.85945570259;48131;48448.757756210995;49303.50696492989;49484.75257623689;49768;38949;38977.41533877051;39317.6068239314;40603;40616.59383406246;39229.41155882721;39396.60603394044;39532;40730;40810.795946021266;33640.327193456484;33904.49143262902;38229.80885095557;39213.607863920995;39491.605083949944;22306.888465557568;33652.663473364955;35758.46362304516;36223.456648149775;37395.62604373921;20795.58408831845;22355.664665030363;22684.773152268266;26227.868860655573;26949.73050269472 23:05:26 INFO - PROCESS | 1658 | |2;jquery.html;315.33936403235214;366.8862652577701;376.7777011563178;384.7825978322246;400.98997525061867;19786.505337366572;20534.794652053475;20711.171553137894;21460.248891288807;21699.674504882438;20325.695114573256;20355;20874.791252087514;20933.685994710093;21175.470613234676;5167.612429067816;5272.551833094172;5950.97024514879;6090.9086363704355;6109.022556390978;5061.595072394218;6070.939290607082;6078.8784224315705;6081.1790408294755;6094.847628809293;5783.161441590967;6009.1286763419275;6081.057436097412;6099.878002439948;6132;5810.82567522975;6089.908651370233;6098.8780224395705;6107;6114.052321890114;5795.507381872504;5874.706264686792;5877.706114694248;5877.970610146965;5890.499307558863;11987.280763154238;12167.391630418531;21677.132914683392;24243.878780605985;24419;2071.792820717931;2362.232274510791;2483.552960467115;2647.7881769458395;2784.7354501322343;10419.531121099568;10472.319299245524;12205.328706921116;15488.845111548853;16132.274047667883;1868.6639053077115;1927.5566619677552;1934.0909772406947;2144.6246906791407;2234.340869443502;132477;142852.571474286;143022;146839;151715;17883.748137630242;20595.485112872;21257.81132830088;21313.68029479559;21768.45578860541;34645.826770865984;35265.47101793475;35361.82319088388;35392.11519711976;35415.4687679685;10672.733181670521;10789;10981.890181098246;11018.283811552223;11161.776764464828;9011.188992990663;9039.864402033974;9046.86429703555;9203.171714545722;9209.677661281823;9620.807583848284;9649.662261820802;9664.75838104053;9827.557759900821;9840.49067487786;2853.7574306184206;2939;3569.286142771456;3706.627758234678;3728.794916279631;689.6517258784282;881.8104107616894;883.8983516895511;937.8265020971006;977.4233202410612;20265.696014559795;21782.78217217839;21816.563668726852;21826.890865545887;21857.781422185893;851.4720873058743;929.6188562689265;940.7365937537501;945.1588086602928;976.7851072763981;10736.570537178426;11459.541618335174;11472.254303470248;11479.942600286946;11749.530018799323;577303;581024;582312;592395;592676;104892;121138;125793;127946.36026819807;130744;951.4291425144996;975.6536429567543;991.0634450444261;992.3103443106971;995.1541189988452;18783.90608046951;19314.420567383;19346.032698364743;19355;19424.61150776948;55586.88826223534;55792.721036394556;55924.440755591924;56813.14780278382;57465.71267143638;77649;80367;82039;82274.17725822666;84050.73923891025;8068.193180681885;8084.151164127918;8220.712275070584;8269.751907442786;8295.170482951657;11377.374244416636;13024.869751302365;13044.478220871059;13478.393472293574;13488.595342139752;11261.380624065756;11476.770464590829;11577.305361678325;11628.767424651629;11644.010259128068;11290.491927863102;11345.659630211107;11444;11710.355930423908;11772.234804737665;11482.483288252215;11483.770324593628;11730.882691173321;11856.22934509254;11971.16201865861;7995.080565734782;8007.959960200162;8217.096119426738;8262.132476090164;8448.070712221774;8231;11038.392888391216;11207.383593902414;11272.380019099028;11582.71043223926 23:05:26 INFO - PROCESS | 1658 | |3;mootools.html;1471.7645176771719;1512.9167895765731;1546.250068716673;1672.1221358786636;1844.5019844641947;8405.705800296992;8782.297416206688;8787.428817126882;8807.251383632383;8847;7743.535387876731;7972.960135199331;8323.25090741833;8549.957250213756;8570.828583428327;2211.933641990735;2242.013514053819;2243.730752309717;2245.831562632801;2250.8649481031107;2299.0458959531857;2334.4163959010248;2342.9297121086393;2357.0571771291534;2357.5874222011166;2003.5492014296813;2311.179531266401;2341.098677009347;2350.306659535441;2353.4587044979667;2303.2399308228246;2321.5937210988095;2324.7559006304264;2328.7671232876746;2332.5801355755957;2326.7673232676766;2326.93019209424;2328.0687724910003;2328.138588722167;2334.1363695432797;7423.925760742378;8736.257418119467;8834.955825220834;8850.291976641918;8863;5155.561777248939;5330.28041214435;5335.493128152819;5339.813106541253;5352.839414817562;3419.46998215277;3494.965050349515;3626.256617393428;3628.8911332660064;3637.0907273181706;1038.927275090744;1070.4273213830563;1080.0333701337338;1089.1178145702006;1090.2150451674781;11466.197366184371;11582.710432239177;11587.130965177561;11611;11612.709682257926;4485.663575231871;4652.278896771006;4753.1444340019125;4778.713277203379;4784.186688263004;9811.607535698493;9814.509274536245;9824.754381140387;9831.459269740233;9839.75400614976;5279.313689220377;5291.550218231417;5291.682499050069;5302.337207849019;5302.469753024667;4130.235906357333;4363.650907927358;4508.661850361236;4827.131116399082;4874.268859671078;3844;3891.494105766289;3980.283548961157;3983.6813054955533;3988.74073185242;1096.4901320885826;1198.1732604502865;1234.4383305595932;1251.6808213905579;1267.2966503590412;896.0949441064441;917.8623206519076;923.330585325647;941.6177170979417;991.2119864707574;5641.576881733887;5790.971045144832;5931.703414829328;5992.43071908168;6046.848828779316;407.20594840062;421.0925455643094;424.32955929631106;428.7920358626064;435.336112428549;3851;3892.4939757831303;3975.284448799186;3981.960180398159;4014.598540145962;8316.501009939422;10723.14214862809;10725.892741072645;10746.570137194425;10752.838707419394;10319;10430.37417754937;10536.947315263375;10582.894171058344;10597.205209609156;7983.12185659577;8067.273945344947;8153.714619988272;8159.428839981263;8162.102168761431;4705.858824235278;5737.713114344183;5780.364159942487;5824.825255242349;5841.211436456194;9915.30592858493;9994.800103997733;9999.250056245955;10001.849972250568;10017.749556261151;9168.220701240469;9502.904970950201;9542.093501117382;9902.900970990198;9939.552720127467;6314.494840412664;7315.451341149329;7533.171351151259;7540.622968851645;7646.579438130956;8023.4784738991775;8175.918240817754;8300.875486867822;8301.6679332826;8343.123971982854;7371.115466144097;7394.519356241827;7444.106707195171;7447.255274472509;7459.254074592497;7286.307800758919;7294.2341054190665;7316.451266155166;7339.59632220233;7348.595827229554;6794.8641027180165;6802.93197068023;6843.041974123527;6863.622500762506;6957.547759395623;4716.575508204278;4958.677685950402;4963.627727920348;5034.521720436553;5146.6911985281;4742.857714268577;4831.468538460696;5029.64792464524;5123.769430375716;5161.793528258828 23:05:26 INFO - PROCESS | 1658 | |4;prototype.html;278.4222737819026;297.6591802386268;299.210085374611;311.80979602442505;316.9556262123303;4215.409842622032;4727.409073865767;5145.202493613491;5233.712145831978;5306.867328316789;5018.523240292176;5203.661761985469;5222.686638801674;5270.393904700961;5321.388040375348;4013.117114234878;4044.9191016179807;4071.674266058723;4075.9388609170887;4088.89777755557;3830.7550046234946;4017.8749950014044;4053.7770422626745;4078.163976384834;4099.1963536044195;3658.35374081663;3983.183447393277;3994.941340544758;4000.9399859002137;4032.919341613166;3648.0150359402815;3737.8504859805576;3996.3006473867104;4002.6597739192202;4029.7582145071096;3145.5936208800285;3330.6169790473923;3511.5434993450945;3519.9472007919903;3544.627814079536;4257.489101307832;4299.591538803808;5020.206555904798;5108.416390918827;5119.257707632372;4616.1229366420275;4627.074585082997;4633.189191891405;4634.328022436762;4634.887626969503;5039.748012599355;5469.507744303032;5497;5592.273004509388;5610.523105536035;3500.964990350115;3765.642263984917;3784.375578029622;3840.884773456773;3868.574456809749;4694.413198350207;5233.764480598382;5258.789648414059;5854.268216472941;5867.295924489089;3516.507688923553;3633.7819730816236;3849.9807500962875;3873.845046198177;3881.9037719631083;3616.2044350242904;3657.335912159961;3785.640364165455;3914.8042597869994;3922.8231530540725;4498.707584007029;4500.819967201275;4529.479109902338;4532.4334458192725;4549.134854709553;5061.240813877947;5171.810483588795;5548.472895074961;5576.797165119755;5610.663360198401;1160.715624671944;1192.6362459449952;1208.6011616166643;1290.9225446473242;1311.5934060441293;854.8760429737653;873.6112429968603;902.5874506397467;907.8819753432011;909.486140330711;4930.827421040246;5109.926915347744;5147.819826306061;5150.768215430315;5154.123798954111;4648.790804413809;5608.383077861432;5640.379558248589;5708.429157084258;5753.309602847685;4052.3111071117983;4347.217500849814;5103.540681338697;5112.693238405707;5118.309028281209;2402.8946684525313;2403.591389463795;2431.562318782636;2480.578301688717;2512.120757734778;4404.933925991113;4600.780793089833;4798.121127323648;4806.375171227788;4865.739954230674;4130.079513026457;4331.371951067078;4587.954120458753;4809.350737650442;4857;4517.650821572764;4754;4779.402574678165;4799.688020278741;4879.633053193285;4712;4778;4782.33047373361;4794.352762377034;4799.448063472745;4653.86038418848;4659.505370971771;4659.883502912454;4679.46186188579;5194.97402512985;5179.420276815607;5424.511793938565;5678.630888992202;5963.850903727442;6023.578349515492;3535.8232088395994;3859.6719278860487;3867.149227170018;3897.4799828067644;3899.6490315871706;4867;5033.785134540791;5384.6769193848495;5814.3604203538425;5824.854378640568;5406.7296635167295;5526.22632831412;5668.659880407189;5686.2323586315315;5688.800891968761;4770.045990801784;5586.692731899784;5598.692071936082;5610.158476228598;5617.634853734494;4759;4919.704817710949;5037.327277854467;5438.537724293479;5530.889382212253;4463.776811159494;5186.9221961669855;5277.788888444573;5612.522935550524;5672.801451949162;5253.605979551472;5498.3127109111365;5503.614746967673;5536.972315138399;5570.58220633446 23:05:26 INFO - PROCESS | 1658 | |5;yui.html;607.5929127484585;683.8939964305533;741.8479211761589;772.1467778105197;787.8542469643116;13881.722365552709;14795.704085918274;16560.171991400442;16568.502944911656;16603.418880339177;16059.598510037255;16463.588410289747;16488.34046638136;16509.669806603863;16635;740.9814754631124;831.4595512916592;873.0440168016044;873.9912600874005;874.038557586656;537.7821982097238;705.6260182103467;835.9038710548274;857.2456238510102;872.746903398017;919.0579655852738;920.5121285718546;921.5714692667908;922.7324076017981;922.8108237811233;918.6463211663491;918.8162367526488;919.4942781470164;919.7930465645243;924.3714274293478;543.323562165103;561.3740679142767;563.2930672006646;563.6646195513663;567.1351189436102;14821.481248156262;14882.330295136637;14933.925330373388;15659.686806263924;15675.686486270324;13435.798463023062;14256.002079854414;14452.783208251885;14472.276386180649;14547.490837820731;11832.763344733057;11867.821982670266;12119.939400302943;12164.2701437914;12367.196132251465;3819.656230939229;3865.2849222893856;3873.709471789627;3887.3391523441082;3926.980365098185;99336.99999999927;113784.62153784638;114220;116769;116895;9056.637734490547;11032.724181895517;11142.832857507145;11199.608013719644;11255.155863310285;21135.26026589062;21930.342089737333;22107.11571537153;22119.336419907107;22410.663840042413;6724.798256052326;8143.83712325762;8239.711610093738;8240.340772738167;8269.29710974562;4209.515905670887;4450.198964186413;4455.1757924784015;4528.162289976365;4556.908861822745;3388.508666243381;3400.489926510994;3701.3522633539023;3754.737168398186;3761.4169803680475;578.2973686970267;665.9433948114367;668.85619591788;670.3330186464401;685.475611157465;583.527342852291;644.5133923887435;648.9383508566679;657.3032585459429;663.9701213445406;9266.907330926739;9483.430994140374;9497;9511.239100871913;9545.379550329206;579.9797007104731;631.908373285871;639.5427269502328;700.6181631011111;700.8072779985545;4557.680962332605;4587.6788624795945;4631.791569379386;4644.117617652635;4675.368825208621;130960;158782;158917;159317.20341398218;161880;136141;137048;138270;141724;142664.28667856596;90253;92574.53712731393;95309.52345238368;104431;107968;8802.60388282516;9181.678641247525;9568.330216884751;9962.750931226778;9966.302358834811;60256.39743602508;60830;61335.38664613475;61353.686722208615;61609.07586386118;61474;86027.56986215028;90374.09625903657;91202;92424.53787731018;12977.675558110745;13221.603351899459;13287.867121329049;13641.86358136406;13728.176309421468;14604.415823366946;15067.246637668291;15263.923680381527;15593.22033898278;15946.4418745348;14067.437302507786;14258.928705356822;14306.998510104193;14749.115053096848;14806.925965370105;13883.236421996888;13977.371107860357;14342.282885855875;14806.548360419094;14849.480268190562;13604.86395136036;13630.182189068688;14009.789853152415;14107.506237281646;14461.78307325412;11241.021129400879;11272.830907536558;11659.125565582446;11836.644900652995;11838.940805296263;9158.594586518535;9728.951355243462;11420.257683250562;11436.142289328167;11651.825222621837 23:05:26 INFO - PROCESS | 1658 | __end_tp_report 23:05:26 INFO - PROCESS | 1658 | __start_cc_report 23:05:26 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1265 23:05:26 INFO - PROCESS | 1658 | __end_cc_report 23:05:26 INFO - PROCESS | 1658 | __startTimestamp1470809126900__endTimestamp 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | ------- Summary: start ------- 23:05:26 INFO - PROCESS | 1658 | Number of tests: 6 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5759.04 Median:5976.88 stddev:634.16 (10.6%) stddev-sans-first:608.19 23:05:26 INFO - PROCESS | 1658 | Values: 3386.9 3391.3 3406.1 3422.4 3466.7 4668.7 5277.3 5718.0 5735.4 5757.8 5740.7 5794.8 5795.2 5804.6 5815.6 5210.1 5238.3 5266.9 5278.9 5330.8 5255.6 5297.0 5314.9 5321.7 5324.3 5241.7 5244.9 5256.6 5277.8 5279.6 5337.2 5343.4 5347.9 5352.1 5399.0 5128.3 5160.9 5179.5 5236.3 5254.4 5681.1 5715.6 5741.9 5812.2 5830.9 6237.7 6241.0 6260.7 6265.4 6276.5 5249.2 6154.6 6164.0 6234.1 6261.2 5961.1 5976.9 5994.9 6005.0 6007.0 5424.2 6306.3 6337.2 6340.7 6341.1 6078.1 6090.9 6145.7 6177.7 6212.8 6248.8 6252.2 6255.8 6259.1 6273.3 6195.3 6197.4 6223.3 6249.2 6253.2 6132.9 6165.0 6201.7 6234.2 6235.8 4923.5 4935.4 4944.3 4946.6 4966.8 4445.3 4456.2 4457.8 4490.1 4498.6 5735.2 5758.6 5782.3 5884.7 5887.7 6163.1 6175.1 6241.4 6257.3 6260.4 5878.6 5896.3 5920.9 5924.0 5943.7 5445.6 5482.9 5485.2 5493.0 5510.2 5235.0 5783.6 5921.0 6333.2 6363.5 5687.2 6199.5 6219.7 6227.8 6261.5 6099.7 6235.9 6245.7 6315.4 6316.4 6253.2 6268.7 6292.9 6294.7 6314.6 5008.8 6272.6 6290.2 6319.6 6362.9 6194.7 6196.2 6200.8 6213.3 6213.7 6145.8 6197.8 6201.6 6202.0 6214.5 5792.4 5947.6 5974.4 6020.3 6084.2 6146.0 6164.3 6208.1 6210.6 6213.7 5876.4 6051.3 6077.3 6087.6 6130.7 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:73055.88 Median:40770.40 stddev:162400.60 (398.3%) stddev-sans-first:162764.72 23:05:26 INFO - PROCESS | 1658 | Values: 462.8 472.8 476.0 492.4 529.2 30227.9 32055.8 41454.0 42053.0 42246.0 43137.0 43642.6 43657.6 43758.3 43880.1 9591.6 9761.0 9763.8 9770.8 9780.4 8682.4 9550.8 9674.6 9675.7 9705.8 9661.7 9699.8 9703.0 9706.0 9711.7 9623.1 9648.4 9680.2 9684.3 9704.6 9613.9 9731.7 9733.7 9739.9 9740.3 33837.8 35988.9 38656.4 41175.4 41185.6 3375.0 3385.8 3438.4 3452.1 3453.9 27662.0 31718.4 31944.8 32267.5 33054.5 3222.5 3226.5 3257.2 3284.9 3339.2 495310.0 501191.0 502814.5 503950.0 506911.0 61307.0 61669.0 61692.0 61798.7 61984.0 34091.7 34666.7 65074.7 65084.4 65276.0 16250.0 16773.9 16785.5 16797.7 16815.9 12791.9 12852.8 12862.6 12896.2 12911.3 19412.7 19444.9 19462.7 19648.0 19680.6 6543.1 7593.6 7644.7 7668.5 7716.3 879.8 973.9 980.9 1008.0 1038.7 23727.8 30921.2 31159.5 31465.5 31617.1 736.9 922.9 1108.4 1126.0 1127.0 14805.4 15227.7 15356.8 15477.0 15644.5 689521.0 689592.0 692917.0 694126.5 694736.0 59668.0 60317.1 60374.5 60392.8 60497.4 1230.5 1266.2 1352.0 1378.3 1402.8 31160.1 31617.7 32482.4 32694.7 32823.7 56891.1 57413.7 57419.4 57450.7 57529.1 607073.0 610492.0 610572.0 611467.0 612754.0 26955.0 27336.3 27421.9 28006.2 28108.9 48131.0 48448.8 49303.5 49484.8 49768.0 38949.0 38977.4 39317.6 40603.0 40616.6 39229.4 39396.6 39532.0 40730.0 40810.8 33640.3 33904.5 38229.8 39213.6 39491.6 22306.9 33652.7 35758.5 36223.5 37395.6 20795.6 22355.7 22684.8 26227.9 26949.7 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:36513.35 Median:1627.17 stddev:98192.84 (6034.6%) stddev-sans-first:98430.68 23:05:26 INFO - PROCESS | 1658 | Values: 315.3 366.9 376.8 384.8 401.0 19786.5 20534.8 20711.2 21460.2 21699.7 20325.7 20355.0 20874.8 20933.7 21175.5 5167.6 5272.6 5951.0 6090.9 6109.0 5061.6 6070.9 6078.9 6081.2 6094.8 5783.2 6009.1 6081.1 6099.9 6132.0 5810.8 6089.9 6098.9 6107.0 6114.1 5795.5 5874.7 5877.7 5878.0 5890.5 11987.3 12167.4 21677.1 24243.9 24419.0 2071.8 2362.2 2483.6 2647.8 2784.7 10419.5 10472.3 12205.3 15488.8 16132.3 1868.7 1927.6 1934.1 2144.6 2234.3 132477.0 142852.6 143022.0 146839.0 151715.0 17883.7 20595.5 21257.8 21313.7 21768.5 34645.8 35265.5 35361.8 35392.1 35415.5 10672.7 10789.0 10981.9 11018.3 11161.8 9011.2 9039.9 9046.9 9203.2 9209.7 9620.8 9649.7 9664.8 9827.6 9840.5 2853.8 2939.0 3569.3 3706.6 3728.8 689.7 881.8 883.9 937.8 977.4 20265.7 21782.8 21816.6 21826.9 21857.8 851.5 929.6 940.7 945.2 976.8 10736.6 11459.5 11472.3 11479.9 11749.5 577303.0 581024.0 582312.0 592395.0 592676.0 104892.0 121138.0 125793.0 127946.4 130744.0 951.4 975.7 991.1 992.3 995.2 18783.9 19314.4 19346.0 19355.0 19424.6 55586.9 55792.7 55924.4 56813.1 57465.7 77649.0 80367.0 82039.0 82274.2 84050.7 8068.2 8084.2 8220.7 8269.8 8295.2 11377.4 13024.9 13044.5 13478.4 13488.6 11261.4 11476.8 11577.3 11628.8 11644.0 11290.5 11345.7 11444.0 11710.4 11772.2 11482.5 11483.8 11730.9 11856.2 11971.2 7995.1 8008.0 8217.1 8262.1 8448.1 8231.0 11038.4 11207.4 11272.4 11582.7 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5607.14 Median:4961.15 stddev:3158.46 (63.7%) stddev-sans-first:3152.03 23:05:26 INFO - PROCESS | 1658 | Values: 1471.8 1512.9 1546.3 1672.1 1844.5 8405.7 8782.3 8787.4 8807.3 8847.0 7743.5 7973.0 8323.3 8550.0 8570.8 2211.9 2242.0 2243.7 2245.8 2250.9 2299.0 2334.4 2342.9 2357.1 2357.6 2003.5 2311.2 2341.1 2350.3 2353.5 2303.2 2321.6 2324.8 2328.8 2332.6 2326.8 2326.9 2328.1 2328.1 2334.1 7423.9 8736.3 8835.0 8850.3 8863.0 5155.6 5330.3 5335.5 5339.8 5352.8 3419.5 3495.0 3626.3 3628.9 3637.1 1038.9 1070.4 1080.0 1089.1 1090.2 11466.2 11582.7 11587.1 11611.0 11612.7 4485.7 4652.3 4753.1 4778.7 4784.2 9811.6 9814.5 9824.8 9831.5 9839.8 5279.3 5291.6 5291.7 5302.3 5302.5 4130.2 4363.7 4508.7 4827.1 4874.3 3844.0 3891.5 3980.3 3983.7 3988.7 1096.5 1198.2 1234.4 1251.7 1267.3 896.1 917.9 923.3 941.6 991.2 5641.6 5791.0 5931.7 5992.4 6046.8 407.2 421.1 424.3 428.8 435.3 3851.0 3892.5 3975.3 3982.0 4014.6 8316.5 10723.1 10725.9 10746.6 10752.8 10319.0 10430.4 10536.9 10582.9 10597.2 7983.1 8067.3 8153.7 8159.4 8162.1 4705.9 5737.7 5780.4 5824.8 5841.2 9915.3 9994.8 9999.3 10001.8 10017.7 9168.2 9502.9 9542.1 9902.9 9939.6 6314.5 7315.5 7533.2 7540.6 7646.6 8023.5 8175.9 8300.9 8301.7 8343.1 7371.1 7394.5 7444.1 7447.3 7459.3 7286.3 7294.2 7316.5 7339.6 7348.6 6794.9 6802.9 6843.0 6863.6 6957.5 4716.6 4958.7 4963.6 5034.5 5146.7 4742.9 4831.5 5029.6 5123.8 5161.8 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4341.58 Median:4756.50 stddev:1316.30 (27.7%) stddev-sans-first:1284.18 23:05:26 INFO - PROCESS | 1658 | Values: 278.4 297.7 299.2 311.8 317.0 4215.4 4727.4 5145.2 5233.7 5306.9 5018.5 5203.7 5222.7 5270.4 5321.4 4013.1 4044.9 4071.7 4075.9 4088.9 3830.8 4017.9 4053.8 4078.2 4099.2 3658.4 3983.2 3994.9 4000.9 4032.9 3648.0 3737.9 3996.3 4002.7 4029.8 3145.6 3330.6 3511.5 3519.9 3544.6 4257.5 4299.6 5020.2 5108.4 5119.3 4616.1 4627.1 4633.2 4634.3 4634.9 5039.7 5469.5 5497.0 5592.3 5610.5 3501.0 3765.6 3784.4 3840.9 3868.6 4694.4 5233.8 5258.8 5854.3 5867.3 3516.5 3633.8 3850.0 3873.8 3881.9 3616.2 3657.3 3785.6 3914.8 3922.8 4498.7 4500.8 4529.5 4532.4 4549.1 5061.2 5171.8 5548.5 5576.8 5610.7 1160.7 1192.6 1208.6 1290.9 1311.6 854.9 873.6 902.6 907.9 909.5 4930.8 5109.9 5147.8 5150.8 5154.1 4648.8 5608.4 5640.4 5708.4 5753.3 4052.3 4347.2 5103.5 5112.7 5118.3 2402.9 2403.6 2431.6 2480.6 2512.1 4404.9 4600.8 4798.1 4806.4 4865.7 4130.1 4331.4 4588.0 4809.4 4857.0 4517.7 4754.0 4779.4 4799.7 4879.6 4712.0 4778.0 4782.3 4794.4 4799.4 4653.9 4659.5 4659.9 4679.5 5195.0 5179.4 5424.5 5678.6 5963.9 6023.6 3535.8 3859.7 3867.1 3897.5 3899.6 4867.0 5033.8 5384.7 5814.4 5824.9 5406.7 5526.2 5668.7 5686.2 5688.8 4770.0 5586.7 5598.7 5610.2 5617.6 4759.0 4919.7 5037.3 5438.5 5530.9 4463.8 5186.9 5277.8 5612.5 5672.8 5253.6 5498.3 5503.6 5537.0 5570.6 23:05:26 INFO - PROCESS | 1658 | 23:05:26 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24968.99 Median:3880.52 stddev:40018.13 (1031.3%) stddev-sans-first:40088.58 23:05:26 INFO - PROCESS | 1658 | Values: 607.6 683.9 741.8 772.1 787.9 13881.7 14795.7 16560.2 16568.5 16603.4 16059.6 16463.6 16488.3 16509.7 16635.0 741.0 831.5 873.0 874.0 874.0 537.8 705.6 835.9 857.2 872.7 919.1 920.5 921.6 922.7 922.8 918.6 918.8 919.5 919.8 924.4 543.3 561.4 563.3 563.7 567.1 14821.5 14882.3 14933.9 15659.7 15675.7 13435.8 14256.0 14452.8 14472.3 14547.5 11832.8 11867.8 12119.9 12164.3 12367.2 3819.7 3865.3 3873.7 3887.3 3927.0 99337.0 113784.6 114220.0 116769.0 116895.0 9056.6 11032.7 11142.8 11199.6 11255.2 21135.3 21930.3 22107.1 22119.3 22410.7 6724.8 8143.8 8239.7 8240.3 8269.3 4209.5 4450.2 4455.2 4528.2 4556.9 3388.5 3400.5 3701.4 3754.7 3761.4 578.3 665.9 668.9 670.3 685.5 583.5 644.5 648.9 657.3 664.0 9266.9 9483.4 9497.0 9511.2 9545.4 580.0 631.9 639.5 700.6 700.8 4557.7 4587.7 4631.8 4644.1 4675.4 130960.0 158782.0 158917.0 159317.2 161880.0 136141.0 137048.0 138270.0 141724.0 142664.3 90253.0 92574.5 95309.5 104431.0 107968.0 8802.6 9181.7 9568.3 9962.8 9966.3 60256.4 60830.0 61335.4 61353.7 61609.1 61474.0 86027.6 90374.1 91202.0 92424.5 12977.7 13221.6 13287.9 13641.9 13728.2 14604.4 15067.2 15263.9 15593.2 15946.4 14067.4 14258.9 14307.0 14749.1 14806.9 13883.2 13977.4 14342.3 14806.5 14849.5 13604.9 13630.2 14009.8 14107.5 14461.8 11241.0 11272.8 11659.1 11836.6 11838.9 9158.6 9729.0 11420.3 11436.1 11651.8 23:05:26 INFO - PROCESS | 1658 | -------- Summary: end -------- 23:05:26 INFO - PROCESS | 1658 | 23:05:27 INFO - TEST-INFO | 1658: exit 0 23:05:27 INFO - TEST-OK | dromaeo_css | took 1117811ms 23:05:27 INFO - TEST-START | kraken 23:05:27 INFO - operating with platform_type : mac_ 23:05:27 INFO - Initialising browser for kraken test... 23:05:27 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpO0jUA_/profile http://localhost:49276/getInfo.html) 23:05:29 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 23:05:29 INFO - PROCESS | 1668 | colorDepth:24 23:05:29 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 23:05:29 INFO - PROCESS | 1668 | __metrics 23:05:30 INFO - PROCESS | 1668 | 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" 23:05:30 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:05:30 INFO - TEST-INFO | 1668: exit 0 23:05:30 INFO - Browser initialized. 23:05:30 INFO - Running cycle 1/1 for kraken test... 23:05:30 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpO0jUA_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:42 INFO - PROCESS | 1669 | RSS: Main: 243638272 23:05:42 INFO - PROCESS | 1669 | 23:05:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 23:05:46 INFO - PROCESS | 1669 | RSS: Main: 293388288 23:05:46 INFO - PROCESS | 1669 | 23:05:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 23:05:49 INFO - PROCESS | 1669 | RSS: Main: 299827200 23:05:49 INFO - PROCESS | 1669 | 23:05:53 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 23:05:53 INFO - PROCESS | 1669 | RSS: Main: 396365824 23:05:53 INFO - PROCESS | 1669 | 23:05:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 23:05:56 INFO - PROCESS | 1669 | RSS: Main: 295358464 23:05:56 INFO - PROCESS | 1669 | 23:05:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:59 INFO - PROCESS | 1669 | RSS: Main: 300449792 23:05:59 INFO - PROCESS | 1669 | 23:06:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 23:06:02 INFO - PROCESS | 1669 | RSS: Main: 336711680 23:06:02 INFO - PROCESS | 1669 | 23:06:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 23:06:05 INFO - PROCESS | 1669 | RSS: Main: 339185664 23:06:05 INFO - PROCESS | 1669 | 23:06:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 23:06:09 INFO - PROCESS | 1669 | RSS: Main: 336470016 23:06:09 INFO - PROCESS | 1669 | 23:06:11 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:06:11 INFO - PROCESS | 1669 | RSS: Main: 314040320 23:06:11 INFO - PROCESS | 1669 | 23:06:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:06:14 INFO - PROCESS | 1669 | RSS: Main: 320069632 23:06:14 INFO - PROCESS | 1669 | 23:06:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:06:17 INFO - PROCESS | 1669 | RSS: Main: 320536576 23:06:17 INFO - PROCESS | 1669 | 23:06:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:06:20 INFO - PROCESS | 1669 | RSS: Main: 327458816 23:06:20 INFO - PROCESS | 1669 | 23:06:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:06:24 INFO - PROCESS | 1669 | RSS: Main: 328769536 23:06:24 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 23:06:26 INFO - PROCESS | 1669 | RSS: Main: 372191232 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | __start_tp_report 23:06:26 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 23:06:26 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 23:06:26 INFO - PROCESS | 1669 | |i|pagename|runs| 23:06:26 INFO - PROCESS | 1669 | |0;ai-astar;102;115;112;113;114;113;112;114;111;113 23:06:26 INFO - PROCESS | 1669 | |1;audio-beat-detection;144;109;111;204;140;119;127;110;144;107 23:06:26 INFO - PROCESS | 1669 | |2;audio-dft;183;184;185;186;185;184;181;183;184;185 23:06:26 INFO - PROCESS | 1669 | |3;audio-fft;79;76;56;87;57;57;58;67;84;56 23:06:26 INFO - PROCESS | 1669 | |4;audio-oscillator;87;91;93;72;70;68;97;70;68;116 23:06:26 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;85;88;86;89;85;89;86;89;84 23:06:26 INFO - PROCESS | 1669 | |6;imaging-darkroom;93;93;93;94;93;94;94;94;93;94 23:06:26 INFO - PROCESS | 1669 | |7;imaging-desaturate;87;85;84;84;99;132;143;86;84;86 23:06:26 INFO - PROCESS | 1669 | |8;json-parse-financial;53;51;51;52;50;52;52;51;51;50 23:06:26 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;74;80;72;71;69;69;71;70;68 23:06:26 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;83;82;90;102;103;81;85;81;85;78 23:06:26 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;138;137;133;136;137;146;154;138;148;142 23:06:26 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;163;165;170;164;223;237;175;163;166;163 23:06:26 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;69;67;66;66;67;66;65;68;78 23:06:26 INFO - PROCESS | 1669 | __end_tp_report 23:06:26 INFO - PROCESS | 1669 | __start_cc_report 23:06:26 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2131 23:06:26 INFO - PROCESS | 1669 | __end_cc_report 23:06:26 INFO - PROCESS | 1669 | __startTimestamp1470809186300__endTimestamp 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | ------- Summary: start ------- 23:06:26 INFO - PROCESS | 1669 | Number of tests: 14 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.67 (3.2%) stddev-sans-first:1.22 23:06:26 INFO - PROCESS | 1669 | Values: 102.0 115.0 112.0 113.0 114.0 113.0 112.0 114.0 111.0 113.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:133.50 stddev:29.45 (22.1%) stddev-sans-first:30.89 23:06:26 INFO - PROCESS | 1669 | Values: 144.0 109.0 111.0 204.0 140.0 119.0 127.0 110.0 144.0 107.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.41 (0.8%) stddev-sans-first:1.45 23:06:26 INFO - PROCESS | 1669 | Values: 183.0 184.0 185.0 186.0 185.0 184.0 181.0 183.0 184.0 185.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:67.70 Median:71.50 stddev:12.61 (17.6%) stddev-sans-first:12.70 23:06:26 INFO - PROCESS | 1669 | Values: 79.0 76.0 56.0 87.0 57.0 57.0 58.0 67.0 84.0 56.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:79.50 stddev:16.24 (20.4%) stddev-sans-first:17.17 23:06:26 INFO - PROCESS | 1669 | Values: 87.0 91.0 93.0 72.0 70.0 68.0 97.0 70.0 68.0 116.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.50 stddev:2.00 (2.3%) stddev-sans-first:1.99 23:06:26 INFO - PROCESS | 1669 | Values: 89.0 85.0 88.0 86.0 89.0 85.0 89.0 86.0 89.0 84.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 23:06:26 INFO - PROCESS | 1669 | Values: 93.0 93.0 93.0 94.0 93.0 94.0 94.0 94.0 93.0 94.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:97.00 Median:85.50 stddev:21.95 (25.7%) stddev-sans-first:22.99 23:06:26 INFO - PROCESS | 1669 | Values: 87.0 85.0 84.0 84.0 99.0 132.0 143.0 86.0 84.0 86.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:0.95 (1.8%) stddev-sans-first:0.78 23:06:26 INFO - PROCESS | 1669 | Values: 53.0 51.0 51.0 52.0 50.0 52.0 52.0 51.0 51.0 50.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.86 (5.4%) stddev-sans-first:3.64 23:06:26 INFO - PROCESS | 1669 | Values: 66.0 74.0 80.0 72.0 71.0 69.0 69.0 71.0 70.0 68.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:82.50 stddev:8.77 (10.6%) stddev-sans-first:9.18 23:06:26 INFO - PROCESS | 1669 | Values: 83.0 82.0 90.0 102.0 103.0 81.0 85.0 81.0 85.0 78.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:140.00 stddev:6.52 (4.7%) stddev-sans-first:6.83 23:06:26 INFO - PROCESS | 1669 | Values: 138.0 137.0 133.0 136.0 137.0 146.0 154.0 138.0 148.0 142.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:168.00 stddev:27.40 (16.3%) stddev-sans-first:28.45 23:06:26 INFO - PROCESS | 1669 | Values: 163.0 165.0 170.0 164.0 223.0 237.0 175.0 163.0 166.0 163.0 23:06:26 INFO - PROCESS | 1669 | 23:06:26 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.00 stddev:3.77 (5.6%) stddev-sans-first:3.94 23:06:26 INFO - PROCESS | 1669 | Values: 66.0 69.0 67.0 66.0 66.0 67.0 66.0 65.0 68.0 78.0 23:06:26 INFO - PROCESS | 1669 | -------- Summary: end -------- 23:06:26 INFO - PROCESS | 1669 | 23:06:27 INFO - TEST-INFO | 1669: exit 0 23:06:27 INFO - TEST-OK | kraken | took 59594ms 23:06:27 INFO - SUITE-END | took 1177s 23:06:27 INFO - Completed test suite (00:19:37) 23:06:27 INFO - javascript benchmark 23:06:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.898393048208, 3391.3217356528685, 3406.148462884279, 3422.3839708852397, 3466.705330046946, 4668.719876807394, 5277.3156127643915, 5718.027935251007, 5735.397783232763, 5757.827265182046, 5740.655560666363, 5794.82615521534, 5795.15970184323, 5804.59367844251, 5815.592908496407, 5210.088234558956, 5238.266642670029, 5266.92099618506, 5278.894422111555, 5330.813421530247, 5255.5795536357, 5296.973515132419, 5314.893702125955, 5321.707306098182, 5324.334458192726, 5241.685498870061, 5244.947550524503, 5256.579473642099, 5277.815276465324, 5279.63042587019, 5337.172738225581, 5343.385510666284, 5347.893042139174, 5352.116900711417, 5398.973005134989, 5128.2564028216075, 5160.896782064376, 5179.533841954205, 5236.345456817898, 5254.395744489394, 5681.062624666925, 5715.571332150105, 5741.942580574183, 5812.244408226946, 5830.912536311958, 6237.719302631347, 6240.968795155995, 6260.655663938482, 6265.373462653743, 6276.497880169575, 5249.2126181073145, 6154.599951003215, 6164.013757798775, 6234.064890266451, 6261.186045814015, 5961.105834124916, 5976.880462390728, 5994.880102397928, 6005.0, 6007.008843540854, 5424.213489044067, 6306.3378345274, 6337.207849018872, 6340.714667839959, 6341.0805433212845, 6078.118672792509, 6090.888650161123, 6145.678679084019, 6177.69111544421, 6212.844678883064, 6248.781292654735, 6252.187215661935, 6255.843603909939, 6259.061140828912, 6273.309935907047, 6195.287541932736, 6197.380261973766, 6223.315435302113, 6249.187605611241, 6253.155823963798, 6132.936844521141, 6165.0444181152, 6201.720922558496, 6234.220722409699, 6235.844103897439, 4923.483034281349, 4935.383077115361, 4944.307796908436, 4946.554810067111, 4966.776495057658, 4445.26653102241, 4456.197884380777, 4457.754823484739, 4490.057088011489, 4498.550144985541, 5735.1684005818925, 5758.596898217084, 5782.277215348082, 5884.735186916599, 5887.705614719247, 6163.075538669146, 6175.073738939195, 6241.375862413723, 6257.342978987232, 6260.373962603703, 5878.559108066826, 5896.321922978912, 5920.940790592125, 5924.0, 5943.732532036069, 5445.618806683573, 5482.890342193134, 5485.17722341644, 5493.0, 5510.173473978935, 5234.97382513085, 5783.566232532493, 5921.023031199804, 6333.20834895638, 6363.490920726424, 5687.232223649754, 6199.535034872493, 6219.657918814509, 6227.844303892258, 6261.530385221217, 6099.725512352044, 6235.949175269595, 6245.7189426476825, 6315.368463153648, 6316.368363163647, 6253.218347706536, 6268.749250029948, 6292.905606415999, 6294.748210071546, 6314.557980941474, 5008.77460514285, 6272.560920735687, 6290.24517057956, 6319.6208227506495, 6362.904556431565, 6194.690265486617, 6196.225471816023, 6200.81397558074, 6213.285472170758, 6213.658248796179, 6145.754169833157, 6197.845053873509, 6201.596896201732, 6201.96899015502, 6214.471769899609, 5792.420757924174, 5947.583669143291, 5974.40255974399, 6020.307664618625, 6084.209052823105, 6146.016637338004, 6164.250919870711, 6208.130861679459, 6210.627362358273, 6213.720382582885, 5876.41235876409, 6051.304100028553, 6077.314953551058, 6087.634741915499, 6130.693465326805], "value": 5720.566732772559, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.78480506564443, 472.7588929645881, 475.9524047595241, 492.35501493044114, 529.1665626638041, 30227.942022029205, 32055.83972080142, 41453.99999999996, 42053.0, 42246.0, 43137.0, 43642.56357436425, 43657.56342436575, 43758.34362484558, 43880.12239755203, 9591.568379422924, 9760.951195244013, 9763.755906102333, 9770.755731106708, 9780.364276322016, 8682.392232543725, 9550.80898382032, 9674.613015479408, 9675.709728708114, 9705.805883882318, 9661.661841835537, 9699.75750606237, 9702.981186975372, 9706.0, 9711.708648740549, 9623.085806848338, 9648.421094734338, 9680.225581953462, 9684.322097453181, 9704.61181552737, 9613.855792163124, 9731.659391921248, 9733.659321923698, 9739.853902191473, 9740.269479788973, 33837.83081084603, 35988.92033239007, 38656.420153697436, 41175.38236926449, 41185.588144118476, 3374.983125084359, 3385.8137802420856, 3438.398280300937, 3452.102453362119, 3453.8791142309897, 27662.0, 31718.365632687448, 31944.840275798706, 32267.51598726021, 33054.50418243728, 3222.4682927316962, 3226.516022596606, 3257.16942179745, 3284.8850290239966, 3339.1652086978256, 495310.0, 501191.0, 502814.485927568, 503949.99999999633, 506911.0, 61307.0, 61669.0, 61692.0, 61798.69100654468, 61984.0, 34091.659083409344, 34666.65333346685, 65074.67462662656, 65084.42035687988, 65276.020859687145, 16250.024998500128, 16773.90969586973, 16785.496435106965, 16797.748033779502, 16815.91592042032, 12791.912687421487, 12852.807207891889, 12862.614121576367, 12896.161749486253, 12911.289879056554, 19412.692646465963, 19444.902775486033, 19462.688341406854, 19648.017599119987, 19680.60638787216, 6543.051257567626, 7593.620318984029, 7644.6942122315595, 7668.469405096304, 7716.2669546393, 879.7624641346929, 973.8782652168479, 980.8970058143935, 1007.9647212347533, 1038.678009816959, 23727.7627223729, 30921.226969325948, 31159.53260701091, 31465.528017079763, 31617.05148845538, 736.867363874497, 922.9492377919145, 1108.3817626265582, 1125.9943700281447, 1126.9549218031186, 14805.407783688532, 15227.695446091016, 15356.769648455282, 15476.976706077572, 15644.452444164628, 689521.0, 689592.0, 692917.0, 694126.5293673499, 694736.0, 59668.0, 60317.095243571384, 60374.49063773353, 60392.772505924164, 60497.39502604918, 1230.5262473947416, 1266.2465832829453, 1352.0462931064715, 1378.3383975691725, 1402.8246469191351, 31160.065198044096, 31617.683823162395, 32482.350352992336, 32694.673053270115, 32823.67176328302, 56891.14663279971, 57413.712931435075, 57419.425805741404, 57450.712746437675, 57529.13706294325, 607073.0, 610492.0, 610572.0, 611467.0, 612754.0, 26955.0, 27336.316592085357, 27421.90312387482, 28006.159815205578, 28108.85945570259, 48131.0, 48448.757756210995, 49303.50696492989, 49484.75257623689, 49768.0, 38949.0, 38977.41533877051, 39317.6068239314, 40603.0, 40616.59383406246, 39229.41155882721, 39396.60603394044, 39532.0, 40730.0, 40810.795946021266, 33640.327193456484, 33904.49143262902, 38229.80885095557, 39213.607863920995, 39491.605083949944, 22306.888465557568, 33652.663473364955, 35758.46362304516, 36223.456648149775, 37395.62604373921, 20795.58408831845, 22355.664665030363, 22684.773152268266, 26227.868860655573, 26949.73050269472], "value": 20079.26196143107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.33936403235214, 366.8862652577701, 376.7777011563178, 384.7825978322246, 400.98997525061867, 19786.505337366572, 20534.794652053475, 20711.171553137894, 21460.248891288807, 21699.674504882438, 20325.695114573256, 20355.0, 20874.791252087514, 20933.685994710093, 21175.470613234676, 5167.612429067816, 5272.551833094172, 5950.97024514879, 6090.9086363704355, 6109.022556390978, 5061.595072394218, 6070.939290607082, 6078.8784224315705, 6081.1790408294755, 6094.847628809293, 5783.161441590967, 6009.1286763419275, 6081.057436097412, 6099.878002439948, 6132.0, 5810.82567522975, 6089.908651370233, 6098.8780224395705, 6107.0, 6114.052321890114, 5795.507381872504, 5874.706264686792, 5877.706114694248, 5877.970610146965, 5890.499307558863, 11987.280763154238, 12167.391630418531, 21677.132914683392, 24243.878780605985, 24419.0, 2071.792820717931, 2362.232274510791, 2483.552960467115, 2647.7881769458395, 2784.7354501322343, 10419.531121099568, 10472.319299245524, 12205.328706921116, 15488.845111548853, 16132.274047667883, 1868.6639053077115, 1927.5566619677552, 1934.0909772406947, 2144.6246906791407, 2234.340869443502, 132477.0, 142852.571474286, 143022.0, 146839.0, 151715.0, 17883.748137630242, 20595.485112872, 21257.81132830088, 21313.68029479559, 21768.45578860541, 34645.826770865984, 35265.47101793475, 35361.82319088388, 35392.11519711976, 35415.4687679685, 10672.733181670521, 10789.0, 10981.890181098246, 11018.283811552223, 11161.776764464828, 9011.188992990663, 9039.864402033974, 9046.86429703555, 9203.171714545722, 9209.677661281823, 9620.807583848284, 9649.662261820802, 9664.75838104053, 9827.557759900821, 9840.49067487786, 2853.7574306184206, 2939.0, 3569.286142771456, 3706.627758234678, 3728.794916279631, 689.6517258784282, 881.8104107616894, 883.8983516895511, 937.8265020971006, 977.4233202410612, 20265.696014559795, 21782.78217217839, 21816.563668726852, 21826.890865545887, 21857.781422185893, 851.4720873058743, 929.6188562689265, 940.7365937537501, 945.1588086602928, 976.7851072763981, 10736.570537178426, 11459.541618335174, 11472.254303470248, 11479.942600286946, 11749.530018799323, 577303.0, 581024.0, 582312.0, 592395.0, 592676.0, 104892.0, 121138.0, 125793.0, 127946.36026819807, 130744.0, 951.4291425144996, 975.6536429567543, 991.0634450444261, 992.3103443106971, 995.1541189988452, 18783.90608046951, 19314.420567383, 19346.032698364743, 19355.0, 19424.61150776948, 55586.88826223534, 55792.721036394556, 55924.440755591924, 56813.14780278382, 57465.71267143638, 77649.0, 80367.0, 82039.0, 82274.17725822666, 84050.73923891025, 8068.193180681885, 8084.151164127918, 8220.712275070584, 8269.751907442786, 8295.170482951657, 11377.374244416636, 13024.869751302365, 13044.478220871059, 13478.393472293574, 13488.595342139752, 11261.380624065756, 11476.770464590829, 11577.305361678325, 11628.767424651629, 11644.010259128068, 11290.491927863102, 11345.659630211107, 11444.0, 11710.355930423908, 11772.234804737665, 11482.483288252215, 11483.770324593628, 11730.882691173321, 11856.22934509254, 11971.16201865861, 7995.080565734782, 8007.959960200162, 8217.096119426738, 8262.132476090164, 8448.070712221774, 8231.0, 11038.392888391216, 11207.383593902414, 11272.380019099028, 11582.71043223926], "value": 10605.810453350057, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.7645176771719, 1512.9167895765731, 1546.250068716673, 1672.1221358786636, 1844.5019844641947, 8405.705800296992, 8782.297416206688, 8787.428817126882, 8807.251383632383, 8847.0, 7743.535387876731, 7972.960135199331, 8323.25090741833, 8549.957250213756, 8570.828583428327, 2211.933641990735, 2242.013514053819, 2243.730752309717, 2245.831562632801, 2250.8649481031107, 2299.0458959531857, 2334.4163959010248, 2342.9297121086393, 2357.0571771291534, 2357.5874222011166, 2003.5492014296813, 2311.179531266401, 2341.098677009347, 2350.306659535441, 2353.4587044979667, 2303.2399308228246, 2321.5937210988095, 2324.7559006304264, 2328.7671232876746, 2332.5801355755957, 2326.7673232676766, 2326.93019209424, 2328.0687724910003, 2328.138588722167, 2334.1363695432797, 7423.925760742378, 8736.257418119467, 8834.955825220834, 8850.291976641918, 8863.0, 5155.561777248939, 5330.28041214435, 5335.493128152819, 5339.813106541253, 5352.839414817562, 3419.46998215277, 3494.965050349515, 3626.256617393428, 3628.8911332660064, 3637.0907273181706, 1038.927275090744, 1070.4273213830563, 1080.0333701337338, 1089.1178145702006, 1090.2150451674781, 11466.197366184371, 11582.710432239177, 11587.130965177561, 11611.0, 11612.709682257926, 4485.663575231871, 4652.278896771006, 4753.1444340019125, 4778.713277203379, 4784.186688263004, 9811.607535698493, 9814.509274536245, 9824.754381140387, 9831.459269740233, 9839.75400614976, 5279.313689220377, 5291.550218231417, 5291.682499050069, 5302.337207849019, 5302.469753024667, 4130.235906357333, 4363.650907927358, 4508.661850361236, 4827.131116399082, 4874.268859671078, 3844.0, 3891.494105766289, 3980.283548961157, 3983.6813054955533, 3988.74073185242, 1096.4901320885826, 1198.1732604502865, 1234.4383305595932, 1251.6808213905579, 1267.2966503590412, 896.0949441064441, 917.8623206519076, 923.330585325647, 941.6177170979417, 991.2119864707574, 5641.576881733887, 5790.971045144832, 5931.703414829328, 5992.43071908168, 6046.848828779316, 407.20594840062, 421.0925455643094, 424.32955929631106, 428.7920358626064, 435.336112428549, 3851.0, 3892.4939757831303, 3975.284448799186, 3981.960180398159, 4014.598540145962, 8316.501009939422, 10723.14214862809, 10725.892741072645, 10746.570137194425, 10752.838707419394, 10319.0, 10430.37417754937, 10536.947315263375, 10582.894171058344, 10597.205209609156, 7983.12185659577, 8067.273945344947, 8153.714619988272, 8159.428839981263, 8162.102168761431, 4705.858824235278, 5737.713114344183, 5780.364159942487, 5824.825255242349, 5841.211436456194, 9915.30592858493, 9994.800103997733, 9999.250056245955, 10001.849972250568, 10017.749556261151, 9168.220701240469, 9502.904970950201, 9542.093501117382, 9902.900970990198, 9939.552720127467, 6314.494840412664, 7315.451341149329, 7533.171351151259, 7540.622968851645, 7646.579438130956, 8023.4784738991775, 8175.918240817754, 8300.875486867822, 8301.6679332826, 8343.123971982854, 7371.115466144097, 7394.519356241827, 7444.106707195171, 7447.255274472509, 7459.254074592497, 7286.307800758919, 7294.2341054190665, 7316.451266155166, 7339.59632220233, 7348.595827229554, 6794.8641027180165, 6802.93197068023, 6843.041974123527, 6863.622500762506, 6957.547759395623, 4716.575508204278, 4958.677685950402, 4963.627727920348, 5034.521720436553, 5146.6911985281, 4742.857714268577, 4831.468538460696, 5029.64792464524, 5123.769430375716, 5161.793528258828], "value": 4429.6678886123445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.4222737819026, 297.6591802386268, 299.210085374611, 311.80979602442505, 316.9556262123303, 4215.409842622032, 4727.409073865767, 5145.202493613491, 5233.712145831978, 5306.867328316789, 5018.523240292176, 5203.661761985469, 5222.686638801674, 5270.393904700961, 5321.388040375348, 4013.117114234878, 4044.9191016179807, 4071.674266058723, 4075.9388609170887, 4088.89777755557, 3830.7550046234946, 4017.8749950014044, 4053.7770422626745, 4078.163976384834, 4099.1963536044195, 3658.35374081663, 3983.183447393277, 3994.941340544758, 4000.9399859002137, 4032.919341613166, 3648.0150359402815, 3737.8504859805576, 3996.3006473867104, 4002.6597739192202, 4029.7582145071096, 3145.5936208800285, 3330.6169790473923, 3511.5434993450945, 3519.9472007919903, 3544.627814079536, 4257.489101307832, 4299.591538803808, 5020.206555904798, 5108.416390918827, 5119.257707632372, 4616.1229366420275, 4627.074585082997, 4633.189191891405, 4634.328022436762, 4634.887626969503, 5039.748012599355, 5469.507744303032, 5497.0, 5592.273004509388, 5610.523105536035, 3500.964990350115, 3765.642263984917, 3784.375578029622, 3840.884773456773, 3868.574456809749, 4694.413198350207, 5233.764480598382, 5258.789648414059, 5854.268216472941, 5867.295924489089, 3516.507688923553, 3633.7819730816236, 3849.9807500962875, 3873.845046198177, 3881.9037719631083, 3616.2044350242904, 3657.335912159961, 3785.640364165455, 3914.8042597869994, 3922.8231530540725, 4498.707584007029, 4500.819967201275, 4529.479109902338, 4532.4334458192725, 4549.134854709553, 5061.240813877947, 5171.810483588795, 5548.472895074961, 5576.797165119755, 5610.663360198401, 1160.715624671944, 1192.6362459449952, 1208.6011616166643, 1290.9225446473242, 1311.5934060441293, 854.8760429737653, 873.6112429968603, 902.5874506397467, 907.8819753432011, 909.486140330711, 4930.827421040246, 5109.926915347744, 5147.819826306061, 5150.768215430315, 5154.123798954111, 4648.790804413809, 5608.383077861432, 5640.379558248589, 5708.429157084258, 5753.309602847685, 4052.3111071117983, 4347.217500849814, 5103.540681338697, 5112.693238405707, 5118.309028281209, 2402.8946684525313, 2403.591389463795, 2431.562318782636, 2480.578301688717, 2512.120757734778, 4404.933925991113, 4600.780793089833, 4798.121127323648, 4806.375171227788, 4865.739954230674, 4130.079513026457, 4331.371951067078, 4587.954120458753, 4809.350737650442, 4857.0, 4517.650821572764, 4754.0, 4779.402574678165, 4799.688020278741, 4879.633053193285, 4712.0, 4778.0, 4782.33047373361, 4794.352762377034, 4799.448063472745, 4653.86038418848, 4659.505370971771, 4659.883502912454, 4679.46186188579, 5194.97402512985, 5179.420276815607, 5424.511793938565, 5678.630888992202, 5963.850903727442, 6023.578349515492, 3535.8232088395994, 3859.6719278860487, 3867.149227170018, 3897.4799828067644, 3899.6490315871706, 4867.0, 5033.785134540791, 5384.6769193848495, 5814.3604203538425, 5824.854378640568, 5406.7296635167295, 5526.22632831412, 5668.659880407189, 5686.2323586315315, 5688.800891968761, 4770.045990801784, 5586.692731899784, 5598.692071936082, 5610.158476228598, 5617.634853734494, 4759.0, 4919.704817710949, 5037.327277854467, 5438.537724293479, 5530.889382212253, 4463.776811159494, 5186.9221961669855, 5277.788888444573, 5612.522935550524, 5672.801451949162, 5253.605979551472, 5498.3127109111365, 5503.614746967673, 5536.972315138399, 5570.58220633446], "value": 3923.0377148279663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.5929127484585, 683.8939964305533, 741.8479211761589, 772.1467778105197, 787.8542469643116, 13881.722365552709, 14795.704085918274, 16560.171991400442, 16568.502944911656, 16603.418880339177, 16059.598510037255, 16463.588410289747, 16488.34046638136, 16509.669806603863, 16635.0, 740.9814754631124, 831.4595512916592, 873.0440168016044, 873.9912600874005, 874.038557586656, 537.7821982097238, 705.6260182103467, 835.9038710548274, 857.2456238510102, 872.746903398017, 919.0579655852738, 920.5121285718546, 921.5714692667908, 922.7324076017981, 922.8108237811233, 918.6463211663491, 918.8162367526488, 919.4942781470164, 919.7930465645243, 924.3714274293478, 543.323562165103, 561.3740679142767, 563.2930672006646, 563.6646195513663, 567.1351189436102, 14821.481248156262, 14882.330295136637, 14933.925330373388, 15659.686806263924, 15675.686486270324, 13435.798463023062, 14256.002079854414, 14452.783208251885, 14472.276386180649, 14547.490837820731, 11832.763344733057, 11867.821982670266, 12119.939400302943, 12164.2701437914, 12367.196132251465, 3819.656230939229, 3865.2849222893856, 3873.709471789627, 3887.3391523441082, 3926.980365098185, 99336.99999999927, 113784.62153784638, 114220.0, 116769.0, 116895.0, 9056.637734490547, 11032.724181895517, 11142.832857507145, 11199.608013719644, 11255.155863310285, 21135.26026589062, 21930.342089737333, 22107.11571537153, 22119.336419907107, 22410.663840042413, 6724.798256052326, 8143.83712325762, 8239.711610093738, 8240.340772738167, 8269.29710974562, 4209.515905670887, 4450.198964186413, 4455.1757924784015, 4528.162289976365, 4556.908861822745, 3388.508666243381, 3400.489926510994, 3701.3522633539023, 3754.737168398186, 3761.4169803680475, 578.2973686970267, 665.9433948114367, 668.85619591788, 670.3330186464401, 685.475611157465, 583.527342852291, 644.5133923887435, 648.9383508566679, 657.3032585459429, 663.9701213445406, 9266.907330926739, 9483.430994140374, 9497.0, 9511.239100871913, 9545.379550329206, 579.9797007104731, 631.908373285871, 639.5427269502328, 700.6181631011111, 700.8072779985545, 4557.680962332605, 4587.6788624795945, 4631.791569379386, 4644.117617652635, 4675.368825208621, 130960.0, 158782.0, 158917.0, 159317.20341398218, 161880.0, 136141.0, 137048.0, 138270.0, 141724.0, 142664.28667856596, 90253.0, 92574.53712731393, 95309.52345238368, 104431.0, 107968.0, 8802.60388282516, 9181.678641247525, 9568.330216884751, 9962.750931226778, 9966.302358834811, 60256.39743602508, 60830.0, 61335.38664613475, 61353.686722208615, 61609.07586386118, 61474.0, 86027.56986215028, 90374.09625903657, 91202.0, 92424.53787731018, 12977.675558110745, 13221.603351899459, 13287.867121329049, 13641.86358136406, 13728.176309421468, 14604.415823366946, 15067.246637668291, 15263.923680381527, 15593.22033898278, 15946.4418745348, 14067.437302507786, 14258.928705356822, 14306.998510104193, 14749.115053096848, 14806.925965370105, 13883.236421996888, 13977.371107860357, 14342.282885855875, 14806.548360419094, 14849.480268190562, 13604.86395136036, 13630.182189068688, 14009.789853152415, 14107.506237281646, 14461.78307325412, 11241.021129400879, 11272.830907536558, 11659.125565582446, 11836.644900652995, 11838.940805296263, 9158.594586518535, 9728.951355243462, 11420.257683250562, 11436.142289328167, 11651.825222621837], "value": 7860.09314648264, "unit": "score"}], "name": "dromaeo_css", "value": 7416.525996750682, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 112.0, 113.0, 114.0, 113.0, 112.0, 114.0, 111.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 111.0, 204.0, 140.0, 119.0, 127.0, 110.0, 144.0, 107.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 186.0, 185.0, 184.0, 181.0, 183.0, 184.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 56.0, 87.0, 57.0, 57.0, 58.0, 67.0, 84.0, 56.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 93.0, 72.0, 70.0, 68.0, 97.0, 70.0, 68.0, 116.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 89.0, 85.0, 89.0, 86.0, 89.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 84.0, 99.0, 132.0, 143.0, 86.0, 84.0, 86.0], "value": 97.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 52.0, 50.0, 52.0, 52.0, 51.0, 51.0, 50.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 80.0, 72.0, 71.0, 69.0, 69.0, 71.0, 70.0, 68.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 90.0, 102.0, 103.0, 81.0, 85.0, 81.0, 85.0, 78.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 133.0, 136.0, 137.0, 146.0, 154.0, 138.0, 148.0, 142.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 170.0, 164.0, 223.0, 237.0, 175.0, 163.0, 166.0, 163.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 66.0, 66.0, 67.0, 66.0, 65.0, 68.0, 78.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1452.7, "alertThreshold": 2.0}]} 23:06:27 INFO - Return code: 0 23:06:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:06:27 INFO - # TBPL SUCCESS # 23:06:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:06:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:06:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:06:27 INFO - 2016-08-09 23:06:27.584 screenresolution[1673:10089] starting screenresolution argv=screenresolution get 23:06:27 INFO - 2016-08-09 23:06:27.587 screenresolution[1673:10089] Display 0: 1600x1200x32@60 23:06:27 INFO - 2016-08-09 23:06:27.593 screenresolution[1674:10092] starting screenresolution argv=screenresolution list 23:06:27 INFO - Available Modes on Display 0 23:06:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:06:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:06:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:06:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:06:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:06:27 INFO - Intel Iris: 23:06:27 INFO - Chipset Model: Intel Iris 23:06:27 INFO - Type: GPU 23:06:27 INFO - Bus: Built-In 23:06:27 INFO - VRAM (Dynamic, Max): 1536 MB 23:06:27 INFO - Vendor: Intel (0x8086) 23:06:27 INFO - Device ID: 0x0a2e 23:06:27 INFO - Revision ID: 0x0009 23:06:27 INFO - Displays: 23:06:27 INFO - Display: 23:06:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:06:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:06:27 INFO - Display Serial Number: 1600x1200 60 23:06:27 INFO - Main Display: Yes 23:06:27 INFO - Mirror: Off 23:06:27 INFO - Online: Yes 23:06:27 INFO - Rotation: Supported 23:06:27 INFO - Return code: 0 23:06:27 INFO - Running post-action listener: _resource_record_post_action 23:06:27 INFO - [mozharness: 2016-08-10 06:06:27.832518Z] Finished run-tests step (success) 23:06:27 INFO - Running post-run listener: _resource_record_post_run 23:06:27 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36824064; Write bytes: 701534208; Read time: 245; Write time: 1983 23:06:27 INFO - TinderboxPrint: CPU usage
53.8% 23:06:27 INFO - TinderboxPrint: I/O read bytes / time
36,824,064 / 245 23:06:27 INFO - TinderboxPrint: I/O write bytes / time
701,534,208 / 1,983 23:06:27 INFO - TinderboxPrint: CPU idle
2,242.5 (46.2%) 23:06:27 INFO - TinderboxPrint: CPU system
973.7 (20.1%) 23:06:27 INFO - TinderboxPrint: CPU user
1,633.5 (33.7%) 23:06:27 INFO - TinderboxPrint: Swap in / out
440,320,000 / 0 23:06:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399086080; Write bytes: 392951296; Read time: 29509; Write time: 848 23:06:27 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34702336; Write bytes: 294648320; Read time: 224; Write time: 1095 23:06:28 INFO - Running post-run listener: _upload_blobber_files 23:06:28 INFO - Blob upload gear active. 23:06:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:06:28 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 23:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:06:28 INFO - (blobuploader) - INFO - Open directory for files ... 23:06:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:06:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:29 INFO - (blobuploader) - INFO - Done attempting. 23:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:06:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:30 INFO - (blobuploader) - INFO - Done attempting. 23:06:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:06:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:06:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:38 INFO - (blobuploader) - INFO - Done attempting. 23:06:38 INFO - (blobuploader) - INFO - Iteration through files over. 23:06:38 INFO - Return code: 0 23:06:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:06:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:06:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fabb5b0214730febe4750ded01200928a9d5eb542b8c0ec9ada6c64349fbd125aeef9055103b485713a5a60c3858f5075573320eaa95a36331c801bf1ea9d22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9a663054748fa194bd304e5a3cc02a6bec120a180e1d4950a25006d5096d4624c31e981dbb5b013a80c1912b938c00405b5932b3d0635a531378e49c68ef2a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04cd00da8423ace622750eb90c2bd008e70829a43405f88c7460d8d3bb5fcd716bfd27235f7da72f55f25020ab53e50236011556ccc4bf12d06c8fd8e399de2e"} 23:06:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:06:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:06:38 INFO - Contents: 23:06:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fabb5b0214730febe4750ded01200928a9d5eb542b8c0ec9ada6c64349fbd125aeef9055103b485713a5a60c3858f5075573320eaa95a36331c801bf1ea9d22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9a663054748fa194bd304e5a3cc02a6bec120a180e1d4950a25006d5096d4624c31e981dbb5b013a80c1912b938c00405b5932b3d0635a531378e49c68ef2a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04cd00da8423ace622750eb90c2bd008e70829a43405f88c7460d8d3bb5fcd716bfd27235f7da72f55f25020ab53e50236011556ccc4bf12d06c8fd8e399de2e"} 23:06:38 INFO - Running post-run listener: copy_logs_to_upload_dir 23:06:38 INFO - Copying logs to upload dir... 23:06:38 INFO - mkdir: /builds/slave/test/build/upload/logs 23:06:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.164230 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-09 23:06:38.139381) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:06:38.142689) ========= 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.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fabb5b0214730febe4750ded01200928a9d5eb542b8c0ec9ada6c64349fbd125aeef9055103b485713a5a60c3858f5075573320eaa95a36331c801bf1ea9d22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9a663054748fa194bd304e5a3cc02a6bec120a180e1d4950a25006d5096d4624c31e981dbb5b013a80c1912b938c00405b5932b3d0635a531378e49c68ef2a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04cd00da8423ace622750eb90c2bd008e70829a43405f88c7460d8d3bb5fcd716bfd27235f7da72f55f25020ab53e50236011556ccc4bf12d06c8fd8e399de2e"} build_url:https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010927 build_url: 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fabb5b0214730febe4750ded01200928a9d5eb542b8c0ec9ada6c64349fbd125aeef9055103b485713a5a60c3858f5075573320eaa95a36331c801bf1ea9d22a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e9a663054748fa194bd304e5a3cc02a6bec120a180e1d4950a25006d5096d4624c31e981dbb5b013a80c1912b938c00405b5932b3d0635a531378e49c68ef2a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04cd00da8423ace622750eb90c2bd008e70829a43405f88c7460d8d3bb5fcd716bfd27235f7da72f55f25020ab53e50236011556ccc4bf12d06c8fd8e399de2e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:06:38.175430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:06:38.175781) ========= 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.r3J94guRqe/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.7tN1BdCOIH/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:06:38.224315) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:06:38.224646) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:06:38.225065) ========= ========= Total master_lag: 0.17 =========