builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0276 starttime: 1471394026.21 results: success (0) buildid: 20160816152615 builduid: 9eaa5fbcfb664d47af209bfb1e29f74c revision: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.207030) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.207494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.207791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.429956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.430245) ========= 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.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-16 17:33:46-- 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% 136M=0s 2016-08-16 17:33:46 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.588578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.588858) ========= 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.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.026184 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:46.643364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 17:33:46.643709) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 14974aa2862f2dd9fac16cd78cd88a6208c951d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 14974aa2862f2dd9fac16cd78cd88a6208c951d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-16 17:33:46,735 truncating revision to first 12 chars 2016-08-16 17:33:46,735 Setting DEBUG logging. 2016-08-16 17:33:46,735 attempt 1/10 2016-08-16 17:33:46,735 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/14974aa2862f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 17:33:47,478 unpacking tar archive at: mozilla-central-14974aa2862f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.010890 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 17:33:47.680421) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:47.680692) ========= script_repo_revision: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:47.681078) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:47.681352) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 17:33:47.697432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-16 17:33:47.697716) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:33:47 INFO - MultiFileLogger online at 20160816 17:33:47 in /builds/slave/test 17:33:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:33:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:33:47 INFO - {'append_to_log': False, 17:33:47 INFO - 'base_work_dir': '/builds/slave/test', 17:33:47 INFO - 'blob_upload_branch': 'Firefox', 17:33:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:33:47 INFO - 'branch': 'Firefox', 17:33:47 INFO - 'buildbot_json_path': 'buildprops.json', 17:33:47 INFO - 'config_files': ('talos/mac_config.py',), 17:33:47 INFO - 'default_actions': ('clobber', 17:33:47 INFO - 'read-buildbot-config', 17:33:47 INFO - 'download-and-extract', 17:33:47 INFO - 'populate-webroot', 17:33:47 INFO - 'create-virtualenv', 17:33:47 INFO - 'install', 17:33:47 INFO - 'run-tests'), 17:33:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:33:47 INFO - 'download_minidump_stackwalk': True, 17:33:47 INFO - 'download_symbols': 'ondemand', 17:33:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:33:47 INFO - 'tooltool.py': '/tools/tooltool.py', 17:33:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:33:47 INFO - '/tools/misc-python/virtualenv.py')}, 17:33:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:33:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:33:47 INFO - 'installer_path': 'installer.exe', 17:33:47 INFO - 'log_level': 'info', 17:33:47 INFO - 'log_name': 'talos', 17:33:47 INFO - 'log_to_console': True, 17:33:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:33:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:33:47 INFO - 'opt_config_files': (), 17:33:47 INFO - 'pip_index': False, 17:33:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:47 INFO - 'cmd': ('bash', 17:33:47 INFO - '-c', 17:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:47 INFO - 'enabled': True, 17:33:47 INFO - 'halt_on_failure': False, 17:33:47 INFO - 'name': 'check_screen_resolution'},), 17:33:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:47 INFO - 'cmd': ('bash', 17:33:47 INFO - '-c', 17:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:47 INFO - 'enabled': True, 17:33:47 INFO - 'halt_on_failure': False, 17:33:47 INFO - 'name': 'check_screen_resolution'},), 17:33:47 INFO - 'run_cmd_checks_enabled': True, 17:33:47 INFO - 'sps_profile': False, 17:33:47 INFO - 'sps_profile_interval': 0, 17:33:47 INFO - 'suite': 'dromaeojs', 17:33:47 INFO - 'system_bits': '32', 17:33:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:33:47 INFO - 'title': 't-yosemite-r7-0276', 17:33:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:33:47 INFO - 'use_talos_json': True, 17:33:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:33:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:33:47 INFO - 'work_dir': 'build'} 17:33:47 INFO - [mozharness: 2016-08-17 00:33:47.901875Z] Running clobber step. 17:33:47 INFO - Running pre-action listener: _resource_record_pre_action 17:33:47 INFO - Running main action method: clobber 17:33:47 INFO - rmtree: /builds/slave/test/build 17:33:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:33:49 INFO - Running post-action listener: _resource_record_post_action 17:33:49 INFO - [mozharness: 2016-08-17 00:33:49.813364Z] Finished clobber step (success) 17:33:49 INFO - [mozharness: 2016-08-17 00:33:49.813500Z] Running read-buildbot-config step. 17:33:49 INFO - Running pre-action listener: _resource_record_pre_action 17:33:49 INFO - Running main action method: read_buildbot_config 17:33:49 INFO - Using buildbot properties: 17:33:49 INFO - { 17:33:49 INFO - "project": "", 17:33:49 INFO - "product": "firefox", 17:33:49 INFO - "script_repo_revision": "production", 17:33:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:33:49 INFO - "repository": "", 17:33:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:33:49 INFO - "buildid": "20160816152615", 17:33:49 INFO - "pgo_build": "False", 17:33:49 INFO - "basedir": "/builds/slave/test", 17:33:49 INFO - "buildnumber": 60, 17:33:49 INFO - "slavename": "t-yosemite-r7-0276", 17:33:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:33:49 INFO - "platform": "yosemite_r7", 17:33:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:33:49 INFO - "branch": "mozilla-central", 17:33:49 INFO - "repo_path": "mozilla-central", 17:33:49 INFO - "revision": "14974aa2862f2dd9fac16cd78cd88a6208c951d6", 17:33:49 INFO - "stage_platform": "macosx64", 17:33:49 INFO - "builduid": "9eaa5fbcfb664d47af209bfb1e29f74c", 17:33:49 INFO - "slavebuilddir": "test" 17:33:49 INFO - } 17:33:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:33:49 INFO - Running post-action listener: _resource_record_post_action 17:33:49 INFO - [mozharness: 2016-08-17 00:33:49.816940Z] Finished read-buildbot-config step (success) 17:33:49 INFO - [mozharness: 2016-08-17 00:33:49.817053Z] Running download-and-extract step. 17:33:49 INFO - Running pre-action listener: _resource_record_pre_action 17:33:49 INFO - Running main action method: download_and_extract 17:33:49 INFO - mkdir: /builds/slave/test/build/tests 17:33:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:49 INFO - trying https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:49 INFO - Downloading https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:50 INFO - Downloaded 1199 bytes. 17:33:50 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:50 INFO - Using the following test package requirements: 17:33:50 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:50 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:33:50 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:33:50 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'jsshell-mac.zip'], 17:33:50 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:33:50 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:50 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:33:50 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:33:50 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:33:50 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:50 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:33:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:33: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')]} 17:33:50 INFO - trying https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:50 INFO - Downloading https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:51 INFO - Downloaded 16333035 bytes. 17:33:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:33:58 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 17:33:58 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')]} 17:33:58 INFO - trying https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:58 INFO - Downloading https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:33:59 INFO - Downloaded 16333035 bytes. 17:33:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 17:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:06 INFO - trying https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:34:06 INFO - Downloading https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:34:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:34:07 INFO - Downloaded 14413719 bytes. 17:34:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 17:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:07 INFO - trying https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:07 INFO - Downloading https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:34:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/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 17:34:12 INFO - Downloaded 111004956 bytes. 17:34:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:12 INFO - mkdir: /builds/slave/test/properties 17:34:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:34:12 INFO - Writing to file /builds/slave/test/properties/build_url 17:34:12 INFO - Contents: 17:34:12 INFO - build_url:https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:13 INFO - Running post-action listener: _resource_record_post_action 17:34:13 INFO - Running post-action listener: set_extra_try_arguments 17:34:13 INFO - [mozharness: 2016-08-17 00:34:13.183556Z] Finished download-and-extract step (success) 17:34:13 INFO - [mozharness: 2016-08-17 00:34:13.183675Z] Running populate-webroot step. 17:34:13 INFO - Running pre-action listener: _resource_record_pre_action 17:34:13 INFO - Running main action method: populate_webroot 17:34:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:34:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:34:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:34:13 INFO - '--tppagecycles', 17:34:13 INFO - '7'], 17:34:13 INFO - 'tests': ['tsvgm']}}, 17:34:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:34:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:34:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:34:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:34:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['damp', 'tps']}, 17:34:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'tests': ['damp', 'tps']}, 17:34:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['dromaeo_dom']}, 17:34:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:34:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['basic_compositor_video']}, 17:34:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:34:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'other-e10s': {'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['a11yr', 17:34:13 INFO - 'ts_paint', 17:34:13 INFO - 'tpaint', 17:34:13 INFO - 'sessionrestore', 17:34:13 INFO - 'sessionrestore_no_auto_restore', 17:34:13 INFO - 'tabpaint']}, 17:34:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['tsvgx', 17:34:13 INFO - 'tsvgr_opacity', 17:34:13 INFO - 'tart', 17:34:13 INFO - 'tscrollx', 17:34:13 INFO - 'cart']}, 17:34:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:34:13 INFO - 'tsvgr_opacity', 17:34:13 INFO - 'tart', 17:34:13 INFO - 'tscrollx', 17:34:13 INFO - 'cart']}, 17:34:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'talos_options': ['--disable-e10s'], 17:34:13 INFO - 'tests': ['tp5o']}, 17:34:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'tests': ['tp5o']}, 17:34:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'talos_options': ['--disable-e10s', 17:34:13 INFO - '--xperf_path', 17:34:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:13 INFO - 'tests': ['tp5n']}, 17:34:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:13 INFO - 'talos_options': ['--xperf_path', 17:34:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:13 INFO - 'tests': ['tp5n']}}, 17:34:13 INFO - 'talos.zip': {'path': '', 17:34:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:34:13 INFO - Running post-action listener: _resource_record_post_action 17:34:13 INFO - [mozharness: 2016-08-17 00:34:13.202228Z] Finished populate-webroot step (success) 17:34:13 INFO - [mozharness: 2016-08-17 00:34:13.202373Z] Running create-virtualenv step. 17:34:13 INFO - Running pre-action listener: _resource_record_pre_action 17:34:13 INFO - Running main action method: create_virtualenv 17:34:13 INFO - Creating virtualenv /builds/slave/test/build/venv 17:34:13 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 17:34:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:34:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:34:13 INFO - Using real prefix '/tools/python27' 17:34:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:34:14 INFO - Installing distribute.............................................................................................................................................................................................done. 17:34:16 INFO - Installing pip.................done. 17:34:16 INFO - Return code: 0 17:34:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:34:16 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')]} 17:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:16 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')]} 17:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:16 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:16 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 17:34:16 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 17:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:16 INFO - 'HOME': '/Users/cltbld', 17:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:16 INFO - 'LOGNAME': 'cltbld', 17:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:16 INFO - 'MOZ_NO_REMOTE': '1', 17:34:16 INFO - 'NO_EM_RESTART': '1', 17:34:16 INFO - 'PAGER': '/bin/cat', 17:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:16 INFO - 'PWD': '/builds/slave/test', 17:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:16 INFO - 'SHELL': '/bin/bash', 17:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:16 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:16 INFO - 'USER': 'cltbld', 17:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:16 INFO - 'XPC_FLAGS': '0x0', 17:34:16 INFO - 'XPC_SERVICE_NAME': '0', 17:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:17 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 17:34:17 INFO - Cleaning up... 17:34:17 INFO - Return code: 0 17:34:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:17 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:17 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 17:34:17 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 17:34:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:17 INFO - 'HOME': '/Users/cltbld', 17:34:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:17 INFO - 'LOGNAME': 'cltbld', 17:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:17 INFO - 'MOZ_NO_REMOTE': '1', 17:34:17 INFO - 'NO_EM_RESTART': '1', 17:34:17 INFO - 'PAGER': '/bin/cat', 17:34:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:17 INFO - 'PWD': '/builds/slave/test', 17:34:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:17 INFO - 'SHELL': '/bin/bash', 17:34:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:17 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:17 INFO - 'USER': 'cltbld', 17:34:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:17 INFO - 'XPC_FLAGS': '0x0', 17:34:17 INFO - 'XPC_SERVICE_NAME': '0', 17:34:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:17 INFO - Downloading/unpacking psutil>=3.1.1 17:34:17 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 17:34:17 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 17:34:17 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 17:34:17 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 17:34:17 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 17:34:17 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 17:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:34:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:21 INFO - Installing collected packages: psutil 17:34:21 INFO - Running setup.py install for psutil 17:34:21 INFO - building 'psutil._psutil_osx' extension 17:34:21 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 17:34:21 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 17:34:21 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 17:34:22 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 17:34:22 INFO - building 'psutil._psutil_posix' extension 17:34:22 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 17:34:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:34:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:34:22 INFO - ^ 17:34:22 INFO - 1 warning generated. 17:34:22 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 17:34:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:22 INFO - Successfully installed psutil 17:34:22 INFO - Cleaning up... 17:34:22 INFO - Return code: 0 17:34:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:22 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 17:34:22 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 17:34:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:22 INFO - 'HOME': '/Users/cltbld', 17:34:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:22 INFO - 'LOGNAME': 'cltbld', 17:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:22 INFO - 'MOZ_NO_REMOTE': '1', 17:34:22 INFO - 'NO_EM_RESTART': '1', 17:34:22 INFO - 'PAGER': '/bin/cat', 17:34:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:22 INFO - 'PWD': '/builds/slave/test', 17:34:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:22 INFO - 'SHELL': '/bin/bash', 17:34:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:22 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:22 INFO - 'USER': 'cltbld', 17:34:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:22 INFO - 'XPC_FLAGS': '0x0', 17:34:22 INFO - 'XPC_SERVICE_NAME': '0', 17:34:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:34:25 INFO - Installing collected packages: mozsystemmonitor 17:34:25 INFO - Running setup.py install for mozsystemmonitor 17:34:25 INFO - Successfully installed mozsystemmonitor 17:34:25 INFO - Cleaning up... 17:34:25 INFO - Return code: 0 17:34:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:25 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:25 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 17:34:25 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 17:34:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:25 INFO - 'HOME': '/Users/cltbld', 17:34:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:25 INFO - 'LOGNAME': 'cltbld', 17:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:25 INFO - 'MOZ_NO_REMOTE': '1', 17:34:25 INFO - 'NO_EM_RESTART': '1', 17:34:25 INFO - 'PAGER': '/bin/cat', 17:34:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:25 INFO - 'PWD': '/builds/slave/test', 17:34:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:25 INFO - 'SHELL': '/bin/bash', 17:34:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:25 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:25 INFO - 'USER': 'cltbld', 17:34:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:25 INFO - 'XPC_FLAGS': '0x0', 17:34:25 INFO - 'XPC_SERVICE_NAME': '0', 17:34:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:25 INFO - Downloading/unpacking jsonschema==2.5.1 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:25 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 17:34:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:34:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:34:28 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 17:34:28 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 17:34:28 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 17:34:28 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 17:34:28 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 17:34:28 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 17:34:28 INFO - Downloading functools32-3.2.3-2.tar.gz 17:34:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:34:28 INFO - warning: no files found matching '*.txt' 17:34:28 INFO - no previously-included directories found matching 'build' 17:34:28 INFO - no previously-included directories found matching 'dist' 17:34:28 INFO - no previously-included directories found matching '.git*' 17:34:28 INFO - Installing collected packages: jsonschema, functools32 17:34:28 INFO - Running setup.py install for functools32 17:34:28 INFO - warning: no files found matching '*.txt' 17:34:28 INFO - no previously-included directories found matching 'build' 17:34:28 INFO - no previously-included directories found matching 'dist' 17:34:28 INFO - no previously-included directories found matching '.git*' 17:34:28 INFO - Successfully installed jsonschema functools32 17:34:28 INFO - Cleaning up... 17:34:28 INFO - Return code: 0 17:34:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:28 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:28 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 17:34:28 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 17:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:28 INFO - 'HOME': '/Users/cltbld', 17:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:28 INFO - 'LOGNAME': 'cltbld', 17:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:28 INFO - 'MOZ_NO_REMOTE': '1', 17:34:28 INFO - 'NO_EM_RESTART': '1', 17:34:28 INFO - 'PAGER': '/bin/cat', 17:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:28 INFO - 'PWD': '/builds/slave/test', 17:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:28 INFO - 'SHELL': '/bin/bash', 17:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:28 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:28 INFO - 'USER': 'cltbld', 17:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:28 INFO - 'XPC_FLAGS': '0x0', 17:34:28 INFO - 'XPC_SERVICE_NAME': '0', 17:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:34:29 INFO - Cleaning up... 17:34:29 INFO - Return code: 0 17:34:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:34:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:34:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:29 INFO - 'HOME': '/Users/cltbld', 17:34:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:29 INFO - 'LOGNAME': 'cltbld', 17:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:29 INFO - 'MOZ_NO_REMOTE': '1', 17:34:29 INFO - 'NO_EM_RESTART': '1', 17:34:29 INFO - 'PAGER': '/bin/cat', 17:34:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:29 INFO - 'PWD': '/builds/slave/test', 17:34:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:29 INFO - 'SHELL': '/bin/bash', 17:34:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:29 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:29 INFO - 'USER': 'cltbld', 17:34:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:29 INFO - 'XPC_FLAGS': '0x0', 17:34:29 INFO - 'XPC_SERVICE_NAME': '0', 17:34:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:29 INFO - Downloading/unpacking blobuploader==1.2.4 17:34:29 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 17:34:29 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 17:34:29 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 17:34:29 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 17:34:29 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 17:34:29 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 17:34:32 INFO - Downloading blobuploader-1.2.4.tar.gz 17:34:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:34:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:34:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:34 INFO - Downloading docopt-0.6.1.tar.gz 17:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:34:34 INFO - Installing collected packages: blobuploader, requests, docopt 17:34:34 INFO - Running setup.py install for blobuploader 17:34:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:34:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:34:34 INFO - Running setup.py install for requests 17:34:34 INFO - Running setup.py install for docopt 17:34:34 INFO - Successfully installed blobuploader requests docopt 17:34:34 INFO - Cleaning up... 17:34:35 INFO - Return code: 0 17:34:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:35 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:35 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 17:34:35 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 17:34:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:35 INFO - 'HOME': '/Users/cltbld', 17:34:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:35 INFO - 'LOGNAME': 'cltbld', 17:34:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:35 INFO - 'MOZ_NO_REMOTE': '1', 17:34:35 INFO - 'NO_EM_RESTART': '1', 17:34:35 INFO - 'PAGER': '/bin/cat', 17:34:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:35 INFO - 'PWD': '/builds/slave/test', 17:34:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:35 INFO - 'SHELL': '/bin/bash', 17:34:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:35 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:35 INFO - 'USER': 'cltbld', 17:34:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:35 INFO - 'XPC_FLAGS': '0x0', 17:34:35 INFO - 'XPC_SERVICE_NAME': '0', 17:34:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uNi08n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YWRasV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-I3_G4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-HUY4Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Aa__LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UAf_tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:35 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DwNEDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3MaPKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YKG_Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iFJB0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Q16UWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AivJmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lHle7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-4NEfrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nbfnvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sJrtrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:36 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-G0888O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:34:37 INFO - Running setup.py install for manifestparser 17:34:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:34:37 INFO - Running setup.py install for mozcrash 17:34:37 INFO - Running setup.py install for mozdebug 17:34:37 INFO - Running setup.py install for mozdevice 17:34:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:34:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:34:37 INFO - Running setup.py install for mozfile 17:34:37 INFO - Running setup.py install for mozhttpd 17:34:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:34:37 INFO - Running setup.py install for mozinfo 17:34:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Running setup.py install for mozInstall 17:34:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Running setup.py install for mozleak 17:34:38 INFO - Running setup.py install for mozlog 17:34:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Running setup.py install for moznetwork 17:34:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Running setup.py install for mozprocess 17:34:38 INFO - Running setup.py install for mozprofile 17:34:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:34:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:34:39 INFO - Running setup.py install for mozrunner 17:34:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:34:39 INFO - Running setup.py install for mozscreenshot 17:34:39 INFO - Running setup.py install for moztest 17:34:39 INFO - Running setup.py install for mozversion 17:34:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:34:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:34:39 INFO - Cleaning up... 17:34:39 INFO - Return code: 0 17:34:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:39 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:39 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 17:34:39 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 17:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:39 INFO - 'HOME': '/Users/cltbld', 17:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:39 INFO - 'LOGNAME': 'cltbld', 17:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:39 INFO - 'MOZ_NO_REMOTE': '1', 17:34:39 INFO - 'NO_EM_RESTART': '1', 17:34:39 INFO - 'PAGER': '/bin/cat', 17:34:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:39 INFO - 'PWD': '/builds/slave/test', 17:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:39 INFO - 'SHELL': '/bin/bash', 17:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:39 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:39 INFO - 'USER': 'cltbld', 17:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:39 INFO - 'XPC_FLAGS': '0x0', 17:34:39 INFO - 'XPC_SERVICE_NAME': '0', 17:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:39 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Caa2cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nFQXyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EgVOmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yBdKle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-qtvgB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rz8jDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-t34no2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-R3RFFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rRl083-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-QlqKmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:40 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)) 17:34:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:40 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tC1XYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-W5ZkkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-8AHnFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-AeWtlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-S0Iky9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uf7TA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:41 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)) 17:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:41 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0dm_2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:41 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)) 17:34:41 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)) 17:34:41 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)) 17:34:41 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)) 17:34:41 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)) 17:34:41 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)) 17:34:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:44 INFO - Downloading blessings-1.6.tar.gz 17:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:34:44 INFO - Installing collected packages: blessings 17:34:44 INFO - Running setup.py install for blessings 17:34:44 INFO - Successfully installed blessings 17:34:44 INFO - Cleaning up... 17:34:44 INFO - Return code: 0 17:34:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:34:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:44 INFO - Reading from file tmpfile_stdout 17:34:44 INFO - Current package versions: 17:34:44 INFO - blessings == 1.6 17:34:44 INFO - blobuploader == 1.2.4 17:34:44 INFO - docopt == 0.6.1 17:34:44 INFO - functools32 == 3.2.3-2 17:34:44 INFO - jsonschema == 2.5.1 17:34:44 INFO - manifestparser == 1.1 17:34:44 INFO - mozInstall == 1.12 17:34:44 INFO - mozcrash == 0.17 17:34:44 INFO - mozdebug == 0.1 17:34:44 INFO - mozdevice == 0.48 17:34:44 INFO - mozfile == 1.2 17:34:44 INFO - mozhttpd == 0.7 17:34:44 INFO - mozinfo == 0.9 17:34:44 INFO - mozleak == 0.1 17:34:44 INFO - mozlog == 3.3 17:34:44 INFO - moznetwork == 0.27 17:34:44 INFO - mozprocess == 0.23 17:34:44 INFO - mozprofile == 0.28 17:34:44 INFO - mozrunner == 6.12 17:34:44 INFO - mozscreenshot == 0.1 17:34:44 INFO - mozsystemmonitor == 0.3 17:34:44 INFO - moztest == 0.7 17:34:44 INFO - mozversion == 1.4 17:34:44 INFO - psutil == 3.1.1 17:34:44 INFO - requests == 1.2.3 17:34:44 INFO - wsgiref == 0.1.2 17:34:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:44 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 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34:44 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 17:34:44 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 17:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:44 INFO - 'HOME': '/Users/cltbld', 17:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:44 INFO - 'LOGNAME': 'cltbld', 17:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:44 INFO - 'MOZ_NO_REMOTE': '1', 17:34:44 INFO - 'NO_EM_RESTART': '1', 17:34:44 INFO - 'PAGER': '/bin/cat', 17:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:44 INFO - 'PWD': '/builds/slave/test', 17:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:44 INFO - 'SHELL': '/bin/bash', 17:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:44 INFO - 'USER': 'cltbld', 17:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:44 INFO - 'XPC_FLAGS': '0x0', 17:34:44 INFO - 'XPC_SERVICE_NAME': '0', 17:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 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)) 17:34:45 INFO - Cleaning up... 17:34:45 INFO - Return code: 0 17:34:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:34: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')]} 17:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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')]} 17:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c57a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cec618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced2ddea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, '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.n7zgG6TDId/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.iYZch4Qo5C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 17:34: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', 'jsonschema'] in /builds/slave/test/build 17:34: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 jsonschema 17:34:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:34:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:45 INFO - 'HOME': '/Users/cltbld', 17:34:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:45 INFO - 'LOGNAME': 'cltbld', 17:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:45 INFO - 'MOZ_NO_REMOTE': '1', 17:34:45 INFO - 'NO_EM_RESTART': '1', 17:34:45 INFO - 'PAGER': '/bin/cat', 17:34:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:45 INFO - 'PWD': '/builds/slave/test', 17:34:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:45 INFO - 'SHELL': '/bin/bash', 17:34:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:34:45 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:34:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:45 INFO - 'USER': 'cltbld', 17:34:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:45 INFO - 'XPC_FLAGS': '0x0', 17:34:45 INFO - 'XPC_SERVICE_NAME': '0', 17:34:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:34:45 INFO - Cleaning up... 17:34:45 INFO - Return code: 0 17:34:45 INFO - Running post-action listener: _resource_record_post_action 17:34:45 INFO - Running post-action listener: _start_resource_monitoring 17:34:45 INFO - Starting resource monitoring. 17:34:45 INFO - [mozharness: 2016-08-17 00:34:45.424684Z] Finished create-virtualenv step (success) 17:34:45 INFO - [mozharness: 2016-08-17 00:34:45.425710Z] Running install step. 17:34:45 INFO - Running pre-action listener: _resource_record_pre_action 17:34:45 INFO - Running main action method: install 17:34:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:45 INFO - Reading from file tmpfile_stdout 17:34:45 INFO - Detecting whether we're running mozinstall >=1.0... 17:34:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:34:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:34:45 INFO - Reading from file tmpfile_stdout 17:34:45 INFO - Output received: 17:34:45 INFO - Usage: mozinstall [options] installer 17:34:45 INFO - Options: 17:34:45 INFO - -h, --help show this help message and exit 17:34:45 INFO - -d DEST, --destination=DEST 17:34:45 INFO - Directory to install application into. [default: 17:34:45 INFO - "/builds/slave/test"] 17:34:45 INFO - --app=APP Application being installed. [default: firefox] 17:34:45 INFO - mkdir: /builds/slave/test/build/application 17:34:45 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'] 17:34:45 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 17:35:19 INFO - Reading from file tmpfile_stdout 17:35:19 INFO - Output received: 17:35:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:35:19 INFO - Running post-action listener: _resource_record_post_action 17:35:19 INFO - [mozharness: 2016-08-17 00:35:19.985256Z] Finished install step (success) 17:35:19 INFO - [mozharness: 2016-08-17 00:35:19.985406Z] Running run-tests step. 17:35:19 INFO - Running pre-action listener: _resource_record_pre_action 17:35:19 INFO - Running main action method: run_tests 17:35:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:35:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:35:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:35:20 INFO - 2016-08-16 17:35:20.000 screenresolution[1646:8279] starting screenresolution argv=screenresolution get 17:35:20 INFO - 2016-08-16 17:35:20.002 screenresolution[1646:8279] Display 0: 1600x1200x32@60 17:35:20 INFO - 2016-08-16 17:35:20.008 screenresolution[1647:8281] starting screenresolution argv=screenresolution list 17:35:20 INFO - Available Modes on Display 0 17:35:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:35:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:35:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:35:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:35:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:35:20 INFO - Intel Iris: 17:35:20 INFO - Chipset Model: Intel Iris 17:35:20 INFO - Type: GPU 17:35:20 INFO - Bus: Built-In 17:35:20 INFO - VRAM (Dynamic, Max): 1536 MB 17:35:20 INFO - Vendor: Intel (0x8086) 17:35:20 INFO - Device ID: 0x0a2e 17:35:20 INFO - Revision ID: 0x0009 17:35:20 INFO - Displays: 17:35:20 INFO - Display: 17:35:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:35:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:35:20 INFO - Display Serial Number: 1600x1200 60 17:35:20 INFO - Main Display: Yes 17:35:20 INFO - Mirror: Off 17:35:20 INFO - Online: Yes 17:35:20 INFO - Rotation: Supported 17:35:20 INFO - Return code: 0 17:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:35:20 INFO - Python 2.7.3 17:35:20 INFO - Return code: 0 17:35:20 INFO - grabbing minidump binary from tooltool 17:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:20 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 0x107bbbd30>, '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 0x107920db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bb3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:35:20 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 17:35:20 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 17:35:20 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 17:35:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:35:20 INFO - Return code: 0 17:35:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:35:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:35:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:35:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:35:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 17:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 17:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n7zgG6TDId/Render', 17:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:20 INFO - 'HOME': '/Users/cltbld', 17:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:20 INFO - 'LOGNAME': 'cltbld', 17:35:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:20 INFO - 'MOZ_NO_REMOTE': '1', 17:35:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:20 INFO - 'NO_EM_RESTART': '1', 17:35:20 INFO - 'PAGER': '/bin/cat', 17:35:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:20 INFO - 'PWD': '/builds/slave/test', 17:35:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:20 INFO - 'SHELL': '/bin/bash', 17:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZch4Qo5C/Listeners', 17:35:20 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 17:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:20 INFO - 'USER': 'cltbld', 17:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:20 INFO - 'XPC_FLAGS': '0x0', 17:35:20 INFO - 'XPC_SERVICE_NAME': '0', 17:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 17:35:20 INFO - mozversion application_buildid: 20160816152615 17:35:20 INFO - mozversion application_changeset: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 17:35:20 INFO - mozversion application_display_name: Nightly 17:35:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:35:20 INFO - mozversion application_name: Firefox 17:35:20 INFO - mozversion application_remotingname: firefox 17:35:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:35:20 INFO - mozversion application_vendor: Mozilla 17:35:20 INFO - mozversion application_version: 51.0a1 17:35:20 INFO - mozversion platform_buildid: 20160816152615 17:35:20 INFO - mozversion platform_changeset: 14974aa2862f2dd9fac16cd78cd88a6208c951d6 17:35:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:35:20 INFO - mozversion platform_version: 51.0a1 17:35:20 INFO - using testdate: 1471394120 17:35:20 INFO - actual date: 1471394120 17:35:20 INFO - starting webserver on 'localhost:49262' 17:35:20 INFO - SUITE-START | Running 2 tests 17:35:20 INFO - TEST-START | dromaeo_css 17:35:20 INFO - operating with platform_type : mac_ 17:35:20 INFO - Initialising browser for dromaeo_css test... 17:35:20 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmphSCkdF/profile http://localhost:49262/getInfo.html) 17:35:22 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 17:35:22 INFO - PROCESS | 1657 | colorDepth:24 17:35:22 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 17:35:22 INFO - PROCESS | 1657 | __metrics 17:35:22 INFO - PROCESS | 1657 | 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" 17:35:22 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:23 INFO - TEST-INFO | 1657: exit 0 17:35:23 INFO - Browser initialized. 17:35:23 INFO - Running cycle 1/1 for dromaeo_css test... 17:35:23 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmphSCkdF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:35 INFO - PROCESS | 1660 | RSS: Main: 228265984 17:35:35 INFO - PROCESS | 1660 | 17:36:26 INFO - PROCESS | 1660 | 1471394186313 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] 17:38:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 17:38:40 INFO - PROCESS | 1660 | RSS: Main: 370262016 17:38:40 INFO - PROCESS | 1660 | 17:41:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 17:41:44 INFO - PROCESS | 1660 | RSS: Main: 371625984 17:41:44 INFO - PROCESS | 1660 | 17:44:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 17:44:47 INFO - PROCESS | 1660 | RSS: Main: 348504064 17:44:47 INFO - PROCESS | 1660 | 17:47:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 17:47:50 INFO - PROCESS | 1660 | RSS: Main: 352067584 17:47:50 INFO - PROCESS | 1660 | 17:50:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 17:50:53 INFO - PROCESS | 1660 | RSS: Main: 336744448 17:50:53 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 17:53:57 INFO - PROCESS | 1660 | RSS: Main: 376520704 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | __start_tp_report 17:53:57 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 17:53:57 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 17:53:57 INFO - PROCESS | 1660 | |i|pagename|runs| 17:53:57 INFO - PROCESS | 1660 | |0;dojo.html;3284.310294838084;3311.6357200707916;3343.3814744272313;3347.0293614851685;3350.4974253861933;5452.291202143726;5489.1217405215175;5528.695921724304;5534.640248383857;5574.275344205248;5494.835154945358;5568.916466252999;5596.2165296858475;5605.7197140143035;5630.493255606994;5008.924866127011;5079.974600127012;5085.669431486942;5097.566706829905;5200.843974680765;5128.743562821863;5134.691918484884;5164.561012313958;5181.404138524081;5192.065428222918;5019.598432125421;5076.54311111998;5081.110805609041;5092.872678183056;5149.2791009258735;5065.772040258197;5102.846914592568;5103.464136265713;5104.43851176374;5113.360829896263;4984.327115839388;5006.274090256892;5077.695338279715;5084.135696931494;5085.974570127163;5499.422560631157;5577;5594.496495315441;5613.158026296048;5626.437356264381;5996.940030599681;6057.697115144225;6067.848303792396;6069.696515174223;6128.816135515942;4815.903681926342;5955.166276721263;5985.610935289193;6084.331056849134;6120.96939515304;5600.439956004367;5641;5642.6614403135945;5645.68948707825;5646.661200328034;6074.483668888194;6187.16473276111;6220.595661282002;6255.46828519581;6260.937390626126;5824.534037277008;5879.823605291848;5887.558433117448;5892.646441213456;5893.499052580579;5106.974465127651;6021.157038014681;6040.09398590215;6047.455728984324;6049.153118563405;5935.376785437553;5952;5957.910631340534;5962.61243019211;5977.193078934375;5914.083317085859;5926.8814623707285;6002.729877155539;6005.3394126646035;6024.518038556993;4776.808927642925;4801.567858892717;4812.663113582085;4822.927656085161;4832.758362081881;4244.02387450888;4267.871963841089;4314.439122914001;4319.460067491564;4343.587359200871;5443.918341224885;5511.0080185566985;5524.364698059774;5536.058869992111;5549.72251387429;6039;6071.666058366833;6108.297545782292;6137.315698286874;6141.486007784275;5550.47270509301;5553.250311208016;5554.833354999276;5558.360788509292;5559.221708960748;5128.153854614022;5272.894542109213;5285.788568457296;5296.179092240709;5341.492558207042;6184.443400094103;6233.345498722569;6251.71867265965;6290.716917738621;6347.873042539031;6100.115483254992;6123;6139.539534534839;6141.662208578571;6141.662208578571;6337.9366206337345;6346.873062538816;6350.904736428864;6361.2048493938255;6365.2361716594305;5818.272715910512;5914.290285165807;5918.822435326947;5966.254218222723;6049.606775559574;6004.699765011819;6047.0627052806885;6052.092186172109;6067.423594758491;6086.908696369469;5832.270966129234;5894.233749612695;5905.084711869667;5916.3492015877355;5918.763249469973;5615.915761263503;5733.426657334233;5830.76676932918;5843.006688862819;5921.082232254007;5774.451427114417;5775.624584402;5809.099589563624;5812.418758124323;5838.095095260242;5073.264376665289;5896.852578685567;5911.822645320647;5915.290165180034;5920.585559010828;4487.6185524230805;5673.177389278614;5698.031334673033;5742.59801813869;5826.737796799239 17:53:57 INFO - PROCESS | 1660 | |1;ext.html;465.4809886976023;493.7111789603082;518.3934796288344;535.1250705097165;536.7343165133262;30493.695063049363;39815;40962;41085.17829643405;41126.7943660282;42163.789181054126;42597.509087181956;42618.360724589154;42719.14561708764;42750.786246068725;9586.18517426018;9756.121949024586;9765.853512197322;9766.853497197548;9784.315097943147;8771.95614021929;8922.776930576723;8932.33007524435;9105.726828195131;9108.362414630978;11709.355985420798;11916.34460104694;11925.344106074162;12136.514539418413;12214.389280535983;10359.637412690596;10360.274780765349;11830.881691183064;11930.582429614922;11963.401829908556;10193.541290641939;11861.11041671878;11872.703182420422;11880.287182769061;11883.88116118845;39236.80381598074;39523.8023809882;39573;39599.20801583981;39629;3479.234568394949;3498.5801703795455;3534.3108093921696;4146.336586146233;4273.807678654468;30502.785529151075;31111.53332699989;31243.531347029813;31995.360092798015;32367;3177.3327601203773;3188.7449004079617;3248.1554795753273;3312.006398080566;3337.3825842219267;358141;361728;362055;362926;363749.9999999974;57020.73199386066;57478.7126064367;57501.42498575044;57511.71244143753;57559.71220143873;33778.32443351119;34103.488447673306;34286.31427371489;60852.69573652192;61061.69469152626;16251;16382.426615068416;16389.918050409673;16396.75404868928;16428.42500512476;12645;12739.490420383267;12744.10791244604;12750.61748147557;12765.872341276468;16144.596385090466;16509.17454127313;18453.1696073677;18658.533536661693;18771.906140469484;7509.324160825552;7617.743072393104;7640.274173953466;7659.8127290270095;7713.807154821175;845.9280961118251;937.8780758501351;940.4827344960245;950.5199874063553;1100.9944950275358;25502.872485637825;29797;29904.850475747917;30053.90635441553;30184.849075754482;1039.417925961464;1044.9477526123662;1054.1619412567027;1054.4253381906879;1074.0280046557878;14877;15351.846481535042;15442.30509627047;15742.527724168292;15763.763543546856;977172;997398;999531;1000023.9998800105;1002163;50839.491605084215;51656.74171629118;51685.7415712919;58880.705596471744;59754.40245597575;1152.3258893547368;1342.2215115233141;1392.5961471173246;1396.699709562449;1399.9090059146122;27745.722542774314;27763.4730089847;29840.60673877906;30882.76468941217;31078.533821992234;50552.24171637355;50998.49001509937;51193;51194.720131995506;51360.48639513557;629999;639490;639703;641330;891600;40671.38992915049;41734.791326043174;42007.57992420036;42084.00201980807;42298.78850605727;45197.38420926384;45833.312500311855;46136.76931615321;46146.76926615345;46235.53764462447;37441.25117497689;38435;39091.6090839088;39418.605813941496;40505.1898962025;34736.47895281624;37500.812495937345;38518.037049073995;39825.80522584327;40023.39964900587;37216.06959826026;37730;38391;38624.80687596544;38792;32374.27671354596;35012.47481287732;35384.82307588446;36017.459738103425;36705.63294367129;23610.88194559016;25256;25501.079708952566;25722.971081156546;25723.614145788204 17:53:57 INFO - PROCESS | 1660 | |2;jquery.html;353.9557555305587;372.94965179700733;374.29819089207734;379.4138056702395;401.28571143364803;15462.072275663453;15809.97235179714;19859;20394.592108157845;20407.87756673386;20917.058732357043;21278.255261065828;21344.57310853782;21642.458938526546;21890.343289701294;5764.221830052941;5919.940800592003;6048.697565121726;6053.941659835655;6240.8439789005415;6282.591631543958;6323.399277068694;6336.746530138812;6338.397852204033;6345.175127233453;3973.1060511384994;6516.739330426801;6892.207396149457;6977.476689248301;7035.683394247246;6267.592606480564;6329.493640508748;6484.221893372825;7012.7545535906;7018.543794653357;5362.785488580452;6135.049067394561;6135.171751813493;6138.600990935575;6156.045812899008;16921.7461738074;24960.750392496207;25403.237902862944;25680.74319256821;25694.843732032103;2080;2178.9782102178933;2265.207177487866;2387.1048356866177;2458.9016439342404;9758;10074.69775906724;10392.63625773102;13345.933270333684;14218.573442796733;1859.7024476083761;1869.957049579188;1883.3690713610956;1908.2748555548942;1945.2510783348512;110532;115080;115582.84417155889;116821.99999999916;117406.23890641647;9834.49220448781;11308.512127331975;11343.943280283545;11446.94276528612;11774.764504709858;14527.055741376776;14549.709005819825;14565.708685826225;14575.052621579563;14590.562283131523;7129.964350178319;8394.748157555283;8511.148885111439;8547.10255423184;8643.827123457622;6965.303469652994;7313;8387.62255698495;8459.365547583955;8631.827363452696;4419.116176764595;4500.707454015478;4501.707389019703;4575.519570445122;4639.791209395586;1885.481492589549;1897.7759345222255;1906.9904650476935;1921.4043646469638;1957.5791204891016;782.2568559868148;815.2621877201143;859.3297228162044;874.6457684637732;892.7723430525175;20816.583668326548;21510;21599.892000539898;21628.675569866464;22103.889480552494;844.9408541402042;866.8773937750628;901.6753968571309;910.4036855859423;917.156216281023;9450.952745236367;10526.368417894952;10711.678649640367;11072.224944253825;11183.944080279547;524728;536418;536610;540108;540873;104537;106097;108672.45663771789;121715;124040.75959240472;1002.1531805624236;1014.4065721552827;1045.1899777672365;1071.5553045486035;1074.1460538871615;19168.424947251606;19626.411207663794;19729.605407891475;19734.802651973296;19760.505987350432;54657.72671136619;55220.89558208894;55764.72117639386;55932;56600;82059.17940820515;82405;83338;83607.5819620898;85124;6781;7392.55644661322;8076.798080047811;8172.713955011546;8230.929979102679;9161.312901532545;12698.619041428772;12780.552680656132;13015.219086854819;13092.410841511964;10611.31026483307;10832.295900766425;10955.726106847393;11063.557457701601;11113.222074455034;10877.619283325046;10921.453927303763;10926.562937482411;11142.331460112418;11224.66326010221;10068.546915388972;10117.544710487899;11122.165837562054;11132.443377831238;11173.240139790314;7882.802929926797;8037.437379383387;8059.274665280153;8089.716859909875;8268.545230012167;9310.020793346064;10689.144868410664;10841.945790271313;10858.945705271422;11015.779684406427 17:53:57 INFO - PROCESS | 1660 | |3;mootools.html;1384.626150939247;1495.8504149585042;1524.245498478253;1561.6408226107994;1684.9443678711966;7709.922900770991;8219.465734727239;8539.018012928507;8674.869876951834;8819.735407937756;8653.56732163391;8710.782230444242;8740.825183496327;8754.912450875489;8767;2048.5902819436096;2375.228050883461;2378.2389635316704;2380.8809559521956;2381.940451488709;2357.8585284882874;2387.248016874679;2405.795507381875;2406.578848701479;2411.855288682676;2363.7399886012613;2380.9285721428387;2390.8326417150806;2391.330427480308;2399.9040038398443;2335.4161459635093;2346.683197768305;2356.1400088967575;2370.111208296889;2385.8091352691745;2064.494198921259;2360.197532838843;2361.8110551156037;2365.503244318695;2365.7042869641296;8165.183481651847;8247.21651443112;8664.740057798213;8706.390552661316;8755.868661970075;5127.333446651949;5170.5346518813485;5287;5287.524122828925;5294.073537131025;3334.7999120052873;3588.766730162531;3609.9892030231576;3706.3328600851837;3712.03487093355;1086.1745073743932;1099.19758576239;1122.141561705296;1128.8701799293103;1130.9434528273637;11399.088072954144;11518.769624607545;11540.942295288554;11545.595904143314;11568.248063875737;4615.699979501322;4724.4094488188975;4856.223004319292;4928.043174095514;4960.652754307164;9195.402298850553;9222.216111630587;9226.81546369069;9227.492487913229;9260.768480788034;5255.658382205144;5260.868478288074;5264.236685680631;5265.578753699771;5272.894542109137;4033.9394909076386;4373.125374925028;4795.256735205979;4897.804087836518;4946.282788995575;3746.6815320698047;3757.455169000534;3805.6765174960437;3862.092408284051;3884.7863367515056;1161.96514104577;1170.3913964738288;1179.7168679516853;1179.7581495793513;1184;785.6419220913316;865.1867244789877;897.9775505612412;946.8590348629884;953.50894289441;5537.889242215133;5819.330776960619;5921.082232253922;6045.909311360333;6126.264848218153;851.4210336970796;951.8381875081253;955.7085089047769;961.5576834655997;1022.616518805448;3903.6877049835944;3925.9214815703526;3972.5828787977425;4036.555978842325;4063.9796801015805;10142.898571014342;10769.138468922467;10913.945430272957;10948.726281842859;10952.507137178845;10709.732256693645;10743.14054875608;10879.891201087888;10902.945485272523;10941.179411544166;7994.68021279154;8239.258466737903;8243.546604936786;8248.979000144884;8267.255946964802;5732;5789.131630255327;5881.529477641864;5910.7340169693325;5948.643081415129;10061.849072264069;10075.596976120873;10083.949580252345;10154.492275386348;10184.439855808001;9300.20948219409;9629.470379129216;9631.470269134985;9818.361806482555;10230.48847557634;6920.4809639276245;7078.1506219254015;7084.539504932163;7948.244916732901;8231.053428855519;7154.964225179049;8287;8344.916550834414;8474.533900635524;8481.194286542768;7037.648117594203;7389.113306403266;7401.444891633041;7464.701411943462;7474.514156579805;7159.140903091662;7298.4891057625455;7332.30343117403;7352.926470735438;7505.774826755206;6821.795346139624;7006.719731210898;7016.964915175391;7027.332403421667;7031.1211098612675;4551.180787458289;4798.4001999750035;4799.1601469743355;4920.188168952082;4959.330490383896;4235.5552666969525;4862.586680132228;4988.950110498994;5030.673006254582;5229.947700522946 17:53:57 INFO - PROCESS | 1660 | |4;prototype.html;268.4175339513256;288.47497554450905;298.4821334983803;306.45451097047254;312.62172770947154;5197.1424714922;5204.739763011853;5221.2429197766305;5260.500252476014;5292.153255479124;5102.387713474388;5118.9744051279695;5135.794568217267;5183.740812959356;5188.351456067991;3977.2840888640108;3988.9601103989025;3990.1022269989166;3992.520897492305;4014.377771445416;3679.4664773607947;3992.8802135935966;3994.48071750672;3997.660198883084;4038.252923209201;3481.843317050726;3915.393114067324;3967.97633207065;3975.60243975603;4008.619181177798;3934.921301573967;3947.921041579181;3948.0129967508124;3970.3647416413237;3993.3610622300293;2933.647962244523;3454.2055327274866;3482.930341393158;3500.529777493459;3501.9299614007577;4462.233415282513;4525.483962872442;4691.507391723888;5155.304033955405;5196.68819870809;4315.691105258703;4542.318652202163;4548.658850586219;4557.74932378719;4602.205139995371;4632;4727.101850648366;5213.973930130325;5417.997670430983;5464.398916119224;3803.3724435468116;3820.274147911916;3835.9232815343535;3855.1664526573227;3865.768053916774;4768.499307572724;5421.83734487962;5422.891542169174;5562.694051827189;5782.08004957498;3721.2557488502407;3731.555830930745;3733.7386382953478;3738.5513738351574;3745.400735882246;3915.647591716759;3916.6083391661177;3926.6858651307825;3931.1744533648252;3939.7786686127392;3957.920841583152;3964.1278918637854;4473.597376236155;4544.545545445429;4547.749873756975;4636.837710680177;5242.135047717084;5383.273258110105;5472.480114389126;5507.504324610724;1156.3004382348727;1331.6604265912324;1345.5626921250632;1345.8788709016235;1355.2884735513935;695;839.0393000015071;851.8764779106995;854.4488804721044;901.4230892228978;3897.7198338971452;3949.6445319921345;3970.550748976603;4324.524302326741;4325.718828276152;4337.243416416356;4433.467983841895;4705.811767529261;4730.077634861238;4767.070421267819;3567.500549923013;3818.882697185729;4168.353905144708;4319.827206911751;4349.020920669352;2166.7974274277612;2196.2532738868863;2229.4092065602626;2259.4577301447534;2463.8398504097177;4164.8542301019315;4805.519448055236;4823.807047718122;4852.236421725212;4876.512348765166;4565.931511027337;4603.815847366068;4745.976270118627;4807.879803004883;4829.092508459209;4338.7200775771225;4859.396399188259;4891.975540122347;5898.1152827076285;5916.970415147896;5383.842473868137;5617.297837770278;5694.886102278014;5696;5714.5999780015;4781.593564547053;5641.153826925994;5659.320881494247;5687.260656114679;5718.456746609066;5434.711999240402;5532.889342213214;5599.412061733622;5749.108888122348;5849.099042811188;3680.2455496622592;3735.9352584513285;3829.6553310202216;3843.1929294848396;3855.7300988930506;5390.409829100503;5678.858028549319;5693;5716.657000579979;5730.398308177748;4951.9752401237765;5329.760160792696;5392.973035134799;5460.535854452416;5652.106544307703;4286.421333119989;4673.836415725434;5230.921536177037;5423.322084739408;5459.781608735765;4756.8097276108565;5324.440933702061;5347.385050719217;5352.545033672026;5352.5450336721815;4643.374818813389;5172.870678233075;5385.057614917296;5443.836684899459;5463.836084917459;4991.450940396626;5383.461653834742;5394.27177331071;5395;5499.1476321170285 17:53:57 INFO - PROCESS | 1660 | |5;yui.html;711.5410560188678;720.0711082703316;764.182324912344;815.5636734347123;822.2846123872232;15182.240887955599;16160.191990400463;16167;16185.27166277516;16262.51212463625;14290.14259144452;14491;14494.85505144951;14500.637484062903;14509.927450362733;678.0879716780922;696.0429409561852;820.5979070255562;822.5558198572778;839.4459656626627;827.3753316246256;844.0926004545108;845.1084106267884;845.5560830563973;847.1401527449643;905.9139381758722;906.9773255668628;908.7364664247393;908.9091090890925;909.3180114913814;717.4654882112859;863.6027427383411;897.7755561109723;902.2015516268117;904.6381447421019;653.8855700252484;654.0222367560527;656.4256275758711;658.6969993802809;660.0231657147401;14147.434102635882;14207.218602976833;14207.218602976936;14209.218492982882;14228.430862765477;13538.187708737507;13768.380422880995;13888.4444622215;13968.790468142986;14063;12394.504219831197;12408.75182496345;12444.066694997911;12583.937080314632;12635.74728505434;4497.955020449786;4544.575804892576;4616.8153273869;4640.65195110368;4656.371389862359;92474;114739;115493.84506154915;118474;118671;10357.22320825926;10407.94796026015;10594.099501542301;10780.024926635233;10892.945535272273;20677.27629532959;20678.379648610568;20706.378808635764;20714;20768.169273229203;6614.272430032693;7697.4226932980255;7993.920060799318;8031.638576264082;8033.919660803317;4268.40242366069;4331.545187755303;4340.457442819647;4510.526394728572;4541.3869127668;3248.756343274264;3340.081477593681;3364.4280472319765;3365.8317084145697;3367.2760356522977;609.8810731907236;616.7502161624549;617.9505639548826;624.7126321891981;632.1529150937765;561.2787567975132;582.347770497046;582.9912551311734;645.4578154350369;649.1787888321277;7276.6725497352745;7482.8877566836545;8286.104166459147;8871.556422178865;8882.644694212158;536.9919451208235;604.694629212245;616.2543322579729;622.4677900395187;638.8083574927504;4096.877093687194;4330.631896288788;4336.848210312624;4352.173087113439;4475.507694153641;139938.3003084978;151144;155665;156828.43171568366;157054;128105;132182.06907585575;134461;134924;136430;107448.46275768727;107840;115354;116178;118355;7498.10022797246;8001.759947201594;8096.392770542115;8402.243798058204;8614.526201059001;50393.74803125961;60951.39048609457;62995;63130;63453.53016752184;90244.5487772557;96074.0392596065;97205;97564.51217743866;98989.50505247715;11005;11509.827352589886;11569.537218511165;11648.301101933912;11733.237339572901;12040.277583344678;12109.212901161396;12292.877071229532;12436.253824770543;12716.427760750603;10368.859425463344;11314.490847912028;11714.355710436008;11780.941095294469;12170.513179472722;11506.252093613888;11622.846240982477;11821.231619944676;12079.094067944763;12158.331291779037;11564.304905120734;11585.594504192313;11627.826414010953;11700.414979251174;11721.531138754355;9616.182624476718;9744.317897747089;9850.950745246228;9852.605895764089;9869.753256168653;9229.676961306322;9752.463614500986;9786.902130978599;9855.35940163887;9885.76558880587 17:53:57 INFO - PROCESS | 1660 | __end_tp_report 17:53:57 INFO - PROCESS | 1660 | __start_cc_report 17:53:57 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1266 17:53:57 INFO - PROCESS | 1660 | __end_cc_report 17:53:57 INFO - PROCESS | 1660 | __startTimestamp1471395237719__endTimestamp 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | ------- Summary: start ------- 17:53:57 INFO - PROCESS | 1660 | Number of tests: 6 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5570.38 Median:5733.43 stddev:622.90 (10.9%) stddev-sans-first:598.44 17:53:57 INFO - PROCESS | 1660 | Values: 3284.3 3311.6 3343.4 3347.0 3350.5 5452.3 5489.1 5528.7 5534.6 5574.3 5494.8 5568.9 5596.2 5605.7 5630.5 5008.9 5080.0 5085.7 5097.6 5200.8 5128.7 5134.7 5164.6 5181.4 5192.1 5019.6 5076.5 5081.1 5092.9 5149.3 5065.8 5102.8 5103.5 5104.4 5113.4 4984.3 5006.3 5077.7 5084.1 5086.0 5499.4 5577.0 5594.5 5613.2 5626.4 5996.9 6057.7 6067.8 6069.7 6128.8 4815.9 5955.2 5985.6 6084.3 6121.0 5600.4 5641.0 5642.7 5645.7 5646.7 6074.5 6187.2 6220.6 6255.5 6260.9 5824.5 5879.8 5887.6 5892.6 5893.5 5107.0 6021.2 6040.1 6047.5 6049.2 5935.4 5952.0 5957.9 5962.6 5977.2 5914.1 5926.9 6002.7 6005.3 6024.5 4776.8 4801.6 4812.7 4822.9 4832.8 4244.0 4267.9 4314.4 4319.5 4343.6 5443.9 5511.0 5524.4 5536.1 5549.7 6039.0 6071.7 6108.3 6137.3 6141.5 5550.5 5553.3 5554.8 5558.4 5559.2 5128.2 5272.9 5285.8 5296.2 5341.5 6184.4 6233.3 6251.7 6290.7 6347.9 6100.1 6123.0 6139.5 6141.7 6141.7 6337.9 6346.9 6350.9 6361.2 6365.2 5818.3 5914.3 5918.8 5966.3 6049.6 6004.7 6047.1 6052.1 6067.4 6086.9 5832.3 5894.2 5905.1 5916.3 5918.8 5615.9 5733.4 5830.8 5843.0 5921.1 5774.5 5775.6 5809.1 5812.4 5838.1 5073.3 5896.9 5911.8 5915.3 5920.6 4487.6 5673.2 5698.0 5742.6 5826.7 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:79485.64 Median:36960.85 stddev:198370.67 (536.7%) stddev-sans-first:198838.42 17:53:57 INFO - PROCESS | 1660 | Values: 465.5 493.7 518.4 535.1 536.7 30493.7 39815.0 40962.0 41085.2 41126.8 42163.8 42597.5 42618.4 42719.1 42750.8 9586.2 9756.1 9765.9 9766.9 9784.3 8772.0 8922.8 8932.3 9105.7 9108.4 11709.4 11916.3 11925.3 12136.5 12214.4 10359.6 10360.3 11830.9 11930.6 11963.4 10193.5 11861.1 11872.7 11880.3 11883.9 39236.8 39523.8 39573.0 39599.2 39629.0 3479.2 3498.6 3534.3 4146.3 4273.8 30502.8 31111.5 31243.5 31995.4 32367.0 3177.3 3188.7 3248.2 3312.0 3337.4 358141.0 361728.0 362055.0 362926.0 363750.0 57020.7 57478.7 57501.4 57511.7 57559.7 33778.3 34103.5 34286.3 60852.7 61061.7 16251.0 16382.4 16389.9 16396.8 16428.4 12645.0 12739.5 12744.1 12750.6 12765.9 16144.6 16509.2 18453.2 18658.5 18771.9 7509.3 7617.7 7640.3 7659.8 7713.8 845.9 937.9 940.5 950.5 1101.0 25502.9 29797.0 29904.9 30053.9 30184.8 1039.4 1044.9 1054.2 1054.4 1074.0 14877.0 15351.8 15442.3 15742.5 15763.8 977172.0 997398.0 999531.0 1000024.0 1002163.0 50839.5 51656.7 51685.7 58880.7 59754.4 1152.3 1342.2 1392.6 1396.7 1399.9 27745.7 27763.5 29840.6 30882.8 31078.5 50552.2 50998.5 51193.0 51194.7 51360.5 629999.0 639490.0 639703.0 641330.0 891600.0 40671.4 41734.8 42007.6 42084.0 42298.8 45197.4 45833.3 46136.8 46146.8 46235.5 37441.3 38435.0 39091.6 39418.6 40505.2 34736.5 37500.8 38518.0 39825.8 40023.4 37216.1 37730.0 38391.0 38624.8 38792.0 32374.3 35012.5 35384.8 36017.5 36705.6 23610.9 25256.0 25501.1 25723.0 25723.6 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:32989.66 Median:13709.83 stddev:89680.30 (654.1%) stddev-sans-first:89898.40 17:53:57 INFO - PROCESS | 1660 | Values: 354.0 372.9 374.3 379.4 401.3 15462.1 15810.0 19859.0 20394.6 20407.9 20917.1 21278.3 21344.6 21642.5 21890.3 5764.2 5919.9 6048.7 6053.9 6240.8 6282.6 6323.4 6336.7 6338.4 6345.2 3973.1 6516.7 6892.2 6977.5 7035.7 6267.6 6329.5 6484.2 7012.8 7018.5 5362.8 6135.0 6135.2 6138.6 6156.0 16921.7 24960.8 25403.2 25680.7 25694.8 2080.0 2179.0 2265.2 2387.1 2458.9 9758.0 10074.7 10392.6 13345.9 14218.6 1859.7 1870.0 1883.4 1908.3 1945.3 110532.0 115080.0 115582.8 116822.0 117406.2 9834.5 11308.5 11343.9 11446.9 11774.8 14527.1 14549.7 14565.7 14575.1 14590.6 7130.0 8394.7 8511.1 8547.1 8643.8 6965.3 7313.0 8387.6 8459.4 8631.8 4419.1 4500.7 4501.7 4575.5 4639.8 1885.5 1897.8 1907.0 1921.4 1957.6 782.3 815.3 859.3 874.6 892.8 20816.6 21510.0 21599.9 21628.7 22103.9 844.9 866.9 901.7 910.4 917.2 9451.0 10526.4 10711.7 11072.2 11183.9 524728.0 536418.0 536610.0 540108.0 540873.0 104537.0 106097.0 108672.5 121715.0 124040.8 1002.2 1014.4 1045.2 1071.6 1074.1 19168.4 19626.4 19729.6 19734.8 19760.5 54657.7 55220.9 55764.7 55932.0 56600.0 82059.2 82405.0 83338.0 83607.6 85124.0 6781.0 7392.6 8076.8 8172.7 8230.9 9161.3 12698.6 12780.6 13015.2 13092.4 10611.3 10832.3 10955.7 11063.6 11113.2 10877.6 10921.5 10926.6 11142.3 11224.7 10068.5 10117.5 11122.2 11132.4 11173.2 7882.8 8037.4 8059.3 8089.7 8268.5 9310.0 10689.1 10841.9 10858.9 11015.8 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5649.47 Median:4937.16 stddev:3159.04 (64.0%) stddev-sans-first:3151.64 17:53:57 INFO - PROCESS | 1660 | Values: 1384.6 1495.9 1524.2 1561.6 1684.9 7709.9 8219.5 8539.0 8674.9 8819.7 8653.6 8710.8 8740.8 8754.9 8767.0 2048.6 2375.2 2378.2 2380.9 2381.9 2357.9 2387.2 2405.8 2406.6 2411.9 2363.7 2380.9 2390.8 2391.3 2399.9 2335.4 2346.7 2356.1 2370.1 2385.8 2064.5 2360.2 2361.8 2365.5 2365.7 8165.2 8247.2 8664.7 8706.4 8755.9 5127.3 5170.5 5287.0 5287.5 5294.1 3334.8 3588.8 3610.0 3706.3 3712.0 1086.2 1099.2 1122.1 1128.9 1130.9 11399.1 11518.8 11540.9 11545.6 11568.2 4615.7 4724.4 4856.2 4928.0 4960.7 9195.4 9222.2 9226.8 9227.5 9260.8 5255.7 5260.9 5264.2 5265.6 5272.9 4033.9 4373.1 4795.3 4897.8 4946.3 3746.7 3757.5 3805.7 3862.1 3884.8 1162.0 1170.4 1179.7 1179.8 1184.0 785.6 865.2 898.0 946.9 953.5 5537.9 5819.3 5921.1 6045.9 6126.3 851.4 951.8 955.7 961.6 1022.6 3903.7 3925.9 3972.6 4036.6 4064.0 10142.9 10769.1 10913.9 10948.7 10952.5 10709.7 10743.1 10879.9 10902.9 10941.2 7994.7 8239.3 8243.5 8249.0 8267.3 5732.0 5789.1 5881.5 5910.7 5948.6 10061.8 10075.6 10083.9 10154.5 10184.4 9300.2 9629.5 9631.5 9818.4 10230.5 6920.5 7078.2 7084.5 7948.2 8231.1 7155.0 8287.0 8344.9 8474.5 8481.2 7037.6 7389.1 7401.4 7464.7 7474.5 7159.1 7298.5 7332.3 7352.9 7505.8 6821.8 7006.7 7017.0 7027.3 7031.1 4551.2 4798.4 4799.2 4920.2 4959.3 4235.6 4862.6 4989.0 5030.7 5229.9 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4297.81 Median:4640.11 stddev:1321.39 (28.5%) stddev-sans-first:1290.02 17:53:57 INFO - PROCESS | 1660 | Values: 268.4 288.5 298.5 306.5 312.6 5197.1 5204.7 5221.2 5260.5 5292.2 5102.4 5119.0 5135.8 5183.7 5188.4 3977.3 3989.0 3990.1 3992.5 4014.4 3679.5 3992.9 3994.5 3997.7 4038.3 3481.8 3915.4 3968.0 3975.6 4008.6 3934.9 3947.9 3948.0 3970.4 3993.4 2933.6 3454.2 3482.9 3500.5 3501.9 4462.2 4525.5 4691.5 5155.3 5196.7 4315.7 4542.3 4548.7 4557.7 4602.2 4632.0 4727.1 5214.0 5418.0 5464.4 3803.4 3820.3 3835.9 3855.2 3865.8 4768.5 5421.8 5422.9 5562.7 5782.1 3721.3 3731.6 3733.7 3738.6 3745.4 3915.6 3916.6 3926.7 3931.2 3939.8 3957.9 3964.1 4473.6 4544.5 4547.7 4636.8 5242.1 5383.3 5472.5 5507.5 1156.3 1331.7 1345.6 1345.9 1355.3 695.0 839.0 851.9 854.4 901.4 3897.7 3949.6 3970.6 4324.5 4325.7 4337.2 4433.5 4705.8 4730.1 4767.1 3567.5 3818.9 4168.4 4319.8 4349.0 2166.8 2196.3 2229.4 2259.5 2463.8 4164.9 4805.5 4823.8 4852.2 4876.5 4565.9 4603.8 4746.0 4807.9 4829.1 4338.7 4859.4 4892.0 5898.1 5917.0 5383.8 5617.3 5694.9 5696.0 5714.6 4781.6 5641.2 5659.3 5687.3 5718.5 5434.7 5532.9 5599.4 5749.1 5849.1 3680.2 3735.9 3829.7 3843.2 3855.7 5390.4 5678.9 5693.0 5716.7 5730.4 4952.0 5329.8 5393.0 5460.5 5652.1 4286.4 4673.8 5230.9 5423.3 5459.8 4756.8 5324.4 5347.4 5352.5 5352.5 4643.4 5172.9 5385.1 5443.8 5463.8 4991.5 5383.5 5394.3 5395.0 5499.1 17:53:57 INFO - PROCESS | 1660 | 17:53:57 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:24842.65 Median:4413.84 stddev:40848.06 (925.5%) stddev-sans-first:40922.47 17:53:57 INFO - PROCESS | 1660 | Values: 711.5 720.1 764.2 815.6 822.3 15182.2 16160.2 16167.0 16185.3 16262.5 14290.1 14491.0 14494.9 14500.6 14509.9 678.1 696.0 820.6 822.6 839.4 827.4 844.1 845.1 845.6 847.1 905.9 907.0 908.7 908.9 909.3 717.5 863.6 897.8 902.2 904.6 653.9 654.0 656.4 658.7 660.0 14147.4 14207.2 14207.2 14209.2 14228.4 13538.2 13768.4 13888.4 13968.8 14063.0 12394.5 12408.8 12444.1 12583.9 12635.7 4498.0 4544.6 4616.8 4640.7 4656.4 92474.0 114739.0 115493.8 118474.0 118671.0 10357.2 10407.9 10594.1 10780.0 10892.9 20677.3 20678.4 20706.4 20714.0 20768.2 6614.3 7697.4 7993.9 8031.6 8033.9 4268.4 4331.5 4340.5 4510.5 4541.4 3248.8 3340.1 3364.4 3365.8 3367.3 609.9 616.8 618.0 624.7 632.2 561.3 582.3 583.0 645.5 649.2 7276.7 7482.9 8286.1 8871.6 8882.6 537.0 604.7 616.3 622.5 638.8 4096.9 4330.6 4336.8 4352.2 4475.5 139938.3 151144.0 155665.0 156828.4 157054.0 128105.0 132182.1 134461.0 134924.0 136430.0 107448.5 107840.0 115354.0 116178.0 118355.0 7498.1 8001.8 8096.4 8402.2 8614.5 50393.7 60951.4 62995.0 63130.0 63453.5 90244.5 96074.0 97205.0 97564.5 98989.5 11005.0 11509.8 11569.5 11648.3 11733.2 12040.3 12109.2 12292.9 12436.3 12716.4 10368.9 11314.5 11714.4 11780.9 12170.5 11506.3 11622.8 11821.2 12079.1 12158.3 11564.3 11585.6 11627.8 11700.4 11721.5 9616.2 9744.3 9851.0 9852.6 9869.8 9229.7 9752.5 9786.9 9855.4 9885.8 17:53:57 INFO - PROCESS | 1660 | -------- Summary: end -------- 17:53:57 INFO - PROCESS | 1660 | 17:53:58 INFO - TEST-INFO | 1660: exit 0 17:53:58 INFO - TEST-OK | dromaeo_css | took 1117773ms 17:53:58 INFO - TEST-START | kraken 17:53:58 INFO - operating with platform_type : mac_ 17:53:58 INFO - Initialising browser for kraken test... 17:53:58 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpINnzty/profile http://localhost:49262/getInfo.html) 17:54:00 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 17:54:00 INFO - PROCESS | 1666 | colorDepth:24 17:54:00 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 17:54:00 INFO - PROCESS | 1666 | __metrics 17:54:00 INFO - PROCESS | 1666 | 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" 17:54:00 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:01 INFO - TEST-INFO | 1666: exit 0 17:54:01 INFO - Browser initialized. 17:54:01 INFO - Running cycle 1/1 for kraken test... 17:54:01 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpINnzty/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:13 INFO - PROCESS | 1667 | RSS: Main: 228278272 17:54:13 INFO - PROCESS | 1667 | 17:54:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 17:54:17 INFO - PROCESS | 1667 | RSS: Main: 286027776 17:54:17 INFO - PROCESS | 1667 | 17:54:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 17:54:20 INFO - PROCESS | 1667 | RSS: Main: 293117952 17:54:20 INFO - PROCESS | 1667 | 17:54:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 17:54:24 INFO - PROCESS | 1667 | RSS: Main: 385912832 17:54:24 INFO - PROCESS | 1667 | 17:54:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 17:54:27 INFO - PROCESS | 1667 | RSS: Main: 287424512 17:54:27 INFO - PROCESS | 1667 | 17:54:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:54:29 INFO - PROCESS | 1667 | RSS: Main: 291246080 17:54:29 INFO - PROCESS | 1667 | 17:54:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 17:54:33 INFO - PROCESS | 1667 | RSS: Main: 328650752 17:54:33 INFO - PROCESS | 1667 | 17:54:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 17:54:36 INFO - PROCESS | 1667 | RSS: Main: 330715136 17:54:36 INFO - PROCESS | 1667 | 17:54:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 17:54:40 INFO - PROCESS | 1667 | RSS: Main: 330850304 17:54:40 INFO - PROCESS | 1667 | 17:54:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:54:42 INFO - PROCESS | 1667 | RSS: Main: 300875776 17:54:42 INFO - PROCESS | 1667 | 17:54:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:54:45 INFO - PROCESS | 1667 | RSS: Main: 307339264 17:54:45 INFO - PROCESS | 1667 | 17:54:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:54:48 INFO - PROCESS | 1667 | RSS: Main: 307314688 17:54:48 INFO - PROCESS | 1667 | 17:54:51 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:54:51 INFO - PROCESS | 1667 | RSS: Main: 313794560 17:54:51 INFO - PROCESS | 1667 | 17:54:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:54:55 INFO - PROCESS | 1667 | RSS: Main: 316284928 17:54:55 INFO - PROCESS | 1667 | 17:54:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 17:54:57 INFO - PROCESS | 1667 | RSS: Main: 359739392 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | __start_tp_report 17:54:57 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 17:54:57 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 17:54:57 INFO - PROCESS | 1667 | |i|pagename|runs| 17:54:57 INFO - PROCESS | 1667 | |0;ai-astar;103;113;113;115;108;115;112;114;113;113 17:54:57 INFO - PROCESS | 1667 | |1;audio-beat-detection;142;107;106;165;108;107;129;106;143;106 17:54:57 INFO - PROCESS | 1667 | |2;audio-dft;186;185;185;183;181;183;184;187;183;182 17:54:57 INFO - PROCESS | 1667 | |3;audio-fft;78;58;60;87;58;58;67;69;84;56 17:54:57 INFO - PROCESS | 1667 | |4;audio-oscillator;86;71;104;70;73;68;97;71;68;95 17:54:57 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;90;73;94;125;109;86;90;87;89;86 17:54:57 INFO - PROCESS | 1667 | |6;imaging-darkroom;95;94;96;95;94;106;142;144;95;94 17:54:57 INFO - PROCESS | 1667 | |7;imaging-desaturate;131;71;133;136;134;87;86;83;84;84 17:54:57 INFO - PROCESS | 1667 | |8;json-parse-financial;55;52;54;53;52;55;53;54;55;55 17:54:57 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;66;71;68;77;72;70;67;68;67;72 17:54:57 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;86;83;83;85;87;85;86;88;91;82 17:54:57 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;138;138;137;137;136;136;139;137;217;153 17:54:57 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;175;168;167;169;168;169;170;169;168;169 17:54:57 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;68;67;68;66;67;66;67;67;65;66 17:54:57 INFO - PROCESS | 1667 | __end_tp_report 17:54:57 INFO - PROCESS | 1667 | __start_cc_report 17:54:57 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,2061 17:54:57 INFO - PROCESS | 1667 | __end_cc_report 17:54:57 INFO - PROCESS | 1667 | __startTimestamp1471395297103__endTimestamp 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | ------- Summary: start ------- 17:54:57 INFO - PROCESS | 1667 | Number of tests: 14 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:3.70 (3.3%) stddev-sans-first:2.09 17:54:57 INFO - PROCESS | 1667 | Values: 103.0 113.0 113.0 115.0 108.0 115.0 112.0 114.0 113.0 113.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:118.50 stddev:21.48 (18.1%) stddev-sans-first:21.52 17:54:57 INFO - PROCESS | 1667 | Values: 142.0 107.0 106.0 165.0 108.0 107.0 129.0 106.0 143.0 106.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.85 (1.0%) stddev-sans-first:1.80 17:54:57 INFO - PROCESS | 1667 | Values: 186.0 185.0 185.0 183.0 181.0 183.0 184.0 187.0 183.0 182.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:67.50 Median:68.00 stddev:11.66 (17.2%) stddev-sans-first:11.74 17:54:57 INFO - PROCESS | 1667 | Values: 78.0 58.0 60.0 87.0 58.0 58.0 67.0 69.0 84.0 56.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:72.00 stddev:13.84 (19.2%) stddev-sans-first:14.53 17:54:57 INFO - PROCESS | 1667 | Values: 86.0 71.0 104.0 70.0 73.0 68.0 97.0 71.0 68.0 95.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.00 stddev:14.33 (16.3%) stddev-sans-first:15.16 17:54:57 INFO - PROCESS | 1667 | Values: 90.0 73.0 94.0 125.0 109.0 86.0 90.0 87.0 89.0 86.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:94.50 stddev:20.09 (21.3%) stddev-sans-first:20.95 17:54:57 INFO - PROCESS | 1667 | Values: 95.0 94.0 96.0 95.0 94.0 106.0 142.0 144.0 95.0 94.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:102.90 Median:83.50 stddev:26.72 (32.0%) stddev-sans-first:26.33 17:54:57 INFO - PROCESS | 1667 | Values: 131.0 71.0 133.0 136.0 134.0 87.0 86.0 83.0 84.0 84.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.50 stddev:1.23 (2.3%) stddev-sans-first:1.22 17:54:57 INFO - PROCESS | 1667 | Values: 55.0 52.0 54.0 53.0 52.0 55.0 53.0 54.0 55.0 55.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.50 stddev:3.33 (4.7%) stddev-sans-first:3.23 17:54:57 INFO - PROCESS | 1667 | Values: 66.0 71.0 68.0 77.0 72.0 70.0 67.0 68.0 67.0 72.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:2.67 (3.1%) stddev-sans-first:2.83 17:54:57 INFO - PROCESS | 1667 | Values: 86.0 83.0 83.0 85.0 87.0 85.0 86.0 88.0 91.0 82.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:138.00 stddev:25.17 (18.2%) stddev-sans-first:26.50 17:54:57 INFO - PROCESS | 1667 | Values: 138.0 138.0 137.0 137.0 136.0 136.0 139.0 137.0 217.0 153.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:169.00 stddev:2.20 (1.3%) stddev-sans-first:0.88 17:54:57 INFO - PROCESS | 1667 | Values: 175.0 168.0 167.0 169.0 168.0 169.0 170.0 169.0 168.0 169.0 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:0.88 17:54:57 INFO - PROCESS | 1667 | Values: 68.0 67.0 68.0 66.0 67.0 66.0 67.0 67.0 65.0 66.0 17:54:57 INFO - PROCESS | 1667 | -------- Summary: end -------- 17:54:57 INFO - PROCESS | 1667 | 17:54:57 INFO - TEST-INFO | 1667: exit 0 17:54:57 INFO - TEST-OK | kraken | took 59619ms 17:54:57 INFO - SUITE-END | took 1177s 17:54:58 INFO - Completed test suite (00:19:37) 17:54:58 INFO - javascript benchmark 17:54:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.310294838084, 3311.6357200707916, 3343.3814744272313, 3347.0293614851685, 3350.4974253861933, 5452.291202143726, 5489.1217405215175, 5528.695921724304, 5534.640248383857, 5574.275344205248, 5494.835154945358, 5568.916466252999, 5596.2165296858475, 5605.7197140143035, 5630.493255606994, 5008.924866127011, 5079.974600127012, 5085.669431486942, 5097.566706829905, 5200.843974680765, 5128.743562821863, 5134.691918484884, 5164.561012313958, 5181.404138524081, 5192.065428222918, 5019.598432125421, 5076.54311111998, 5081.110805609041, 5092.872678183056, 5149.2791009258735, 5065.772040258197, 5102.846914592568, 5103.464136265713, 5104.43851176374, 5113.360829896263, 4984.327115839388, 5006.274090256892, 5077.695338279715, 5084.135696931494, 5085.974570127163, 5499.422560631157, 5577.0, 5594.496495315441, 5613.158026296048, 5626.437356264381, 5996.940030599681, 6057.697115144225, 6067.848303792396, 6069.696515174223, 6128.816135515942, 4815.903681926342, 5955.166276721263, 5985.610935289193, 6084.331056849134, 6120.96939515304, 5600.439956004367, 5641.0, 5642.6614403135945, 5645.68948707825, 5646.661200328034, 6074.483668888194, 6187.16473276111, 6220.595661282002, 6255.46828519581, 6260.937390626126, 5824.534037277008, 5879.823605291848, 5887.558433117448, 5892.646441213456, 5893.499052580579, 5106.974465127651, 6021.157038014681, 6040.09398590215, 6047.455728984324, 6049.153118563405, 5935.376785437553, 5952.0, 5957.910631340534, 5962.61243019211, 5977.193078934375, 5914.083317085859, 5926.8814623707285, 6002.729877155539, 6005.3394126646035, 6024.518038556993, 4776.808927642925, 4801.567858892717, 4812.663113582085, 4822.927656085161, 4832.758362081881, 4244.02387450888, 4267.871963841089, 4314.439122914001, 4319.460067491564, 4343.587359200871, 5443.918341224885, 5511.0080185566985, 5524.364698059774, 5536.058869992111, 5549.72251387429, 6039.0, 6071.666058366833, 6108.297545782292, 6137.315698286874, 6141.486007784275, 5550.47270509301, 5553.250311208016, 5554.833354999276, 5558.360788509292, 5559.221708960748, 5128.153854614022, 5272.894542109213, 5285.788568457296, 5296.179092240709, 5341.492558207042, 6184.443400094103, 6233.345498722569, 6251.71867265965, 6290.716917738621, 6347.873042539031, 6100.115483254992, 6123.0, 6139.539534534839, 6141.662208578571, 6141.662208578571, 6337.9366206337345, 6346.873062538816, 6350.904736428864, 6361.2048493938255, 6365.2361716594305, 5818.272715910512, 5914.290285165807, 5918.822435326947, 5966.254218222723, 6049.606775559574, 6004.699765011819, 6047.0627052806885, 6052.092186172109, 6067.423594758491, 6086.908696369469, 5832.270966129234, 5894.233749612695, 5905.084711869667, 5916.3492015877355, 5918.763249469973, 5615.915761263503, 5733.426657334233, 5830.76676932918, 5843.006688862819, 5921.082232254007, 5774.451427114417, 5775.624584402, 5809.099589563624, 5812.418758124323, 5838.095095260242, 5073.264376665289, 5896.852578685567, 5911.822645320647, 5915.290165180034, 5920.585559010828, 4487.6185524230805, 5673.177389278614, 5698.031334673033, 5742.59801813869, 5826.737796799239], "value": 5532.162672216295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.4809886976023, 493.7111789603082, 518.3934796288344, 535.1250705097165, 536.7343165133262, 30493.695063049363, 39815.0, 40962.0, 41085.17829643405, 41126.7943660282, 42163.789181054126, 42597.509087181956, 42618.360724589154, 42719.14561708764, 42750.786246068725, 9586.18517426018, 9756.121949024586, 9765.853512197322, 9766.853497197548, 9784.315097943147, 8771.95614021929, 8922.776930576723, 8932.33007524435, 9105.726828195131, 9108.362414630978, 11709.355985420798, 11916.34460104694, 11925.344106074162, 12136.514539418413, 12214.389280535983, 10359.637412690596, 10360.274780765349, 11830.881691183064, 11930.582429614922, 11963.401829908556, 10193.541290641939, 11861.11041671878, 11872.703182420422, 11880.287182769061, 11883.88116118845, 39236.80381598074, 39523.8023809882, 39573.0, 39599.20801583981, 39629.0, 3479.234568394949, 3498.5801703795455, 3534.3108093921696, 4146.336586146233, 4273.807678654468, 30502.785529151075, 31111.53332699989, 31243.531347029813, 31995.360092798015, 32367.0, 3177.3327601203773, 3188.7449004079617, 3248.1554795753273, 3312.006398080566, 3337.3825842219267, 358141.0, 361728.0, 362055.0, 362926.0, 363749.9999999974, 57020.73199386066, 57478.7126064367, 57501.42498575044, 57511.71244143753, 57559.71220143873, 33778.32443351119, 34103.488447673306, 34286.31427371489, 60852.69573652192, 61061.69469152626, 16251.0, 16382.426615068416, 16389.918050409673, 16396.75404868928, 16428.42500512476, 12645.0, 12739.490420383267, 12744.10791244604, 12750.61748147557, 12765.872341276468, 16144.596385090466, 16509.17454127313, 18453.1696073677, 18658.533536661693, 18771.906140469484, 7509.324160825552, 7617.743072393104, 7640.274173953466, 7659.8127290270095, 7713.807154821175, 845.9280961118251, 937.8780758501351, 940.4827344960245, 950.5199874063553, 1100.9944950275358, 25502.872485637825, 29797.0, 29904.850475747917, 30053.90635441553, 30184.849075754482, 1039.417925961464, 1044.9477526123662, 1054.1619412567027, 1054.4253381906879, 1074.0280046557878, 14877.0, 15351.846481535042, 15442.30509627047, 15742.527724168292, 15763.763543546856, 977172.0, 997398.0, 999531.0, 1000023.9998800105, 1002163.0, 50839.491605084215, 51656.74171629118, 51685.7415712919, 58880.705596471744, 59754.40245597575, 1152.3258893547368, 1342.2215115233141, 1392.5961471173246, 1396.699709562449, 1399.9090059146122, 27745.722542774314, 27763.4730089847, 29840.60673877906, 30882.76468941217, 31078.533821992234, 50552.24171637355, 50998.49001509937, 51193.0, 51194.720131995506, 51360.48639513557, 629999.0, 639490.0, 639703.0, 641330.0, 891600.0, 40671.38992915049, 41734.791326043174, 42007.57992420036, 42084.00201980807, 42298.78850605727, 45197.38420926384, 45833.312500311855, 46136.76931615321, 46146.76926615345, 46235.53764462447, 37441.25117497689, 38435.0, 39091.6090839088, 39418.605813941496, 40505.1898962025, 34736.47895281624, 37500.812495937345, 38518.037049073995, 39825.80522584327, 40023.39964900587, 37216.06959826026, 37730.0, 38391.0, 38624.80687596544, 38792.0, 32374.27671354596, 35012.47481287732, 35384.82307588446, 36017.459738103425, 36705.63294367129, 23610.88194559016, 25256.0, 25501.079708952566, 25722.971081156546, 25723.614145788204], "value": 20439.225097626855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9557555305587, 372.94965179700733, 374.29819089207734, 379.4138056702395, 401.28571143364803, 15462.072275663453, 15809.97235179714, 19859.0, 20394.592108157845, 20407.87756673386, 20917.058732357043, 21278.255261065828, 21344.57310853782, 21642.458938526546, 21890.343289701294, 5764.221830052941, 5919.940800592003, 6048.697565121726, 6053.941659835655, 6240.8439789005415, 6282.591631543958, 6323.399277068694, 6336.746530138812, 6338.397852204033, 6345.175127233453, 3973.1060511384994, 6516.739330426801, 6892.207396149457, 6977.476689248301, 7035.683394247246, 6267.592606480564, 6329.493640508748, 6484.221893372825, 7012.7545535906, 7018.543794653357, 5362.785488580452, 6135.049067394561, 6135.171751813493, 6138.600990935575, 6156.045812899008, 16921.7461738074, 24960.750392496207, 25403.237902862944, 25680.74319256821, 25694.843732032103, 2080.0, 2178.9782102178933, 2265.207177487866, 2387.1048356866177, 2458.9016439342404, 9758.0, 10074.69775906724, 10392.63625773102, 13345.933270333684, 14218.573442796733, 1859.7024476083761, 1869.957049579188, 1883.3690713610956, 1908.2748555548942, 1945.2510783348512, 110532.0, 115080.0, 115582.84417155889, 116821.99999999916, 117406.23890641647, 9834.49220448781, 11308.512127331975, 11343.943280283545, 11446.94276528612, 11774.764504709858, 14527.055741376776, 14549.709005819825, 14565.708685826225, 14575.052621579563, 14590.562283131523, 7129.964350178319, 8394.748157555283, 8511.148885111439, 8547.10255423184, 8643.827123457622, 6965.303469652994, 7313.0, 8387.62255698495, 8459.365547583955, 8631.827363452696, 4419.116176764595, 4500.707454015478, 4501.707389019703, 4575.519570445122, 4639.791209395586, 1885.481492589549, 1897.7759345222255, 1906.9904650476935, 1921.4043646469638, 1957.5791204891016, 782.2568559868148, 815.2621877201143, 859.3297228162044, 874.6457684637732, 892.7723430525175, 20816.583668326548, 21510.0, 21599.892000539898, 21628.675569866464, 22103.889480552494, 844.9408541402042, 866.8773937750628, 901.6753968571309, 910.4036855859423, 917.156216281023, 9450.952745236367, 10526.368417894952, 10711.678649640367, 11072.224944253825, 11183.944080279547, 524728.0, 536418.0, 536610.0, 540108.0, 540873.0, 104537.0, 106097.0, 108672.45663771789, 121715.0, 124040.75959240472, 1002.1531805624236, 1014.4065721552827, 1045.1899777672365, 1071.5553045486035, 1074.1460538871615, 19168.424947251606, 19626.411207663794, 19729.605407891475, 19734.802651973296, 19760.505987350432, 54657.72671136619, 55220.89558208894, 55764.72117639386, 55932.0, 56600.0, 82059.17940820515, 82405.0, 83338.0, 83607.5819620898, 85124.0, 6781.0, 7392.55644661322, 8076.798080047811, 8172.713955011546, 8230.929979102679, 9161.312901532545, 12698.619041428772, 12780.552680656132, 13015.219086854819, 13092.410841511964, 10611.31026483307, 10832.295900766425, 10955.726106847393, 11063.557457701601, 11113.222074455034, 10877.619283325046, 10921.453927303763, 10926.562937482411, 11142.331460112418, 11224.66326010221, 10068.546915388972, 10117.544710487899, 11122.165837562054, 11132.443377831238, 11173.240139790314, 7882.802929926797, 8037.437379383387, 8059.274665280153, 8089.716859909875, 8268.545230012167, 9310.020793346064, 10689.144868410664, 10841.945790271313, 10858.945705271422, 11015.779684406427], "value": 9532.434966411, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.626150939247, 1495.8504149585042, 1524.245498478253, 1561.6408226107994, 1684.9443678711966, 7709.922900770991, 8219.465734727239, 8539.018012928507, 8674.869876951834, 8819.735407937756, 8653.56732163391, 8710.782230444242, 8740.825183496327, 8754.912450875489, 8767.0, 2048.5902819436096, 2375.228050883461, 2378.2389635316704, 2380.8809559521956, 2381.940451488709, 2357.8585284882874, 2387.248016874679, 2405.795507381875, 2406.578848701479, 2411.855288682676, 2363.7399886012613, 2380.9285721428387, 2390.8326417150806, 2391.330427480308, 2399.9040038398443, 2335.4161459635093, 2346.683197768305, 2356.1400088967575, 2370.111208296889, 2385.8091352691745, 2064.494198921259, 2360.197532838843, 2361.8110551156037, 2365.503244318695, 2365.7042869641296, 8165.183481651847, 8247.21651443112, 8664.740057798213, 8706.390552661316, 8755.868661970075, 5127.333446651949, 5170.5346518813485, 5287.0, 5287.524122828925, 5294.073537131025, 3334.7999120052873, 3588.766730162531, 3609.9892030231576, 3706.3328600851837, 3712.03487093355, 1086.1745073743932, 1099.19758576239, 1122.141561705296, 1128.8701799293103, 1130.9434528273637, 11399.088072954144, 11518.769624607545, 11540.942295288554, 11545.595904143314, 11568.248063875737, 4615.699979501322, 4724.4094488188975, 4856.223004319292, 4928.043174095514, 4960.652754307164, 9195.402298850553, 9222.216111630587, 9226.81546369069, 9227.492487913229, 9260.768480788034, 5255.658382205144, 5260.868478288074, 5264.236685680631, 5265.578753699771, 5272.894542109137, 4033.9394909076386, 4373.125374925028, 4795.256735205979, 4897.804087836518, 4946.282788995575, 3746.6815320698047, 3757.455169000534, 3805.6765174960437, 3862.092408284051, 3884.7863367515056, 1161.96514104577, 1170.3913964738288, 1179.7168679516853, 1179.7581495793513, 1184.0, 785.6419220913316, 865.1867244789877, 897.9775505612412, 946.8590348629884, 953.50894289441, 5537.889242215133, 5819.330776960619, 5921.082232253922, 6045.909311360333, 6126.264848218153, 851.4210336970796, 951.8381875081253, 955.7085089047769, 961.5576834655997, 1022.616518805448, 3903.6877049835944, 3925.9214815703526, 3972.5828787977425, 4036.555978842325, 4063.9796801015805, 10142.898571014342, 10769.138468922467, 10913.945430272957, 10948.726281842859, 10952.507137178845, 10709.732256693645, 10743.14054875608, 10879.891201087888, 10902.945485272523, 10941.179411544166, 7994.68021279154, 8239.258466737903, 8243.546604936786, 8248.979000144884, 8267.255946964802, 5732.0, 5789.131630255327, 5881.529477641864, 5910.7340169693325, 5948.643081415129, 10061.849072264069, 10075.596976120873, 10083.949580252345, 10154.492275386348, 10184.439855808001, 9300.20948219409, 9629.470379129216, 9631.470269134985, 9818.361806482555, 10230.48847557634, 6920.4809639276245, 7078.1506219254015, 7084.539504932163, 7948.244916732901, 8231.053428855519, 7154.964225179049, 8287.0, 8344.916550834414, 8474.533900635524, 8481.194286542768, 7037.648117594203, 7389.113306403266, 7401.444891633041, 7464.701411943462, 7474.514156579805, 7159.140903091662, 7298.4891057625455, 7332.30343117403, 7352.926470735438, 7505.774826755206, 6821.795346139624, 7006.719731210898, 7016.964915175391, 7027.332403421667, 7031.1211098612675, 4551.180787458289, 4798.4001999750035, 4799.1601469743355, 4920.188168952082, 4959.330490383896, 4235.5552666969525, 4862.586680132228, 4988.950110498994, 5030.673006254582, 5229.947700522946], "value": 4543.63384803036, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.4175339513256, 288.47497554450905, 298.4821334983803, 306.45451097047254, 312.62172770947154, 5197.1424714922, 5204.739763011853, 5221.2429197766305, 5260.500252476014, 5292.153255479124, 5102.387713474388, 5118.9744051279695, 5135.794568217267, 5183.740812959356, 5188.351456067991, 3977.2840888640108, 3988.9601103989025, 3990.1022269989166, 3992.520897492305, 4014.377771445416, 3679.4664773607947, 3992.8802135935966, 3994.48071750672, 3997.660198883084, 4038.252923209201, 3481.843317050726, 3915.393114067324, 3967.97633207065, 3975.60243975603, 4008.619181177798, 3934.921301573967, 3947.921041579181, 3948.0129967508124, 3970.3647416413237, 3993.3610622300293, 2933.647962244523, 3454.2055327274866, 3482.930341393158, 3500.529777493459, 3501.9299614007577, 4462.233415282513, 4525.483962872442, 4691.507391723888, 5155.304033955405, 5196.68819870809, 4315.691105258703, 4542.318652202163, 4548.658850586219, 4557.74932378719, 4602.205139995371, 4632.0, 4727.101850648366, 5213.973930130325, 5417.997670430983, 5464.398916119224, 3803.3724435468116, 3820.274147911916, 3835.9232815343535, 3855.1664526573227, 3865.768053916774, 4768.499307572724, 5421.83734487962, 5422.891542169174, 5562.694051827189, 5782.08004957498, 3721.2557488502407, 3731.555830930745, 3733.7386382953478, 3738.5513738351574, 3745.400735882246, 3915.647591716759, 3916.6083391661177, 3926.6858651307825, 3931.1744533648252, 3939.7786686127392, 3957.920841583152, 3964.1278918637854, 4473.597376236155, 4544.545545445429, 4547.749873756975, 4636.837710680177, 5242.135047717084, 5383.273258110105, 5472.480114389126, 5507.504324610724, 1156.3004382348727, 1331.6604265912324, 1345.5626921250632, 1345.8788709016235, 1355.2884735513935, 695.0, 839.0393000015071, 851.8764779106995, 854.4488804721044, 901.4230892228978, 3897.7198338971452, 3949.6445319921345, 3970.550748976603, 4324.524302326741, 4325.718828276152, 4337.243416416356, 4433.467983841895, 4705.811767529261, 4730.077634861238, 4767.070421267819, 3567.500549923013, 3818.882697185729, 4168.353905144708, 4319.827206911751, 4349.020920669352, 2166.7974274277612, 2196.2532738868863, 2229.4092065602626, 2259.4577301447534, 2463.8398504097177, 4164.8542301019315, 4805.519448055236, 4823.807047718122, 4852.236421725212, 4876.512348765166, 4565.931511027337, 4603.815847366068, 4745.976270118627, 4807.879803004883, 4829.092508459209, 4338.7200775771225, 4859.396399188259, 4891.975540122347, 5898.1152827076285, 5916.970415147896, 5383.842473868137, 5617.297837770278, 5694.886102278014, 5696.0, 5714.5999780015, 4781.593564547053, 5641.153826925994, 5659.320881494247, 5687.260656114679, 5718.456746609066, 5434.711999240402, 5532.889342213214, 5599.412061733622, 5749.108888122348, 5849.099042811188, 3680.2455496622592, 3735.9352584513285, 3829.6553310202216, 3843.1929294848396, 3855.7300988930506, 5390.409829100503, 5678.858028549319, 5693.0, 5716.657000579979, 5730.398308177748, 4951.9752401237765, 5329.760160792696, 5392.973035134799, 5460.535854452416, 5652.106544307703, 4286.421333119989, 4673.836415725434, 5230.921536177037, 5423.322084739408, 5459.781608735765, 4756.8097276108565, 5324.440933702061, 5347.385050719217, 5352.545033672026, 5352.5450336721815, 4643.374818813389, 5172.870678233075, 5385.057614917296, 5443.836684899459, 5463.836084917459, 4991.450940396626, 5383.461653834742, 5394.27177331071, 5395.0, 5499.1476321170285], "value": 3876.381241705151, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.5410560188678, 720.0711082703316, 764.182324912344, 815.5636734347123, 822.2846123872232, 15182.240887955599, 16160.191990400463, 16167.0, 16185.27166277516, 16262.51212463625, 14290.14259144452, 14491.0, 14494.85505144951, 14500.637484062903, 14509.927450362733, 678.0879716780922, 696.0429409561852, 820.5979070255562, 822.5558198572778, 839.4459656626627, 827.3753316246256, 844.0926004545108, 845.1084106267884, 845.5560830563973, 847.1401527449643, 905.9139381758722, 906.9773255668628, 908.7364664247393, 908.9091090890925, 909.3180114913814, 717.4654882112859, 863.6027427383411, 897.7755561109723, 902.2015516268117, 904.6381447421019, 653.8855700252484, 654.0222367560527, 656.4256275758711, 658.6969993802809, 660.0231657147401, 14147.434102635882, 14207.218602976833, 14207.218602976936, 14209.218492982882, 14228.430862765477, 13538.187708737507, 13768.380422880995, 13888.4444622215, 13968.790468142986, 14063.0, 12394.504219831197, 12408.75182496345, 12444.066694997911, 12583.937080314632, 12635.74728505434, 4497.955020449786, 4544.575804892576, 4616.8153273869, 4640.65195110368, 4656.371389862359, 92474.0, 114739.0, 115493.84506154915, 118474.0, 118671.0, 10357.22320825926, 10407.94796026015, 10594.099501542301, 10780.024926635233, 10892.945535272273, 20677.27629532959, 20678.379648610568, 20706.378808635764, 20714.0, 20768.169273229203, 6614.272430032693, 7697.4226932980255, 7993.920060799318, 8031.638576264082, 8033.919660803317, 4268.40242366069, 4331.545187755303, 4340.457442819647, 4510.526394728572, 4541.3869127668, 3248.756343274264, 3340.081477593681, 3364.4280472319765, 3365.8317084145697, 3367.2760356522977, 609.8810731907236, 616.7502161624549, 617.9505639548826, 624.7126321891981, 632.1529150937765, 561.2787567975132, 582.347770497046, 582.9912551311734, 645.4578154350369, 649.1787888321277, 7276.6725497352745, 7482.8877566836545, 8286.104166459147, 8871.556422178865, 8882.644694212158, 536.9919451208235, 604.694629212245, 616.2543322579729, 622.4677900395187, 638.8083574927504, 4096.877093687194, 4330.631896288788, 4336.848210312624, 4352.173087113439, 4475.507694153641, 139938.3003084978, 151144.0, 155665.0, 156828.43171568366, 157054.0, 128105.0, 132182.06907585575, 134461.0, 134924.0, 136430.0, 107448.46275768727, 107840.0, 115354.0, 116178.0, 118355.0, 7498.10022797246, 8001.759947201594, 8096.392770542115, 8402.243798058204, 8614.526201059001, 50393.74803125961, 60951.39048609457, 62995.0, 63130.0, 63453.53016752184, 90244.5487772557, 96074.0392596065, 97205.0, 97564.51217743866, 98989.50505247715, 11005.0, 11509.827352589886, 11569.537218511165, 11648.301101933912, 11733.237339572901, 12040.277583344678, 12109.212901161396, 12292.877071229532, 12436.253824770543, 12716.427760750603, 10368.859425463344, 11314.490847912028, 11714.355710436008, 11780.941095294469, 12170.513179472722, 11506.252093613888, 11622.846240982477, 11821.231619944676, 12079.094067944763, 12158.331291779037, 11564.304905120734, 11585.594504192313, 11627.826414010953, 11700.414979251174, 11721.531138754355, 9616.182624476718, 9744.317897747089, 9850.950745246228, 9852.605895764089, 9869.753256168653, 9229.676961306322, 9752.463614500986, 9786.902130978599, 9855.35940163887, 9885.76558880587], "value": 7513.0027361462135, "unit": "score"}], "name": "dromaeo_css", "value": 7228.41038965892, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 115.0, 108.0, 115.0, 112.0, 114.0, 113.0, 113.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 106.0, 165.0, 108.0, 107.0, 129.0, 106.0, 143.0, 106.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 185.0, 183.0, 181.0, 183.0, 184.0, 187.0, 183.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 60.0, 87.0, 58.0, 58.0, 67.0, 69.0, 84.0, 56.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 104.0, 70.0, 73.0, 68.0, 97.0, 71.0, 68.0, 95.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 94.0, 125.0, 109.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 95.0, 94.0, 106.0, 142.0, 144.0, 95.0, 94.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 71.0, 133.0, 136.0, 134.0, 87.0, 86.0, 83.0, 84.0, 84.0], "value": 102.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 54.0, 53.0, 52.0, 55.0, 53.0, 54.0, 55.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 77.0, 72.0, 70.0, 67.0, 68.0, 67.0, 72.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 83.0, 85.0, 87.0, 85.0, 86.0, 88.0, 91.0, 82.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 137.0, 137.0, 136.0, 136.0, 139.0, 137.0, 217.0, 153.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 168.0, 167.0, 169.0, 168.0, 169.0, 170.0, 169.0, 168.0, 169.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 67.0, 66.0, 67.0, 67.0, 65.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1458.6999999999998, "alertThreshold": 2.0}]} 17:54:58 INFO - Return code: 0 17:54:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:54:58 INFO - # TBPL SUCCESS # 17:54:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:54:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:54:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:54:58 INFO - 2016-08-16 17:54:58.433 screenresolution[1671:10526] starting screenresolution argv=screenresolution get 17:54:58 INFO - 2016-08-16 17:54:58.436 screenresolution[1671:10526] Display 0: 1600x1200x32@60 17:54:58 INFO - 2016-08-16 17:54:58.441 screenresolution[1672:10529] starting screenresolution argv=screenresolution list 17:54:58 INFO - Available Modes on Display 0 17:54:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:54:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:54:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:54:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:54:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:54:58 INFO - Intel Iris: 17:54:58 INFO - Chipset Model: Intel Iris 17:54:58 INFO - Type: GPU 17:54:58 INFO - Bus: Built-In 17:54:58 INFO - VRAM (Dynamic, Max): 1536 MB 17:54:58 INFO - Vendor: Intel (0x8086) 17:54:58 INFO - Device ID: 0x0a2e 17:54:58 INFO - Revision ID: 0x0009 17:54:58 INFO - Displays: 17:54:58 INFO - Display: 17:54:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:54:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:54:58 INFO - Display Serial Number: 1600x1200 60 17:54:58 INFO - Main Display: Yes 17:54:58 INFO - Mirror: Off 17:54:58 INFO - Online: Yes 17:54:58 INFO - Rotation: Supported 17:54:58 INFO - Return code: 0 17:54:58 INFO - Running post-action listener: _resource_record_post_action 17:54:58 INFO - [mozharness: 2016-08-17 00:54:58.687201Z] Finished run-tests step (success) 17:54:58 INFO - Running post-run listener: _resource_record_post_run 17:54:58 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31931392; Write bytes: 705951232; Read time: 200; Write time: 2039 17:54:58 INFO - TinderboxPrint: CPU usage
53.9% 17:54:58 INFO - TinderboxPrint: I/O read bytes / time
31,931,392 / 200 17:54:58 INFO - TinderboxPrint: I/O write bytes / time
705,951,232 / 2,039 17:54:58 INFO - TinderboxPrint: CPU idle
2,234.7 (46.1%) 17:54:58 INFO - TinderboxPrint: CPU system
975.3 (20.1%) 17:54:58 INFO - TinderboxPrint: CPU user
1,639.7 (33.8%) 17:54:58 INFO - TinderboxPrint: Swap in / out
433,270,784 / 0 17:54:58 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394236416; Write bytes: 388864000; Read time: 29207; Write time: 867 17:54:58 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30055424; Write bytes: 297078272; Read time: 185; Write time: 1119 17:54:59 INFO - Running post-run listener: _upload_blobber_files 17:54:59 INFO - Blob upload gear active. 17:54:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:54:59 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 17:54:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:54:59 INFO - (blobuploader) - INFO - Open directory for files ... 17:54:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:54:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:55:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:00 INFO - (blobuploader) - INFO - Done attempting. 17:55:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:55:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:55:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:01 INFO - (blobuploader) - INFO - Done attempting. 17:55:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:55:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:55:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:55:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:55:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:55:03 INFO - (blobuploader) - INFO - Done attempting. 17:55:03 INFO - (blobuploader) - INFO - Iteration through files over. 17:55:03 INFO - Return code: 0 17:55:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:55:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:55:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f677598a998783cba290fd63ba0a73b4a84365f9ded9aa144e055b28f44731e31e97ac996d9d3c722065ab787196dfe92eb605d25d6b3dd3549a17e5daf0438d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a25205b940f772977bccfc3bdf7215933a2c8386a526808f0439d4baa641a368aa43e6d38d21e6e72c67530c4416b4c907a028dfd688c38a7f64d24cc79a90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42a0c2a20978a3016ef5fa4c0a56d16e2a174ed995fca12225f763251072c1094520ae002df07e205104bac1dcd672d1534ccdbe9836cb80aedfa53f4bfbb89e"} 17:55:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:55:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:55:03 INFO - Contents: 17:55:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f677598a998783cba290fd63ba0a73b4a84365f9ded9aa144e055b28f44731e31e97ac996d9d3c722065ab787196dfe92eb605d25d6b3dd3549a17e5daf0438d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a25205b940f772977bccfc3bdf7215933a2c8386a526808f0439d4baa641a368aa43e6d38d21e6e72c67530c4416b4c907a028dfd688c38a7f64d24cc79a90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42a0c2a20978a3016ef5fa4c0a56d16e2a174ed995fca12225f763251072c1094520ae002df07e205104bac1dcd672d1534ccdbe9836cb80aedfa53f4bfbb89e"} 17:55:03 INFO - Running post-run listener: copy_logs_to_upload_dir 17:55:03 INFO - Copying logs to upload dir... 17:55:03 INFO - mkdir: /builds/slave/test/build/upload/logs 17:55:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.227871 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-16 17:55:04.033042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 17:55:04.036363) ========= 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.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f677598a998783cba290fd63ba0a73b4a84365f9ded9aa144e055b28f44731e31e97ac996d9d3c722065ab787196dfe92eb605d25d6b3dd3549a17e5daf0438d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a25205b940f772977bccfc3bdf7215933a2c8386a526808f0439d4baa641a368aa43e6d38d21e6e72c67530c4416b4c907a028dfd688c38a7f64d24cc79a90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42a0c2a20978a3016ef5fa4c0a56d16e2a174ed995fca12225f763251072c1094520ae002df07e205104bac1dcd672d1534ccdbe9836cb80aedfa53f4bfbb89e"} build_url:https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/f6oP3JAMQ9-n74BaQOwEWg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f677598a998783cba290fd63ba0a73b4a84365f9ded9aa144e055b28f44731e31e97ac996d9d3c722065ab787196dfe92eb605d25d6b3dd3549a17e5daf0438d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a25205b940f772977bccfc3bdf7215933a2c8386a526808f0439d4baa641a368aa43e6d38d21e6e72c67530c4416b4c907a028dfd688c38a7f64d24cc79a90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42a0c2a20978a3016ef5fa4c0a56d16e2a174ed995fca12225f763251072c1094520ae002df07e205104bac1dcd672d1534ccdbe9836cb80aedfa53f4bfbb89e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 17:55:04.069391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:55:04.069739) ========= 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.n7zgG6TDId/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.iYZch4Qo5C/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005016 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 17:55:04.117676) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 17:55:04.117999) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 17:55:04.118306) ========= ========= Total master_lag: 0.25 =========