builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0174 starttime: 1472512266.12 results: success (0) buildid: 20160829144430 builduid: d4b0375e1dc2462e84cbe8a82a584158 revision: 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.116979) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.117460) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.117762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.143374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.143793) ========= 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.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-29 16:11:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.90M=0.001s 2016-08-29 16:11:06 (8.90 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.252997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.253321) ========= 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.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.027970 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.301865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:06.302234) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a --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 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-29 16:11:06,376 truncating revision to first 12 chars 2016-08-29 16:11:06,376 Setting DEBUG logging. 2016-08-29 16:11:06,376 attempt 1/10 2016-08-29 16:11:06,377 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/29f27e5ab217?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 16:11:06,994 unpacking tar archive at: autoland-29f27e5ab217/testing/mozharness/ program finished with exit code 0 elapsedTime=0.862085 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:07.186942) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:07.187268) ========= script_repo_revision: 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:07.187676) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:07.187952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:11:07.206602) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-29 16:11:07.206900) ========= /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.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:07 INFO - MultiFileLogger online at 20160829 16:11:07 in /builds/slave/test 16:11:07 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 16:11:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:11:07 INFO - {'append_to_log': False, 16:11:07 INFO - 'base_work_dir': '/builds/slave/test', 16:11:07 INFO - 'blob_upload_branch': 'Autoland', 16:11:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:11:07 INFO - 'branch': 'Autoland', 16:11:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:11:07 INFO - 'config_files': ('talos/mac_config.py',), 16:11:07 INFO - 'default_actions': ('clobber', 16:11:07 INFO - 'read-buildbot-config', 16:11:07 INFO - 'download-and-extract', 16:11:07 INFO - 'populate-webroot', 16:11:07 INFO - 'create-virtualenv', 16:11:07 INFO - 'install', 16:11:07 INFO - 'run-tests'), 16:11:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:11:07 INFO - 'download_minidump_stackwalk': True, 16:11:07 INFO - 'download_symbols': 'ondemand', 16:11:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:11:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:11:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:11:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:11:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:11:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:11:07 INFO - 'installer_path': 'installer.exe', 16:11:07 INFO - 'log_level': 'info', 16:11:07 INFO - 'log_name': 'talos', 16:11:07 INFO - 'log_to_console': True, 16:11:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:11:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:11:07 INFO - 'opt_config_files': (), 16:11:07 INFO - 'pip_index': False, 16:11:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:11:07 INFO - 'cmd': ('bash', 16:11:07 INFO - '-c', 16:11:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:11:07 INFO - 'enabled': True, 16:11:07 INFO - 'halt_on_failure': False, 16:11:07 INFO - 'name': 'check_screen_resolution'},), 16:11:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:11:07 INFO - 'cmd': ('bash', 16:11:07 INFO - '-c', 16:11:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:11:07 INFO - 'enabled': True, 16:11:07 INFO - 'halt_on_failure': False, 16:11:07 INFO - 'name': 'check_screen_resolution'},), 16:11:07 INFO - 'run_cmd_checks_enabled': True, 16:11:07 INFO - 'sps_profile': False, 16:11:07 INFO - 'sps_profile_interval': 0, 16:11:07 INFO - 'suite': 'dromaeojs', 16:11:07 INFO - 'system_bits': '32', 16:11:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:11:07 INFO - 'title': 't-yosemite-r7-0174', 16:11:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:11:07 INFO - 'use_talos_json': True, 16:11:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:11:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:11:07 INFO - 'work_dir': 'build'} 16:11:07 INFO - [mozharness: 2016-08-29 23:11:07.402111Z] Running clobber step. 16:11:07 INFO - Running pre-action listener: _resource_record_pre_action 16:11:07 INFO - Running main action method: clobber 16:11:07 INFO - rmtree: /builds/slave/test/build 16:11:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:11:09 INFO - Running post-action listener: _resource_record_post_action 16:11:09 INFO - [mozharness: 2016-08-29 23:11:09.477632Z] Finished clobber step (success) 16:11:09 INFO - [mozharness: 2016-08-29 23:11:09.477836Z] Running read-buildbot-config step. 16:11:09 INFO - Running pre-action listener: _resource_record_pre_action 16:11:09 INFO - Running main action method: read_buildbot_config 16:11:09 INFO - Using buildbot properties: 16:11:09 INFO - { 16:11:09 INFO - "project": "", 16:11:09 INFO - "product": "firefox", 16:11:09 INFO - "script_repo_revision": "production", 16:11:09 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:11:09 INFO - "repository": "", 16:11:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:11:09 INFO - "buildid": "20160829144430", 16:11:09 INFO - "pgo_build": "False", 16:11:09 INFO - "basedir": "/builds/slave/test", 16:11:09 INFO - "buildnumber": 161, 16:11:09 INFO - "slavename": "t-yosemite-r7-0174", 16:11:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:11:09 INFO - "platform": "yosemite_r7", 16:11:09 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:11:09 INFO - "branch": "autoland", 16:11:09 INFO - "repo_path": "integration/autoland", 16:11:09 INFO - "revision": "29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a", 16:11:09 INFO - "stage_platform": "macosx64", 16:11:09 INFO - "builduid": "d4b0375e1dc2462e84cbe8a82a584158", 16:11:09 INFO - "slavebuilddir": "test" 16:11:09 INFO - } 16:11:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:11:09 INFO - Running post-action listener: _resource_record_post_action 16:11:09 INFO - [mozharness: 2016-08-29 23:11:09.483524Z] Finished read-buildbot-config step (success) 16:11:09 INFO - [mozharness: 2016-08-29 23:11:09.483725Z] Running download-and-extract step. 16:11:09 INFO - Running pre-action listener: _resource_record_pre_action 16:11:09 INFO - Running main action method: download_and_extract 16:11:09 INFO - mkdir: /builds/slave/test/build/tests 16:11: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')]} 16:11:09 INFO - trying https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:11:09 INFO - Downloading https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:10 INFO - Downloaded 1199 bytes. 16:11:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:11:10 INFO - Using the following test package requirements: 16:11:10 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:11:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:11:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:11:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'jsshell-mac.zip'], 16:11:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:11:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:11:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:11:10 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:11:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:11:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:11:10 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:11:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:11:10 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')]} 16:11:10 INFO - trying https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:11:10 INFO - Downloading https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:11 INFO - Downloaded 16372552 bytes. 16:11:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:11:18 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 16:11:18 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')]} 16:11:18 INFO - trying https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:11:18 INFO - Downloading https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:19 INFO - Downloaded 16372552 bytes. 16:11:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:25 INFO - trying https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:26 INFO - Downloaded 14413998 bytes. 16:11:26 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:11:26 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')]} 16:11:26 INFO - trying https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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 16:11:29 INFO - Downloaded 111558230 bytes. 16:11:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:11:29 INFO - mkdir: /builds/slave/test/properties 16:11:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:11:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:11:29 INFO - Contents: 16:11:29 INFO - build_url:https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:11:30 INFO - Running post-action listener: _resource_record_post_action 16:11:30 INFO - Running post-action listener: set_extra_try_arguments 16:11:30 INFO - [mozharness: 2016-08-29 23:11:30.439157Z] Finished download-and-extract step (success) 16:11:30 INFO - [mozharness: 2016-08-29 23:11:30.439298Z] Running populate-webroot step. 16:11:30 INFO - Running pre-action listener: _resource_record_pre_action 16:11:30 INFO - Running main action method: populate_webroot 16:11:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:11:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:11:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:11:30 INFO - '--tppagecycles', 16:11:30 INFO - '7'], 16:11:30 INFO - 'tests': ['tsvgm']}}, 16:11:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:11:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:11:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:11:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:11:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:11:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['damp', 'tps']}, 16:11:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'tests': ['damp', 'tps']}, 16:11:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['dromaeo_dom']}, 16:11:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:11:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['basic_compositor_video']}, 16:11:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:11:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['a11yr', 16:11:30 INFO - 'ts_paint', 16:11:30 INFO - 'tpaint', 16:11:30 INFO - 'sessionrestore', 16:11:30 INFO - 'sessionrestore_no_auto_restore', 16:11:30 INFO - 'tabpaint']}, 16:11:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['tsvgx', 16:11:30 INFO - 'tsvgr_opacity', 16:11:30 INFO - 'tart', 16:11:30 INFO - 'tscrollx', 16:11:30 INFO - 'cart']}, 16:11:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:11:30 INFO - 'tsvgr_opacity', 16:11:30 INFO - 'tart', 16:11:30 INFO - 'tscrollx', 16:11:30 INFO - 'cart']}, 16:11:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'talos_options': ['--disable-e10s'], 16:11:30 INFO - 'tests': ['tp5o']}, 16:11:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'tests': ['tp5o']}, 16:11:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'talos_options': ['--disable-e10s', 16:11:30 INFO - '--xperf_path', 16:11:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:30 INFO - 'tests': ['tp5n']}, 16:11:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:11:30 INFO - 'talos_options': ['--xperf_path', 16:11:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:11:30 INFO - 'tests': ['tp5n']}}, 16:11:30 INFO - 'talos.zip': {'path': '', 16:11:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:11:30 INFO - Running post-action listener: _resource_record_post_action 16:11:30 INFO - [mozharness: 2016-08-29 23:11:30.458210Z] Finished populate-webroot step (success) 16:11:30 INFO - [mozharness: 2016-08-29 23:11:30.458321Z] Running create-virtualenv step. 16:11:30 INFO - Running pre-action listener: _resource_record_pre_action 16:11:30 INFO - Running main action method: create_virtualenv 16:11:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:11:30 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 16:11:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:11:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:11:30 INFO - Using real prefix '/tools/python27' 16:11:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:11:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:11:34 INFO - Installing pip.................done. 16:11:34 INFO - Return code: 0 16:11:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:34 INFO - 'HOME': '/Users/cltbld', 16:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:34 INFO - 'LOGNAME': 'cltbld', 16:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:34 INFO - 'MOZ_NO_REMOTE': '1', 16:11:34 INFO - 'NO_EM_RESTART': '1', 16:11:34 INFO - 'PAGER': '/bin/cat', 16:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:34 INFO - 'PWD': '/builds/slave/test', 16:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:34 INFO - 'SHELL': '/bin/bash', 16:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:34 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:34 INFO - 'USER': 'cltbld', 16:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:34 INFO - 'XPC_FLAGS': '0x0', 16:11:34 INFO - 'XPC_SERVICE_NAME': '0', 16:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:34 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 16:11:34 INFO - Cleaning up... 16:11:34 INFO - Return code: 0 16:11:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:11:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:34 INFO - 'HOME': '/Users/cltbld', 16:11:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:34 INFO - 'LOGNAME': 'cltbld', 16:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:34 INFO - 'MOZ_NO_REMOTE': '1', 16:11:34 INFO - 'NO_EM_RESTART': '1', 16:11:34 INFO - 'PAGER': '/bin/cat', 16:11:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:34 INFO - 'PWD': '/builds/slave/test', 16:11:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:34 INFO - 'SHELL': '/bin/bash', 16:11:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:34 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:34 INFO - 'USER': 'cltbld', 16:11:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:34 INFO - 'XPC_FLAGS': '0x0', 16:11:34 INFO - 'XPC_SERVICE_NAME': '0', 16:11:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:34 INFO - Downloading/unpacking psutil>=3.1.1 16:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:11:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:37 INFO - Installing collected packages: psutil 16:11:37 INFO - Running setup.py install for psutil 16:11:37 INFO - building 'psutil._psutil_osx' extension 16:11:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:11:38 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 16:11:38 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 16:11:38 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 16:11:38 INFO - building 'psutil._psutil_posix' extension 16:11:38 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 16:11:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:11:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:11:38 INFO - ^ 16:11:38 INFO - 1 warning generated. 16:11:38 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 16:11:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:11:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:11:38 INFO - Successfully installed psutil 16:11:38 INFO - Cleaning up... 16:11:39 INFO - Return code: 0 16:11:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:39 INFO - 'HOME': '/Users/cltbld', 16:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:39 INFO - 'LOGNAME': 'cltbld', 16:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:39 INFO - 'MOZ_NO_REMOTE': '1', 16:11:39 INFO - 'NO_EM_RESTART': '1', 16:11:39 INFO - 'PAGER': '/bin/cat', 16:11:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:39 INFO - 'PWD': '/builds/slave/test', 16:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:39 INFO - 'SHELL': '/bin/bash', 16:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:39 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:39 INFO - 'USER': 'cltbld', 16:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:39 INFO - 'XPC_FLAGS': '0x0', 16:11:39 INFO - 'XPC_SERVICE_NAME': '0', 16:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:11:41 INFO - Installing collected packages: mozsystemmonitor 16:11:41 INFO - Running setup.py install for mozsystemmonitor 16:11:42 INFO - Successfully installed mozsystemmonitor 16:11:42 INFO - Cleaning up... 16:11:42 INFO - Return code: 0 16:11:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:11:42 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')]} 16:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:42 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')]} 16:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:42 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:42 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 16:11:42 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 16:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:42 INFO - 'HOME': '/Users/cltbld', 16:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:42 INFO - 'LOGNAME': 'cltbld', 16:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:42 INFO - 'MOZ_NO_REMOTE': '1', 16:11:42 INFO - 'NO_EM_RESTART': '1', 16:11:42 INFO - 'PAGER': '/bin/cat', 16:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:42 INFO - 'PWD': '/builds/slave/test', 16:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:42 INFO - 'SHELL': '/bin/bash', 16:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:42 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:42 INFO - 'USER': 'cltbld', 16:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:42 INFO - 'XPC_FLAGS': '0x0', 16:11:42 INFO - 'XPC_SERVICE_NAME': '0', 16:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:42 INFO - Downloading/unpacking jsonschema==2.5.1 16:11:42 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 16:11:42 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 16:11:42 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 16:11:42 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 16:11:42 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 16:11:42 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 16:11:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:45 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:45 INFO - warning: no files found matching '*.txt' 16:11:45 INFO - no previously-included directories found matching 'build' 16:11:45 INFO - no previously-included directories found matching 'dist' 16:11:45 INFO - no previously-included directories found matching '.git*' 16:11:45 INFO - Installing collected packages: jsonschema, functools32 16:11:45 INFO - Running setup.py install for functools32 16:11:45 INFO - warning: no files found matching '*.txt' 16:11:45 INFO - no previously-included directories found matching 'build' 16:11:45 INFO - no previously-included directories found matching 'dist' 16:11:45 INFO - no previously-included directories found matching '.git*' 16:11:45 INFO - Successfully installed jsonschema functools32 16:11:45 INFO - Cleaning up... 16:11:45 INFO - Return code: 0 16:11:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:45 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:45 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 16:11:45 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 16:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:45 INFO - 'HOME': '/Users/cltbld', 16:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:45 INFO - 'LOGNAME': 'cltbld', 16:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:45 INFO - 'MOZ_NO_REMOTE': '1', 16:11:45 INFO - 'NO_EM_RESTART': '1', 16:11:45 INFO - 'PAGER': '/bin/cat', 16:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:45 INFO - 'PWD': '/builds/slave/test', 16:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:45 INFO - 'SHELL': '/bin/bash', 16:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:45 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:45 INFO - 'USER': 'cltbld', 16:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:45 INFO - 'XPC_FLAGS': '0x0', 16:11:45 INFO - 'XPC_SERVICE_NAME': '0', 16:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:45 INFO - Cleaning up... 16:11:45 INFO - Return code: 0 16:11:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11: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')]} 16:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11: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')]} 16:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:45 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:45 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 16:11:45 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 16:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:45 INFO - 'HOME': '/Users/cltbld', 16:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:45 INFO - 'LOGNAME': 'cltbld', 16:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:45 INFO - 'MOZ_NO_REMOTE': '1', 16:11:45 INFO - 'NO_EM_RESTART': '1', 16:11:45 INFO - 'PAGER': '/bin/cat', 16:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:45 INFO - 'PWD': '/builds/slave/test', 16:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:45 INFO - 'SHELL': '/bin/bash', 16:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:45 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:45 INFO - 'USER': 'cltbld', 16:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:45 INFO - 'XPC_FLAGS': '0x0', 16:11:45 INFO - 'XPC_SERVICE_NAME': '0', 16:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:46 INFO - Downloading/unpacking blobuploader==1.2.4 16:11:46 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 16:11:46 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 16:11:46 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 16:11:46 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 16:11:46 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 16:11:46 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 16:11:48 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:49 INFO - Downloading docopt-0.6.1.tar.gz 16:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:49 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:49 INFO - Running setup.py install for blobuploader 16:11:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:50 INFO - Running setup.py install for requests 16:11:50 INFO - Running setup.py install for docopt 16:11:50 INFO - Successfully installed blobuploader requests docopt 16:11:50 INFO - Cleaning up... 16:11:50 INFO - Return code: 0 16:11:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:50 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:50 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 16:11:50 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 16:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:50 INFO - 'HOME': '/Users/cltbld', 16:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:50 INFO - 'LOGNAME': 'cltbld', 16:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:50 INFO - 'MOZ_NO_REMOTE': '1', 16:11:50 INFO - 'NO_EM_RESTART': '1', 16:11:50 INFO - 'PAGER': '/bin/cat', 16:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:50 INFO - 'PWD': '/builds/slave/test', 16:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:50 INFO - 'SHELL': '/bin/bash', 16:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:50 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:50 INFO - 'USER': 'cltbld', 16:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:50 INFO - 'XPC_FLAGS': '0x0', 16:11:50 INFO - 'XPC_SERVICE_NAME': '0', 16:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-MHq4ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-kWlm3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-lh3Nwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-0Zj63B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-juMv7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ZBhqln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-F3Ihww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-5a3vka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-dChvGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:51 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-onZsYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-O0juxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-eotgJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-jaQvoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-IK5fri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-QvtWWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-A4rQQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:52 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-INExpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:52 INFO - Running setup.py install for manifestparser 16:11:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:52 INFO - Running setup.py install for mozcrash 16:11:53 INFO - Running setup.py install for mozdebug 16:11:53 INFO - Running setup.py install for mozdevice 16:11:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Running setup.py install for mozfile 16:11:53 INFO - Running setup.py install for mozhttpd 16:11:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Running setup.py install for mozinfo 16:11:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Running setup.py install for mozInstall 16:11:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:53 INFO - Running setup.py install for mozleak 16:11:54 INFO - Running setup.py install for mozlog 16:11:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Running setup.py install for moznetwork 16:11:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Running setup.py install for mozprocess 16:11:54 INFO - Running setup.py install for mozprofile 16:11:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Running setup.py install for mozrunner 16:11:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:54 INFO - Running setup.py install for mozscreenshot 16:11:55 INFO - Running setup.py install for moztest 16:11:55 INFO - Running setup.py install for mozversion 16:11:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:55 INFO - Cleaning up... 16:11:55 INFO - Return code: 0 16:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:55 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:11:55 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 16:11:55 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 16:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:55 INFO - 'HOME': '/Users/cltbld', 16:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:55 INFO - 'LOGNAME': 'cltbld', 16:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:55 INFO - 'MOZ_NO_REMOTE': '1', 16:11:55 INFO - 'NO_EM_RESTART': '1', 16:11:55 INFO - 'PAGER': '/bin/cat', 16:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:55 INFO - 'PWD': '/builds/slave/test', 16:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:55 INFO - 'SHELL': '/bin/bash', 16:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:11:55 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:55 INFO - 'USER': 'cltbld', 16:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:55 INFO - 'XPC_FLAGS': '0x0', 16:11:55 INFO - 'XPC_SERVICE_NAME': '0', 16:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:55 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-GgNIp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:55 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)) 16:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:55 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-mzmMlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:55 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)) 16:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:55 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-vv6GB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:55 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)) 16:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-dqb20B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-tTeSsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-tUi5DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-MWmxPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4M33bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-wcaIpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-y7VH0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-oRr2_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Tc9Z8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:56 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)) 16:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:56 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-RPKJvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:57 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)) 16:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:57 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-bxXazg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:57 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)) 16:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:57 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-CZilzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:57 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)) 16:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:57 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-8i6rmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:57 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)) 16:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:57 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-BR13fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 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)) 16:11:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:57 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 16:11:57 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 16:11:57 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 16:11:57 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 16:11:57 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 16:11:57 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 16:11:59 INFO - Downloading blessings-1.6.tar.gz 16:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:00 INFO - Installing collected packages: blessings 16:12:00 INFO - Running setup.py install for blessings 16:12:00 INFO - Successfully installed blessings 16:12:00 INFO - Cleaning up... 16:12:00 INFO - Return code: 0 16:12:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:00 INFO - Reading from file tmpfile_stdout 16:12:00 INFO - Current package versions: 16:12:00 INFO - blessings == 1.6 16:12:00 INFO - blobuploader == 1.2.4 16:12:00 INFO - docopt == 0.6.1 16:12:00 INFO - functools32 == 3.2.3-2 16:12:00 INFO - jsonschema == 2.5.1 16:12:00 INFO - manifestparser == 1.1 16:12:00 INFO - mozInstall == 1.12 16:12:00 INFO - mozcrash == 0.17 16:12:00 INFO - mozdebug == 0.1 16:12:00 INFO - mozdevice == 0.48 16:12:00 INFO - mozfile == 1.2 16:12:00 INFO - mozhttpd == 0.7 16:12:00 INFO - mozinfo == 0.9 16:12:00 INFO - mozleak == 0.1 16:12:00 INFO - mozlog == 3.3 16:12:00 INFO - moznetwork == 0.27 16:12:00 INFO - mozprocess == 0.23 16:12:00 INFO - mozprofile == 0.28 16:12:00 INFO - mozrunner == 6.12 16:12:00 INFO - mozscreenshot == 0.1 16:12:00 INFO - mozsystemmonitor == 0.3 16:12:00 INFO - moztest == 0.7 16:12:00 INFO - mozversion == 1.4 16:12:00 INFO - psutil == 3.1.1 16:12:00 INFO - requests == 1.2.3 16:12:00 INFO - wsgiref == 0.1.2 16:12:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:12:00 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 16:12:00 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 16:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:00 INFO - 'HOME': '/Users/cltbld', 16:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:00 INFO - 'LOGNAME': 'cltbld', 16:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:00 INFO - 'MOZ_NO_REMOTE': '1', 16:12:00 INFO - 'NO_EM_RESTART': '1', 16:12:00 INFO - 'PAGER': '/bin/cat', 16:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:00 INFO - 'PWD': '/builds/slave/test', 16:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:00 INFO - 'SHELL': '/bin/bash', 16:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:12:00 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:00 INFO - 'USER': 'cltbld', 16:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:00 INFO - 'XPC_FLAGS': '0x0', 16:12:00 INFO - 'XPC_SERVICE_NAME': '0', 16:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 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)) 16:12:00 INFO - Cleaning up... 16:12:00 INFO - Return code: 0 16:12:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:00 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 0x100823a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008b8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd239ca3990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, '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.dB6QL3IUv9/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.OXvat4FoZa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 16:12:00 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 16:12:00 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 16:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:00 INFO - 'HOME': '/Users/cltbld', 16:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:00 INFO - 'LOGNAME': 'cltbld', 16:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:00 INFO - 'MOZ_NO_REMOTE': '1', 16:12:00 INFO - 'NO_EM_RESTART': '1', 16:12:00 INFO - 'PAGER': '/bin/cat', 16:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:00 INFO - 'PWD': '/builds/slave/test', 16:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:00 INFO - 'SHELL': '/bin/bash', 16:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:12:00 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:00 INFO - 'USER': 'cltbld', 16:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:00 INFO - 'XPC_FLAGS': '0x0', 16:12:00 INFO - 'XPC_SERVICE_NAME': '0', 16:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:12:01 INFO - Cleaning up... 16:12:01 INFO - Return code: 0 16:12:01 INFO - Running post-action listener: _resource_record_post_action 16:12:01 INFO - Running post-action listener: _start_resource_monitoring 16:12:01 INFO - Starting resource monitoring. 16:12:01 INFO - [mozharness: 2016-08-29 23:12:01.084255Z] Finished create-virtualenv step (success) 16:12:01 INFO - [mozharness: 2016-08-29 23:12:01.085229Z] Running install step. 16:12:01 INFO - Running pre-action listener: _resource_record_pre_action 16:12:01 INFO - Running main action method: install 16:12:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:01 INFO - Reading from file tmpfile_stdout 16:12:01 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:12:01 INFO - Reading from file tmpfile_stdout 16:12:01 INFO - Output received: 16:12:01 INFO - Usage: mozinstall [options] installer 16:12:01 INFO - Options: 16:12:01 INFO - -h, --help show this help message and exit 16:12:01 INFO - -d DEST, --destination=DEST 16:12:01 INFO - Directory to install application into. [default: 16:12:01 INFO - "/builds/slave/test"] 16:12:01 INFO - --app=APP Application being installed. [default: firefox] 16:12:01 INFO - mkdir: /builds/slave/test/build/application 16:12:01 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'] 16:12:01 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 16:12:35 INFO - Reading from file tmpfile_stdout 16:12:35 INFO - Output received: 16:12:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:12:35 INFO - Running post-action listener: _resource_record_post_action 16:12:35 INFO - [mozharness: 2016-08-29 23:12:35.398547Z] Finished install step (success) 16:12:35 INFO - [mozharness: 2016-08-29 23:12:35.398731Z] Running run-tests step. 16:12:35 INFO - Running pre-action listener: _resource_record_pre_action 16:12:35 INFO - Running main action method: run_tests 16:12:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:35 INFO - 2016-08-29 16:12:35.413 screenresolution[1632:7374] starting screenresolution argv=screenresolution get 16:12:35 INFO - 2016-08-29 16:12:35.415 screenresolution[1632:7374] Display 0: 1600x1200x32@60 16:12:35 INFO - 2016-08-29 16:12:35.421 screenresolution[1633:7376] starting screenresolution argv=screenresolution list 16:12:35 INFO - Available Modes on Display 0 16:12:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:35 INFO - Intel Iris: 16:12:35 INFO - Chipset Model: Intel Iris 16:12:35 INFO - Type: GPU 16:12:35 INFO - Bus: Built-In 16:12:35 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:35 INFO - Vendor: Intel (0x8086) 16:12:35 INFO - Device ID: 0x0a2e 16:12:35 INFO - Revision ID: 0x0009 16:12:35 INFO - Displays: 16:12:35 INFO - Display: 16:12:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:35 INFO - Display Serial Number: 1600x1200 60 16:12:35 INFO - Main Display: Yes 16:12:35 INFO - Mirror: Off 16:12:35 INFO - Online: Yes 16:12:35 INFO - Rotation: Supported 16:12:35 INFO - Return code: 0 16:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:35 INFO - Python 2.7.3 16:12:35 INFO - Return code: 0 16:12:35 INFO - grabbing minidump binary from tooltool 16:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100787d30>, '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 0x1004ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10077fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:12:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:12:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:12:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:35 INFO - Return code: 0 16:12:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:12:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:12:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:35 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/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--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 16:12:35 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/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --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 16:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dB6QL3IUv9/Render', 16:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:35 INFO - 'HOME': '/Users/cltbld', 16:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:35 INFO - 'LOGNAME': 'cltbld', 16:12:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:35 INFO - 'MOZ_NO_REMOTE': '1', 16:12:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:35 INFO - 'NO_EM_RESTART': '1', 16:12:35 INFO - 'PAGER': '/bin/cat', 16:12:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:35 INFO - 'PWD': '/builds/slave/test', 16:12:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:35 INFO - 'SHELL': '/bin/bash', 16:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OXvat4FoZa/Listeners', 16:12:35 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 16:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:35 INFO - 'USER': 'cltbld', 16:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:35 INFO - 'XPC_FLAGS': '0x0', 16:12:35 INFO - 'XPC_SERVICE_NAME': '0', 16:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:35 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/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--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 16:12:36 INFO - mozversion application_buildid: 20160829144430 16:12:36 INFO - mozversion application_changeset: 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a 16:12:36 INFO - mozversion application_display_name: Nightly 16:12:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:36 INFO - mozversion application_name: Firefox 16:12:36 INFO - mozversion application_remotingname: firefox 16:12:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:12:36 INFO - mozversion application_vendor: Mozilla 16:12:36 INFO - mozversion application_version: 51.0a1 16:12:36 INFO - mozversion platform_buildid: 20160829144430 16:12:36 INFO - mozversion platform_changeset: 29f27e5ab2177d8f4ee0c3c9eb06d8705985b14a 16:12:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:12:36 INFO - mozversion platform_version: 51.0a1 16:12:36 INFO - using testdate: 1472512356 16:12:36 INFO - actual date: 1472512356 16:12:36 INFO - starting webserver on 'localhost:49269' 16:12:36 INFO - SUITE-START | Running 2 tests 16:12:36 INFO - TEST-START | dromaeo_css 16:12:36 INFO - operating with platform_type : mac_ 16:12:36 INFO - Initialising browser for dromaeo_css test... 16:12:36 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpZ56YEK/profile http://localhost:49269/getInfo.html) 16:12:38 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 16:12:38 INFO - PROCESS | 1643 | colorDepth:24 16:12:38 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 16:12:38 INFO - PROCESS | 1643 | __metrics 16:12:38 INFO - PROCESS | 1643 | 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" 16:12:38 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:12:39 INFO - TEST-INFO | 1643: exit 0 16:12:39 INFO - Browser initialized. 16:12:39 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:39 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpZ56YEK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:51 INFO - PROCESS | 1645 | RSS: Main: 234213376 16:12:51 INFO - PROCESS | 1645 | 16:13:42 INFO - PROCESS | 1645 | 1472512422533 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] 16:15:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 16:15:56 INFO - PROCESS | 1645 | RSS: Main: 538206208 16:15:56 INFO - PROCESS | 1645 | 16:18:59 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 16:19:00 INFO - PROCESS | 1645 | RSS: Main: 374329344 16:19:00 INFO - PROCESS | 1645 | 16:22:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 16:22:03 INFO - PROCESS | 1645 | RSS: Main: 362721280 16:22:03 INFO - PROCESS | 1645 | 16:25:05 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 16:25:06 INFO - PROCESS | 1645 | RSS: Main: 338751488 16:25:06 INFO - PROCESS | 1645 | 16:28:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 16:28:10 INFO - PROCESS | 1645 | RSS: Main: 333979648 16:28:10 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 16:31:13 INFO - PROCESS | 1645 | RSS: Main: 551788544 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | __start_tp_report 16:31:13 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 16:31:13 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 16:31:13 INFO - PROCESS | 1645 | |i|pagename|runs| 16:31:13 INFO - PROCESS | 1645 | |0;dojo.html;3264.3960867239557;3286.129175768421;3305.5702758641387;3327.866885324584;3377;5451.836444906649;5477.726113694319;5525.4474552544725;5577.888442231153;5607.186957891104;5509.338879334476;5556.888862222753;5564.638298510594;5565.693886836222;5581.246531718217;5051.31807206027;5090.541851233388;5101.260317253996;5159.22611608258;5160.612954028444;5097.388313402378;5160.458151894035;5165.793368265265;5167.6641018333685;5185.533302002819;5104.974475127638;5111.897762044776;5134.4095429025765;5134.948650513485;5160.716160611165;5199.792008319662;5203.401608814959;5217.973910130463;5238.68567885928;5278.524932756032;5015.3229314042865;5048.1670524363435;5050.772715227824;5118.744062796883;5140.563052140573;5503.091989821674;5533.529649979757;5545.805896793633;5575.5539556835765;5612.579056570733;6083.969580152071;6088.66512341821;6100.389961003909;6103.023516237425;6133.969330153321;5959.433853783884;6143.508519318489;6170.352113028157;6170.413810688023;6202.28673702521;4400.889977750518;5626.1842032905;5648.9717551412805;5675.233843431125;5688.857778555569;6254.906176407358;6277.18396608438;6317.810465686037;6322.4942004639515;6342.429181373606;5716.28546431696;5838.649681019152;5866.73599688015;5954.345022047572;5974.551908606872;6095.512359011268;6100.603460775035;6104.398524058527;6105.206323177958;6124.989376752875;5978.671173085522;5986.790462333797;5993.70031498432;6011.48902343297;6036.547258955596;5814.970925145347;5837.766489340379;5851.561132915049;6007.219061522062;6030.728617212236;4750.14497390473;4835.685680430761;4838.274258861199;4842.128416884924;4845.781939812717;4341.6526677865695;4378.956210437918;4380.802863871133;4383.517813040563;4389.648828093745;5462.481064298885;5468.37113731918;5471.151971444432;5501.559875209973;5557.6109672323355;6105.64170830808;6137.285055221596;6159.5688301818445;6160.8767824643255;6163.19878415812;5554.361248456398;5558.666480011212;5567.554595632339;5575.609707320448;5603.943960560342;5236.371635403776;5318.9202161967605;5319.202119682079;5322;5325.147976323769;6189.133521307021;6218.595791273643;6283.088952102011;6283.120363149163;6295.653739044396;6144.600600960924;6166.136740856194;6167.506599471942;6173.783917562864;6176.845578860564;6305.085762564494;6307.369263073656;6322.715477803418;6333;6344.175257216532;5828.0966450200285;5891.05743081105;5900.911486327623;6031.185789918305;6033.75864965414;6065.969670151621;6071.392860713893;6077.24034495688;6079.996800527863;6122.510199183966;5826.359100498857;5845.883082338244;5863.472287494146;5879.088741245114;5887.8233652990475;4656.697314674535;5802.448767367094;5879.529637628894;5892.970535147297;5898;5751.367349591458;5757.769689212385;5767.048437007847;5767.048437008015;5772.740226689725;5806.1000544915605;5841.386654401226;5848.181254624271;5850.0932355484965;5851.590388672752;5654.7455364509515;5719.885602287848;5742.08126699726;5781.017227071492;5803.9419605803405 16:31:13 INFO - PROCESS | 1645 | |1;ext.html;465.96971196872204;528.1048622584718;533.7411355492585;534.462864820855;560;30746.538801917963;32233.033009009738;32284.677153228462;32372.219527925954;40858.59141408585;38796;40738;43288.78355608218;43580.91047723808;43590.89727438161;9692.806143877118;9877.950610246939;9886.851697224547;9893.604255829794;9917.950410247975;8716.25911797498;8941.731748047569;9652.613895444174;9768.023197680246;9786.902130978706;11898.643040708792;12197.329146896916;12223.816642750366;12393.070519711013;12474.064445166603;12045.45795439203;12126;12128.696782580506;12131.393430328448;12137.089718271076;8640.61117249719;8830.823383532357;8854.158854908774;11909.22590031645;12026.39868006605;34145.14637134067;39290.89218401618;40325.99185020369;42183.9454013649;42271.57728422738;3328.4674452087793;3349.246419555605;3351.6480769519094;3356.3958487472246;3479.199784049683;26362.604560931602;31045;31093.067207983797;31547.36905261882;31583;3262.9184270393193;3269.1663625775514;3273.3289675616443;3286.359159963808;3404.489326601005;478071;478266;479283;481468;483065;52361.73819130956;64083;65628.67185664042;65827.67086164538;65840.34159658341;65777.67111164509;66053.669731652;66200;66229.66885165543;66255.674886502;16312.836871631132;16469;16482;16502.42241521541;16511.33954641825;12612.747745045232;12767.680807979876;12769.361531923367;12940.029497787704;12950.417231224617;15731.528054158394;16091.517254482384;17639.029853357933;18142.5464363392;18261.63476730458;5707.315122185281;6724.293949135368;6854.246032936373;6868.793936181923;6904.240533541307;850.6469815026818;900.3472482450171;973.7614284500343;1058.2697938422464;1097.5829184909683;28781.424371512454;29486.705132948395;29951.550726739115;29958.101256962327;31003.844980774953;937.6202637931649;1012.4532752313668;1020.3469779341227;1063.1494804156644;1110.0786347331696;13401.865981340257;15633.530994070195;15640.608984775472;16158.919205403898;16188.75716864248;640430.3935440879;644633;645184;646205;647663;49800.50199497959;56839;57842.84314313775;57910.4208957905;60977.69511152416;1332.3005422153446;1356.7964805279287;1373.7870630052157;1376.8416632087237;1379.6068120585987;28991;29203.85398072996;31327.530087048257;31698.607011391974;31825.204369890012;51057.74471127769;51574.96850063053;51617;51660.48339516557;51752.96494070173;560866;567010;567314;567551;568866;26167.084152054587;26812.195634131007;40624.79687601661;40742;40904;46744.06511869811;47241.76379118083;47904;48172.75913620409;48333.51666483431;39238.607613923494;39366.606333936295;39503.60496395114;39687.80156099201;40563.59436405598;39061.41407878827;39221.6077839218;40125.59874401218;40275.1944961105;40462.59537404706;38142.04644883789;38650.42024369693;38707.80646096864;39349.409758853064;39479.80260098681;32228.83885580557;34307.828460857534;34503.5715710927;34745.65254347424;35060.474092888115;25232;25297.367565810266;25447.745522544537;25590.616140758277;25755 16:31:13 INFO - PROCESS | 1645 | |2;jquery.html;347.55686499712846;369.4071016019289;370.3981030824911;382.9253295607357;402.70401255077513;15079.321430535625;15572.45496407626;20614.69077963828;20774.610919744217;20910.26814061508;20838.583228335425;20881.060352284145;21493.990841282233;21515.354539363845;21551.245706400277;5229.790808367661;5816.680082595455;6047.15339852421;6085.878282434349;6091.2690477142805;5954.285485741717;6014.458698717114;6022.126791615213;6052.757889684407;6057.363976782441;5460.808871689492;6005.039193729004;6017.819465416044;6691.899621505681;6751.561148525355;6171.105189747506;6724.697388617499;6732.865342693119;6744.325567443241;6753.358430949052;5820.563457740686;5860.472557469806;5877.559183061245;5894.4989675877605;5913.704314784243;13256.867431325756;13497.98765092622;24435.87782061078;24866.751332486623;25431;2177.923772667965;2244.7418546866993;2298.321995011459;2523.3943853475216;2558.692956845172;8383.790405239857;9457.574409151604;10128.341657792294;10409.219308551888;10465.79068418635;1818.9818101818944;1911.742707554226;1916.60709554541;1926.8554858385678;1935.6032013437352;111617;115765.42117289444;120971;122148;122272.77727222792;8901.421407608485;10920.344779313267;11398.259113157617;11412.28815677655;11606.535738570363;14293.64265893361;14311.928440357731;14313.854891608855;14336.49822256216;14346.56960291193;8264.421490495728;8506;8521.53131577769;8677.262432693291;8687.34844886636;7021.22766495685;8576;8577.528235946958;8712.128787121364;8752.606132723915;4345.9130817383475;4438.378626992224;4448.377227188196;4502.527234640381;4557.384753058361;1657.9088150151615;1692.69531484334;1746.1443892492587;1835.5961688428524;1932.94201173965;675.8394524844886;919.296737995434;939.5771902643837;988.7478692933399;997.3268044070223;20212;20611.69082463764;21945.82890555248;21959.852429472005;21984.99682644393;887.6183241206344;932.8647346134773;1000.4547521600745;1044.1650917758323;1044.3420644993605;10019.499025048864;10574.83676795552;11269.54921803135;11394.1454390921;11397.316161030365;423025;484546;486269;559927;563863;104282;125912;126391;127491;137587.31206344103;966.4539535162611;980.4362491567377;1000.2348203624233;1033.5968972100886;1039.0856046678873;19458.497470454888;19869.900650496656;19888;19961.700574491686;20022.099005544493;54372.456275436736;55630.887382252935;56145;56749;57213.71393143007;58147.4185258142;77203.61398192972;78818.6059069701;80004;81182;5836.941630583639;7854.882176767239;8028.518288902685;8044.597770111588;8209.71266005687;7479.551226926402;8998.460092394478;12755.234685918875;13176.934115329363;13408.932955335162;11182.832257516306;11374.54501819918;11635.53457861676;11661.416929153338;11716.121290903387;11013.614523491638;11203.935626115506;11312.095032397554;11451.969322760991;11711.355875426932;11572.768544629229;11612.245204062046;11828;11975.520979160736;12032.157748957488;6954.617496037767;6968.268331825086;7292.525985810906;7387.335139837441;7631.580263085361;10896.418122085493;11271.774564508827;11292.249701296312;11360.602378916697;11584 16:31:13 INFO - PROCESS | 1645 | |3;mootools.html;1405.4448492845327;1525.9771103433447;1708.419137493252;1859.4328729737424;1905.2950408348913;8411.663533458663;8919.241864441514;8922.197002269802;8927.732168034969;8957.14907083826;7890.132085470594;8088.029436467632;8547.444416112963;8684.261837743783;8724.694635687752;1968.8720233184797;2360.5750964826325;2385.666006759055;2393.365758074112;2394.4971555973266;2086.6765651324067;2345.7888790008897;2374.513224788914;2397.3287479505766;2403.9639405408934;2348.037304705071;2375.406148462884;2378.250850981935;2381.773731495505;2392.1627430399394;2207.249535158038;2239.7536271010176;2368.052778888441;2370.431096536836;2370.9762902370926;2360;2360.5986982213067;2369.9052037918636;2371.454565449939;2374.8693821839793;8747.737567872973;8888.288936885034;8894.199522043047;8905.332100092519;8928.107189281085;5184.018470328121;5188.533032027098;5348.812791552277;5355.919661205085;5356.491133342326;3097.845107744604;3521.154922818505;3577.2308953574848;3652.2330310634798;3659.7804131751914;1050.123147172112;1085.5711993762498;1090.1006669497617;1108.2630051016065;1109.7724966381952;11486.770264594745;11597.826032609517;11613.419329033599;11633.418329083512;11636.534538618445;4475.865724028285;4566.88582785433;4715.22198837188;4750.311204875274;4759.690620109682;9823.607055717692;9828.361156524803;9835.704928852147;9835.950820245853;9892.356996795184;5335.653182543122;5343.278657381282;5343.81296654623;5344.599155063386;5358.812441564526;4251.256030194703;4432.936095337096;4593.885152871205;4897.338859253955;4908.656394052454;3812.1422679897355;3817.255635151119;3940.4877365942243;3957.2876882161486;3969.980150099231;1252.1485389934749;1273.6815796050987;1279.2132838304376;1287.7102651903247;1290.7741145299535;736.2821249281907;840.4999025579771;843.2832092721261;927.1146055516965;965.4545181972163;5776.42235776419;5793.7392817323325;5876.853078672982;6001.5798894077;6142.416470435302;438.99561004389545;448.87207145963896;449.6807266840514;459.4739023817777;460.5486623109372;3368.6631336866117;3966.8809935701975;3983.980080099639;4066.471358723347;4091.3862920562156;9221.585028673726;10286.588536458457;10504.369737815756;10808.837867431996;10842.457877106272;9331.486768227813;10717.410542420243;10723.517441715141;10729.409882456539;10739.140868730483;7979.601019948979;8198.221168988937;8314;8318.750437486764;8320.83358332842;5059.898802024012;5863.912041319469;5883.235179426647;5924.259467566554;5960.850978725566;9781.462019588991;10035.24735644815;10099.899001009895;10117.241206909364;10128;9798.804023919623;10230.925752796147;10267.794644106927;10292.845607316047;10307.484625768831;6880.139982502187;7076.433885289061;7400.222976587381;7495.850082998419;7497.10034795828;8115.75652730419;8281.751547453587;8354.164583541597;8450.788730281793;8473.83052338962;7434.962825185839;7519.210482899217;7560.697572097055;7659.502132361378;7709.7301594443925;7293.161286452126;7305.159906610807;7336.339729424378;7370.226126256666;7533.547987120571;6847.931520684929;6982.231954484901;6991.370776630128;6992.1259842519685;6997.685104170223;4981.352424184833;5025.497450254945;5040.092783298895;5111.923321150261;5121.283020377225;4937.35814344133;4966.850994470172;5073.879564068146;5226.268322434938;5242.370915490013 16:31:13 INFO - PROCESS | 1645 | |4;prototype.html;298.48064367999683;304.2803769086112;306.9631644202696;313.94035133324667;316.28992910914997;4369.650427965763;4762.02378512405;4981.978694367655;5099.847004589864;5120.820771273006;4206.537280899099;4411.228035093862;5047.621428392867;5073.264376665381;5102.464241254671;3677.9632203677957;3767.5102236709326;3854.8458061677497;3881.7282790204695;3891.7664940103687;2926.531754919213;3788.8444024572495;3937.5471820740554;3952.7271818704216;3962.3065963456424;3635.745497815154;3864.516935383077;3868.845246190163;3875.5237700455878;3895.6493915547594;3258.1917036044983;3388.508666243381;3552.3960926642403;3808.1812410331636;3877.9612203878164;3347.2134048498583;3348.4642457206974;3352.966470335314;3359.4960755886814;3360.7815491993188;4225.309876049574;4728.196206644879;4912.715587251945;4922;4925.187344088221;4099.569545197741;4370.8907277318;4374.912501749979;4388.363687265329;4419.204543182226;4223.958749700211;4410.698843841043;5234.790608375661;5276.076686579833;5320.308359913225;3430.2453460238708;3696.9445458318146;3710.610385909468;3712.5916149223563;3723.765740555672;5188.377394712658;5579.609427340088;5580.748866301026;5600.299962504687;5605.887882242332;3718.2377612589353;3765.6799172070682;3781.716371272166;3784.0729021389934;3785.6025117362833;3240.513922911535;3387.9158669225612;3559.5728512578658;3685.686716629063;3841.5582208045876;3695.870644527429;4334.804933777925;4362.385917210652;4373.316273234827;4399.6040356368085;4524.411826462539;4687.695299805502;4926.33494478248;5293.470652934753;5318.654287471302;1289.6260084575367;1305.778017736987;1311.7835557132967;1315.2963164706837;1318.7735406072445;778.7157687444155;815.8939337886037;817.8405210983801;818.7912082419064;820.3601191070974;4092.5293591237387;4181.540030596632;4723.6929599575915;4741.288806679026;4903.975480122576;4623.53764623535;5370.113931201387;5609.49514543693;5628.326544891144;5633.267675202197;4296.4414626098405;4681.555252251099;5086.18621020635;5089.8727531812;5116.258142569381;2582.13498478012;2875.4536638038708;2890.2340879666904;2909.3017675758088;2910.2578842395055;5318.813841515605;5363.007843548956;5660.603757737002;5696.886062278814;5769.423057694281;4645.442546894395;5260.92108618371;5627.212190293362;5646.717664116778;5668.829935101953;4822.397200349957;5200.6619569727845;5229.921551176812;5722.685252311163;5798.275215598051;4715.622750180046;4765.427408955105;5175.120229561059;5402.972985135207;5479.6026463592825;4114.76990562419;5114.8977020460125;5403.216533602527;5422.48486393792;5464.836054918359;5691.8292451226525;5704.80033198836;5706.543476521785;5721.5136713379825;5723.08276727301;3531.947020794639;3757.755745876509;3804.31522325984;3828.961710382972;3837.6354246346555;4380.890477738082;4581.656375771764;5414.593905457028;5439.646422982493;5449.809256675997;5109.627645753784;5219.556337711185;5272.393674727456;5286.709230992333;5296.443873393392;5243.292155559103;5246.632735708464;5273.709945953009;5291.76187071575;5308.54015145838;4434.357018232403;4864.440589332272;5151.484851514969;5164.302819119371;5169.4830516948;5059.569936555434;5231.686098834082;5269.235960785588;5271.525562699376;5300.800167930266;4073.409355643356;4107.055377263306;4943.802247910187;5106.719130447861;5147.79026928664 16:31:13 INFO - PROCESS | 1645 | |5;yui.html;721.8844984802431;736.6427282767856;778.1712476212833;812.3217113710052;841.1924552429667;14826.481073162442;14950.38368285551;15576.844231557681;15590.06459612424;15939.521814345588;14218.928905355484;14306.785398219035;14398.136111833297;14415.279236038183;14420.711585768278;811.7199566149669;837.0624900111883;837.9107160691108;842.1830824100643;844.3371953016883;688.5558814564603;705.4532737128715;860.4923095373729;891.7235656946367;893.6425429828056;922.898481167071;923.3905622289291;924.8196601662678;925.4586067150725;928.2156577691874;921.0789210789211;922.3958307308724;922.6263363337858;923.0630909626701;924.996378928863;532.1565318969438;670.8691805098008;682.1030345096183;688.5145972089665;688.9699898651498;14779.33492992818;14782.63043423923;14785.630359240997;14792.40830366784;14806;13503.66240843977;13839.861601384058;13963.650908727363;13974.602539746045;14247.219097612799;12385.938070309681;12560.246385216917;12618;12693.365331733468;13194.736105277841;4459.843905463294;4505.43682039745;4570.405847239871;4574.61115805157;4722.976385118087;119516.40241798823;120595;122738;123019.38490307581;124537;10298.588056477807;10787.460626968621;10917.56329746817;10988;11289.774204516028;20747.896260518603;20825.37523874286;21093.36719898375;21114.04986775568;21136.682949755766;6485.286618471964;7551.395888328921;7717.459777815499;7757.650905709143;7969;4421.2483877740215;4436.290193569013;4445.399871017371;4514.7291162530355;4578.175928332866;3419.8803041893416;3520.964790352115;3538.15084379747;3570.6429357064085;3585.6952159066773;648.8183308673579;649.4317472211815;660.0297562582996;663.7477758451757;665.4077870695086;538.64449463354;559.4154108956151;615.7906311853991;628.84593274648;634.5780056262533;8545.786355341043;9165.220956218664;9215.308851836138;9293.581788819383;9400.294977876687;507.959363250939;550.9421510741395;554.0829926471696;592.280379339105;619.3960888134123;4214.3678448232395;4315.697901146889;4535.160995215896;4609.810340088498;4993.925091123636;148994.51005490022;152637;152969;153884;157836.21081894517;104151;104356;107629;130155;134602.32698836442;80993.19006809975;92587.61118583361;102898;104716.47641761895;105495.47252263689;8398.118197589409;9415.011423800403;9710.660126895526;9936.701898943043;9944.850827237453;56945.715271423374;57954.13068803887;58076;58133.4186658128;59221;29100.563491547222;29237.7076229235;29246.7075329244;29306.853465732536;80796;11410.486528106421;13445.394957226752;13470.057096003186;13485.86514134846;13608.523701670394;14856.777148342568;15066.54800355991;15225.6193595161;15506.5348039559;15642.687146256783;13561.593152205449;13866.4381172575;13910.58268251954;14135.15189088658;14258.572242832732;13776.862231377558;13974.930125349309;14195.503157389854;14338.426462941365;14394.928025360225;13828.93085534566;13868.653283667585;14016.299185040503;14486.565403037926;14548.781768273273;10881.619143329945;10989.175811814306;11081.335119892832;11311.943440282746;11751.882481175078;6614.503912206507;8398.580070996304;9961.950190249003;10603.310784798963;11053.557857685602 16:31:13 INFO - PROCESS | 1645 | __end_tp_report 16:31:13 INFO - PROCESS | 1645 | __start_cc_report 16:31:13 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2830 16:31:13 INFO - PROCESS | 1645 | __end_cc_report 16:31:13 INFO - PROCESS | 1645 | __startTimestamp1472513473891__endTimestamp 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | ------- Summary: start ------- 16:31:13 INFO - PROCESS | 1645 | Number of tests: 6 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5599.43 Median:5767.05 stddev:624.74 (10.8%) stddev-sans-first:599.20 16:31:13 INFO - PROCESS | 1645 | Values: 3264.4 3286.1 3305.6 3327.9 3377.0 5451.8 5477.7 5525.4 5577.9 5607.2 5509.3 5556.9 5564.6 5565.7 5581.2 5051.3 5090.5 5101.3 5159.2 5160.6 5097.4 5160.5 5165.8 5167.7 5185.5 5105.0 5111.9 5134.4 5134.9 5160.7 5199.8 5203.4 5218.0 5238.7 5278.5 5015.3 5048.2 5050.8 5118.7 5140.6 5503.1 5533.5 5545.8 5575.6 5612.6 6084.0 6088.7 6100.4 6103.0 6134.0 5959.4 6143.5 6170.4 6170.4 6202.3 4400.9 5626.2 5649.0 5675.2 5688.9 6254.9 6277.2 6317.8 6322.5 6342.4 5716.3 5838.6 5866.7 5954.3 5974.6 6095.5 6100.6 6104.4 6105.2 6125.0 5978.7 5986.8 5993.7 6011.5 6036.5 5815.0 5837.8 5851.6 6007.2 6030.7 4750.1 4835.7 4838.3 4842.1 4845.8 4341.7 4379.0 4380.8 4383.5 4389.6 5462.5 5468.4 5471.2 5501.6 5557.6 6105.6 6137.3 6159.6 6160.9 6163.2 5554.4 5558.7 5567.6 5575.6 5603.9 5236.4 5318.9 5319.2 5322.0 5325.1 6189.1 6218.6 6283.1 6283.1 6295.7 6144.6 6166.1 6167.5 6173.8 6176.8 6305.1 6307.4 6322.7 6333.0 6344.2 5828.1 5891.1 5900.9 6031.2 6033.8 6066.0 6071.4 6077.2 6080.0 6122.5 5826.4 5845.9 5863.5 5879.1 5887.8 4656.7 5802.4 5879.5 5893.0 5898.0 5751.4 5757.8 5767.0 5767.0 5772.7 5806.1 5841.4 5848.2 5850.1 5851.6 5654.7 5719.9 5742.1 5781.0 5803.9 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:70214.61 Median:39230.11 stddev:151584.77 (386.4%) stddev-sans-first:151919.54 16:31:13 INFO - PROCESS | 1645 | Values: 466.0 528.1 533.7 534.5 560.0 30746.5 32233.0 32284.7 32372.2 40858.6 38796.0 40738.0 43288.8 43580.9 43590.9 9692.8 9878.0 9886.9 9893.6 9918.0 8716.3 8941.7 9652.6 9768.0 9786.9 11898.6 12197.3 12223.8 12393.1 12474.1 12045.5 12126.0 12128.7 12131.4 12137.1 8640.6 8830.8 8854.2 11909.2 12026.4 34145.1 39290.9 40326.0 42183.9 42271.6 3328.5 3349.2 3351.6 3356.4 3479.2 26362.6 31045.0 31093.1 31547.4 31583.0 3262.9 3269.2 3273.3 3286.4 3404.5 478071.0 478266.0 479283.0 481468.0 483065.0 52361.7 64083.0 65628.7 65827.7 65840.3 65777.7 66053.7 66200.0 66229.7 66255.7 16312.8 16469.0 16482.0 16502.4 16511.3 12612.7 12767.7 12769.4 12940.0 12950.4 15731.5 16091.5 17639.0 18142.5 18261.6 5707.3 6724.3 6854.2 6868.8 6904.2 850.6 900.3 973.8 1058.3 1097.6 28781.4 29486.7 29951.6 29958.1 31003.8 937.6 1012.5 1020.3 1063.1 1110.1 13401.9 15633.5 15640.6 16158.9 16188.8 640430.4 644633.0 645184.0 646205.0 647663.0 49800.5 56839.0 57842.8 57910.4 60977.7 1332.3 1356.8 1373.8 1376.8 1379.6 28991.0 29203.9 31327.5 31698.6 31825.2 51057.7 51575.0 51617.0 51660.5 51753.0 560866.0 567010.0 567314.0 567551.0 568866.0 26167.1 26812.2 40624.8 40742.0 40904.0 46744.1 47241.8 47904.0 48172.8 48333.5 39238.6 39366.6 39503.6 39687.8 40563.6 39061.4 39221.6 40125.6 40275.2 40462.6 38142.0 38650.4 38707.8 39349.4 39479.8 32228.8 34307.8 34503.6 34745.7 35060.5 25232.0 25297.4 25447.7 25590.6 25755.0 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:32161.72 Median:13367.10 stddev:85444.09 (639.2%) stddev-sans-first:85650.39 16:31:13 INFO - PROCESS | 1645 | Values: 347.6 369.4 370.4 382.9 402.7 15079.3 15572.5 20614.7 20774.6 20910.3 20838.6 20881.1 21494.0 21515.4 21551.2 5229.8 5816.7 6047.2 6085.9 6091.3 5954.3 6014.5 6022.1 6052.8 6057.4 5460.8 6005.0 6017.8 6691.9 6751.6 6171.1 6724.7 6732.9 6744.3 6753.4 5820.6 5860.5 5877.6 5894.5 5913.7 13256.9 13498.0 24435.9 24866.8 25431.0 2177.9 2244.7 2298.3 2523.4 2558.7 8383.8 9457.6 10128.3 10409.2 10465.8 1819.0 1911.7 1916.6 1926.9 1935.6 111617.0 115765.4 120971.0 122148.0 122272.8 8901.4 10920.3 11398.3 11412.3 11606.5 14293.6 14311.9 14313.9 14336.5 14346.6 8264.4 8506.0 8521.5 8677.3 8687.3 7021.2 8576.0 8577.5 8712.1 8752.6 4345.9 4438.4 4448.4 4502.5 4557.4 1657.9 1692.7 1746.1 1835.6 1932.9 675.8 919.3 939.6 988.7 997.3 20212.0 20611.7 21945.8 21959.9 21985.0 887.6 932.9 1000.5 1044.2 1044.3 10019.5 10574.8 11269.5 11394.1 11397.3 423025.0 484546.0 486269.0 559927.0 563863.0 104282.0 125912.0 126391.0 127491.0 137587.3 966.5 980.4 1000.2 1033.6 1039.1 19458.5 19869.9 19888.0 19961.7 20022.1 54372.5 55630.9 56145.0 56749.0 57213.7 58147.4 77203.6 78818.6 80004.0 81182.0 5836.9 7854.9 8028.5 8044.6 8209.7 7479.6 8998.5 12755.2 13176.9 13408.9 11182.8 11374.5 11635.5 11661.4 11716.1 11013.6 11203.9 11312.1 11452.0 11711.4 11572.8 11612.2 11828.0 11975.5 12032.2 6954.6 6968.3 7292.5 7387.3 7631.6 10896.4 11271.8 11292.2 11360.6 11584.0 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5674.80 Median:4923.01 stddev:3198.29 (65.0%) stddev-sans-first:3191.17 16:31:13 INFO - PROCESS | 1645 | Values: 1405.4 1526.0 1708.4 1859.4 1905.3 8411.7 8919.2 8922.2 8927.7 8957.1 7890.1 8088.0 8547.4 8684.3 8724.7 1968.9 2360.6 2385.7 2393.4 2394.5 2086.7 2345.8 2374.5 2397.3 2404.0 2348.0 2375.4 2378.3 2381.8 2392.2 2207.2 2239.8 2368.1 2370.4 2371.0 2360.0 2360.6 2369.9 2371.5 2374.9 8747.7 8888.3 8894.2 8905.3 8928.1 5184.0 5188.5 5348.8 5355.9 5356.5 3097.8 3521.2 3577.2 3652.2 3659.8 1050.1 1085.6 1090.1 1108.3 1109.8 11486.8 11597.8 11613.4 11633.4 11636.5 4475.9 4566.9 4715.2 4750.3 4759.7 9823.6 9828.4 9835.7 9836.0 9892.4 5335.7 5343.3 5343.8 5344.6 5358.8 4251.3 4432.9 4593.9 4897.3 4908.7 3812.1 3817.3 3940.5 3957.3 3970.0 1252.1 1273.7 1279.2 1287.7 1290.8 736.3 840.5 843.3 927.1 965.5 5776.4 5793.7 5876.9 6001.6 6142.4 439.0 448.9 449.7 459.5 460.5 3368.7 3966.9 3984.0 4066.5 4091.4 9221.6 10286.6 10504.4 10808.8 10842.5 9331.5 10717.4 10723.5 10729.4 10739.1 7979.6 8198.2 8314.0 8318.8 8320.8 5059.9 5863.9 5883.2 5924.3 5960.9 9781.5 10035.2 10099.9 10117.2 10128.0 9798.8 10230.9 10267.8 10292.8 10307.5 6880.1 7076.4 7400.2 7495.9 7497.1 8115.8 8281.8 8354.2 8450.8 8473.8 7435.0 7519.2 7560.7 7659.5 7709.7 7293.2 7305.2 7336.3 7370.2 7533.5 6847.9 6982.2 6991.4 6992.1 6997.7 4981.4 5025.5 5040.1 5111.9 5121.3 4937.4 4966.9 5073.9 5226.3 5242.4 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4276.27 Median:4719.66 stddev:1306.22 (27.7%) stddev-sans-first:1275.31 16:31:13 INFO - PROCESS | 1645 | Values: 298.5 304.3 307.0 313.9 316.3 4369.7 4762.0 4982.0 5099.8 5120.8 4206.5 4411.2 5047.6 5073.3 5102.5 3678.0 3767.5 3854.8 3881.7 3891.8 2926.5 3788.8 3937.5 3952.7 3962.3 3635.7 3864.5 3868.8 3875.5 3895.6 3258.2 3388.5 3552.4 3808.2 3878.0 3347.2 3348.5 3353.0 3359.5 3360.8 4225.3 4728.2 4912.7 4922.0 4925.2 4099.6 4370.9 4374.9 4388.4 4419.2 4224.0 4410.7 5234.8 5276.1 5320.3 3430.2 3696.9 3710.6 3712.6 3723.8 5188.4 5579.6 5580.7 5600.3 5605.9 3718.2 3765.7 3781.7 3784.1 3785.6 3240.5 3387.9 3559.6 3685.7 3841.6 3695.9 4334.8 4362.4 4373.3 4399.6 4524.4 4687.7 4926.3 5293.5 5318.7 1289.6 1305.8 1311.8 1315.3 1318.8 778.7 815.9 817.8 818.8 820.4 4092.5 4181.5 4723.7 4741.3 4904.0 4623.5 5370.1 5609.5 5628.3 5633.3 4296.4 4681.6 5086.2 5089.9 5116.3 2582.1 2875.5 2890.2 2909.3 2910.3 5318.8 5363.0 5660.6 5696.9 5769.4 4645.4 5260.9 5627.2 5646.7 5668.8 4822.4 5200.7 5229.9 5722.7 5798.3 4715.6 4765.4 5175.1 5403.0 5479.6 4114.8 5114.9 5403.2 5422.5 5464.8 5691.8 5704.8 5706.5 5721.5 5723.1 3531.9 3757.8 3804.3 3829.0 3837.6 4380.9 4581.7 5414.6 5439.6 5449.8 5109.6 5219.6 5272.4 5286.7 5296.4 5243.3 5246.6 5273.7 5291.8 5308.5 4434.4 4864.4 5151.5 5164.3 5169.5 5059.6 5231.7 5269.2 5271.5 5300.8 4073.4 4107.1 4943.8 5106.7 5147.8 16:31:13 INFO - PROCESS | 1645 | 16:31:13 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:23068.67 Median:3529.56 stddev:37723.35 (1068.8%) stddev-sans-first:37791.86 16:31:13 INFO - PROCESS | 1645 | Values: 721.9 736.6 778.2 812.3 841.2 14826.5 14950.4 15576.8 15590.1 15939.5 14218.9 14306.8 14398.1 14415.3 14420.7 811.7 837.1 837.9 842.2 844.3 688.6 705.5 860.5 891.7 893.6 922.9 923.4 924.8 925.5 928.2 921.1 922.4 922.6 923.1 925.0 532.2 670.9 682.1 688.5 689.0 14779.3 14782.6 14785.6 14792.4 14806.0 13503.7 13839.9 13963.7 13974.6 14247.2 12385.9 12560.2 12618.0 12693.4 13194.7 4459.8 4505.4 4570.4 4574.6 4723.0 119516.4 120595.0 122738.0 123019.4 124537.0 10298.6 10787.5 10917.6 10988.0 11289.8 20747.9 20825.4 21093.4 21114.0 21136.7 6485.3 7551.4 7717.5 7757.7 7969.0 4421.2 4436.3 4445.4 4514.7 4578.2 3419.9 3521.0 3538.2 3570.6 3585.7 648.8 649.4 660.0 663.7 665.4 538.6 559.4 615.8 628.8 634.6 8545.8 9165.2 9215.3 9293.6 9400.3 508.0 550.9 554.1 592.3 619.4 4214.4 4315.7 4535.2 4609.8 4993.9 148994.5 152637.0 152969.0 153884.0 157836.2 104151.0 104356.0 107629.0 130155.0 134602.3 80993.2 92587.6 102898.0 104716.5 105495.5 8398.1 9415.0 9710.7 9936.7 9944.9 56945.7 57954.1 58076.0 58133.4 59221.0 29100.6 29237.7 29246.7 29306.9 80796.0 11410.5 13445.4 13470.1 13485.9 13608.5 14856.8 15066.5 15225.6 15506.5 15642.7 13561.6 13866.4 13910.6 14135.2 14258.6 13776.9 13974.9 14195.5 14338.4 14394.9 13828.9 13868.7 14016.3 14486.6 14548.8 10881.6 10989.2 11081.3 11311.9 11751.9 6614.5 8398.6 9962.0 10603.3 11053.6 16:31:13 INFO - PROCESS | 1645 | -------- Summary: end -------- 16:31:13 INFO - PROCESS | 1645 | 16:31:14 INFO - TEST-INFO | 1645: exit 0 16:31:14 INFO - TEST-OK | dromaeo_css | took 1118506ms 16:31:14 INFO - TEST-START | kraken 16:31:14 INFO - operating with platform_type : mac_ 16:31:14 INFO - Initialising browser for kraken test... 16:31:14 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp87Q94H/profile http://localhost:49269/getInfo.html) 16:31:16 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 16:31:16 INFO - PROCESS | 1656 | colorDepth:24 16:31:16 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 16:31:16 INFO - PROCESS | 1656 | __metrics 16:31:16 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:31:16 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:31:17 INFO - TEST-INFO | 1656: exit 0 16:31:17 INFO - Browser initialized. 16:31:17 INFO - Running cycle 1/1 for kraken test... 16:31:17 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmp87Q94H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:29 INFO - PROCESS | 1657 | RSS: Main: 234610688 16:31:29 INFO - PROCESS | 1657 | 16:31:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:33 INFO - PROCESS | 1657 | RSS: Main: 292331520 16:31:33 INFO - PROCESS | 1657 | 16:31:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 16:31:36 INFO - PROCESS | 1657 | RSS: Main: 288923648 16:31:36 INFO - PROCESS | 1657 | 16:31:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 16:31:40 INFO - PROCESS | 1657 | RSS: Main: 380366848 16:31:40 INFO - PROCESS | 1657 | 16:31:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 16:31:42 INFO - PROCESS | 1657 | RSS: Main: 282619904 16:31:42 INFO - PROCESS | 1657 | 16:31:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:45 INFO - PROCESS | 1657 | RSS: Main: 284995584 16:31:45 INFO - PROCESS | 1657 | 16:31:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:48 INFO - PROCESS | 1657 | RSS: Main: 323121152 16:31:48 INFO - PROCESS | 1657 | 16:31:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:52 INFO - PROCESS | 1657 | RSS: Main: 323354624 16:31:52 INFO - PROCESS | 1657 | 16:31:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 16:31:55 INFO - PROCESS | 1657 | RSS: Main: 322916352 16:31:55 INFO - PROCESS | 1657 | 16:31:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:57 INFO - PROCESS | 1657 | RSS: Main: 297803776 16:31:57 INFO - PROCESS | 1657 | 16:31:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:00 INFO - PROCESS | 1657 | RSS: Main: 302141440 16:32:00 INFO - PROCESS | 1657 | 16:32:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:03 INFO - PROCESS | 1657 | RSS: Main: 304160768 16:32:03 INFO - PROCESS | 1657 | 16:32:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:06 INFO - PROCESS | 1657 | RSS: Main: 319614976 16:32:06 INFO - PROCESS | 1657 | 16:32:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:10 INFO - PROCESS | 1657 | RSS: Main: 315756544 16:32:10 INFO - PROCESS | 1657 | 16:32:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 16:32:12 INFO - PROCESS | 1657 | RSS: Main: 359026688 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | __start_tp_report 16:32:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 16:32:12 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 16:32:12 INFO - PROCESS | 1657 | |i|pagename|runs| 16:32:12 INFO - PROCESS | 1657 | |0;ai-astar;113;113;134;113;112;158;162;113;112;113 16:32:12 INFO - PROCESS | 1657 | |1;audio-beat-detection;147;114;131;159;108;130;128;109;159;108 16:32:12 INFO - PROCESS | 1657 | |2;audio-dft;184;184;183;184;185;179;182;184;182;183 16:32:12 INFO - PROCESS | 1657 | |3;audio-fft;77;73;78;148;56;56;59;68;138;57 16:32:12 INFO - PROCESS | 1657 | |4;audio-oscillator;82;81;132;70;73;88;115;69;69;117 16:32:12 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;86;84;87;82;85;82;87;83;86;83 16:32:12 INFO - PROCESS | 1657 | |6;imaging-darkroom;101;92;92;95;92;93;92;95;92;100 16:32:12 INFO - PROCESS | 1657 | |7;imaging-desaturate;87;85;87;85;85;82;85;86;85;86 16:32:12 INFO - PROCESS | 1657 | |8;json-parse-financial;53;54;57;54;55;53;55;53;53;54 16:32:12 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;71;66;75;80;78;72;70;70;71;73 16:32:12 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;86;85;85;84;87;84;85;90;89 16:32:12 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;137;135;142;152;145;149;136;180;140 16:32:12 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;168;168;166;171;167;167;167;170;166 16:32:12 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;67;67;71;83;71;59;82;81;81 16:32:12 INFO - PROCESS | 1657 | __end_tp_report 16:32:12 INFO - PROCESS | 1657 | __start_cc_report 16:32:12 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2010 16:32:12 INFO - PROCESS | 1657 | __end_cc_report 16:32:12 INFO - PROCESS | 1657 | __startTimestamp1472513532236__endTimestamp 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | ------- Summary: start ------- 16:32:12 INFO - PROCESS | 1657 | Number of tests: 14 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.30 Median:113.00 stddev:19.98 (17.7%) stddev-sans-first:20.77 16:32:12 INFO - PROCESS | 1657 | Values: 113.0 113.0 134.0 113.0 112.0 158.0 162.0 113.0 112.0 113.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:130.50 stddev:20.10 (15.4%) stddev-sans-first:20.27 16:32:12 INFO - PROCESS | 1657 | Values: 147.0 114.0 131.0 159.0 108.0 130.0 128.0 109.0 159.0 108.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.70 (0.9%) stddev-sans-first:1.76 16:32:12 INFO - PROCESS | 1657 | Values: 184.0 184.0 183.0 184.0 185.0 179.0 182.0 184.0 182.0 183.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:81.00 Median:63.50 stddev:33.84 (53.3%) stddev-sans-first:35.86 16:32:12 INFO - PROCESS | 1657 | Values: 77.0 73.0 78.0 148.0 56.0 56.0 59.0 68.0 138.0 57.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:71.50 stddev:23.18 (32.4%) stddev-sans-first:24.42 16:32:12 INFO - PROCESS | 1657 | Values: 82.0 81.0 132.0 70.0 73.0 88.0 115.0 69.0 69.0 117.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:84.50 Median:85.50 stddev:1.96 (2.3%) stddev-sans-first:2.00 16:32:12 INFO - PROCESS | 1657 | Values: 86.0 84.0 87.0 82.0 85.0 82.0 87.0 83.0 86.0 83.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:92.00 stddev:3.44 (3.7%) stddev-sans-first:2.69 16:32:12 INFO - PROCESS | 1657 | Values: 101.0 92.0 92.0 95.0 92.0 93.0 92.0 95.0 92.0 100.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.42 (1.7%) stddev-sans-first:1.36 16:32:12 INFO - PROCESS | 1657 | Values: 87.0 85.0 87.0 85.0 85.0 82.0 85.0 86.0 85.0 86.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.29 (2.4%) stddev-sans-first:1.30 16:32:12 INFO - PROCESS | 1657 | Values: 53.0 54.0 57.0 54.0 55.0 53.0 55.0 53.0 53.0 54.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:72.50 stddev:4.12 (5.7%) stddev-sans-first:4.32 16:32:12 INFO - PROCESS | 1657 | Values: 71.0 66.0 75.0 80.0 78.0 72.0 70.0 70.0 71.0 73.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.50 stddev:2.05 (2.4%) stddev-sans-first:2.15 16:32:12 INFO - PROCESS | 1657 | Values: 85.0 86.0 85.0 85.0 84.0 87.0 84.0 85.0 90.0 89.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:143.50 stddev:13.47 (9.4%) stddev-sans-first:13.94 16:32:12 INFO - PROCESS | 1657 | Values: 137.0 137.0 135.0 142.0 152.0 145.0 149.0 136.0 180.0 140.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.00 stddev:1.62 (1.0%) stddev-sans-first:1.72 16:32:12 INFO - PROCESS | 1657 | Values: 168.0 168.0 168.0 166.0 171.0 167.0 167.0 167.0 170.0 166.0 16:32:12 INFO - PROCESS | 1657 | 16:32:12 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:76.00 stddev:8.31 (10.9%) stddev-sans-first:8.53 16:32:12 INFO - PROCESS | 1657 | Values: 67.0 67.0 67.0 71.0 83.0 71.0 59.0 82.0 81.0 81.0 16:32:12 INFO - PROCESS | 1657 | -------- Summary: end -------- 16:32:12 INFO - PROCESS | 1657 | 16:32:13 INFO - TEST-INFO | 1657: exit 0 16:32:13 INFO - TEST-OK | kraken | took 58546ms 16:32:13 INFO - SUITE-END | took 1177s 16:32:13 INFO - Completed test suite (00:19:37) 16:32:13 INFO - javascript benchmark 16:32:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.3960867239557, 3286.129175768421, 3305.5702758641387, 3327.866885324584, 3377.0, 5451.836444906649, 5477.726113694319, 5525.4474552544725, 5577.888442231153, 5607.186957891104, 5509.338879334476, 5556.888862222753, 5564.638298510594, 5565.693886836222, 5581.246531718217, 5051.31807206027, 5090.541851233388, 5101.260317253996, 5159.22611608258, 5160.612954028444, 5097.388313402378, 5160.458151894035, 5165.793368265265, 5167.6641018333685, 5185.533302002819, 5104.974475127638, 5111.897762044776, 5134.4095429025765, 5134.948650513485, 5160.716160611165, 5199.792008319662, 5203.401608814959, 5217.973910130463, 5238.68567885928, 5278.524932756032, 5015.3229314042865, 5048.1670524363435, 5050.772715227824, 5118.744062796883, 5140.563052140573, 5503.091989821674, 5533.529649979757, 5545.805896793633, 5575.5539556835765, 5612.579056570733, 6083.969580152071, 6088.66512341821, 6100.389961003909, 6103.023516237425, 6133.969330153321, 5959.433853783884, 6143.508519318489, 6170.352113028157, 6170.413810688023, 6202.28673702521, 4400.889977750518, 5626.1842032905, 5648.9717551412805, 5675.233843431125, 5688.857778555569, 6254.906176407358, 6277.18396608438, 6317.810465686037, 6322.4942004639515, 6342.429181373606, 5716.28546431696, 5838.649681019152, 5866.73599688015, 5954.345022047572, 5974.551908606872, 6095.512359011268, 6100.603460775035, 6104.398524058527, 6105.206323177958, 6124.989376752875, 5978.671173085522, 5986.790462333797, 5993.70031498432, 6011.48902343297, 6036.547258955596, 5814.970925145347, 5837.766489340379, 5851.561132915049, 6007.219061522062, 6030.728617212236, 4750.14497390473, 4835.685680430761, 4838.274258861199, 4842.128416884924, 4845.781939812717, 4341.6526677865695, 4378.956210437918, 4380.802863871133, 4383.517813040563, 4389.648828093745, 5462.481064298885, 5468.37113731918, 5471.151971444432, 5501.559875209973, 5557.6109672323355, 6105.64170830808, 6137.285055221596, 6159.5688301818445, 6160.8767824643255, 6163.19878415812, 5554.361248456398, 5558.666480011212, 5567.554595632339, 5575.609707320448, 5603.943960560342, 5236.371635403776, 5318.9202161967605, 5319.202119682079, 5322.0, 5325.147976323769, 6189.133521307021, 6218.595791273643, 6283.088952102011, 6283.120363149163, 6295.653739044396, 6144.600600960924, 6166.136740856194, 6167.506599471942, 6173.783917562864, 6176.845578860564, 6305.085762564494, 6307.369263073656, 6322.715477803418, 6333.0, 6344.175257216532, 5828.0966450200285, 5891.05743081105, 5900.911486327623, 6031.185789918305, 6033.75864965414, 6065.969670151621, 6071.392860713893, 6077.24034495688, 6079.996800527863, 6122.510199183966, 5826.359100498857, 5845.883082338244, 5863.472287494146, 5879.088741245114, 5887.8233652990475, 4656.697314674535, 5802.448767367094, 5879.529637628894, 5892.970535147297, 5898.0, 5751.367349591458, 5757.769689212385, 5767.048437007847, 5767.048437008015, 5772.740226689725, 5806.1000544915605, 5841.386654401226, 5848.181254624271, 5850.0932355484965, 5851.590388672752, 5654.7455364509515, 5719.885602287848, 5742.08126699726, 5781.017227071492, 5803.9419605803405], "value": 5560.344045501653, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.96971196872204, 528.1048622584718, 533.7411355492585, 534.462864820855, 560.0, 30746.538801917963, 32233.033009009738, 32284.677153228462, 32372.219527925954, 40858.59141408585, 38796.0, 40738.0, 43288.78355608218, 43580.91047723808, 43590.89727438161, 9692.806143877118, 9877.950610246939, 9886.851697224547, 9893.604255829794, 9917.950410247975, 8716.25911797498, 8941.731748047569, 9652.613895444174, 9768.023197680246, 9786.902130978706, 11898.643040708792, 12197.329146896916, 12223.816642750366, 12393.070519711013, 12474.064445166603, 12045.45795439203, 12126.0, 12128.696782580506, 12131.393430328448, 12137.089718271076, 8640.61117249719, 8830.823383532357, 8854.158854908774, 11909.22590031645, 12026.39868006605, 34145.14637134067, 39290.89218401618, 40325.99185020369, 42183.9454013649, 42271.57728422738, 3328.4674452087793, 3349.246419555605, 3351.6480769519094, 3356.3958487472246, 3479.199784049683, 26362.604560931602, 31045.0, 31093.067207983797, 31547.36905261882, 31583.0, 3262.9184270393193, 3269.1663625775514, 3273.3289675616443, 3286.359159963808, 3404.489326601005, 478071.0, 478266.0, 479283.0, 481468.0, 483065.0, 52361.73819130956, 64083.0, 65628.67185664042, 65827.67086164538, 65840.34159658341, 65777.67111164509, 66053.669731652, 66200.0, 66229.66885165543, 66255.674886502, 16312.836871631132, 16469.0, 16482.0, 16502.42241521541, 16511.33954641825, 12612.747745045232, 12767.680807979876, 12769.361531923367, 12940.029497787704, 12950.417231224617, 15731.528054158394, 16091.517254482384, 17639.029853357933, 18142.5464363392, 18261.63476730458, 5707.315122185281, 6724.293949135368, 6854.246032936373, 6868.793936181923, 6904.240533541307, 850.6469815026818, 900.3472482450171, 973.7614284500343, 1058.2697938422464, 1097.5829184909683, 28781.424371512454, 29486.705132948395, 29951.550726739115, 29958.101256962327, 31003.844980774953, 937.6202637931649, 1012.4532752313668, 1020.3469779341227, 1063.1494804156644, 1110.0786347331696, 13401.865981340257, 15633.530994070195, 15640.608984775472, 16158.919205403898, 16188.75716864248, 640430.3935440879, 644633.0, 645184.0, 646205.0, 647663.0, 49800.50199497959, 56839.0, 57842.84314313775, 57910.4208957905, 60977.69511152416, 1332.3005422153446, 1356.7964805279287, 1373.7870630052157, 1376.8416632087237, 1379.6068120585987, 28991.0, 29203.85398072996, 31327.530087048257, 31698.607011391974, 31825.204369890012, 51057.74471127769, 51574.96850063053, 51617.0, 51660.48339516557, 51752.96494070173, 560866.0, 567010.0, 567314.0, 567551.0, 568866.0, 26167.084152054587, 26812.195634131007, 40624.79687601661, 40742.0, 40904.0, 46744.06511869811, 47241.76379118083, 47904.0, 48172.75913620409, 48333.51666483431, 39238.607613923494, 39366.606333936295, 39503.60496395114, 39687.80156099201, 40563.59436405598, 39061.41407878827, 39221.6077839218, 40125.59874401218, 40275.1944961105, 40462.59537404706, 38142.04644883789, 38650.42024369693, 38707.80646096864, 39349.409758853064, 39479.80260098681, 32228.83885580557, 34307.828460857534, 34503.5715710927, 34745.65254347424, 35060.474092888115, 25232.0, 25297.367565810266, 25447.745522544537, 25590.616140758277, 25755.0], "value": 20312.508266457906, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.55686499712846, 369.4071016019289, 370.3981030824911, 382.9253295607357, 402.70401255077513, 15079.321430535625, 15572.45496407626, 20614.69077963828, 20774.610919744217, 20910.26814061508, 20838.583228335425, 20881.060352284145, 21493.990841282233, 21515.354539363845, 21551.245706400277, 5229.790808367661, 5816.680082595455, 6047.15339852421, 6085.878282434349, 6091.2690477142805, 5954.285485741717, 6014.458698717114, 6022.126791615213, 6052.757889684407, 6057.363976782441, 5460.808871689492, 6005.039193729004, 6017.819465416044, 6691.899621505681, 6751.561148525355, 6171.105189747506, 6724.697388617499, 6732.865342693119, 6744.325567443241, 6753.358430949052, 5820.563457740686, 5860.472557469806, 5877.559183061245, 5894.4989675877605, 5913.704314784243, 13256.867431325756, 13497.98765092622, 24435.87782061078, 24866.751332486623, 25431.0, 2177.923772667965, 2244.7418546866993, 2298.321995011459, 2523.3943853475216, 2558.692956845172, 8383.790405239857, 9457.574409151604, 10128.341657792294, 10409.219308551888, 10465.79068418635, 1818.9818101818944, 1911.742707554226, 1916.60709554541, 1926.8554858385678, 1935.6032013437352, 111617.0, 115765.42117289444, 120971.0, 122148.0, 122272.77727222792, 8901.421407608485, 10920.344779313267, 11398.259113157617, 11412.28815677655, 11606.535738570363, 14293.64265893361, 14311.928440357731, 14313.854891608855, 14336.49822256216, 14346.56960291193, 8264.421490495728, 8506.0, 8521.53131577769, 8677.262432693291, 8687.34844886636, 7021.22766495685, 8576.0, 8577.528235946958, 8712.128787121364, 8752.606132723915, 4345.9130817383475, 4438.378626992224, 4448.377227188196, 4502.527234640381, 4557.384753058361, 1657.9088150151615, 1692.69531484334, 1746.1443892492587, 1835.5961688428524, 1932.94201173965, 675.8394524844886, 919.296737995434, 939.5771902643837, 988.7478692933399, 997.3268044070223, 20212.0, 20611.69082463764, 21945.82890555248, 21959.852429472005, 21984.99682644393, 887.6183241206344, 932.8647346134773, 1000.4547521600745, 1044.1650917758323, 1044.3420644993605, 10019.499025048864, 10574.83676795552, 11269.54921803135, 11394.1454390921, 11397.316161030365, 423025.0, 484546.0, 486269.0, 559927.0, 563863.0, 104282.0, 125912.0, 126391.0, 127491.0, 137587.31206344103, 966.4539535162611, 980.4362491567377, 1000.2348203624233, 1033.5968972100886, 1039.0856046678873, 19458.497470454888, 19869.900650496656, 19888.0, 19961.700574491686, 20022.099005544493, 54372.456275436736, 55630.887382252935, 56145.0, 56749.0, 57213.71393143007, 58147.4185258142, 77203.61398192972, 78818.6059069701, 80004.0, 81182.0, 5836.941630583639, 7854.882176767239, 8028.518288902685, 8044.597770111588, 8209.71266005687, 7479.551226926402, 8998.460092394478, 12755.234685918875, 13176.934115329363, 13408.932955335162, 11182.832257516306, 11374.54501819918, 11635.53457861676, 11661.416929153338, 11716.121290903387, 11013.614523491638, 11203.935626115506, 11312.095032397554, 11451.969322760991, 11711.355875426932, 11572.768544629229, 11612.245204062046, 11828.0, 11975.520979160736, 12032.157748957488, 6954.617496037767, 6968.268331825086, 7292.525985810906, 7387.335139837441, 7631.580263085361, 10896.418122085493, 11271.774564508827, 11292.249701296312, 11360.602378916697, 11584.0], "value": 9458.93178326763, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.4448492845327, 1525.9771103433447, 1708.419137493252, 1859.4328729737424, 1905.2950408348913, 8411.663533458663, 8919.241864441514, 8922.197002269802, 8927.732168034969, 8957.14907083826, 7890.132085470594, 8088.029436467632, 8547.444416112963, 8684.261837743783, 8724.694635687752, 1968.8720233184797, 2360.5750964826325, 2385.666006759055, 2393.365758074112, 2394.4971555973266, 2086.6765651324067, 2345.7888790008897, 2374.513224788914, 2397.3287479505766, 2403.9639405408934, 2348.037304705071, 2375.406148462884, 2378.250850981935, 2381.773731495505, 2392.1627430399394, 2207.249535158038, 2239.7536271010176, 2368.052778888441, 2370.431096536836, 2370.9762902370926, 2360.0, 2360.5986982213067, 2369.9052037918636, 2371.454565449939, 2374.8693821839793, 8747.737567872973, 8888.288936885034, 8894.199522043047, 8905.332100092519, 8928.107189281085, 5184.018470328121, 5188.533032027098, 5348.812791552277, 5355.919661205085, 5356.491133342326, 3097.845107744604, 3521.154922818505, 3577.2308953574848, 3652.2330310634798, 3659.7804131751914, 1050.123147172112, 1085.5711993762498, 1090.1006669497617, 1108.2630051016065, 1109.7724966381952, 11486.770264594745, 11597.826032609517, 11613.419329033599, 11633.418329083512, 11636.534538618445, 4475.865724028285, 4566.88582785433, 4715.22198837188, 4750.311204875274, 4759.690620109682, 9823.607055717692, 9828.361156524803, 9835.704928852147, 9835.950820245853, 9892.356996795184, 5335.653182543122, 5343.278657381282, 5343.81296654623, 5344.599155063386, 5358.812441564526, 4251.256030194703, 4432.936095337096, 4593.885152871205, 4897.338859253955, 4908.656394052454, 3812.1422679897355, 3817.255635151119, 3940.4877365942243, 3957.2876882161486, 3969.980150099231, 1252.1485389934749, 1273.6815796050987, 1279.2132838304376, 1287.7102651903247, 1290.7741145299535, 736.2821249281907, 840.4999025579771, 843.2832092721261, 927.1146055516965, 965.4545181972163, 5776.42235776419, 5793.7392817323325, 5876.853078672982, 6001.5798894077, 6142.416470435302, 438.99561004389545, 448.87207145963896, 449.6807266840514, 459.4739023817777, 460.5486623109372, 3368.6631336866117, 3966.8809935701975, 3983.980080099639, 4066.471358723347, 4091.3862920562156, 9221.585028673726, 10286.588536458457, 10504.369737815756, 10808.837867431996, 10842.457877106272, 9331.486768227813, 10717.410542420243, 10723.517441715141, 10729.409882456539, 10739.140868730483, 7979.601019948979, 8198.221168988937, 8314.0, 8318.750437486764, 8320.83358332842, 5059.898802024012, 5863.912041319469, 5883.235179426647, 5924.259467566554, 5960.850978725566, 9781.462019588991, 10035.24735644815, 10099.899001009895, 10117.241206909364, 10128.0, 9798.804023919623, 10230.925752796147, 10267.794644106927, 10292.845607316047, 10307.484625768831, 6880.139982502187, 7076.433885289061, 7400.222976587381, 7495.850082998419, 7497.10034795828, 8115.75652730419, 8281.751547453587, 8354.164583541597, 8450.788730281793, 8473.83052338962, 7434.962825185839, 7519.210482899217, 7560.697572097055, 7659.502132361378, 7709.7301594443925, 7293.161286452126, 7305.159906610807, 7336.339729424378, 7370.226126256666, 7533.547987120571, 6847.931520684929, 6982.231954484901, 6991.370776630128, 6992.1259842519685, 6997.685104170223, 4981.352424184833, 5025.497450254945, 5040.092783298895, 5111.923321150261, 5121.283020377225, 4937.35814344133, 4966.850994470172, 5073.879564068146, 5226.268322434938, 5242.370915490013], "value": 4483.199777860897, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.48064367999683, 304.2803769086112, 306.9631644202696, 313.94035133324667, 316.28992910914997, 4369.650427965763, 4762.02378512405, 4981.978694367655, 5099.847004589864, 5120.820771273006, 4206.537280899099, 4411.228035093862, 5047.621428392867, 5073.264376665381, 5102.464241254671, 3677.9632203677957, 3767.5102236709326, 3854.8458061677497, 3881.7282790204695, 3891.7664940103687, 2926.531754919213, 3788.8444024572495, 3937.5471820740554, 3952.7271818704216, 3962.3065963456424, 3635.745497815154, 3864.516935383077, 3868.845246190163, 3875.5237700455878, 3895.6493915547594, 3258.1917036044983, 3388.508666243381, 3552.3960926642403, 3808.1812410331636, 3877.9612203878164, 3347.2134048498583, 3348.4642457206974, 3352.966470335314, 3359.4960755886814, 3360.7815491993188, 4225.309876049574, 4728.196206644879, 4912.715587251945, 4922.0, 4925.187344088221, 4099.569545197741, 4370.8907277318, 4374.912501749979, 4388.363687265329, 4419.204543182226, 4223.958749700211, 4410.698843841043, 5234.790608375661, 5276.076686579833, 5320.308359913225, 3430.2453460238708, 3696.9445458318146, 3710.610385909468, 3712.5916149223563, 3723.765740555672, 5188.377394712658, 5579.609427340088, 5580.748866301026, 5600.299962504687, 5605.887882242332, 3718.2377612589353, 3765.6799172070682, 3781.716371272166, 3784.0729021389934, 3785.6025117362833, 3240.513922911535, 3387.9158669225612, 3559.5728512578658, 3685.686716629063, 3841.5582208045876, 3695.870644527429, 4334.804933777925, 4362.385917210652, 4373.316273234827, 4399.6040356368085, 4524.411826462539, 4687.695299805502, 4926.33494478248, 5293.470652934753, 5318.654287471302, 1289.6260084575367, 1305.778017736987, 1311.7835557132967, 1315.2963164706837, 1318.7735406072445, 778.7157687444155, 815.8939337886037, 817.8405210983801, 818.7912082419064, 820.3601191070974, 4092.5293591237387, 4181.540030596632, 4723.6929599575915, 4741.288806679026, 4903.975480122576, 4623.53764623535, 5370.113931201387, 5609.49514543693, 5628.326544891144, 5633.267675202197, 4296.4414626098405, 4681.555252251099, 5086.18621020635, 5089.8727531812, 5116.258142569381, 2582.13498478012, 2875.4536638038708, 2890.2340879666904, 2909.3017675758088, 2910.2578842395055, 5318.813841515605, 5363.007843548956, 5660.603757737002, 5696.886062278814, 5769.423057694281, 4645.442546894395, 5260.92108618371, 5627.212190293362, 5646.717664116778, 5668.829935101953, 4822.397200349957, 5200.6619569727845, 5229.921551176812, 5722.685252311163, 5798.275215598051, 4715.622750180046, 4765.427408955105, 5175.120229561059, 5402.972985135207, 5479.6026463592825, 4114.76990562419, 5114.8977020460125, 5403.216533602527, 5422.48486393792, 5464.836054918359, 5691.8292451226525, 5704.80033198836, 5706.543476521785, 5721.5136713379825, 5723.08276727301, 3531.947020794639, 3757.755745876509, 3804.31522325984, 3828.961710382972, 3837.6354246346555, 4380.890477738082, 4581.656375771764, 5414.593905457028, 5439.646422982493, 5449.809256675997, 5109.627645753784, 5219.556337711185, 5272.393674727456, 5286.709230992333, 5296.443873393392, 5243.292155559103, 5246.632735708464, 5273.709945953009, 5291.76187071575, 5308.54015145838, 4434.357018232403, 4864.440589332272, 5151.484851514969, 5164.302819119371, 5169.4830516948, 5059.569936555434, 5231.686098834082, 5269.235960785588, 5271.525562699376, 5300.800167930266, 4073.409355643356, 4107.055377263306, 4943.802247910187, 5106.719130447861, 5147.79026928664], "value": 3868.2860042958873, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.8844984802431, 736.6427282767856, 778.1712476212833, 812.3217113710052, 841.1924552429667, 14826.481073162442, 14950.38368285551, 15576.844231557681, 15590.06459612424, 15939.521814345588, 14218.928905355484, 14306.785398219035, 14398.136111833297, 14415.279236038183, 14420.711585768278, 811.7199566149669, 837.0624900111883, 837.9107160691108, 842.1830824100643, 844.3371953016883, 688.5558814564603, 705.4532737128715, 860.4923095373729, 891.7235656946367, 893.6425429828056, 922.898481167071, 923.3905622289291, 924.8196601662678, 925.4586067150725, 928.2156577691874, 921.0789210789211, 922.3958307308724, 922.6263363337858, 923.0630909626701, 924.996378928863, 532.1565318969438, 670.8691805098008, 682.1030345096183, 688.5145972089665, 688.9699898651498, 14779.33492992818, 14782.63043423923, 14785.630359240997, 14792.40830366784, 14806.0, 13503.66240843977, 13839.861601384058, 13963.650908727363, 13974.602539746045, 14247.219097612799, 12385.938070309681, 12560.246385216917, 12618.0, 12693.365331733468, 13194.736105277841, 4459.843905463294, 4505.43682039745, 4570.405847239871, 4574.61115805157, 4722.976385118087, 119516.40241798823, 120595.0, 122738.0, 123019.38490307581, 124537.0, 10298.588056477807, 10787.460626968621, 10917.56329746817, 10988.0, 11289.774204516028, 20747.896260518603, 20825.37523874286, 21093.36719898375, 21114.04986775568, 21136.682949755766, 6485.286618471964, 7551.395888328921, 7717.459777815499, 7757.650905709143, 7969.0, 4421.2483877740215, 4436.290193569013, 4445.399871017371, 4514.7291162530355, 4578.175928332866, 3419.8803041893416, 3520.964790352115, 3538.15084379747, 3570.6429357064085, 3585.6952159066773, 648.8183308673579, 649.4317472211815, 660.0297562582996, 663.7477758451757, 665.4077870695086, 538.64449463354, 559.4154108956151, 615.7906311853991, 628.84593274648, 634.5780056262533, 8545.786355341043, 9165.220956218664, 9215.308851836138, 9293.581788819383, 9400.294977876687, 507.959363250939, 550.9421510741395, 554.0829926471696, 592.280379339105, 619.3960888134123, 4214.3678448232395, 4315.697901146889, 4535.160995215896, 4609.810340088498, 4993.925091123636, 148994.51005490022, 152637.0, 152969.0, 153884.0, 157836.21081894517, 104151.0, 104356.0, 107629.0, 130155.0, 134602.32698836442, 80993.19006809975, 92587.61118583361, 102898.0, 104716.47641761895, 105495.47252263689, 8398.118197589409, 9415.011423800403, 9710.660126895526, 9936.701898943043, 9944.850827237453, 56945.715271423374, 57954.13068803887, 58076.0, 58133.4186658128, 59221.0, 29100.563491547222, 29237.7076229235, 29246.7075329244, 29306.853465732536, 80796.0, 11410.486528106421, 13445.394957226752, 13470.057096003186, 13485.86514134846, 13608.523701670394, 14856.777148342568, 15066.54800355991, 15225.6193595161, 15506.5348039559, 15642.687146256783, 13561.593152205449, 13866.4381172575, 13910.58268251954, 14135.15189088658, 14258.572242832732, 13776.862231377558, 13974.930125349309, 14195.503157389854, 14338.426462941365, 14394.928025360225, 13828.93085534566, 13868.653283667585, 14016.299185040503, 14486.565403037926, 14548.781768273273, 10881.619143329945, 10989.175811814306, 11081.335119892832, 11311.943440282746, 11751.882481175078, 6614.503912206507, 8398.580070996304, 9961.950190249003, 10603.310784798963, 11053.557857685602], "value": 7600.668532222316, "unit": "score"}], "name": "dromaeo_css", "value": 7213.05699941922, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 134.0, 113.0, 112.0, 158.0, 162.0, 113.0, 112.0, 113.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 131.0, 159.0, 108.0, 130.0, 128.0, 109.0, 159.0, 108.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 184.0, 185.0, 179.0, 182.0, 184.0, 182.0, 183.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 78.0, 148.0, 56.0, 56.0, 59.0, 68.0, 138.0, 57.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 132.0, 70.0, 73.0, 88.0, 115.0, 69.0, 69.0, 117.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 82.0, 85.0, 82.0, 87.0, 83.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 92.0, 92.0, 95.0, 92.0, 93.0, 92.0, 95.0, 92.0, 100.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 85.0, 82.0, 85.0, 86.0, 85.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 57.0, 54.0, 55.0, 53.0, 55.0, 53.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 75.0, 80.0, 78.0, 72.0, 70.0, 70.0, 71.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 84.0, 87.0, 84.0, 85.0, 90.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 135.0, 142.0, 152.0, 145.0, 149.0, 136.0, 180.0, 140.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 168.0, 166.0, 171.0, 167.0, 167.0, 167.0, 170.0, 166.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 71.0, 83.0, 71.0, 59.0, 82.0, 81.0, 81.0], "value": 72.9, "unit": "score"}], "name": "kraken", "value": 1470.1, "alertThreshold": 2.0}]} 16:32:13 INFO - Return code: 0 16:32:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:32:13 INFO - # TBPL SUCCESS # 16:32:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:32:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:32:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:32:13 INFO - 2016-08-29 16:32:13.536 screenresolution[1661:9684] starting screenresolution argv=screenresolution get 16:32:13 INFO - 2016-08-29 16:32:13.539 screenresolution[1661:9684] Display 0: 1600x1200x32@60 16:32:13 INFO - 2016-08-29 16:32:13.545 screenresolution[1662:9687] starting screenresolution argv=screenresolution list 16:32:13 INFO - Available Modes on Display 0 16:32:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:32:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:32:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:32:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:32:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:32:13 INFO - Intel Iris: 16:32:13 INFO - Chipset Model: Intel Iris 16:32:13 INFO - Type: GPU 16:32:13 INFO - Bus: Built-In 16:32:13 INFO - VRAM (Dynamic, Max): 1536 MB 16:32:13 INFO - Vendor: Intel (0x8086) 16:32:13 INFO - Device ID: 0x0a2e 16:32:13 INFO - Revision ID: 0x0009 16:32:13 INFO - Displays: 16:32:13 INFO - Display: 16:32:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:32:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:32:13 INFO - Display Serial Number: 1600x1200 60 16:32:13 INFO - Main Display: Yes 16:32:13 INFO - Mirror: Off 16:32:13 INFO - Online: Yes 16:32:13 INFO - Rotation: Supported 16:32:13 INFO - Return code: 0 16:32:13 INFO - Running post-action listener: _resource_record_post_action 16:32:13 INFO - [mozharness: 2016-08-29 23:32:13.780423Z] Finished run-tests step (success) 16:32:13 INFO - Running post-run listener: _resource_record_post_run 16:32:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36676608; Write bytes: 703791104; Read time: 371; Write time: 1973 16:32:13 INFO - TinderboxPrint: CPU usage
53.7% 16:32:13 INFO - TinderboxPrint: I/O read bytes / time
36,676,608 / 371 16:32:13 INFO - TinderboxPrint: I/O write bytes / time
703,791,104 / 1,973 16:32:13 INFO - TinderboxPrint: CPU idle
2,246.2 (46.3%) 16:32:13 INFO - TinderboxPrint: CPU system
974.5 (20.1%) 16:32:13 INFO - TinderboxPrint: CPU user
1,629.0 (33.6%) 16:32:13 INFO - TinderboxPrint: Swap in / out
442,867,712 / 0 16:32:13 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403089920; Write bytes: 396957184; Read time: 29662; Write time: 667 16:32:13 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34518016; Write bytes: 294164992; Read time: 344; Write time: 1272 16:32:14 INFO - Running post-run listener: _upload_blobber_files 16:32:14 INFO - Blob upload gear active. 16:32:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:32:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:32:14 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:15 INFO - (blobuploader) - INFO - Done attempting. 16:32:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:32:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:16 INFO - (blobuploader) - INFO - Done attempting. 16:32:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:21 INFO - (blobuploader) - INFO - Done attempting. 16:32:21 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:21 INFO - Return code: 0 16:32:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7758a38733b7cf5c3adf692a051fc7f7b11a7e3cb8bfeaa701163ebbc008401b69b985673478da7551340e3d2a772b2a399c1d5227e2a1d0ebc31b06f4f8d81b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2fd8e173d7b69d29a931aa87a4bfb631acfe4c56a1a75f0e94797d9fa2875288dec6f805261b1c340529e75439b4304ff8f8f8e18e6585e1dbe9924b3b357a18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9686a039b4caa891735b258e50de367cc1414449dc39c935f520c607ae4daebf13bb2c5ecdcac763e5e0b8b57a73346d9f22eafcefe7e14d97f020da28d83678"} 16:32:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:21 INFO - Contents: 16:32:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7758a38733b7cf5c3adf692a051fc7f7b11a7e3cb8bfeaa701163ebbc008401b69b985673478da7551340e3d2a772b2a399c1d5227e2a1d0ebc31b06f4f8d81b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2fd8e173d7b69d29a931aa87a4bfb631acfe4c56a1a75f0e94797d9fa2875288dec6f805261b1c340529e75439b4304ff8f8f8e18e6585e1dbe9924b3b357a18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9686a039b4caa891735b258e50de367cc1414449dc39c935f520c607ae4daebf13bb2c5ecdcac763e5e0b8b57a73346d9f22eafcefe7e14d97f020da28d83678"} 16:32:21 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:21 INFO - Copying logs to upload dir... 16:32:21 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.712852 ========= master_lag: 3.83 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-08-29 16:32:25.745807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:32:25.749086) ========= 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.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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/7758a38733b7cf5c3adf692a051fc7f7b11a7e3cb8bfeaa701163ebbc008401b69b985673478da7551340e3d2a772b2a399c1d5227e2a1d0ebc31b06f4f8d81b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2fd8e173d7b69d29a931aa87a4bfb631acfe4c56a1a75f0e94797d9fa2875288dec6f805261b1c340529e75439b4304ff8f8f8e18e6585e1dbe9924b3b357a18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9686a039b4caa891735b258e50de367cc1414449dc39c935f520c607ae4daebf13bb2c5ecdcac763e5e0b8b57a73346d9f22eafcefe7e14d97f020da28d83678"} build_url:https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010570 build_url: 'https://queue.taskcluster.net/v1/task/T76tALmdQ_elNG_bXgmgpg/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/7758a38733b7cf5c3adf692a051fc7f7b11a7e3cb8bfeaa701163ebbc008401b69b985673478da7551340e3d2a772b2a399c1d5227e2a1d0ebc31b06f4f8d81b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2fd8e173d7b69d29a931aa87a4bfb631acfe4c56a1a75f0e94797d9fa2875288dec6f805261b1c340529e75439b4304ff8f8f8e18e6585e1dbe9924b3b357a18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9686a039b4caa891735b258e50de367cc1414449dc39c935f520c607ae4daebf13bb2c5ecdcac763e5e0b8b57a73346d9f22eafcefe7e14d97f020da28d83678"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:32:25.787030) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:32:25.787359) ========= 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.dB6QL3IUv9/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.OXvat4FoZa/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005218 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:32:25.835607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:32:25.835932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:32:25.836251) ========= ========= Total master_lag: 3.96 =========