builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1471052851.82 results: success (0) buildid: 20160812162417 builduid: 83e89564aaaa414db026c8c066b15fa2 revision: 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:31.819261) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:31.819759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:31.820048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:31.851414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:31.851812) ========= 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.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-12 18:47:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.2M=0s 2016-08-12 18:47:32 (27.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.198966 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:32.073759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:32.074080) ========= 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.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.027489 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:32.122534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 18:47:32.122938) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-12 18:47:32,197 truncating revision to first 12 chars 2016-08-12 18:47:32,197 Setting DEBUG logging. 2016-08-12 18:47:32,197 attempt 1/10 2016-08-12 18:47:32,197 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5acd4f7ea715?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 18:47:33,103 unpacking tar archive at: autoland-5acd4f7ea715/testing/mozharness/ program finished with exit code 0 elapsedTime=1.172407 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 18:47:33.321656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:33.322106) ========= script_repo_revision: 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:33.322584) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:33.322860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 18:47:33.341627) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-12 18:47:33.342193) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:47:33 INFO - MultiFileLogger online at 20160812 18:47:33 in /builds/slave/test 18:47:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:47:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:47:33 INFO - {'append_to_log': False, 18:47:33 INFO - 'base_work_dir': '/builds/slave/test', 18:47:33 INFO - 'blob_upload_branch': 'Autoland', 18:47:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:47:33 INFO - 'branch': 'Autoland', 18:47:33 INFO - 'buildbot_json_path': 'buildprops.json', 18:47:33 INFO - 'config_files': ('talos/mac_config.py',), 18:47:33 INFO - 'default_actions': ('clobber', 18:47:33 INFO - 'read-buildbot-config', 18:47:33 INFO - 'download-and-extract', 18:47:33 INFO - 'populate-webroot', 18:47:33 INFO - 'create-virtualenv', 18:47:33 INFO - 'install', 18:47:33 INFO - 'run-tests'), 18:47:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:47:33 INFO - 'download_minidump_stackwalk': True, 18:47:33 INFO - 'download_symbols': 'ondemand', 18:47:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:47:33 INFO - 'tooltool.py': '/tools/tooltool.py', 18:47:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:47:33 INFO - '/tools/misc-python/virtualenv.py')}, 18:47:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:47:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:47:33 INFO - 'installer_path': 'installer.exe', 18:47:33 INFO - 'log_level': 'info', 18:47:33 INFO - 'log_name': 'talos', 18:47:33 INFO - 'log_to_console': True, 18:47:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:47:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:47:33 INFO - 'opt_config_files': (), 18:47:33 INFO - 'pip_index': False, 18:47:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:33 INFO - 'cmd': ('bash', 18:47:33 INFO - '-c', 18:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:33 INFO - 'enabled': True, 18:47:33 INFO - 'halt_on_failure': False, 18:47:33 INFO - 'name': 'check_screen_resolution'},), 18:47:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:47:33 INFO - 'cmd': ('bash', 18:47:33 INFO - '-c', 18:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:47:33 INFO - 'enabled': True, 18:47:33 INFO - 'halt_on_failure': False, 18:47:33 INFO - 'name': 'check_screen_resolution'},), 18:47:33 INFO - 'run_cmd_checks_enabled': True, 18:47:33 INFO - 'sps_profile': False, 18:47:33 INFO - 'sps_profile_interval': 0, 18:47:33 INFO - 'suite': 'dromaeojs', 18:47:33 INFO - 'system_bits': '32', 18:47:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:47:33 INFO - 'title': 't-yosemite-r7-0132', 18:47:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:47:33 INFO - 'use_talos_json': True, 18:47:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:47:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:47:33 INFO - 'work_dir': 'build'} 18:47:33 INFO - [mozharness: 2016-08-13 01:47:33.543801Z] Running clobber step. 18:47:33 INFO - Running pre-action listener: _resource_record_pre_action 18:47:33 INFO - Running main action method: clobber 18:47:33 INFO - rmtree: /builds/slave/test/build 18:47:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:47:36 INFO - Running post-action listener: _resource_record_post_action 18:47:36 INFO - [mozharness: 2016-08-13 01:47:36.094990Z] Finished clobber step (success) 18:47:36 INFO - [mozharness: 2016-08-13 01:47:36.095158Z] Running read-buildbot-config step. 18:47:36 INFO - Running pre-action listener: _resource_record_pre_action 18:47:36 INFO - Running main action method: read_buildbot_config 18:47:36 INFO - Using buildbot properties: 18:47:36 INFO - { 18:47:36 INFO - "project": "", 18:47:36 INFO - "product": "firefox", 18:47:36 INFO - "script_repo_revision": "production", 18:47:36 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:47:36 INFO - "repository": "", 18:47:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:47:36 INFO - "buildid": "20160812162417", 18:47:36 INFO - "pgo_build": "False", 18:47:36 INFO - "basedir": "/builds/slave/test", 18:47:36 INFO - "buildnumber": 78, 18:47:36 INFO - "slavename": "t-yosemite-r7-0132", 18:47:36 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:47:36 INFO - "platform": "yosemite_r7", 18:47:36 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:47:36 INFO - "branch": "autoland", 18:47:36 INFO - "repo_path": "integration/autoland", 18:47:36 INFO - "revision": "5acd4f7ea715daeef7ac6abf88e805c5c5883be7", 18:47:36 INFO - "stage_platform": "macosx64", 18:47:36 INFO - "builduid": "83e89564aaaa414db026c8c066b15fa2", 18:47:36 INFO - "slavebuilddir": "test" 18:47:36 INFO - } 18:47:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:47:36 INFO - Running post-action listener: _resource_record_post_action 18:47:36 INFO - [mozharness: 2016-08-13 01:47:36.098537Z] Finished read-buildbot-config step (success) 18:47:36 INFO - [mozharness: 2016-08-13 01:47:36.098686Z] Running download-and-extract step. 18:47:36 INFO - Running pre-action listener: _resource_record_pre_action 18:47:36 INFO - Running main action method: download_and_extract 18:47:36 INFO - mkdir: /builds/slave/test/build/tests 18:47:36 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')]} 18:47:36 INFO - trying https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:47:36 INFO - Downloading https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:37 INFO - Downloaded 1199 bytes. 18:47:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:47:37 INFO - Using the following test package requirements: 18:47:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:47:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:47:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:47:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'jsshell-mac.zip'], 18:47:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:47:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:47:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:47:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:47:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:47:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:47:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:47:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:37 INFO - trying https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:47:37 INFO - Downloading https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:38 INFO - Downloaded 16329718 bytes. 18:47:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:47:45 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 18:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:45 INFO - trying https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:47:45 INFO - Downloading https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:46 INFO - Downloaded 16329718 bytes. 18:47:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:47: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')]} 18:47:52 INFO - trying https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:47:52 INFO - Downloading https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:54 INFO - Downloaded 14413719 bytes. 18:47:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:54 INFO - trying https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:54 INFO - Downloading https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:47:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/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 18:47:57 INFO - Downloaded 110970498 bytes. 18:47:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:57 INFO - mkdir: /builds/slave/test/properties 18:47:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:57 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:57 INFO - Contents: 18:47:57 INFO - build_url:https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:57 INFO - Running post-action listener: _resource_record_post_action 18:47:57 INFO - Running post-action listener: set_extra_try_arguments 18:47:57 INFO - [mozharness: 2016-08-13 01:47:57.882212Z] Finished download-and-extract step (success) 18:47:57 INFO - [mozharness: 2016-08-13 01:47:57.882333Z] Running populate-webroot step. 18:47:57 INFO - Running pre-action listener: _resource_record_pre_action 18:47:57 INFO - Running main action method: populate_webroot 18:47:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:47:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:47:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:47:57 INFO - '--tppagecycles', 18:47:57 INFO - '7'], 18:47:57 INFO - 'tests': ['tsvgm']}}, 18:47:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['damp', 'tps']}, 18:47:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'tests': ['damp', 'tps']}, 18:47:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['dromaeo_dom']}, 18:47:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['basic_compositor_video']}, 18:47:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:47:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['a11yr', 18:47:57 INFO - 'ts_paint', 18:47:57 INFO - 'tpaint', 18:47:57 INFO - 'sessionrestore', 18:47:57 INFO - 'sessionrestore_no_auto_restore', 18:47:57 INFO - 'tabpaint']}, 18:47:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['tsvgx', 18:47:57 INFO - 'tsvgr_opacity', 18:47:57 INFO - 'tart', 18:47:57 INFO - 'tscrollx', 18:47:57 INFO - 'cart']}, 18:47:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:57 INFO - 'tsvgr_opacity', 18:47:57 INFO - 'tart', 18:47:57 INFO - 'tscrollx', 18:47:57 INFO - 'cart']}, 18:47:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'talos_options': ['--disable-e10s'], 18:47:57 INFO - 'tests': ['tp5o']}, 18:47:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'tests': ['tp5o']}, 18:47:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'talos_options': ['--disable-e10s', 18:47:57 INFO - '--xperf_path', 18:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:57 INFO - 'tests': ['tp5n']}, 18:47:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:57 INFO - 'talos_options': ['--xperf_path', 18:47:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:57 INFO - 'tests': ['tp5n']}}, 18:47:57 INFO - 'talos.zip': {'path': '', 18:47:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:47:57 INFO - Running post-action listener: _resource_record_post_action 18:47:57 INFO - [mozharness: 2016-08-13 01:47:57.900955Z] Finished populate-webroot step (success) 18:47:57 INFO - [mozharness: 2016-08-13 01:47:57.901067Z] Running create-virtualenv step. 18:47:57 INFO - Running pre-action listener: _resource_record_pre_action 18:47:57 INFO - Running main action method: create_virtualenv 18:47:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:57 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 18:47:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:58 INFO - Using real prefix '/tools/python27' 18:47:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:48:01 INFO - Installing pip.................done. 18:48:01 INFO - Return code: 0 18:48:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:48:01 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')]} 18:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:01 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')]} 18:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:01 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:01 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 18:48:01 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 18:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:01 INFO - 'HOME': '/Users/cltbld', 18:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:01 INFO - 'LOGNAME': 'cltbld', 18:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:01 INFO - 'MOZ_NO_REMOTE': '1', 18:48:01 INFO - 'NO_EM_RESTART': '1', 18:48:01 INFO - 'PAGER': '/bin/cat', 18:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:01 INFO - 'PWD': '/builds/slave/test', 18:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:01 INFO - 'SHELL': '/bin/bash', 18:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:01 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:01 INFO - 'USER': 'cltbld', 18:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:01 INFO - 'XPC_FLAGS': '0x0', 18:48:01 INFO - 'XPC_SERVICE_NAME': '0', 18:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:01 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 18:48:01 INFO - Cleaning up... 18:48:01 INFO - Return code: 0 18:48:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:48:01 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')]} 18:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:01 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')]} 18:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:01 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:01 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 18:48:01 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 18:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:01 INFO - 'HOME': '/Users/cltbld', 18:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:01 INFO - 'LOGNAME': 'cltbld', 18:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:01 INFO - 'MOZ_NO_REMOTE': '1', 18:48:01 INFO - 'NO_EM_RESTART': '1', 18:48:01 INFO - 'PAGER': '/bin/cat', 18:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:01 INFO - 'PWD': '/builds/slave/test', 18:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:01 INFO - 'SHELL': '/bin/bash', 18:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:01 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:01 INFO - 'USER': 'cltbld', 18:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:01 INFO - 'XPC_FLAGS': '0x0', 18:48:01 INFO - 'XPC_SERVICE_NAME': '0', 18:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:02 INFO - Downloading/unpacking psutil>=3.1.1 18:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:05 INFO - Installing collected packages: psutil 18:48:05 INFO - Running setup.py install for psutil 18:48:05 INFO - building 'psutil._psutil_osx' extension 18:48:05 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 18:48:05 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 18:48:05 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 18:48:06 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 18:48:06 INFO - building 'psutil._psutil_posix' extension 18:48:06 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 18:48:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:48:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:48:06 INFO - ^ 18:48:06 INFO - 1 warning generated. 18:48:06 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 18:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:06 INFO - Successfully installed psutil 18:48:06 INFO - Cleaning up... 18:48:06 INFO - Return code: 0 18:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:06 INFO - 'HOME': '/Users/cltbld', 18:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:06 INFO - 'LOGNAME': 'cltbld', 18:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:06 INFO - 'MOZ_NO_REMOTE': '1', 18:48:06 INFO - 'NO_EM_RESTART': '1', 18:48:06 INFO - 'PAGER': '/bin/cat', 18:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:06 INFO - 'PWD': '/builds/slave/test', 18:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:06 INFO - 'SHELL': '/bin/bash', 18:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:06 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:06 INFO - 'USER': 'cltbld', 18:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:06 INFO - 'XPC_FLAGS': '0x0', 18:48:06 INFO - 'XPC_SERVICE_NAME': '0', 18:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:48:06 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 18:48:06 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 18:48:06 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 18:48:06 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 18:48:06 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 18:48:06 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 18:48:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:48:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:48:09 INFO - Installing collected packages: mozsystemmonitor 18:48:09 INFO - Running setup.py install for mozsystemmonitor 18:48:09 INFO - Successfully installed mozsystemmonitor 18:48:09 INFO - Cleaning up... 18:48:09 INFO - Return code: 0 18:48:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:48: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')]} 18:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48: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')]} 18:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:09 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:09 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 18:48:09 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 18:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:09 INFO - 'HOME': '/Users/cltbld', 18:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:09 INFO - 'LOGNAME': 'cltbld', 18:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:09 INFO - 'MOZ_NO_REMOTE': '1', 18:48:09 INFO - 'NO_EM_RESTART': '1', 18:48:09 INFO - 'PAGER': '/bin/cat', 18:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:09 INFO - 'PWD': '/builds/slave/test', 18:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:09 INFO - 'SHELL': '/bin/bash', 18:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:09 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:09 INFO - 'USER': 'cltbld', 18:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:09 INFO - 'XPC_FLAGS': '0x0', 18:48:09 INFO - 'XPC_SERVICE_NAME': '0', 18:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:48:09 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 18:48:09 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 18:48:09 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 18:48:09 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 18:48:09 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 18:48:09 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 18:48:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:48:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:48:12 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 18:48:12 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 18:48:12 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 18:48:12 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 18:48:12 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 18:48:12 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 18:48:12 INFO - Downloading functools32-3.2.3-2.tar.gz 18:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:48:12 INFO - warning: no files found matching '*.txt' 18:48:12 INFO - no previously-included directories found matching 'build' 18:48:12 INFO - no previously-included directories found matching 'dist' 18:48:12 INFO - no previously-included directories found matching '.git*' 18:48:12 INFO - Installing collected packages: jsonschema, functools32 18:48:12 INFO - Running setup.py install for functools32 18:48:12 INFO - warning: no files found matching '*.txt' 18:48:12 INFO - no previously-included directories found matching 'build' 18:48:12 INFO - no previously-included directories found matching 'dist' 18:48:12 INFO - no previously-included directories found matching '.git*' 18:48:12 INFO - Successfully installed jsonschema functools32 18:48:12 INFO - Cleaning up... 18:48:12 INFO - Return code: 0 18:48:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:12 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:12 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 18:48:12 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 18:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:12 INFO - 'HOME': '/Users/cltbld', 18:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:12 INFO - 'LOGNAME': 'cltbld', 18:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:12 INFO - 'MOZ_NO_REMOTE': '1', 18:48:12 INFO - 'NO_EM_RESTART': '1', 18:48:12 INFO - 'PAGER': '/bin/cat', 18:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:12 INFO - 'PWD': '/builds/slave/test', 18:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:12 INFO - 'SHELL': '/bin/bash', 18:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:12 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:12 INFO - 'USER': 'cltbld', 18:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:12 INFO - 'XPC_FLAGS': '0x0', 18:48:12 INFO - 'XPC_SERVICE_NAME': '0', 18:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:48:13 INFO - Cleaning up... 18:48:13 INFO - Return code: 0 18:48:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:13 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:13 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 18:48:13 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 18:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:13 INFO - 'HOME': '/Users/cltbld', 18:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:13 INFO - 'LOGNAME': 'cltbld', 18:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:13 INFO - 'MOZ_NO_REMOTE': '1', 18:48:13 INFO - 'NO_EM_RESTART': '1', 18:48:13 INFO - 'PAGER': '/bin/cat', 18:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:13 INFO - 'PWD': '/builds/slave/test', 18:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:13 INFO - 'SHELL': '/bin/bash', 18:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:13 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:13 INFO - 'USER': 'cltbld', 18:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:13 INFO - 'XPC_FLAGS': '0x0', 18:48:13 INFO - 'XPC_SERVICE_NAME': '0', 18:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:48:13 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 18:48:13 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 18:48:13 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 18:48:13 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 18:48:13 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 18:48:13 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 18:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:48:15 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 18:48:15 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 18:48:15 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 18:48:15 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 18:48:15 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 18:48:15 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 18:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:48:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:48:16 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 18:48:16 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 18:48:16 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 18:48:16 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 18:48:16 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 18:48:16 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 18:48:16 INFO - Downloading docopt-0.6.1.tar.gz 18:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:48:17 INFO - Installing collected packages: blobuploader, requests, docopt 18:48:17 INFO - Running setup.py install for blobuploader 18:48:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:48:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:48:17 INFO - Running setup.py install for requests 18:48:17 INFO - Running setup.py install for docopt 18:48:17 INFO - Successfully installed blobuploader requests docopt 18:48:17 INFO - Cleaning up... 18:48:17 INFO - Return code: 0 18:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:17 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')]} 18:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:17 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')]} 18:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:17 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:17 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 18:48:17 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 18:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:17 INFO - 'HOME': '/Users/cltbld', 18:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:17 INFO - 'LOGNAME': 'cltbld', 18:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:17 INFO - 'MOZ_NO_REMOTE': '1', 18:48:17 INFO - 'NO_EM_RESTART': '1', 18:48:17 INFO - 'PAGER': '/bin/cat', 18:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:17 INFO - 'PWD': '/builds/slave/test', 18:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:17 INFO - 'SHELL': '/bin/bash', 18:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:17 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:17 INFO - 'USER': 'cltbld', 18:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:17 INFO - 'XPC_FLAGS': '0x0', 18:48:17 INFO - 'XPC_SERVICE_NAME': '0', 18:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-62iUoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-R5mtPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-csRODI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-d_DIx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Gp3VHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-hbiTy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xwMyhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:18 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-RWNq_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gbuUih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-elAbPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-FLhPsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zfFNJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ocnjcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kMDiSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-HNib5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eaFdQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:19 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-aQOvse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:48:19 INFO - Running setup.py install for manifestparser 18:48:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:48:20 INFO - Running setup.py install for mozcrash 18:48:20 INFO - Running setup.py install for mozdebug 18:48:20 INFO - Running setup.py install for mozdevice 18:48:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:48:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:48:20 INFO - Running setup.py install for mozfile 18:48:20 INFO - Running setup.py install for mozhttpd 18:48:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:48:20 INFO - Running setup.py install for mozinfo 18:48:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:48:20 INFO - Running setup.py install for mozInstall 18:48:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Running setup.py install for mozleak 18:48:21 INFO - Running setup.py install for mozlog 18:48:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Running setup.py install for moznetwork 18:48:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Running setup.py install for mozprocess 18:48:21 INFO - Running setup.py install for mozprofile 18:48:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:48:21 INFO - Running setup.py install for mozrunner 18:48:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:48:22 INFO - Running setup.py install for mozscreenshot 18:48:22 INFO - Running setup.py install for moztest 18:48:22 INFO - Running setup.py install for mozversion 18:48:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:48:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:48:22 INFO - Cleaning up... 18:48:22 INFO - Return code: 0 18:48:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:22 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:22 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 18:48:22 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 18:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:22 INFO - 'HOME': '/Users/cltbld', 18:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:22 INFO - 'LOGNAME': 'cltbld', 18:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:22 INFO - 'MOZ_NO_REMOTE': '1', 18:48:22 INFO - 'NO_EM_RESTART': '1', 18:48:22 INFO - 'PAGER': '/bin/cat', 18:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:22 INFO - 'PWD': '/builds/slave/test', 18:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:22 INFO - 'SHELL': '/bin/bash', 18:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:22 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:22 INFO - 'USER': 'cltbld', 18:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:22 INFO - 'XPC_FLAGS': '0x0', 18:48:22 INFO - 'XPC_SERVICE_NAME': '0', 18:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-XxU60T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GlBhUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qV8w_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-LEPHlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-B7YuvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4c7jv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cVbI7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:23 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)) 18:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:23 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DW8fGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-l4ft0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GlI6Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-FLJEih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-61rzq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7hPjXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gFIQVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-79l7qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WkGKq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:24 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)) 18:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:24 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-BALT8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:24 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)) 18:48:24 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)) 18:48:24 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)) 18:48:24 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)) 18:48:24 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)) 18:48:24 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)) 18:48:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:24 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 18:48:24 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 18:48:24 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 18:48:24 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 18:48:24 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 18:48:24 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 18:48:28 INFO - Downloading blessings-1.6.tar.gz 18:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:48:28 INFO - Installing collected packages: blessings 18:48:28 INFO - Running setup.py install for blessings 18:48:28 INFO - Successfully installed blessings 18:48:28 INFO - Cleaning up... 18:48:28 INFO - Return code: 0 18:48:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:48:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:28 INFO - Reading from file tmpfile_stdout 18:48:28 INFO - Current package versions: 18:48:28 INFO - blessings == 1.6 18:48:28 INFO - blobuploader == 1.2.4 18:48:28 INFO - docopt == 0.6.1 18:48:28 INFO - functools32 == 3.2.3-2 18:48:28 INFO - jsonschema == 2.5.1 18:48:28 INFO - manifestparser == 1.1 18:48:28 INFO - mozInstall == 1.12 18:48:28 INFO - mozcrash == 0.17 18:48:28 INFO - mozdebug == 0.1 18:48:28 INFO - mozdevice == 0.48 18:48:28 INFO - mozfile == 1.2 18:48:28 INFO - mozhttpd == 0.7 18:48:28 INFO - mozinfo == 0.9 18:48:28 INFO - mozleak == 0.1 18:48:28 INFO - mozlog == 3.3 18:48:28 INFO - moznetwork == 0.27 18:48:28 INFO - mozprocess == 0.23 18:48:28 INFO - mozprofile == 0.28 18:48:28 INFO - mozrunner == 6.12 18:48:28 INFO - mozscreenshot == 0.1 18:48:28 INFO - mozsystemmonitor == 0.3 18:48:28 INFO - moztest == 0.7 18:48:28 INFO - mozversion == 1.4 18:48:28 INFO - psutil == 3.1.1 18:48:28 INFO - requests == 1.2.3 18:48:28 INFO - wsgiref == 0.1.2 18:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:28 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')]} 18:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:28 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')]} 18:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:28 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 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:28 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 18:48:28 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 18:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:28 INFO - 'HOME': '/Users/cltbld', 18:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:28 INFO - 'LOGNAME': 'cltbld', 18:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:28 INFO - 'MOZ_NO_REMOTE': '1', 18:48:28 INFO - 'NO_EM_RESTART': '1', 18:48:28 INFO - 'PAGER': '/bin/cat', 18:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:28 INFO - 'PWD': '/builds/slave/test', 18:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:28 INFO - 'SHELL': '/bin/bash', 18:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:28 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:28 INFO - 'USER': 'cltbld', 18:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:28 INFO - 'XPC_FLAGS': '0x0', 18:48:28 INFO - 'XPC_SERVICE_NAME': '0', 18:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 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)) 18:48:29 INFO - Cleaning up... 18:48:29 INFO - Return code: 0 18:48:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:48:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a7d9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a86e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6bad7ae10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, '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.l8oPJeoQWU/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.kI0c0Oap39/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 18:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:29 INFO - 'HOME': '/Users/cltbld', 18:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:29 INFO - 'LOGNAME': 'cltbld', 18:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:29 INFO - 'MOZ_NO_REMOTE': '1', 18:48:29 INFO - 'NO_EM_RESTART': '1', 18:48:29 INFO - 'PAGER': '/bin/cat', 18:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:29 INFO - 'PWD': '/builds/slave/test', 18:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:29 INFO - 'SHELL': '/bin/bash', 18:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:48:29 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:29 INFO - 'USER': 'cltbld', 18:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:29 INFO - 'XPC_FLAGS': '0x0', 18:48:29 INFO - 'XPC_SERVICE_NAME': '0', 18:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:48:29 INFO - Cleaning up... 18:48:29 INFO - Return code: 0 18:48:29 INFO - Running post-action listener: _resource_record_post_action 18:48:29 INFO - Running post-action listener: _start_resource_monitoring 18:48:29 INFO - Starting resource monitoring. 18:48:29 INFO - [mozharness: 2016-08-13 01:48:29.399769Z] Finished create-virtualenv step (success) 18:48:29 INFO - [mozharness: 2016-08-13 01:48:29.400629Z] Running install step. 18:48:29 INFO - Running pre-action listener: _resource_record_pre_action 18:48:29 INFO - Running main action method: install 18:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:29 INFO - Reading from file tmpfile_stdout 18:48:29 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:29 INFO - Reading from file tmpfile_stdout 18:48:29 INFO - Output received: 18:48:29 INFO - Usage: mozinstall [options] installer 18:48:29 INFO - Options: 18:48:29 INFO - -h, --help show this help message and exit 18:48:29 INFO - -d DEST, --destination=DEST 18:48:29 INFO - Directory to install application into. [default: 18:48:29 INFO - "/builds/slave/test"] 18:48:29 INFO - --app=APP Application being installed. [default: firefox] 18:48:29 INFO - mkdir: /builds/slave/test/build/application 18:48:29 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'] 18:48:29 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 18:49:04 INFO - Reading from file tmpfile_stdout 18:49:04 INFO - Output received: 18:49:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:49:04 INFO - Running post-action listener: _resource_record_post_action 18:49:04 INFO - [mozharness: 2016-08-13 01:49:04.038478Z] Finished install step (success) 18:49:04 INFO - [mozharness: 2016-08-13 01:49:04.038616Z] Running run-tests step. 18:49:04 INFO - Running pre-action listener: _resource_record_pre_action 18:49:04 INFO - Running main action method: run_tests 18:49:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:49:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:49:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:49:04 INFO - 2016-08-12 18:49:04.053 screenresolution[1637:7442] starting screenresolution argv=screenresolution get 18:49:04 INFO - 2016-08-12 18:49:04.055 screenresolution[1637:7442] Display 0: 1600x1200x32@60 18:49:04 INFO - 2016-08-12 18:49:04.062 screenresolution[1638:7444] starting screenresolution argv=screenresolution list 18:49:04 INFO - Available Modes on Display 0 18:49:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:49:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:49:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:49:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:49:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:49:04 INFO - Intel Iris: 18:49:04 INFO - Chipset Model: Intel Iris 18:49:04 INFO - Type: GPU 18:49:04 INFO - Bus: Built-In 18:49:04 INFO - VRAM (Dynamic, Max): 1536 MB 18:49:04 INFO - Vendor: Intel (0x8086) 18:49:04 INFO - Device ID: 0x0a2e 18:49:04 INFO - Revision ID: 0x0009 18:49:04 INFO - Displays: 18:49:04 INFO - Display: 18:49:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:49:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:49:04 INFO - Display Serial Number: 1600x1200 60 18:49:04 INFO - Main Display: Yes 18:49:04 INFO - Mirror: Off 18:49:04 INFO - Online: Yes 18:49:04 INFO - Rotation: Supported 18:49:04 INFO - Return code: 0 18:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:49:04 INFO - Python 2.7.3 18:49:04 INFO - Return code: 0 18:49:04 INFO - grabbing minidump binary from tooltool 18:49: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')]} 18:49:04 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 0x10a73dd30>, '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 0x10a4a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a735b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:49:04 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 18:49:04 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 18:49:04 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 18:49:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:49:04 INFO - Return code: 0 18:49:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:49:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:49:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:49:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:49:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 18:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --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 18:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8oPJeoQWU/Render', 18:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:49:04 INFO - 'HOME': '/Users/cltbld', 18:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:49:04 INFO - 'LOGNAME': 'cltbld', 18:49:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:49:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:04 INFO - 'MOZ_NO_REMOTE': '1', 18:49:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:49:04 INFO - 'NO_EM_RESTART': '1', 18:49:04 INFO - 'PAGER': '/bin/cat', 18:49:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:49:04 INFO - 'PWD': '/builds/slave/test', 18:49:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:49:04 INFO - 'SHELL': '/bin/bash', 18:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kI0c0Oap39/Listeners', 18:49:04 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 18:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:49:04 INFO - 'USER': 'cltbld', 18:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:04 INFO - 'XPC_FLAGS': '0x0', 18:49:04 INFO - 'XPC_SERVICE_NAME': '0', 18:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:49:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 18:49:04 INFO - mozversion application_buildid: 20160812162417 18:49:04 INFO - mozversion application_changeset: 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 18:49:04 INFO - mozversion application_display_name: Nightly 18:49:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:49:04 INFO - mozversion application_name: Firefox 18:49:04 INFO - mozversion application_remotingname: firefox 18:49:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:49:04 INFO - mozversion application_vendor: Mozilla 18:49:04 INFO - mozversion application_version: 51.0a1 18:49:04 INFO - mozversion platform_buildid: 20160812162417 18:49:04 INFO - mozversion platform_changeset: 5acd4f7ea715daeef7ac6abf88e805c5c5883be7 18:49:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:49:04 INFO - mozversion platform_version: 51.0a1 18:49:04 INFO - using testdate: 1471052944 18:49:04 INFO - actual date: 1471052944 18:49:04 INFO - starting webserver on 'localhost:49267' 18:49:04 INFO - SUITE-START | Running 2 tests 18:49:04 INFO - TEST-START | dromaeo_css 18:49:04 INFO - operating with platform_type : mac_ 18:49:04 INFO - Initialising browser for dromaeo_css test... 18:49:04 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp0BnTvs/profile http://localhost:49267/getInfo.html) 18:49:06 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 18:49:06 INFO - PROCESS | 1648 | colorDepth:24 18:49:06 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 18:49:06 INFO - PROCESS | 1648 | __metrics 18:49:07 INFO - PROCESS | 1648 | 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" 18:49:07 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:07 INFO - TEST-INFO | 1648: exit 0 18:49:07 INFO - Browser initialized. 18:49:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:49:07 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp0BnTvs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:19 INFO - PROCESS | 1650 | RSS: Main: 243716096 18:49:19 INFO - PROCESS | 1650 | 18:50:11 INFO - PROCESS | 1650 | 1471053011409 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] 18:52:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 18:52:25 INFO - PROCESS | 1650 | RSS: Main: 370876416 18:52:25 INFO - PROCESS | 1650 | 18:55:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 18:55:28 INFO - PROCESS | 1650 | RSS: Main: 386613248 18:55:28 INFO - PROCESS | 1650 | 18:58:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 18:58:31 INFO - PROCESS | 1650 | RSS: Main: 353574912 18:58:31 INFO - PROCESS | 1650 | 19:01:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 19:01:34 INFO - PROCESS | 1650 | RSS: Main: 351997952 19:01:34 INFO - PROCESS | 1650 | 19:04:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 19:04:38 INFO - PROCESS | 1650 | RSS: Main: 342601728 19:04:38 INFO - PROCESS | 1650 | 19:07:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 19:07:42 INFO - PROCESS | 1650 | RSS: Main: 390336512 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | __start_tp_report 19:07:42 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 19:07:42 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 19:07:42 INFO - PROCESS | 1650 | |i|pagename|runs| 19:07:42 INFO - PROCESS | 1650 | |0;dojo.html;2870.641169853768;3362.243495213576;3372.8482218300173;3399.2861499085193;3428.8799892003767;5534.557235421166;5590.18942253373;5653.321601407832;5661.915071273934;5686.488216060554;5646.858828529279;5689;5695.231143795586;5698.487136157745;5703.40114288;5151.613628977823;5195.376554813409;5227.503387178212;5231.346081739783;5268.8155914542995;5240.240165176047;5246.186841039645;5246.239295302179;5248.8687782805355;5251.947480525184;5133.3326667533165;5150.124478838607;5152.433232344439;5160.096983027974;5242.056429842627;5241.8427447176455;5255.343082114736;5260.579153667697;5293.841184764463;5330.173823057432;5043.924341134886;5072.898542029176;5079.619028572872;5171.482851714836;5184.922226166611;5506.063969125258;5553.8333849984565;5587.8603034924045;5589.469000444951;5646.491815736563;6207.720652570645;6219.315875253718;6222.471089957359;6231.626102433868;6236.750529978795;6125.6017461851925;6150.815475535741;6198.256209254873;6218.129461875295;6288.87422251557;5757.942420575824;5766.913496297559;5779.306483221956;5780.16187652788;5782.3639399666;5423.538999185034;6315.526335524854;6324.9051264231075;6333.20834895638;6340.873182536324;5054.82308119214;5942.049272116441;5963.7912673056235;6076.553590509196;6100.786472473531;6250.531210159256;6254.468370188494;6255.24937007562;6282.465990390777;6298.464630506458;6134.7852825151795;6206.255249370104;6206.689665516707;6219.036049412348;6228.408301211378;6051.969740151271;6055.6063855849225;6065.787697430657;6239.937600623935;6247.3440288769025;4883.316335713003;4885.487023862514;4914.189158788759;4916.631252656065;4920.803167873245;4429.49060857999;4447.955520444819;4466.017476155241;4472.44094488189;4478.104379124188;5683.26117604717;5693.743781529841;5694.715264236855;5698.629589076697;5702;6144.433169541739;6167.044108163242;6239.719212635442;6245;6301.180846490019;5725.799597014085;5744.913826292609;5749.453801888814;5750.654960702372;5768.567357448208;5296.814611488579;5413.702246376487;5428.2671839301975;5440.809571664972;5446.7276636168035;6231.158793562901;6305.306416294204;6308.779192728324;6320.273168585488;6321.11504389395;6272.905906411499;6287.937120628735;6298.0867774171575;6302.243730752156;6311.990081586924;6298.275698294755;6303.527235457267;6323.399277068671;6328.398802113792;6350.968245158745;6023.397660233942;6051.788187413419;6073.240844894388;6125.050617154348;6140.600860944024;5637.633553818989;6107;6144.832481828438;6255.124282600531;6269.592476489013;5189.740512974261;5926.851828704317;6076.51387888959;6083.6349819011;6102.962496375716;5925;6046.425589568984;6069.757209711562;6109.75560977556;6115.785947491817;5791.652500849962;5810.186573879576;5816.331121920864;5816.447437493432;5902.734376952962;5947.762089516371;5956.523478121827;5957.7319020645045;5960.254968128984;5969.641821490724;5743.8851222976145;5808.15781711641;5847.766089356378;5849.268841394825;5851.093080572518 19:07:42 INFO - PROCESS | 1650 | |1;ext.html;430.90950900310935;484.85212010336835;523.0898237067504;533.5411546070379;542.1434134068172;30975.380492390166;32734.67265327346;40565.985850353754;42319.57680423195;42353.576464235346;43966;44548.554514454845;44570.33144502835;44576.88557786057;44740;9381.76545586359;9536.141747242747;9538.856917146248;9547;9557.61769529218;9714.61141554337;9834.26243031772;9841.163501102415;9849.310548261625;9866.161376283024;9848.655297064604;9902.900970990306;9919.40483570988;9921.007899210057;9931.900680993205;8930.883639545056;9358.485283309416;9839.311248212627;9855.162311203556;9894;9697.90302096977;9773.853392199122;9783.755406114833;9793.559289831903;9794.510274486318;40603;40977.79511102426;40984;41048.97377565555;41206.9698257543;3690.815459227028;3828.7511311764647;4096.836126554934;4107.158032603339;4289.871303860889;24174.51650966971;32841.17897052569;32935.34129317424;33254.83372583122;34267.485987710206;3192;3243.6269828969735;3255.869765209389;3363.0751543325537;3379.9493007604906;510601;519629;520318;520841;521627;67140;68415.65792171007;68472;68500.6574967122;68563.65718171476;35683.4647480288;36375.09062273411;36502.81748591293;37760.055998600255;38230.23539529194;16439.91780041092;16558.6688266234;16570.668586628442;16604.50186494407;16631.501054968372;12561.874381256253;12694;12703.872961270268;12714.745705086032;12718.554850580185;21064.894675526524;21194.364169074954;21221.36335909925;21279.680804787942;21343.67984480234;7353.963230183922;8623.396362254582;8787.956060219658;8790.648374065093;8844.823103538021;796.366888323784;861.8319427711536;940.1632547033146;1045.1273186889032;1077.283606401734;26769.598456023174;28552;30969.8451507741;31309.060728178192;31459.056228313188;966.9806603868024;989.475577943691;1002.1281485108003;1051.9316244444087;1099.2250463423427;15914.681706365807;15928.203589820463;16131.758023629654;16190.433334833224;16439.506814795575;690932;700496;701250;702216;703002;47043.76478117656;59679.403205967385;59862;59923.700381497816;60007.49981250503;1250.9937450312873;1357.9796303055264;1385.5704731533258;1386.9306534673428;1418.7091646212707;31026.069217923497;31811.36377272488;32029.35941281208;32612.673873261912;32751.836240818644;50191.74904125456;50891.2366314513;50918.745406272734;51001.744991274805;51100.488995109576;545997;548012;548953;549316;550548;24293.75706242915;27128.593071103554;27694;27841.303967400978;27912.441751164457;47793;48033.79915502141;48852.755736221094;48996;49433.752831235615;39376.803115984236;40110.39834402428;40449.79775101106;40801.41532064566;40836.387454187614;32898.83550582232;39653.81038568848;40338.394924075576;40619.59380406158;40943.7952810246;38237.23525529418;39041.60958390493;39275.60724392834;39331.803340984254;39355.21289574135;32973.83513082419;33931.15172120717;35010.64989350074;35153.64846351504;35193.94418167459;23649.881750591136;25483.58632176918;25653.743462565137;25724.295445571846;25764.484710305314 19:07:42 INFO - PROCESS | 1650 | |2;jquery.html;333.17871446883436;354.5337880686895;362.2718336144349;374.4121728885649;374.78075325934327;14892.627684307885;15216.315265813037;17486.21312040958;20563.28028519002;21237.36287911365;20247.797522024775;20850;21339.67990480144;21494.89252553735;21704;5373.301470808789;5959.255093113372;6190.288116866551;6205.472534834545;6236.126942228069;5516.889662206773;6003.849903752397;6033.7888173913925;6205.906911396332;6208;5180.274761533369;5819.709014549277;6116.204893363856;6169.383061693839;6170.2904166021035;6171.166892469527;6223.844403889939;6226.564140510166;6233.345498722614;6249.562530622836;4958.454569997298;5749.885002299972;6021.217241758543;6031.276246850362;6039.8792024159275;12608.558700445441;12799.936000320033;12869.485220591165;24244.636330455058;25316.24051278446;2023.676211806104;2048.3240530624857;2165.101482884601;2169.0781417897488;2525.9621105683427;10049.095581397636;10224.94887525565;10740.355578665227;13033.674158146028;15152.469663561722;1613.9193040347936;1867.131783720563;1920.1839218332152;1923.9519012024812;1925.5763731979082;115520.26719599213;119478.86750077055;123554;130103.04845427326;133894;11361.54553817838;11479.426028698532;11653.184277100521;11678.70803229926;11847.940760296317;14592.562223133324;14608.05047671898;14647.853521464862;14666.413343466142;14693.338799754038;7070.92929070713;8249.958750206211;8459.238668519863;8488.83022339562;8590.441621294596;7551.169371369145;7827.060752709711;8379.790505237417;8568.57157142153;8587.48475091484;4529;4588.793504292248;4603.332516785114;4613.746243956614;4620.584147426748;1642.5565097423867;1895.715642653613;1953.082051435823;1954.8142926421967;1956.2957335359258;865.4504389712451;908.40953380302;914.2366124286162;940.5767404667927;960.6493629825063;16087;21069.683954740693;21872.781272187392;22618.815519129013;23984;854.5214679779343;867.9696210632598;956.4596003258137;956.6269155029544;991.9107280344659;8164.6325915333955;10065.69802905914;11142.610008649659;11168.385738784282;11341.26281791681;577719;587994;588026;597836;599159;91374.53785253856;139363;142708;145696;149476.75784863235;933.1088810186255;981.3915372469115;995.9352642078387;1010.1060561403211;1010.2974543097185;19424.70862937029;19646.41060768122;19771.40685779429;19811.306604269357;19827;59031;59706.40293597182;60154.69922650359;60367.09449358175;60661.32725400291;85151;87655.56172219098;87677.86254962091;88114.55942720246;88192.55903720697;7920.910724984837;7937.8412431752195;7995.800104997422;8239.258466738022;8379.539125348165;12691.111622186727;13084.476620935056;13265.668358290734;13621.455141794217;13881;11363.886361136283;11502.48238829238;11759.882401175879;11792.882071179178;12069.336186509827;11323.943380283046;11587.768244634892;11597;11879.406029698308;11959.820602690792;11566.479508421973;11768.470418831344;11953;12101.636950891487;12128.21166624202;8215.589220539068;8295.668173273001;8483.27892129176;8494.575271236537;8641.833352497333;11095.833562496407;11404.714882128013;11796.823047654463;11802.82295765547;11848.70378240551 19:07:42 INFO - PROCESS | 1650 | |3;mootools.html;1507.02796696131;1537.085434166671;1736.3054778088763;1857.9349722759707;1900.0309841980595;8262.669493220272;8834.77913052174;8851.026387097421;8858.95570522148;8866.866996995042;7693.499922505033;7963.362930965523;7964.721234756785;8088.352931765459;8664.046954834963;2113.661814109743;2158.84888057836;2276.2147059264526;2357.5992081346126;2361.421451744325;2350.976490235101;2351.9412014699597;2352.329586067971;2352.4589344450787;2355.528894221163;2299.195281651417;2330.1611419888827;2356.3402247370764;2362.574736547421;2364.326167042393;2308.2613563659634;2332.5568142052957;2336.3458231695067;2339.2982105368324;2343.94140146496;2291.667708182304;2315.270689732729;2320.8375413721046;2327.104064934995;2342.26218741096;8650.740477785677;8829.735107946772;8844.027157012724;8845.557722113934;8898.644054237822;4733.55626533907;5032.723200223986;5193.195054766517;5289.497497737709;5293.04725149473;3429.725621950238;3572.3391172633146;3644.27114577083;3656.323580137683;3667.6332366763377;1043.4313299251926;1071;1075.37090801881;1097.401915955806;1099.455769394147;11563.595274165364;11671.883281167164;11674.41627918609;11675.766484670345;11693.766124677544;4403.889902752393;4657.4876763555985;4713.622910167179;4765.571098601143;4775.092732380836;9772.12050915421;9794.21646268311;9805.362651427635;9809.068138526829;9818.754531136636;5272.340957380327;5275.788968441219;5277.419483856773;5279.392869819943;5280.551153152025;4688.9531104689195;4850.854474365775;4850.854474365775;4853.320535125085;4855.028994201174;3517.824108794601;3587.192881601619;3679.7608155470343;3731.402975523903;3764.6235376462137;1145.255583870474;1147.4893672315907;1153.8903804138592;1158.692946369213;1160.3038177093845;811.3103861717493;892.205936716323;900.9639614415481;932.7295084425441;981.8134554434635;5014.924776128361;5598.496135347838;5645.4072322405555;5919.4080591940465;6000.309964354068;798.0024968789014;916.8808054952947;933.9392939458913;935.5836652689624;942.844430668932;3862.6523612874416;3865.362215234511;3917.4123881417445;4001.979990100031;4028.879133625996;10315.587376505006;10615.787684246272;10888.891111088788;10892.074173695324;10905.509252083675;10831.754348249895;10832.566697332177;10834.566617335377;10841.67474975752;11014.614488492864;8011.958445402177;8204.671813127527;8292.212239837207;8303.8339233215;8331.625281828552;5736.139579063007;5800.970995144998;5873.148393482836;5908.911366329595;5912.438318359749;9701.951490242503;9871.555779989774;10044.447555384524;10128.392296462236;10131.240156988402;10207.387556746618;10356.964303569583;10374.948125259627;10375.688729338131;10389.896101039196;7070.540414873017;7147.249538798559;7148.821279468055;7157.928420715726;7161.677724502514;7043.2604576518725;7447.218042105501;7661.463697541119;8052.637631306488;8220.177982201732;7397.26027397256;7404.557265640573;7407.740729074457;7517.849643007;7609.505382150142;7302.233265507258;7308.488405811542;7332.523385979895;7347.228541003117;7411.851762964603;6684.565503242274;6924.549904256207;6939.791806245821;7030.156381234285;7041.507094503336;4241.512226094039;4858.684185527929;4973.229149481836;4976.129177393869;5138.997895410359;4984.576311013604;5045.445001049955;5048.217526283432;5146.150885104065;5215.348081489814 19:07:42 INFO - PROCESS | 1650 | |4;prototype.html;300.2733385207797;311.6244924865537;313.46397660001395;317.40169779964776;319.6739325887596;4529.479109902362;5324.54741346985;5359.973200133999;5363.283749200257;5390.245365648808;4317.76252306123;5288.8413347599635;5301.761420736067;5327.866803329919;5350.2777125088105;3864.439656249849;3897.941530877039;4036.354183330653;4041.7372870763306;4088.6268828216694;3405.3700065487965;3642.9981755017316;3660.4875317455576;4130.586941305875;4143.979280103596;4081.244969680604;4094.897627559305;4097.9180416391655;4101.241270364977;4124.97937510312;3680.2087551176623;3905.3165696003084;4040.7979601020124;4111.116110036357;4111.917761644765;3518.982405087984;3537.231384307846;3543.114221444639;3566.268914872445;3569.5716514018222;4039.858604948842;4442.955570444286;5151.6136289778415;5175.687559961628;5177.948220517785;4057.2494088593407;4517.683762136652;4603.378543896564;4697.365855609517;4720.858374248778;4576.306766496385;4640.213517795673;5001.849574597863;5563.833085007456;5568.805091821767;3708.610595887447;3960.3663413853646;3973.205358928226;3983.98008009961;3997.620226078518;4933.324673118514;5626.606137570372;5854.268216472983;5908.586398952075;5983.636463596855;3736.925261494755;3994;4021.4369988201674;4044.4944381952255;4055.959440405617;4122.505299364096;4134.875953721393;4137.1518838638;4142.46148000758;4144.7513149211145;3925.941110883339;3927.2930872443235;4054.189162167578;4166.937301083701;4731.858044258678;5326.280952071498;5630.8029218977135;5630.873825234969;5632.97183514088;5652.689102099342;1262.551794113095;1377.1461693749939;1398.9440422383195;1404.2908331292836;1410.5909286307062;693.3482526425145;861.6639510590875;868.5874209750444;878.2359347367703;881.6605606841413;4404.691671582958;4745.6440766942615;4908.410990681141;5138.4604616514725;5142.845714628567;4553.33976573395;5052.898942021213;5461.644993075437;5487.835364939058;5651.463111004448;5226.92159617606;5295.311609490742;5307.7080760557765;5321.281626980308;5343.732813359395;2793.916182514528;2832.895170883357;2872.4973129702216;3026.5157574787927;3048.3750831079574;5276.736163191902;5777.826665200051;6015;6049.274087109575;6099.268087829489;5560.165975103767;5882.147123656035;5909;5944.821655350346;6017.909731354033;4708.011317623269;5525.4474552544425;5934.940650593525;6023.981966041425;6028.849278768066;4833.419989601271;5730.5129064030025;5785.971070144622;5831.766729330949;5853.9121913172185;5273.78904843802;5387.3804512481565;5655.349634792052;5697.971510142423;5704.429557044263;6002.939970600238;6003.8198854034445;6026.608270462406;6074.7266373012435;6127.844160257382;3178.409596179199;3268.7385009199684;3358.614287689376;3405.318936212817;3407.8807241747413;4696.671233013656;4733.597644200281;4816.975915120402;4818.988132417239;4889.62853016294;4412.84555040572;4428.667849911205;4767.380240568704;4783.593394561502;4870.220764677776;3981.92036159281;4675.555822196886;4711.811527538997;4714.057188562233;4724.204802175152;4106.733062350938;4304.612584867377;4398.802053907646;4638.420197475316;4718.834840780556;4430.851037246997;4791.592714619296;5171.422716071635;5494.697791621499;5528.336599607912;4729.787159577896;4785.282207668877;5409.621326507264;5432.348118225838;5452.482014208644 19:07:42 INFO - PROCESS | 1650 | |5;yui.html;614.5667304550291;723.6056349289637;798.8562058829408;806.431465816599;832.392353581885;15352.232388380578;15629.374825007;16218.7567186492;16224.188790560484;16273.349066037374;14231.501897433593;14721.337539810707;14892.851071489282;14982.175980321077;15012.699746005073;832.126267419209;841.1210285251926;849.9830003399928;858.7981824271292;860.2043110123142;834.5952213176605;839.7648658375665;841.2765022081036;841.3227351981683;841.7853447370912;893.5442924108719;896.932730045246;897.2597606974273;897.5018864530183;899.6716198587534;880.282569705691;886.9246114080311;890.247740659142;892.1390857822167;894.7718331825408;660.5904339309611;660.867826434715;661.2692974263466;662.3674390956674;663.7709990053423;13473.528426505123;13916.721665566633;13960.232187229698;14005.649858753512;14010.719785604231;11973.700657483545;12092.33492157931;12148.514059437612;12221.694457638541;12708.809367859489;10751.677449676523;11245.775084498346;11334.829977550344;11346.262492938014;11778.705532361673;3433.2275238071484;3764.981175094107;3781.7541859779303;3791.7724936503623;3956.090099277182;60337.698311508604;103434;104340;118519.8148018526;119147;9701.6119355225;10353.585856565804;10919.781604367869;11555.42222888869;12318.384080795924;18662.34681786131;18893.338733144275;18900;18909.43271701851;18930.24279028851;5512.590729499862;6989.405900498515;8215.753527394188;8371.330293576499;8438;3593.6765691087926;4518.6385089193445;4594.264917613166;4632.745199014019;4735.337052812609;3556.76881002734;3623.9818800906355;3718.4608231806237;3730.757500762442;3732.5520937487677;642.6369101457755;656.3370995294787;669.9564528305644;679.2562144451817;681.5501768832547;533.6744585802658;583.8365257727843;657.3853447027045;687.8417963868338;688.6625553478859;8248.505089694638;9396.48319342429;9522.669698601687;9667.371620844764;9703.320767546345;608.270075908911;634.1344065350763;680.8799524781805;689.2487188963953;690.944724422045;4415.889602759892;4555.043440877386;4557.886052848706;4653;4829.855104346875;129981;130780;156436;157271;158896;112155;123093;125694;126969.36515317549;127017.36491317484;75866.20668966419;96946.51526742321;99032;105757.47121264345;107979;9538.618455261712;9992.300538962203;10094;10109.49452527356;10132;61336.69331653313;63089.42276442942;63185.68407157935;64233.35766642274;64850;91417.08582914085;96586;97484.5125774395;98356.50821745845;98855.01144988458;10585.470726463493;12745.808812868;12803;13862.65343366424;13900.860991390362;15093.39626414931;15316.693666126392;15457.84542154609;15890.68218635644;15923.124228167562;11221.66335009951;14121.576352709435;14257.572272831832;14419.927900360432;14541.709165816414;14115.858841411455;14145.929270353583;14195.148291102567;14410;14614.561563153122;13613.52352667652;13687.247201404018;13879.930600346934;14054;14235.35940882642;11357.318560886373;11435.199536032731;11548.42257887119;11713.531458741554;11736.823947640964;9355.858722456513;10387.771870947256;11398.829017564578;11663.533458661559;11683.941580292045 19:07:42 INFO - PROCESS | 1650 | __end_tp_report 19:07:42 INFO - PROCESS | 1650 | __start_cc_report 19:07:42 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1358 19:07:42 INFO - PROCESS | 1650 | __end_cc_report 19:07:42 INFO - PROCESS | 1650 | __startTimestamp1471054062115__endTimestamp 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | ------- Summary: start ------- 19:07:42 INFO - PROCESS | 1650 | Number of tests: 6 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5697.06 Median:5810.19 stddev:637.26 (11.0%) stddev-sans-first:599.40 19:07:42 INFO - PROCESS | 1650 | Values: 2870.6 3362.2 3372.8 3399.3 3428.9 5534.6 5590.2 5653.3 5661.9 5686.5 5646.9 5689.0 5695.2 5698.5 5703.4 5151.6 5195.4 5227.5 5231.3 5268.8 5240.2 5246.2 5246.2 5248.9 5251.9 5133.3 5150.1 5152.4 5160.1 5242.1 5241.8 5255.3 5260.6 5293.8 5330.2 5043.9 5072.9 5079.6 5171.5 5184.9 5506.1 5553.8 5587.9 5589.5 5646.5 6207.7 6219.3 6222.5 6231.6 6236.8 6125.6 6150.8 6198.3 6218.1 6288.9 5757.9 5766.9 5779.3 5780.2 5782.4 5423.5 6315.5 6324.9 6333.2 6340.9 5054.8 5942.0 5963.8 6076.6 6100.8 6250.5 6254.5 6255.2 6282.5 6298.5 6134.8 6206.3 6206.7 6219.0 6228.4 6052.0 6055.6 6065.8 6239.9 6247.3 4883.3 4885.5 4914.2 4916.6 4920.8 4429.5 4448.0 4466.0 4472.4 4478.1 5683.3 5693.7 5694.7 5698.6 5702.0 6144.4 6167.0 6239.7 6245.0 6301.2 5725.8 5744.9 5749.5 5750.7 5768.6 5296.8 5413.7 5428.3 5440.8 5446.7 6231.2 6305.3 6308.8 6320.3 6321.1 6272.9 6287.9 6298.1 6302.2 6312.0 6298.3 6303.5 6323.4 6328.4 6351.0 6023.4 6051.8 6073.2 6125.1 6140.6 5637.6 6107.0 6144.8 6255.1 6269.6 5189.7 5926.9 6076.5 6083.6 6103.0 5925.0 6046.4 6069.8 6109.8 6115.8 5791.7 5810.2 5816.3 5816.4 5902.7 5947.8 5956.5 5957.7 5960.3 5969.6 5743.9 5808.2 5847.8 5849.3 5851.1 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:71803.00 Median:22520.32 stddev:158992.59 (706.0%) stddev-sans-first:159348.30 19:07:42 INFO - PROCESS | 1650 | Values: 430.9 484.9 523.1 533.5 542.1 30975.4 32734.7 40566.0 42319.6 42353.6 43966.0 44548.6 44570.3 44576.9 44740.0 9381.8 9536.1 9538.9 9547.0 9557.6 9714.6 9834.3 9841.2 9849.3 9866.2 9848.7 9902.9 9919.4 9921.0 9931.9 8930.9 9358.5 9839.3 9855.2 9894.0 9697.9 9773.9 9783.8 9793.6 9794.5 40603.0 40977.8 40984.0 41049.0 41207.0 3690.8 3828.8 4096.8 4107.2 4289.9 24174.5 32841.2 32935.3 33254.8 34267.5 3192.0 3243.6 3255.9 3363.1 3379.9 510601.0 519629.0 520318.0 520841.0 521627.0 67140.0 68415.7 68472.0 68500.7 68563.7 35683.5 36375.1 36502.8 37760.1 38230.2 16439.9 16558.7 16570.7 16604.5 16631.5 12561.9 12694.0 12703.9 12714.7 12718.6 21064.9 21194.4 21221.4 21279.7 21343.7 7354.0 8623.4 8788.0 8790.6 8844.8 796.4 861.8 940.2 1045.1 1077.3 26769.6 28552.0 30969.8 31309.1 31459.1 967.0 989.5 1002.1 1051.9 1099.2 15914.7 15928.2 16131.8 16190.4 16439.5 690932.0 700496.0 701250.0 702216.0 703002.0 47043.8 59679.4 59862.0 59923.7 60007.5 1251.0 1358.0 1385.6 1386.9 1418.7 31026.1 31811.4 32029.4 32612.7 32751.8 50191.7 50891.2 50918.7 51001.7 51100.5 545997.0 548012.0 548953.0 549316.0 550548.0 24293.8 27128.6 27694.0 27841.3 27912.4 47793.0 48033.8 48852.8 48996.0 49433.8 39376.8 40110.4 40449.8 40801.4 40836.4 32898.8 39653.8 40338.4 40619.6 40943.8 38237.2 39041.6 39275.6 39331.8 39355.2 32973.8 33931.2 35010.6 35153.6 35193.9 23649.9 25483.6 25653.7 25724.3 25764.5 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:35505.09 Median:23301.41 stddev:99136.65 (425.5%) stddev-sans-first:99379.58 19:07:42 INFO - PROCESS | 1650 | Values: 333.2 354.5 362.3 374.4 374.8 14892.6 15216.3 17486.2 20563.3 21237.4 20247.8 20850.0 21339.7 21494.9 21704.0 5373.3 5959.3 6190.3 6205.5 6236.1 5516.9 6003.8 6033.8 6205.9 6208.0 5180.3 5819.7 6116.2 6169.4 6170.3 6171.2 6223.8 6226.6 6233.3 6249.6 4958.5 5749.9 6021.2 6031.3 6039.9 12608.6 12799.9 12869.5 24244.6 25316.2 2023.7 2048.3 2165.1 2169.1 2526.0 10049.1 10224.9 10740.4 13033.7 15152.5 1613.9 1867.1 1920.2 1924.0 1925.6 115520.3 119478.9 123554.0 130103.0 133894.0 11361.5 11479.4 11653.2 11678.7 11847.9 14592.6 14608.1 14647.9 14666.4 14693.3 7070.9 8250.0 8459.2 8488.8 8590.4 7551.2 7827.1 8379.8 8568.6 8587.5 4529.0 4588.8 4603.3 4613.7 4620.6 1642.6 1895.7 1953.1 1954.8 1956.3 865.5 908.4 914.2 940.6 960.6 16087.0 21069.7 21872.8 22618.8 23984.0 854.5 868.0 956.5 956.6 991.9 8164.6 10065.7 11142.6 11168.4 11341.3 577719.0 587994.0 588026.0 597836.0 599159.0 91374.5 139363.0 142708.0 145696.0 149476.8 933.1 981.4 995.9 1010.1 1010.3 19424.7 19646.4 19771.4 19811.3 19827.0 59031.0 59706.4 60154.7 60367.1 60661.3 85151.0 87655.6 87677.9 88114.6 88192.6 7920.9 7937.8 7995.8 8239.3 8379.5 12691.1 13084.5 13265.7 13621.5 13881.0 11363.9 11502.5 11759.9 11792.9 12069.3 11323.9 11587.8 11597.0 11879.4 11959.8 11566.5 11768.5 11953.0 12101.6 12128.2 8215.6 8295.7 8483.3 8494.6 8641.8 11095.8 11404.7 11796.8 11802.8 11848.7 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5655.07 Median:4915.96 stddev:3195.01 (65.0%) stddev-sans-first:3188.77 19:07:42 INFO - PROCESS | 1650 | Values: 1507.0 1537.1 1736.3 1857.9 1900.0 8262.7 8834.8 8851.0 8859.0 8866.9 7693.5 7963.4 7964.7 8088.4 8664.0 2113.7 2158.8 2276.2 2357.6 2361.4 2351.0 2351.9 2352.3 2352.5 2355.5 2299.2 2330.2 2356.3 2362.6 2364.3 2308.3 2332.6 2336.3 2339.3 2343.9 2291.7 2315.3 2320.8 2327.1 2342.3 8650.7 8829.7 8844.0 8845.6 8898.6 4733.6 5032.7 5193.2 5289.5 5293.0 3429.7 3572.3 3644.3 3656.3 3667.6 1043.4 1071.0 1075.4 1097.4 1099.5 11563.6 11671.9 11674.4 11675.8 11693.8 4403.9 4657.5 4713.6 4765.6 4775.1 9772.1 9794.2 9805.4 9809.1 9818.8 5272.3 5275.8 5277.4 5279.4 5280.6 4689.0 4850.9 4850.9 4853.3 4855.0 3517.8 3587.2 3679.8 3731.4 3764.6 1145.3 1147.5 1153.9 1158.7 1160.3 811.3 892.2 901.0 932.7 981.8 5014.9 5598.5 5645.4 5919.4 6000.3 798.0 916.9 933.9 935.6 942.8 3862.7 3865.4 3917.4 4002.0 4028.9 10315.6 10615.8 10888.9 10892.1 10905.5 10831.8 10832.6 10834.6 10841.7 11014.6 8012.0 8204.7 8292.2 8303.8 8331.6 5736.1 5801.0 5873.1 5908.9 5912.4 9702.0 9871.6 10044.4 10128.4 10131.2 10207.4 10357.0 10374.9 10375.7 10389.9 7070.5 7147.2 7148.8 7157.9 7161.7 7043.3 7447.2 7661.5 8052.6 8220.2 7397.3 7404.6 7407.7 7517.8 7609.5 7302.2 7308.5 7332.5 7347.2 7411.9 6684.6 6924.5 6939.8 7030.2 7041.5 4241.5 4858.7 4973.2 4976.1 5139.0 4984.6 5045.4 5048.2 5146.2 5215.3 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4414.61 Median:4722.53 stddev:1352.03 (28.6%) stddev-sans-first:1320.09 19:07:42 INFO - PROCESS | 1650 | Values: 300.3 311.6 313.5 317.4 319.7 4529.5 5324.5 5360.0 5363.3 5390.2 4317.8 5288.8 5301.8 5327.9 5350.3 3864.4 3897.9 4036.4 4041.7 4088.6 3405.4 3643.0 3660.5 4130.6 4144.0 4081.2 4094.9 4097.9 4101.2 4125.0 3680.2 3905.3 4040.8 4111.1 4111.9 3519.0 3537.2 3543.1 3566.3 3569.6 4039.9 4443.0 5151.6 5175.7 5177.9 4057.2 4517.7 4603.4 4697.4 4720.9 4576.3 4640.2 5001.8 5563.8 5568.8 3708.6 3960.4 3973.2 3984.0 3997.6 4933.3 5626.6 5854.3 5908.6 5983.6 3736.9 3994.0 4021.4 4044.5 4056.0 4122.5 4134.9 4137.2 4142.5 4144.8 3925.9 3927.3 4054.2 4166.9 4731.9 5326.3 5630.8 5630.9 5633.0 5652.7 1262.6 1377.1 1398.9 1404.3 1410.6 693.3 861.7 868.6 878.2 881.7 4404.7 4745.6 4908.4 5138.5 5142.8 4553.3 5052.9 5461.6 5487.8 5651.5 5226.9 5295.3 5307.7 5321.3 5343.7 2793.9 2832.9 2872.5 3026.5 3048.4 5276.7 5777.8 6015.0 6049.3 6099.3 5560.2 5882.1 5909.0 5944.8 6017.9 4708.0 5525.4 5934.9 6024.0 6028.8 4833.4 5730.5 5786.0 5831.8 5853.9 5273.8 5387.4 5655.3 5698.0 5704.4 6002.9 6003.8 6026.6 6074.7 6127.8 3178.4 3268.7 3358.6 3405.3 3407.9 4696.7 4733.6 4817.0 4819.0 4889.6 4412.8 4428.7 4767.4 4783.6 4870.2 3981.9 4675.6 4711.8 4714.1 4724.2 4106.7 4304.6 4398.8 4638.4 4718.8 4430.9 4791.6 5171.4 5494.7 5528.3 4729.8 4785.3 5409.6 5432.3 5452.5 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:24124.56 Median:3671.22 stddev:38285.25 (1042.8%) stddev-sans-first:38351.96 19:07:42 INFO - PROCESS | 1650 | Values: 614.6 723.6 798.9 806.4 832.4 15352.2 15629.4 16218.8 16224.2 16273.3 14231.5 14721.3 14892.9 14982.2 15012.7 832.1 841.1 850.0 858.8 860.2 834.6 839.8 841.3 841.3 841.8 893.5 896.9 897.3 897.5 899.7 880.3 886.9 890.2 892.1 894.8 660.6 660.9 661.3 662.4 663.8 13473.5 13916.7 13960.2 14005.6 14010.7 11973.7 12092.3 12148.5 12221.7 12708.8 10751.7 11245.8 11334.8 11346.3 11778.7 3433.2 3765.0 3781.8 3791.8 3956.1 60337.7 103434.0 104340.0 118519.8 119147.0 9701.6 10353.6 10919.8 11555.4 12318.4 18662.3 18893.3 18900.0 18909.4 18930.2 5512.6 6989.4 8215.8 8371.3 8438.0 3593.7 4518.6 4594.3 4632.7 4735.3 3556.8 3624.0 3718.5 3730.8 3732.6 642.6 656.3 670.0 679.3 681.6 533.7 583.8 657.4 687.8 688.7 8248.5 9396.5 9522.7 9667.4 9703.3 608.3 634.1 680.9 689.2 690.9 4415.9 4555.0 4557.9 4653.0 4829.9 129981.0 130780.0 156436.0 157271.0 158896.0 112155.0 123093.0 125694.0 126969.4 127017.4 75866.2 96946.5 99032.0 105757.5 107979.0 9538.6 9992.3 10094.0 10109.5 10132.0 61336.7 63089.4 63185.7 64233.4 64850.0 91417.1 96586.0 97484.5 98356.5 98855.0 10585.5 12745.8 12803.0 13862.7 13900.9 15093.4 15316.7 15457.8 15890.7 15923.1 11221.7 14121.6 14257.6 14419.9 14541.7 14115.9 14145.9 14195.1 14410.0 14614.6 13613.5 13687.2 13879.9 14054.0 14235.4 11357.3 11435.2 11548.4 11713.5 11736.8 9355.9 10387.8 11398.8 11663.5 11683.9 19:07:42 INFO - PROCESS | 1650 | -------- Summary: end -------- 19:07:42 INFO - PROCESS | 1650 | 19:07:42 INFO - TEST-INFO | 1650: exit 0 19:07:42 INFO - TEST-OK | dromaeo_css | took 1118106ms 19:07:42 INFO - TEST-START | kraken 19:07:42 INFO - operating with platform_type : mac_ 19:07:42 INFO - Initialising browser for kraken test... 19:07:42 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpAS1hJX/profile http://localhost:49267/getInfo.html) 19:07:45 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 19:07:45 INFO - PROCESS | 1659 | colorDepth:24 19:07:45 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 19:07:45 INFO - PROCESS | 1659 | __metrics 19:07:45 INFO - PROCESS | 1659 | 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" 19:07:45 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:07:46 INFO - TEST-INFO | 1659: exit 0 19:07:46 INFO - Browser initialized. 19:07:46 INFO - Running cycle 1/1 for kraken test... 19:07:46 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpAS1hJX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:58 INFO - PROCESS | 1660 | RSS: Main: 243425280 19:07:58 INFO - PROCESS | 1660 | 19:08:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:01 INFO - PROCESS | 1660 | RSS: Main: 293568512 19:08:01 INFO - PROCESS | 1660 | 19:08:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 19:08:04 INFO - PROCESS | 1660 | RSS: Main: 300204032 19:08:04 INFO - PROCESS | 1660 | 19:08:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 19:08:09 INFO - PROCESS | 1660 | RSS: Main: 393138176 19:08:09 INFO - PROCESS | 1660 | 19:08:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 19:08:11 INFO - PROCESS | 1660 | RSS: Main: 297693184 19:08:11 INFO - PROCESS | 1660 | 19:08:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:08:14 INFO - PROCESS | 1660 | RSS: Main: 302034944 19:08:14 INFO - PROCESS | 1660 | 19:08:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 19:08:18 INFO - PROCESS | 1660 | RSS: Main: 340815872 19:08:18 INFO - PROCESS | 1660 | 19:08:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 19:08:21 INFO - PROCESS | 1660 | RSS: Main: 341532672 19:08:21 INFO - PROCESS | 1660 | 19:08:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 19:08:25 INFO - PROCESS | 1660 | RSS: Main: 341856256 19:08:25 INFO - PROCESS | 1660 | 19:08:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:08:27 INFO - PROCESS | 1660 | RSS: Main: 314413056 19:08:27 INFO - PROCESS | 1660 | 19:08:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:08:29 INFO - PROCESS | 1660 | RSS: Main: 319713280 19:08:29 INFO - PROCESS | 1660 | 19:08:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:08:32 INFO - PROCESS | 1660 | RSS: Main: 327630848 19:08:32 INFO - PROCESS | 1660 | 19:08:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:08:36 INFO - PROCESS | 1660 | RSS: Main: 331145216 19:08:36 INFO - PROCESS | 1660 | 19:08:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:08:40 INFO - PROCESS | 1660 | RSS: Main: 332210176 19:08:40 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 19:08:41 INFO - PROCESS | 1660 | RSS: Main: 375885824 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | __start_tp_report 19:08:41 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:08:41 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:08:41 INFO - PROCESS | 1660 | |i|pagename|runs| 19:08:41 INFO - PROCESS | 1660 | |0;ai-astar;102;112;113;113;113;114;113;113;112;113 19:08:41 INFO - PROCESS | 1660 | |1;audio-beat-detection;149;108;105;169;106;109;136;112;174;132 19:08:41 INFO - PROCESS | 1660 | |2;audio-dft;184;183;185;184;185;198;245;263;181;184 19:08:41 INFO - PROCESS | 1660 | |3;audio-fft;80;58;64;87;60;58;64;61;84;58 19:08:41 INFO - PROCESS | 1660 | |4;audio-oscillator;86;69;102;71;75;69;101;70;67;103 19:08:41 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;90;91;94;93;134;128;89;87;95;86 19:08:41 INFO - PROCESS | 1660 | |6;imaging-darkroom;94;94;94;95;95;94;94;95;94;94 19:08:41 INFO - PROCESS | 1660 | |7;imaging-desaturate;90;82;83;84;84;84;87;83;86;85 19:08:41 INFO - PROCESS | 1660 | |8;json-parse-financial;55;52;53;53;51;54;56;52;52;53 19:08:41 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;77;82;71;75;72;72;97;106;83 19:08:41 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;85;86;85;83;83;83;86;94;89 19:08:41 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;133;148;149;146;145;152;135;134;170;150 19:08:41 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;165;166;225;213;164;171;167;169;167;165 19:08:41 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;67;67;67;66;66;66;67;66;65 19:08:41 INFO - PROCESS | 1660 | __end_tp_report 19:08:41 INFO - PROCESS | 1660 | __start_cc_report 19:08:41 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2137 19:08:41 INFO - PROCESS | 1660 | __end_cc_report 19:08:41 INFO - PROCESS | 1660 | __startTimestamp1471054121978__endTimestamp 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:08:41 INFO - PROCESS | 1660 | Number of tests: 14 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.49 (3.1%) stddev-sans-first:0.60 19:08:41 INFO - PROCESS | 1660 | Values: 102.0 112.0 113.0 113.0 113.0 114.0 113.0 113.0 112.0 113.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:134.00 stddev:26.47 (19.8%) stddev-sans-first:27.17 19:08:41 INFO - PROCESS | 1660 | Values: 149.0 108.0 105.0 169.0 106.0 109.0 136.0 112.0 174.0 132.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:199.20 Median:185.00 stddev:29.55 (16.0%) stddev-sans-first:30.83 19:08:41 INFO - PROCESS | 1660 | Values: 184.0 183.0 185.0 184.0 185.0 198.0 245.0 263.0 181.0 184.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:67.40 Median:64.00 stddev:11.56 (18.1%) stddev-sans-first:11.32 19:08:41 INFO - PROCESS | 1660 | Values: 80.0 58.0 64.0 87.0 60.0 58.0 64.0 61.0 84.0 58.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:69.50 stddev:15.24 (21.9%) stddev-sans-first:16.07 19:08:41 INFO - PROCESS | 1660 | Values: 86.0 69.0 102.0 71.0 75.0 69.0 101.0 70.0 67.0 103.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:98.70 Median:90.50 stddev:17.32 (19.1%) stddev-sans-first:18.08 19:08:41 INFO - PROCESS | 1660 | Values: 90.0 91.0 94.0 93.0 134.0 128.0 89.0 87.0 95.0 86.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:08:41 INFO - PROCESS | 1660 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.50 stddev:2.35 (2.8%) stddev-sans-first:1.56 19:08:41 INFO - PROCESS | 1660 | Values: 90.0 82.0 83.0 84.0 84.0 84.0 87.0 83.0 86.0 85.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.52 (2.9%) stddev-sans-first:1.45 19:08:41 INFO - PROCESS | 1660 | Values: 55.0 52.0 53.0 53.0 51.0 54.0 56.0 52.0 52.0 53.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:76.00 stddev:12.43 (16.3%) stddev-sans-first:12.23 19:08:41 INFO - PROCESS | 1660 | Values: 67.0 77.0 82.0 71.0 75.0 72.0 72.0 97.0 106.0 83.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:3.37 (3.9%) stddev-sans-first:3.57 19:08:41 INFO - PROCESS | 1660 | Values: 86.0 85.0 86.0 85.0 83.0 83.0 83.0 86.0 94.0 89.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:148.50 stddev:10.93 (7.4%) stddev-sans-first:10.50 19:08:41 INFO - PROCESS | 1660 | Values: 133.0 148.0 149.0 146.0 145.0 152.0 135.0 134.0 170.0 150.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:168.00 stddev:22.30 (13.3%) stddev-sans-first:23.22 19:08:41 INFO - PROCESS | 1660 | Values: 165.0 166.0 225.0 213.0 164.0 171.0 167.0 169.0 167.0 165.0 19:08:41 INFO - PROCESS | 1660 | 19:08:41 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 19:08:41 INFO - PROCESS | 1660 | Values: 68.0 67.0 67.0 67.0 66.0 66.0 66.0 67.0 66.0 65.0 19:08:41 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:08:41 INFO - PROCESS | 1660 | 19:08:42 INFO - TEST-INFO | 1660: exit 0 19:08:42 INFO - TEST-OK | kraken | took 60122ms 19:08:42 INFO - SUITE-END | took 1178s 19:08:43 INFO - Completed test suite (00:19:38) 19:08:43 INFO - javascript benchmark 19:08:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2870.641169853768, 3362.243495213576, 3372.8482218300173, 3399.2861499085193, 3428.8799892003767, 5534.557235421166, 5590.18942253373, 5653.321601407832, 5661.915071273934, 5686.488216060554, 5646.858828529279, 5689.0, 5695.231143795586, 5698.487136157745, 5703.40114288, 5151.613628977823, 5195.376554813409, 5227.503387178212, 5231.346081739783, 5268.8155914542995, 5240.240165176047, 5246.186841039645, 5246.239295302179, 5248.8687782805355, 5251.947480525184, 5133.3326667533165, 5150.124478838607, 5152.433232344439, 5160.096983027974, 5242.056429842627, 5241.8427447176455, 5255.343082114736, 5260.579153667697, 5293.841184764463, 5330.173823057432, 5043.924341134886, 5072.898542029176, 5079.619028572872, 5171.482851714836, 5184.922226166611, 5506.063969125258, 5553.8333849984565, 5587.8603034924045, 5589.469000444951, 5646.491815736563, 6207.720652570645, 6219.315875253718, 6222.471089957359, 6231.626102433868, 6236.750529978795, 6125.6017461851925, 6150.815475535741, 6198.256209254873, 6218.129461875295, 6288.87422251557, 5757.942420575824, 5766.913496297559, 5779.306483221956, 5780.16187652788, 5782.3639399666, 5423.538999185034, 6315.526335524854, 6324.9051264231075, 6333.20834895638, 6340.873182536324, 5054.82308119214, 5942.049272116441, 5963.7912673056235, 6076.553590509196, 6100.786472473531, 6250.531210159256, 6254.468370188494, 6255.24937007562, 6282.465990390777, 6298.464630506458, 6134.7852825151795, 6206.255249370104, 6206.689665516707, 6219.036049412348, 6228.408301211378, 6051.969740151271, 6055.6063855849225, 6065.787697430657, 6239.937600623935, 6247.3440288769025, 4883.316335713003, 4885.487023862514, 4914.189158788759, 4916.631252656065, 4920.803167873245, 4429.49060857999, 4447.955520444819, 4466.017476155241, 4472.44094488189, 4478.104379124188, 5683.26117604717, 5693.743781529841, 5694.715264236855, 5698.629589076697, 5702.0, 6144.433169541739, 6167.044108163242, 6239.719212635442, 6245.0, 6301.180846490019, 5725.799597014085, 5744.913826292609, 5749.453801888814, 5750.654960702372, 5768.567357448208, 5296.814611488579, 5413.702246376487, 5428.2671839301975, 5440.809571664972, 5446.7276636168035, 6231.158793562901, 6305.306416294204, 6308.779192728324, 6320.273168585488, 6321.11504389395, 6272.905906411499, 6287.937120628735, 6298.0867774171575, 6302.243730752156, 6311.990081586924, 6298.275698294755, 6303.527235457267, 6323.399277068671, 6328.398802113792, 6350.968245158745, 6023.397660233942, 6051.788187413419, 6073.240844894388, 6125.050617154348, 6140.600860944024, 5637.633553818989, 6107.0, 6144.832481828438, 6255.124282600531, 6269.592476489013, 5189.740512974261, 5926.851828704317, 6076.51387888959, 6083.6349819011, 6102.962496375716, 5925.0, 6046.425589568984, 6069.757209711562, 6109.75560977556, 6115.785947491817, 5791.652500849962, 5810.186573879576, 5816.331121920864, 5816.447437493432, 5902.734376952962, 5947.762089516371, 5956.523478121827, 5957.7319020645045, 5960.254968128984, 5969.641821490724, 5743.8851222976145, 5808.15781711641, 5847.766089356378, 5849.268841394825, 5851.093080572518], "value": 5656.197123444527, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.90950900310935, 484.85212010336835, 523.0898237067504, 533.5411546070379, 542.1434134068172, 30975.380492390166, 32734.67265327346, 40565.985850353754, 42319.57680423195, 42353.576464235346, 43966.0, 44548.554514454845, 44570.33144502835, 44576.88557786057, 44740.0, 9381.76545586359, 9536.141747242747, 9538.856917146248, 9547.0, 9557.61769529218, 9714.61141554337, 9834.26243031772, 9841.163501102415, 9849.310548261625, 9866.161376283024, 9848.655297064604, 9902.900970990306, 9919.40483570988, 9921.007899210057, 9931.900680993205, 8930.883639545056, 9358.485283309416, 9839.311248212627, 9855.162311203556, 9894.0, 9697.90302096977, 9773.853392199122, 9783.755406114833, 9793.559289831903, 9794.510274486318, 40603.0, 40977.79511102426, 40984.0, 41048.97377565555, 41206.9698257543, 3690.815459227028, 3828.7511311764647, 4096.836126554934, 4107.158032603339, 4289.871303860889, 24174.51650966971, 32841.17897052569, 32935.34129317424, 33254.83372583122, 34267.485987710206, 3192.0, 3243.6269828969735, 3255.869765209389, 3363.0751543325537, 3379.9493007604906, 510601.0, 519629.0, 520318.0, 520841.0, 521627.0, 67140.0, 68415.65792171007, 68472.0, 68500.6574967122, 68563.65718171476, 35683.4647480288, 36375.09062273411, 36502.81748591293, 37760.055998600255, 38230.23539529194, 16439.91780041092, 16558.6688266234, 16570.668586628442, 16604.50186494407, 16631.501054968372, 12561.874381256253, 12694.0, 12703.872961270268, 12714.745705086032, 12718.554850580185, 21064.894675526524, 21194.364169074954, 21221.36335909925, 21279.680804787942, 21343.67984480234, 7353.963230183922, 8623.396362254582, 8787.956060219658, 8790.648374065093, 8844.823103538021, 796.366888323784, 861.8319427711536, 940.1632547033146, 1045.1273186889032, 1077.283606401734, 26769.598456023174, 28552.0, 30969.8451507741, 31309.060728178192, 31459.056228313188, 966.9806603868024, 989.475577943691, 1002.1281485108003, 1051.9316244444087, 1099.2250463423427, 15914.681706365807, 15928.203589820463, 16131.758023629654, 16190.433334833224, 16439.506814795575, 690932.0, 700496.0, 701250.0, 702216.0, 703002.0, 47043.76478117656, 59679.403205967385, 59862.0, 59923.700381497816, 60007.49981250503, 1250.9937450312873, 1357.9796303055264, 1385.5704731533258, 1386.9306534673428, 1418.7091646212707, 31026.069217923497, 31811.36377272488, 32029.35941281208, 32612.673873261912, 32751.836240818644, 50191.74904125456, 50891.2366314513, 50918.745406272734, 51001.744991274805, 51100.488995109576, 545997.0, 548012.0, 548953.0, 549316.0, 550548.0, 24293.75706242915, 27128.593071103554, 27694.0, 27841.303967400978, 27912.441751164457, 47793.0, 48033.79915502141, 48852.755736221094, 48996.0, 49433.752831235615, 39376.803115984236, 40110.39834402428, 40449.79775101106, 40801.41532064566, 40836.387454187614, 32898.83550582232, 39653.81038568848, 40338.394924075576, 40619.59380406158, 40943.7952810246, 38237.23525529418, 39041.60958390493, 39275.60724392834, 39331.803340984254, 39355.21289574135, 32973.83513082419, 33931.15172120717, 35010.64989350074, 35153.64846351504, 35193.94418167459, 23649.881750591136, 25483.58632176918, 25653.743462565137, 25724.295445571846, 25764.484710305314], "value": 20193.422738104935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.17871446883436, 354.5337880686895, 362.2718336144349, 374.4121728885649, 374.78075325934327, 14892.627684307885, 15216.315265813037, 17486.21312040958, 20563.28028519002, 21237.36287911365, 20247.797522024775, 20850.0, 21339.67990480144, 21494.89252553735, 21704.0, 5373.301470808789, 5959.255093113372, 6190.288116866551, 6205.472534834545, 6236.126942228069, 5516.889662206773, 6003.849903752397, 6033.7888173913925, 6205.906911396332, 6208.0, 5180.274761533369, 5819.709014549277, 6116.204893363856, 6169.383061693839, 6170.2904166021035, 6171.166892469527, 6223.844403889939, 6226.564140510166, 6233.345498722614, 6249.562530622836, 4958.454569997298, 5749.885002299972, 6021.217241758543, 6031.276246850362, 6039.8792024159275, 12608.558700445441, 12799.936000320033, 12869.485220591165, 24244.636330455058, 25316.24051278446, 2023.676211806104, 2048.3240530624857, 2165.101482884601, 2169.0781417897488, 2525.9621105683427, 10049.095581397636, 10224.94887525565, 10740.355578665227, 13033.674158146028, 15152.469663561722, 1613.9193040347936, 1867.131783720563, 1920.1839218332152, 1923.9519012024812, 1925.5763731979082, 115520.26719599213, 119478.86750077055, 123554.0, 130103.04845427326, 133894.0, 11361.54553817838, 11479.426028698532, 11653.184277100521, 11678.70803229926, 11847.940760296317, 14592.562223133324, 14608.05047671898, 14647.853521464862, 14666.413343466142, 14693.338799754038, 7070.92929070713, 8249.958750206211, 8459.238668519863, 8488.83022339562, 8590.441621294596, 7551.169371369145, 7827.060752709711, 8379.790505237417, 8568.57157142153, 8587.48475091484, 4529.0, 4588.793504292248, 4603.332516785114, 4613.746243956614, 4620.584147426748, 1642.5565097423867, 1895.715642653613, 1953.082051435823, 1954.8142926421967, 1956.2957335359258, 865.4504389712451, 908.40953380302, 914.2366124286162, 940.5767404667927, 960.6493629825063, 16087.0, 21069.683954740693, 21872.781272187392, 22618.815519129013, 23984.0, 854.5214679779343, 867.9696210632598, 956.4596003258137, 956.6269155029544, 991.9107280344659, 8164.6325915333955, 10065.69802905914, 11142.610008649659, 11168.385738784282, 11341.26281791681, 577719.0, 587994.0, 588026.0, 597836.0, 599159.0, 91374.53785253856, 139363.0, 142708.0, 145696.0, 149476.75784863235, 933.1088810186255, 981.3915372469115, 995.9352642078387, 1010.1060561403211, 1010.2974543097185, 19424.70862937029, 19646.41060768122, 19771.40685779429, 19811.306604269357, 19827.0, 59031.0, 59706.40293597182, 60154.69922650359, 60367.09449358175, 60661.32725400291, 85151.0, 87655.56172219098, 87677.86254962091, 88114.55942720246, 88192.55903720697, 7920.910724984837, 7937.8412431752195, 7995.800104997422, 8239.258466738022, 8379.539125348165, 12691.111622186727, 13084.476620935056, 13265.668358290734, 13621.455141794217, 13881.0, 11363.886361136283, 11502.48238829238, 11759.882401175879, 11792.882071179178, 12069.336186509827, 11323.943380283046, 11587.768244634892, 11597.0, 11879.406029698308, 11959.820602690792, 11566.479508421973, 11768.470418831344, 11953.0, 12101.636950891487, 12128.21166624202, 8215.589220539068, 8295.668173273001, 8483.27892129176, 8494.575271236537, 8641.833352497333, 11095.833562496407, 11404.714882128013, 11796.823047654463, 11802.82295765547, 11848.70378240551], "value": 9645.354890180683, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.02796696131, 1537.085434166671, 1736.3054778088763, 1857.9349722759707, 1900.0309841980595, 8262.669493220272, 8834.77913052174, 8851.026387097421, 8858.95570522148, 8866.866996995042, 7693.499922505033, 7963.362930965523, 7964.721234756785, 8088.352931765459, 8664.046954834963, 2113.661814109743, 2158.84888057836, 2276.2147059264526, 2357.5992081346126, 2361.421451744325, 2350.976490235101, 2351.9412014699597, 2352.329586067971, 2352.4589344450787, 2355.528894221163, 2299.195281651417, 2330.1611419888827, 2356.3402247370764, 2362.574736547421, 2364.326167042393, 2308.2613563659634, 2332.5568142052957, 2336.3458231695067, 2339.2982105368324, 2343.94140146496, 2291.667708182304, 2315.270689732729, 2320.8375413721046, 2327.104064934995, 2342.26218741096, 8650.740477785677, 8829.735107946772, 8844.027157012724, 8845.557722113934, 8898.644054237822, 4733.55626533907, 5032.723200223986, 5193.195054766517, 5289.497497737709, 5293.04725149473, 3429.725621950238, 3572.3391172633146, 3644.27114577083, 3656.323580137683, 3667.6332366763377, 1043.4313299251926, 1071.0, 1075.37090801881, 1097.401915955806, 1099.455769394147, 11563.595274165364, 11671.883281167164, 11674.41627918609, 11675.766484670345, 11693.766124677544, 4403.889902752393, 4657.4876763555985, 4713.622910167179, 4765.571098601143, 4775.092732380836, 9772.12050915421, 9794.21646268311, 9805.362651427635, 9809.068138526829, 9818.754531136636, 5272.340957380327, 5275.788968441219, 5277.419483856773, 5279.392869819943, 5280.551153152025, 4688.9531104689195, 4850.854474365775, 4850.854474365775, 4853.320535125085, 4855.028994201174, 3517.824108794601, 3587.192881601619, 3679.7608155470343, 3731.402975523903, 3764.6235376462137, 1145.255583870474, 1147.4893672315907, 1153.8903804138592, 1158.692946369213, 1160.3038177093845, 811.3103861717493, 892.205936716323, 900.9639614415481, 932.7295084425441, 981.8134554434635, 5014.924776128361, 5598.496135347838, 5645.4072322405555, 5919.4080591940465, 6000.309964354068, 798.0024968789014, 916.8808054952947, 933.9392939458913, 935.5836652689624, 942.844430668932, 3862.6523612874416, 3865.362215234511, 3917.4123881417445, 4001.979990100031, 4028.879133625996, 10315.587376505006, 10615.787684246272, 10888.891111088788, 10892.074173695324, 10905.509252083675, 10831.754348249895, 10832.566697332177, 10834.566617335377, 10841.67474975752, 11014.614488492864, 8011.958445402177, 8204.671813127527, 8292.212239837207, 8303.8339233215, 8331.625281828552, 5736.139579063007, 5800.970995144998, 5873.148393482836, 5908.911366329595, 5912.438318359749, 9701.951490242503, 9871.555779989774, 10044.447555384524, 10128.392296462236, 10131.240156988402, 10207.387556746618, 10356.964303569583, 10374.948125259627, 10375.688729338131, 10389.896101039196, 7070.540414873017, 7147.249538798559, 7148.821279468055, 7157.928420715726, 7161.677724502514, 7043.2604576518725, 7447.218042105501, 7661.463697541119, 8052.637631306488, 8220.177982201732, 7397.26027397256, 7404.557265640573, 7407.740729074457, 7517.849643007, 7609.505382150142, 7302.233265507258, 7308.488405811542, 7332.523385979895, 7347.228541003117, 7411.851762964603, 6684.565503242274, 6924.549904256207, 6939.791806245821, 7030.156381234285, 7041.507094503336, 4241.512226094039, 4858.684185527929, 4973.229149481836, 4976.129177393869, 5138.997895410359, 4984.576311013604, 5045.445001049955, 5048.217526283432, 5146.150885104065, 5215.348081489814], "value": 4535.421992119102, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.2733385207797, 311.6244924865537, 313.46397660001395, 317.40169779964776, 319.6739325887596, 4529.479109902362, 5324.54741346985, 5359.973200133999, 5363.283749200257, 5390.245365648808, 4317.76252306123, 5288.8413347599635, 5301.761420736067, 5327.866803329919, 5350.2777125088105, 3864.439656249849, 3897.941530877039, 4036.354183330653, 4041.7372870763306, 4088.6268828216694, 3405.3700065487965, 3642.9981755017316, 3660.4875317455576, 4130.586941305875, 4143.979280103596, 4081.244969680604, 4094.897627559305, 4097.9180416391655, 4101.241270364977, 4124.97937510312, 3680.2087551176623, 3905.3165696003084, 4040.7979601020124, 4111.116110036357, 4111.917761644765, 3518.982405087984, 3537.231384307846, 3543.114221444639, 3566.268914872445, 3569.5716514018222, 4039.858604948842, 4442.955570444286, 5151.6136289778415, 5175.687559961628, 5177.948220517785, 4057.2494088593407, 4517.683762136652, 4603.378543896564, 4697.365855609517, 4720.858374248778, 4576.306766496385, 4640.213517795673, 5001.849574597863, 5563.833085007456, 5568.805091821767, 3708.610595887447, 3960.3663413853646, 3973.205358928226, 3983.98008009961, 3997.620226078518, 4933.324673118514, 5626.606137570372, 5854.268216472983, 5908.586398952075, 5983.636463596855, 3736.925261494755, 3994.0, 4021.4369988201674, 4044.4944381952255, 4055.959440405617, 4122.505299364096, 4134.875953721393, 4137.1518838638, 4142.46148000758, 4144.7513149211145, 3925.941110883339, 3927.2930872443235, 4054.189162167578, 4166.937301083701, 4731.858044258678, 5326.280952071498, 5630.8029218977135, 5630.873825234969, 5632.97183514088, 5652.689102099342, 1262.551794113095, 1377.1461693749939, 1398.9440422383195, 1404.2908331292836, 1410.5909286307062, 693.3482526425145, 861.6639510590875, 868.5874209750444, 878.2359347367703, 881.6605606841413, 4404.691671582958, 4745.6440766942615, 4908.410990681141, 5138.4604616514725, 5142.845714628567, 4553.33976573395, 5052.898942021213, 5461.644993075437, 5487.835364939058, 5651.463111004448, 5226.92159617606, 5295.311609490742, 5307.7080760557765, 5321.281626980308, 5343.732813359395, 2793.916182514528, 2832.895170883357, 2872.4973129702216, 3026.5157574787927, 3048.3750831079574, 5276.736163191902, 5777.826665200051, 6015.0, 6049.274087109575, 6099.268087829489, 5560.165975103767, 5882.147123656035, 5909.0, 5944.821655350346, 6017.909731354033, 4708.011317623269, 5525.4474552544425, 5934.940650593525, 6023.981966041425, 6028.849278768066, 4833.419989601271, 5730.5129064030025, 5785.971070144622, 5831.766729330949, 5853.9121913172185, 5273.78904843802, 5387.3804512481565, 5655.349634792052, 5697.971510142423, 5704.429557044263, 6002.939970600238, 6003.8198854034445, 6026.608270462406, 6074.7266373012435, 6127.844160257382, 3178.409596179199, 3268.7385009199684, 3358.614287689376, 3405.318936212817, 3407.8807241747413, 4696.671233013656, 4733.597644200281, 4816.975915120402, 4818.988132417239, 4889.62853016294, 4412.84555040572, 4428.667849911205, 4767.380240568704, 4783.593394561502, 4870.220764677776, 3981.92036159281, 4675.555822196886, 4711.811527538997, 4714.057188562233, 4724.204802175152, 4106.733062350938, 4304.612584867377, 4398.802053907646, 4638.420197475316, 4718.834840780556, 4430.851037246997, 4791.592714619296, 5171.422716071635, 5494.697791621499, 5528.336599607912, 4729.787159577896, 4785.282207668877, 5409.621326507264, 5432.348118225838, 5452.482014208644], "value": 3991.545064112874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.5667304550291, 723.6056349289637, 798.8562058829408, 806.431465816599, 832.392353581885, 15352.232388380578, 15629.374825007, 16218.7567186492, 16224.188790560484, 16273.349066037374, 14231.501897433593, 14721.337539810707, 14892.851071489282, 14982.175980321077, 15012.699746005073, 832.126267419209, 841.1210285251926, 849.9830003399928, 858.7981824271292, 860.2043110123142, 834.5952213176605, 839.7648658375665, 841.2765022081036, 841.3227351981683, 841.7853447370912, 893.5442924108719, 896.932730045246, 897.2597606974273, 897.5018864530183, 899.6716198587534, 880.282569705691, 886.9246114080311, 890.247740659142, 892.1390857822167, 894.7718331825408, 660.5904339309611, 660.867826434715, 661.2692974263466, 662.3674390956674, 663.7709990053423, 13473.528426505123, 13916.721665566633, 13960.232187229698, 14005.649858753512, 14010.719785604231, 11973.700657483545, 12092.33492157931, 12148.514059437612, 12221.694457638541, 12708.809367859489, 10751.677449676523, 11245.775084498346, 11334.829977550344, 11346.262492938014, 11778.705532361673, 3433.2275238071484, 3764.981175094107, 3781.7541859779303, 3791.7724936503623, 3956.090099277182, 60337.698311508604, 103434.0, 104340.0, 118519.8148018526, 119147.0, 9701.6119355225, 10353.585856565804, 10919.781604367869, 11555.42222888869, 12318.384080795924, 18662.34681786131, 18893.338733144275, 18900.0, 18909.43271701851, 18930.24279028851, 5512.590729499862, 6989.405900498515, 8215.753527394188, 8371.330293576499, 8438.0, 3593.6765691087926, 4518.6385089193445, 4594.264917613166, 4632.745199014019, 4735.337052812609, 3556.76881002734, 3623.9818800906355, 3718.4608231806237, 3730.757500762442, 3732.5520937487677, 642.6369101457755, 656.3370995294787, 669.9564528305644, 679.2562144451817, 681.5501768832547, 533.6744585802658, 583.8365257727843, 657.3853447027045, 687.8417963868338, 688.6625553478859, 8248.505089694638, 9396.48319342429, 9522.669698601687, 9667.371620844764, 9703.320767546345, 608.270075908911, 634.1344065350763, 680.8799524781805, 689.2487188963953, 690.944724422045, 4415.889602759892, 4555.043440877386, 4557.886052848706, 4653.0, 4829.855104346875, 129981.0, 130780.0, 156436.0, 157271.0, 158896.0, 112155.0, 123093.0, 125694.0, 126969.36515317549, 127017.36491317484, 75866.20668966419, 96946.51526742321, 99032.0, 105757.47121264345, 107979.0, 9538.618455261712, 9992.300538962203, 10094.0, 10109.49452527356, 10132.0, 61336.69331653313, 63089.42276442942, 63185.68407157935, 64233.35766642274, 64850.0, 91417.08582914085, 96586.0, 97484.5125774395, 98356.50821745845, 98855.01144988458, 10585.470726463493, 12745.808812868, 12803.0, 13862.65343366424, 13900.860991390362, 15093.39626414931, 15316.693666126392, 15457.84542154609, 15890.68218635644, 15923.124228167562, 11221.66335009951, 14121.576352709435, 14257.572272831832, 14419.927900360432, 14541.709165816414, 14115.858841411455, 14145.929270353583, 14195.148291102567, 14410.0, 14614.561563153122, 13613.52352667652, 13687.247201404018, 13879.930600346934, 14054.0, 14235.35940882642, 11357.318560886373, 11435.199536032731, 11548.42257887119, 11713.531458741554, 11736.823947640964, 9355.858722456513, 10387.771870947256, 11398.829017564578, 11663.533458661559, 11683.941580292045], "value": 7756.422100262426, "unit": "score"}], "name": "dromaeo_css", "value": 7326.898546503112, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 112.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 108.0, 105.0, 169.0, 106.0, 109.0, 136.0, 112.0, 174.0, 132.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 184.0, 185.0, 198.0, 245.0, 263.0, 181.0, 184.0], "value": 199.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 64.0, 87.0, 60.0, 58.0, 64.0, 61.0, 84.0, 58.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 102.0, 71.0, 75.0, 69.0, 101.0, 70.0, 67.0, 103.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 94.0, 93.0, 134.0, 128.0, 89.0, 87.0, 95.0, 86.0], "value": 98.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 82.0, 83.0, 84.0, 84.0, 84.0, 87.0, 83.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 51.0, 54.0, 56.0, 52.0, 52.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 82.0, 71.0, 75.0, 72.0, 72.0, 97.0, 106.0, 83.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 83.0, 83.0, 83.0, 86.0, 94.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 148.0, 149.0, 146.0, 145.0, 152.0, 135.0, 134.0, 170.0, 150.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 225.0, 213.0, 164.0, 171.0, 167.0, 169.0, 167.0, 165.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1476.7, "alertThreshold": 2.0}]} 19:08:43 INFO - Return code: 0 19:08:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:08:43 INFO - # TBPL SUCCESS # 19:08:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:43 INFO - 2016-08-12 19:08:43.330 screenresolution[1664:9718] starting screenresolution argv=screenresolution get 19:08:43 INFO - 2016-08-12 19:08:43.333 screenresolution[1664:9718] Display 0: 1600x1200x32@60 19:08:43 INFO - 2016-08-12 19:08:43.339 screenresolution[1665:9722] starting screenresolution argv=screenresolution list 19:08:43 INFO - Available Modes on Display 0 19:08:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:43 INFO - Intel Iris: 19:08:43 INFO - Chipset Model: Intel Iris 19:08:43 INFO - Type: GPU 19:08:43 INFO - Bus: Built-In 19:08:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:43 INFO - Vendor: Intel (0x8086) 19:08:43 INFO - Device ID: 0x0a2e 19:08:43 INFO - Revision ID: 0x0009 19:08:43 INFO - Displays: 19:08:43 INFO - Display: 19:08:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:43 INFO - Display Serial Number: 1600x1200 60 19:08:43 INFO - Main Display: Yes 19:08:43 INFO - Mirror: Off 19:08:43 INFO - Online: Yes 19:08:43 INFO - Rotation: Supported 19:08:43 INFO - Return code: 0 19:08:43 INFO - Running post-action listener: _resource_record_post_action 19:08:43 INFO - [mozharness: 2016-08-13 02:08:43.576172Z] Finished run-tests step (success) 19:08:43 INFO - Running post-run listener: _resource_record_post_run 19:08:43 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37643264; Write bytes: 703425536; Read time: 385; Write time: 2114 19:08:43 INFO - TinderboxPrint: CPU usage
53.4% 19:08:43 INFO - TinderboxPrint: I/O read bytes / time
37,643,264 / 385 19:08:43 INFO - TinderboxPrint: I/O write bytes / time
703,425,536 / 2,114 19:08:43 INFO - TinderboxPrint: CPU idle
2,262.7 (46.6%) 19:08:43 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 19:08:43 INFO - TinderboxPrint: CPU user
1,624.5 (33.5%) 19:08:43 INFO - TinderboxPrint: Swap in / out
441,577,472 / 0 19:08:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393431552; Write bytes: 387352576; Read time: 29509; Write time: 864 19:08:43 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35832832; Write bytes: 296084480; Read time: 364; Write time: 1199 19:08:44 INFO - Running post-run listener: _upload_blobber_files 19:08:44 INFO - Blob upload gear active. 19:08:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:08:44 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 19:08:44 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:08:44 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:08:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:45 INFO - (blobuploader) - INFO - Done attempting. 19:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:08:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:46 INFO - (blobuploader) - INFO - Done attempting. 19:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:49 INFO - (blobuploader) - INFO - Done attempting. 19:08:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:49 INFO - Return code: 0 19:08:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb029ea31958d7dae1304a1d78a367bb7174b689d78ff13043c1e696783d80fa0a77368695815557be7a5037599d27d85d618c11c0abec657049a6a8e7f18c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a14eabeb975f4d53013514f0dcf8104783fa340914f82bcc1b5d5218159fd3ad6a7a190192087dd1a511f4131c994f705a8421b8dacdd2e1d32987657ff08f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdea0d17387b8fdfbce13e0a943dfb2aba7be0ba6ac83cb049ffaefa873541c08d882950b9de6374660547f2fd6189948799a8fa780b53fe2c80571cafafff12"} 19:08:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:49 INFO - Contents: 19:08:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb029ea31958d7dae1304a1d78a367bb7174b689d78ff13043c1e696783d80fa0a77368695815557be7a5037599d27d85d618c11c0abec657049a6a8e7f18c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a14eabeb975f4d53013514f0dcf8104783fa340914f82bcc1b5d5218159fd3ad6a7a190192087dd1a511f4131c994f705a8421b8dacdd2e1d32987657ff08f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdea0d17387b8fdfbce13e0a943dfb2aba7be0ba6ac83cb049ffaefa873541c08d882950b9de6374660547f2fd6189948799a8fa780b53fe2c80571cafafff12"} 19:08:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:49 INFO - Copying logs to upload dir... 19:08:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.453933 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-12 19:08:49.833468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:08:49.836957) ========= 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.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/Autoland/sha512/eb029ea31958d7dae1304a1d78a367bb7174b689d78ff13043c1e696783d80fa0a77368695815557be7a5037599d27d85d618c11c0abec657049a6a8e7f18c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a14eabeb975f4d53013514f0dcf8104783fa340914f82bcc1b5d5218159fd3ad6a7a190192087dd1a511f4131c994f705a8421b8dacdd2e1d32987657ff08f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdea0d17387b8fdfbce13e0a943dfb2aba7be0ba6ac83cb049ffaefa873541c08d882950b9de6374660547f2fd6189948799a8fa780b53fe2c80571cafafff12"} build_url:https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010950 build_url: 'https://queue.taskcluster.net/v1/task/A2lsnt5KTqmYoSehZft6rA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb029ea31958d7dae1304a1d78a367bb7174b689d78ff13043c1e696783d80fa0a77368695815557be7a5037599d27d85d618c11c0abec657049a6a8e7f18c41", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a14eabeb975f4d53013514f0dcf8104783fa340914f82bcc1b5d5218159fd3ad6a7a190192087dd1a511f4131c994f705a8421b8dacdd2e1d32987657ff08f71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bdea0d17387b8fdfbce13e0a943dfb2aba7be0ba6ac83cb049ffaefa873541c08d882950b9de6374660547f2fd6189948799a8fa780b53fe2c80571cafafff12"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:08:49.872210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:08:49.872797) ========= 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.l8oPJeoQWU/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.kI0c0Oap39/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005147 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:08:49.923858) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:08:49.924155) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:08:49.924446) ========= ========= Total master_lag: 0.18 =========