builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0105 starttime: 1488870651.88 results: success (0) buildid: 20170306205430 builduid: f91d55b0e93247659e382deb0e799572 revision: b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:51.883286) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:51.883731) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:51.884009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:51.908790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:51.909178) ========= 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.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 --2017-03-06 23:10:51-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 66.0M=0s 2017-03-06 23:10:52 (66.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:52.074226) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:52.074571) ========= 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.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.021241 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:52.123451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-06 23:10:52.123817) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 2017-03-06 23:10:52,207 truncating revision to first 12 chars 2017-03-06 23:10:52,208 Setting DEBUG logging. 2017-03-06 23:10:52,208 attempt 1/10 2017-03-06 23:10:52,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/b7e42143bbbc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-06 23:10:53,423 unpacking tar archive at: larch-b7e42143bbbc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458274 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-06 23:10:53.605483) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:53.605821) ========= script_repo_revision: b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:53.606195) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:53.606460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 23:10:53.626260) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-03-06 23:10:53.626670) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:10:53 INFO - MultiFileLogger online at 20170306 23:10:53 in /builds/slave/test 23:10:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 23:10:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:10:53 INFO - {'append_to_log': False, 23:10:53 INFO - 'base_work_dir': '/builds/slave/test', 23:10:53 INFO - 'blob_upload_branch': 'Larch', 23:10:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:10:53 INFO - 'branch': 'Larch', 23:10:53 INFO - 'buildbot_json_path': 'buildprops.json', 23:10:53 INFO - 'config_files': ('talos/mac_config.py',), 23:10:53 INFO - 'default_actions': ('clobber', 23:10:53 INFO - 'read-buildbot-config', 23:10:53 INFO - 'download-and-extract', 23:10:53 INFO - 'populate-webroot', 23:10:53 INFO - 'create-virtualenv', 23:10:53 INFO - 'install', 23:10:53 INFO - 'run-tests'), 23:10:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:10:53 INFO - 'download_minidump_stackwalk': True, 23:10:53 INFO - 'download_symbols': 'ondemand', 23:10:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:10:53 INFO - 'tooltool.py': '/tools/tooltool.py', 23:10:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:10:53 INFO - '/tools/misc-python/virtualenv.py')}, 23:10:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:10:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:10:53 INFO - 'gecko_profile': False, 23:10:53 INFO - 'gecko_profile_interval': 0, 23:10:53 INFO - 'installer_path': 'installer.exe', 23:10:53 INFO - 'log_level': 'info', 23:10:53 INFO - 'log_name': 'talos', 23:10:53 INFO - 'log_to_console': True, 23:10:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:10:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:10:53 INFO - 'opt_config_files': (), 23:10:53 INFO - 'pip_index': False, 23:10:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:10:53 INFO - 'cmd': ('bash', 23:10:53 INFO - '-c', 23:10:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:10:53 INFO - 'enabled': True, 23:10:53 INFO - 'halt_on_failure': False, 23:10:53 INFO - 'name': 'check_screen_resolution'},), 23:10:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:10:53 INFO - 'cmd': ('bash', 23:10:53 INFO - '-c', 23:10:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:10:53 INFO - 'enabled': True, 23:10:53 INFO - 'halt_on_failure': False, 23:10:53 INFO - 'name': 'check_screen_resolution'},), 23:10:53 INFO - 'run_cmd_checks_enabled': True, 23:10:53 INFO - 'suite': 'dromaeojs-e10s', 23:10:53 INFO - 'system_bits': '32', 23:10:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:10:53 INFO - 'title': 't-yosemite-r7-0105', 23:10:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:10:53 INFO - 'use_talos_json': True, 23:10:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:10:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:10:53 INFO - 'work_dir': 'build'} 23:10:53 INFO - [mozharness: 2017-03-07 07:10:53.837678Z] Running clobber step. 23:10:53 INFO - Running pre-action listener: _resource_record_pre_action 23:10:53 INFO - Running main action method: clobber 23:10:53 INFO - rmtree: /builds/slave/test/build 23:10:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:10:57 INFO - Running post-action listener: _resource_record_post_action 23:10:57 INFO - [mozharness: 2017-03-07 07:10:57.175249Z] Finished clobber step (success) 23:10:57 INFO - [mozharness: 2017-03-07 07:10:57.175540Z] Running read-buildbot-config step. 23:10:57 INFO - Running pre-action listener: _resource_record_pre_action 23:10:57 INFO - Running main action method: read_buildbot_config 23:10:57 INFO - Using buildbot properties: 23:10:57 INFO - { 23:10:57 INFO - "project": "", 23:10:57 INFO - "product": "firefox", 23:10:57 INFO - "script_repo_revision": "production", 23:10:57 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 23:10:57 INFO - "repository": "", 23:10:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 23:10:57 INFO - "buildid": "20170306205430", 23:10:57 INFO - "pgo_build": "False", 23:10:57 INFO - "basedir": "/builds/slave/test", 23:10:57 INFO - "buildnumber": 0, 23:10:57 INFO - "slavename": "t-yosemite-r7-0105", 23:10:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:10:57 INFO - "platform": "yosemite_r7", 23:10:57 INFO - "scheduler": "tests-larch-macosx64-talos", 23:10:57 INFO - "branch": "larch", 23:10:57 INFO - "repo_path": "projects/larch", 23:10:57 INFO - "revision": "b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4", 23:10:57 INFO - "stage_platform": "macosx64", 23:10:57 INFO - "builduid": "f91d55b0e93247659e382deb0e799572", 23:10:57 INFO - "slavebuilddir": "test" 23:10:57 INFO - } 23:10:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 23:10:57 INFO - Running post-action listener: _resource_record_post_action 23:10:57 INFO - [mozharness: 2017-03-07 07:10:57.181229Z] Finished read-buildbot-config step (success) 23:10:57 INFO - [mozharness: 2017-03-07 07:10:57.181381Z] Running download-and-extract step. 23:10:57 INFO - Running pre-action listener: _resource_record_pre_action 23:10:57 INFO - Running main action method: download_and_extract 23:10:57 INFO - mkdir: /builds/slave/test/build/tests 23:10:57 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')]} 23:10:57 INFO - trying https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 23:10:57 INFO - Downloading https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:10:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 23:10:58 INFO - Downloaded 1199 bytes. 23:10:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 23:10:58 INFO - Using the following test package requirements: 23:10:58 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:10:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 23:10:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 23:10:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'jsshell-mac.zip'], 23:10:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 23:10:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 23:10:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 23:10:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 23:10:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 23:10:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 23:10:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 23:10:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:10:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:10:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:10:58 INFO - Fetch https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:10:59 INFO - Expected file size: 51778450 23:10:59 INFO - Obtained file size: 51778450 23:11:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:11:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 23:11:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:11:15 INFO - Fetch https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 23:11:19 INFO - Expected file size: 51778450 23:11:19 INFO - Obtained file size: 51778450 23:11:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 23:11:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:11:35 INFO - Fetch https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 23:11:39 INFO - Expected file size: 14404424 23:11:39 INFO - Obtained file size: 14404424 23:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:39 INFO - trying https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:11:39 INFO - Downloading https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 23:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 23:11:45 INFO - Downloaded 74960531 bytes. 23:11:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:11:45 INFO - mkdir: /builds/slave/test/properties 23:11:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:11:45 INFO - Writing to file /builds/slave/test/properties/build_url 23:11:45 INFO - Contents: 23:11:45 INFO - build_url:https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 23:11:46 INFO - Running post-action listener: _resource_record_post_action 23:11:46 INFO - Running post-action listener: set_extra_try_arguments 23:11:46 INFO - [mozharness: 2017-03-07 07:11:46.006480Z] Finished download-and-extract step (success) 23:11:46 INFO - [mozharness: 2017-03-07 07:11:46.006600Z] Running populate-webroot step. 23:11:46 INFO - Running pre-action listener: _resource_record_pre_action 23:11:46 INFO - Running main action method: populate_webroot 23:11:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:11:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:11:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:11:46 INFO - '--tppagecycles', 23:11:46 INFO - '7'], 23:11:46 INFO - 'tests': ['tsvgm']}}, 23:11:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:11:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:11:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:11:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:11:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['damp', 'tps']}, 23:11:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'tests': ['damp', 'tps']}, 23:11:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['dromaeo_dom']}, 23:11:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:11:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['basic_compositor_video']}, 23:11:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:11:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'other-e10s': {'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['a11yr', 23:11:46 INFO - 'ts_paint', 23:11:46 INFO - 'tpaint', 23:11:46 INFO - 'sessionrestore', 23:11:46 INFO - 'sessionrestore_no_auto_restore', 23:11:46 INFO - 'tabpaint']}, 23:11:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['tsvgx', 23:11:46 INFO - 'tsvgr_opacity', 23:11:46 INFO - 'tart', 23:11:46 INFO - 'tscrollx', 23:11:46 INFO - 'cart', 23:11:46 INFO - 'tsvg_static']}, 23:11:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:11:46 INFO - 'tsvgr_opacity', 23:11:46 INFO - 'tart', 23:11:46 INFO - 'tscrollx', 23:11:46 INFO - 'cart', 23:11:46 INFO - 'tsvg_static']}, 23:11:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'talos_options': ['--disable-e10s'], 23:11:46 INFO - 'tests': ['tp5o']}, 23:11:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:11:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'talos_options': ['--disable-e10s', 23:11:46 INFO - '--xperf_path', 23:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:46 INFO - 'tests': ['tp5n']}, 23:11:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:11:46 INFO - 'talos_options': ['--xperf_path', 23:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:46 INFO - 'tests': ['tp5n']}}, 23:11:46 INFO - 'talos.zip': {'path': '', 23:11:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:11:46 INFO - Running post-action listener: _resource_record_post_action 23:11:46 INFO - [mozharness: 2017-03-07 07:11:46.024804Z] Finished populate-webroot step (success) 23:11:46 INFO - [mozharness: 2017-03-07 07:11:46.024923Z] Running create-virtualenv step. 23:11:46 INFO - Running pre-action listener: _resource_record_pre_action 23:11:46 INFO - Running main action method: create_virtualenv 23:11:46 INFO - Creating virtualenv /builds/slave/test/build/venv 23:11:46 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 23:11:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:11:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:11:46 INFO - Using real prefix '/tools/python27' 23:11:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:11:46 INFO - Installing distribute.............................................................................................................................................................................................done. 23:11:49 INFO - Installing pip.................done. 23:11:49 INFO - Return code: 0 23:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:11:49 INFO - Reading from file tmpfile_stdout 23:11:49 INFO - Output received: 23:11:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:11: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')]} 23:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:50 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:11:50 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 23:11:50 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 23:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:11:50 INFO - 'HOME': '/Users/cltbld', 23:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:11:50 INFO - 'LOGNAME': 'cltbld', 23:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:50 INFO - 'MOZ_NO_REMOTE': '1', 23:11:50 INFO - 'NO_EM_RESTART': '1', 23:11:50 INFO - 'PAGER': '/bin/cat', 23:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:50 INFO - 'PWD': '/builds/slave/test', 23:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:11:50 INFO - 'SHELL': '/bin/bash', 23:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:11:50 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:11:50 INFO - 'USER': 'cltbld', 23:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:50 INFO - 'XPC_FLAGS': '0x0', 23:11:50 INFO - 'XPC_SERVICE_NAME': '0', 23:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:51 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 23:11:51 INFO - Cleaning up... 23:11:51 INFO - Return code: 0 23:11:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:11:51 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')]} 23:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:51 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')]} 23:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:51 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:11:51 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 23:11:51 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 23:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:11:51 INFO - 'HOME': '/Users/cltbld', 23:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:11:51 INFO - 'LOGNAME': 'cltbld', 23:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:51 INFO - 'MOZ_NO_REMOTE': '1', 23:11:51 INFO - 'NO_EM_RESTART': '1', 23:11:51 INFO - 'PAGER': '/bin/cat', 23:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:51 INFO - 'PWD': '/builds/slave/test', 23:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:11:51 INFO - 'SHELL': '/bin/bash', 23:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:11:51 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:11:51 INFO - 'USER': 'cltbld', 23:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:51 INFO - 'XPC_FLAGS': '0x0', 23:11:51 INFO - 'XPC_SERVICE_NAME': '0', 23:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:51 INFO - Downloading/unpacking psutil>=3.1.1 23:11:51 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 23:11:51 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 23:11:51 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 23:11:51 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 23:11:51 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 23:11:51 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 23:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:11:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:55 INFO - Installing collected packages: psutil 23:11:55 INFO - Running setup.py install for psutil 23:11:55 INFO - building 'psutil._psutil_osx' extension 23:11:55 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 23:11:56 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 23:11:56 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 23:11:56 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 23:11:56 INFO - building 'psutil._psutil_posix' extension 23:11:56 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 23:11:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:11:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:11:56 INFO - ^ 23:11:56 INFO - 1 warning generated. 23:11:56 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 23:11:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:56 INFO - Successfully installed psutil 23:11:56 INFO - Cleaning up... 23:11:56 INFO - Return code: 0 23:11:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:11:56 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')]} 23:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:56 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')]} 23:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:56 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:11:56 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 23:11:56 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 23:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:11:56 INFO - 'HOME': '/Users/cltbld', 23:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:11:56 INFO - 'LOGNAME': 'cltbld', 23:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:56 INFO - 'MOZ_NO_REMOTE': '1', 23:11:56 INFO - 'NO_EM_RESTART': '1', 23:11:56 INFO - 'PAGER': '/bin/cat', 23:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:56 INFO - 'PWD': '/builds/slave/test', 23:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:11:56 INFO - 'SHELL': '/bin/bash', 23:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:11:56 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:11:56 INFO - 'USER': 'cltbld', 23:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:56 INFO - 'XPC_FLAGS': '0x0', 23:11:56 INFO - 'XPC_SERVICE_NAME': '0', 23:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:11:59 INFO - Installing collected packages: mozsystemmonitor 23:11:59 INFO - Running setup.py install for mozsystemmonitor 23:11:59 INFO - Successfully installed mozsystemmonitor 23:11:59 INFO - Cleaning up... 23:11:59 INFO - Return code: 0 23:11:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:11:59 INFO - 'HOME': '/Users/cltbld', 23:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:11:59 INFO - 'LOGNAME': 'cltbld', 23:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:59 INFO - 'MOZ_NO_REMOTE': '1', 23:11:59 INFO - 'NO_EM_RESTART': '1', 23:11:59 INFO - 'PAGER': '/bin/cat', 23:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:59 INFO - 'PWD': '/builds/slave/test', 23:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:11:59 INFO - 'SHELL': '/bin/bash', 23:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:11:59 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:11:59 INFO - 'USER': 'cltbld', 23:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:59 INFO - 'XPC_FLAGS': '0x0', 23:11:59 INFO - 'XPC_SERVICE_NAME': '0', 23:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:00 INFO - Downloading/unpacking jsonschema==2.5.1 23:12:00 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 23:12:00 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 23:12:00 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 23:12:00 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 23:12:00 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 23:12:00 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 23:12:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:12:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:03 INFO - Downloading functools32-3.2.3-2.tar.gz 23:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:12:03 INFO - warning: no files found matching '*.txt' 23:12:03 INFO - no previously-included directories found matching 'build' 23:12:03 INFO - no previously-included directories found matching 'dist' 23:12:03 INFO - no previously-included directories found matching '.git*' 23:12:03 INFO - Installing collected packages: jsonschema, functools32 23:12:03 INFO - Running setup.py install for functools32 23:12:03 INFO - warning: no files found matching '*.txt' 23:12:03 INFO - no previously-included directories found matching 'build' 23:12:03 INFO - no previously-included directories found matching 'dist' 23:12:03 INFO - no previously-included directories found matching '.git*' 23:12:03 INFO - Successfully installed jsonschema functools32 23:12:03 INFO - Cleaning up... 23:12:03 INFO - Return code: 0 23:12:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:12:03 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')]} 23:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:03 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')]} 23:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:03 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:03 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 23:12:03 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 23:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:03 INFO - 'HOME': '/Users/cltbld', 23:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:03 INFO - 'LOGNAME': 'cltbld', 23:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:03 INFO - 'MOZ_NO_REMOTE': '1', 23:12:03 INFO - 'NO_EM_RESTART': '1', 23:12:03 INFO - 'PAGER': '/bin/cat', 23:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:03 INFO - 'PWD': '/builds/slave/test', 23:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:03 INFO - 'SHELL': '/bin/bash', 23:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:03 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:03 INFO - 'USER': 'cltbld', 23:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:03 INFO - 'XPC_FLAGS': '0x0', 23:12:03 INFO - 'XPC_SERVICE_NAME': '0', 23:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:12:03 INFO - Cleaning up... 23:12:03 INFO - Return code: 0 23:12:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:12:03 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')]} 23:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:03 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')]} 23:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:03 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:03 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 23:12:03 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 23:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:03 INFO - 'HOME': '/Users/cltbld', 23:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:03 INFO - 'LOGNAME': 'cltbld', 23:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:03 INFO - 'MOZ_NO_REMOTE': '1', 23:12:03 INFO - 'NO_EM_RESTART': '1', 23:12:03 INFO - 'PAGER': '/bin/cat', 23:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:03 INFO - 'PWD': '/builds/slave/test', 23:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:03 INFO - 'SHELL': '/bin/bash', 23:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:03 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:03 INFO - 'USER': 'cltbld', 23:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:03 INFO - 'XPC_FLAGS': '0x0', 23:12:03 INFO - 'XPC_SERVICE_NAME': '0', 23:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:03 INFO - Downloading/unpacking blobuploader==1.2.4 23:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:06 INFO - Downloading blobuploader-1.2.4.tar.gz 23:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:12:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:12:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:12:07 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 23:12:07 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 23:12:07 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 23:12:07 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 23:12:07 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 23:12:07 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 23:12:07 INFO - Downloading docopt-0.6.1.tar.gz 23:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:12:07 INFO - Installing collected packages: blobuploader, requests, docopt 23:12:07 INFO - Running setup.py install for blobuploader 23:12:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:12:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:12:08 INFO - Running setup.py install for requests 23:12:08 INFO - Running setup.py install for docopt 23:12:08 INFO - Successfully installed blobuploader requests docopt 23:12:08 INFO - Cleaning up... 23:12:08 INFO - Return code: 0 23:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12:08 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')]} 23:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:08 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')]} 23:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:08 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:08 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 23:12:08 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 23:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:08 INFO - 'HOME': '/Users/cltbld', 23:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:08 INFO - 'LOGNAME': 'cltbld', 23:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:08 INFO - 'MOZ_NO_REMOTE': '1', 23:12:08 INFO - 'NO_EM_RESTART': '1', 23:12:08 INFO - 'PAGER': '/bin/cat', 23:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:08 INFO - 'PWD': '/builds/slave/test', 23:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:08 INFO - 'SHELL': '/bin/bash', 23:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:08 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:08 INFO - 'USER': 'cltbld', 23:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:08 INFO - 'XPC_FLAGS': '0x0', 23:12:08 INFO - 'XPC_SERVICE_NAME': '0', 23:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-M0Pqk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-JUdzlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-pRTIBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-mEHFef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2BmBhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-sl0NBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-T9y3sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AM6Emc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zMCoTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:12:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2vEc1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Fo2VHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gaibk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-rN_G49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gDHflc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hPFyBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uHrcJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:12:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-i1cT0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:12:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:12:10 INFO - Running setup.py install for manifestparser 23:12:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:12:10 INFO - Running setup.py install for mozcrash 23:12:11 INFO - Running setup.py install for mozdebug 23:12:11 INFO - Running setup.py install for mozdevice 23:12:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:12:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:12:11 INFO - Running setup.py install for mozfile 23:12:11 INFO - Running setup.py install for mozhttpd 23:12:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:12:11 INFO - Running setup.py install for mozinfo 23:12:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:12:11 INFO - Running setup.py install for mozInstall 23:12:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Running setup.py install for mozleak 23:12:12 INFO - Running setup.py install for mozlog 23:12:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Running setup.py install for moznetwork 23:12:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Running setup.py install for mozprocess 23:12:12 INFO - Running setup.py install for mozprofile 23:12:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:12:12 INFO - Running setup.py install for mozrunner 23:12:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:12:13 INFO - Running setup.py install for mozscreenshot 23:12:13 INFO - Running setup.py install for moztest 23:12:13 INFO - Running setup.py install for mozversion 23:12:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:12:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:12:13 INFO - Cleaning up... 23:12:13 INFO - Return code: 0 23:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:13 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:13 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 23:12:13 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 23:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:13 INFO - 'HOME': '/Users/cltbld', 23:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:13 INFO - 'LOGNAME': 'cltbld', 23:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:13 INFO - 'MOZ_NO_REMOTE': '1', 23:12:13 INFO - 'NO_EM_RESTART': '1', 23:12:13 INFO - 'PAGER': '/bin/cat', 23:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:13 INFO - 'PWD': '/builds/slave/test', 23:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:13 INFO - 'SHELL': '/bin/bash', 23:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:13 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:13 INFO - 'USER': 'cltbld', 23:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:13 INFO - 'XPC_FLAGS': '0x0', 23:12:13 INFO - 'XPC_SERVICE_NAME': '0', 23:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:12:13 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zZVHQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:12:13 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)) 23:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:12:13 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-YyFQAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 23:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:12:13 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-c5iZAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ffyvor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-jw_bx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-YpyULo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-1tEtTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-uZOGl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-n2nnig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-6EumJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-DGRPpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:12:14 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)) 23:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:12:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-aMCDOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 23:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:12:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-86y9bK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:12:15 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)) 23:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:12:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-2keiW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 23:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-yjzS0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:12:15 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)) 23:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:12:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-N9mB8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 23:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:12:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-hGNFcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:12:15 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)) 23:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:12:15 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)) 23:12:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:12:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:12:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:12:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:12:18 INFO - Downloading blessings-1.6.tar.gz 23:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:12:18 INFO - Installing collected packages: blessings 23:12:18 INFO - Running setup.py install for blessings 23:12:18 INFO - Successfully installed blessings 23:12:18 INFO - Cleaning up... 23:12:18 INFO - Return code: 0 23:12:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:12:18 INFO - Reading from file tmpfile_stdout 23:12:18 INFO - Current package versions: 23:12:18 INFO - blessings == 1.6 23:12:18 INFO - blobuploader == 1.2.4 23:12:18 INFO - docopt == 0.6.1 23:12:18 INFO - functools32 == 3.2.3-2 23:12:18 INFO - jsonschema == 2.5.1 23:12:18 INFO - manifestparser == 1.1 23:12:18 INFO - mozInstall == 1.12 23:12:18 INFO - mozcrash == 1.0 23:12:18 INFO - mozdebug == 0.1 23:12:18 INFO - mozdevice == 0.49 23:12:18 INFO - mozfile == 1.2 23:12:18 INFO - mozhttpd == 0.7 23:12:18 INFO - mozinfo == 0.9 23:12:18 INFO - mozleak == 0.1 23:12:18 INFO - mozlog == 3.4 23:12:18 INFO - moznetwork == 0.27 23:12:18 INFO - mozprocess == 0.25 23:12:18 INFO - mozprofile == 0.28 23:12:18 INFO - mozrunner == 6.13 23:12:18 INFO - mozscreenshot == 0.1 23:12:18 INFO - mozsystemmonitor == 0.3 23:12:18 INFO - moztest == 0.8 23:12:18 INFO - mozversion == 1.4 23:12:18 INFO - psutil == 3.1.1 23:12:18 INFO - requests == 1.2.3 23:12:18 INFO - wsgiref == 0.1.2 23:12:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:18 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:18 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 23:12:18 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 23:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:18 INFO - 'HOME': '/Users/cltbld', 23:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:18 INFO - 'LOGNAME': 'cltbld', 23:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:18 INFO - 'MOZ_NO_REMOTE': '1', 23:12:18 INFO - 'NO_EM_RESTART': '1', 23:12:18 INFO - 'PAGER': '/bin/cat', 23:12:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:18 INFO - 'PWD': '/builds/slave/test', 23:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:18 INFO - 'SHELL': '/bin/bash', 23:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:18 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:18 INFO - 'USER': 'cltbld', 23:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:18 INFO - 'XPC_FLAGS': '0x0', 23:12:18 INFO - 'XPC_SERVICE_NAME': '0', 23:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 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)) 23:12:19 INFO - Cleaning up... 23:12:19 INFO - Return code: 0 23:12:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:12:19 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')]} 23:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:19 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')]} 23:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:12:19 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 0x10188db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101890768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3abcb0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, '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.Modogb4dlE/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.SXyS1lFyUe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 23:12:19 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 23:12:19 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 23:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:19 INFO - 'HOME': '/Users/cltbld', 23:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:19 INFO - 'LOGNAME': 'cltbld', 23:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:19 INFO - 'MOZ_NO_REMOTE': '1', 23:12:19 INFO - 'NO_EM_RESTART': '1', 23:12:19 INFO - 'PAGER': '/bin/cat', 23:12:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:19 INFO - 'PWD': '/builds/slave/test', 23:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:19 INFO - 'SHELL': '/bin/bash', 23:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:19 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:19 INFO - 'USER': 'cltbld', 23:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:19 INFO - 'XPC_FLAGS': '0x0', 23:12:19 INFO - 'XPC_SERVICE_NAME': '0', 23:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:12:19 INFO - Cleaning up... 23:12:19 INFO - Return code: 0 23:12:19 INFO - Running post-action listener: _resource_record_post_action 23:12:19 INFO - Running post-action listener: _start_resource_monitoring 23:12:19 INFO - Starting resource monitoring. 23:12:19 INFO - [mozharness: 2017-03-07 07:12:19.476572Z] Finished create-virtualenv step (success) 23:12:19 INFO - [mozharness: 2017-03-07 07:12:19.477576Z] Running install step. 23:12:19 INFO - Running pre-action listener: _resource_record_pre_action 23:12:19 INFO - Running main action method: install 23:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:12:19 INFO - Reading from file tmpfile_stdout 23:12:19 INFO - Detecting whether we're running mozinstall >=1.0... 23:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:12:19 INFO - Reading from file tmpfile_stdout 23:12:19 INFO - Output received: 23:12:19 INFO - Usage: mozinstall [options] installer 23:12:19 INFO - Options: 23:12:19 INFO - -h, --help show this help message and exit 23:12:19 INFO - -d DEST, --destination=DEST 23:12:19 INFO - Directory to install application into. [default: 23:12:19 INFO - "/builds/slave/test"] 23:12:19 INFO - --app=APP Application being installed. [default: firefox] 23:12:19 INFO - mkdir: /builds/slave/test/build/application 23:12:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:12:40 INFO - Reading from file tmpfile_stdout 23:12:40 INFO - Output received: 23:12:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:12:40 INFO - Running post-action listener: _resource_record_post_action 23:12:40 INFO - [mozharness: 2017-03-07 07:12:40.370332Z] Finished install step (success) 23:12:40 INFO - [mozharness: 2017-03-07 07:12:40.370476Z] Running run-tests step. 23:12:40 INFO - Running pre-action listener: _resource_record_pre_action 23:12:40 INFO - Running main action method: run_tests 23:12:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:12:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:12:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:12:40 INFO - 2017-03-06 23:12:40.385 screenresolution[2211:24911] starting screenresolution argv=screenresolution get 23:12:40 INFO - 2017-03-06 23:12:40.388 screenresolution[2211:24911] Display 0: 1600x1200x32@60 23:12:40 INFO - 2017-03-06 23:12:40.394 screenresolution[2212:24913] starting screenresolution argv=screenresolution list 23:12:40 INFO - Available Modes on Display 0 23:12:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:12:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:12:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:12:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:12:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:12:40 INFO - Intel Iris: 23:12:40 INFO - Chipset Model: Intel Iris 23:12:40 INFO - Type: GPU 23:12:40 INFO - Bus: Built-In 23:12:40 INFO - VRAM (Dynamic, Max): 1536 MB 23:12:40 INFO - Vendor: Intel (0x8086) 23:12:40 INFO - Device ID: 0x0a2e 23:12:40 INFO - Revision ID: 0x0009 23:12:40 INFO - Displays: 23:12:40 INFO - Display: 23:12:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:12:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:12:40 INFO - Display Serial Number: 1600x1200 60 23:12:40 INFO - Main Display: Yes 23:12:40 INFO - Mirror: Off 23:12:40 INFO - Online: Yes 23:12:40 INFO - Rotation: Supported 23:12:40 INFO - Return code: 0 23:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:12:40 INFO - Python 2.7.3 23:12:40 INFO - Return code: 0 23:12:40 INFO - grabbing minidump binary from tooltool 23:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:40 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 0x10166bd30>, '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 0x101592db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101660be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:12:40 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 23:12:40 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 23:12:40 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 23:12:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:12:40 INFO - Return code: 0 23:12:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:12:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:12:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:12:40 INFO - ENV: RUST_BACKTRACE is now 1 23:12:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:12:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Modogb4dlE/Render', 23:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:12:40 INFO - 'HOME': '/Users/cltbld', 23:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:12:40 INFO - 'LOGNAME': 'cltbld', 23:12:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:12:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:40 INFO - 'MOZ_NO_REMOTE': '1', 23:12:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:12:40 INFO - 'NO_EM_RESTART': '1', 23:12:40 INFO - 'PAGER': '/bin/cat', 23:12:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:40 INFO - 'PWD': '/builds/slave/test', 23:12:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:12:40 INFO - 'RUST_BACKTRACE': '1', 23:12:40 INFO - 'SHELL': '/bin/bash', 23:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SXyS1lFyUe/Listeners', 23:12:40 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 23:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:12:40 INFO - 'USER': 'cltbld', 23:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:40 INFO - 'XPC_FLAGS': '0x0', 23:12:40 INFO - 'XPC_SERVICE_NAME': '0', 23:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:12:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:12:40 INFO - mozversion application_buildid: 20170306205430 23:12:40 INFO - mozversion application_changeset: b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 23:12:40 INFO - mozversion application_display_name: Nightly 23:12:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:12:40 INFO - mozversion application_name: Firefox 23:12:40 INFO - mozversion application_remotingname: firefox 23:12:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 23:12:40 INFO - mozversion application_vendor: Mozilla 23:12:40 INFO - mozversion application_version: 55.0a1 23:12:40 INFO - mozversion platform_buildid: 20170306205430 23:12:40 INFO - mozversion platform_changeset: b7e42143bbbc9dc3e5c05bd1e93b6485ce1d0ad4 23:12:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 23:12:40 INFO - mozversion platform_version: 55.0a1 23:12:40 INFO - using testdate: 1488870760 23:12:40 INFO - actual date: 1488870760 23:12:40 INFO - starting webserver on 'localhost:58608' 23:12:40 INFO - SUITE-START | Running 2 tests 23:12:40 INFO - TEST-START | dromaeo_css 23:12:40 INFO - operating with platform_type : mac_ 23:12:40 INFO - Initialising browser for dromaeo_css test... 23:12:40 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpWixMxk/profile http://localhost:58608/getInfo.html) 23:12:43 INFO - PROCESS | 2222 | 2017-03-06 23:12:43.626 plugin-container[2224:25082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 23:12:43 INFO - PROCESS | 2222 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:12:43 INFO - PROCESS | 2222 | 2017-03-06 23:12:43.629 plugin-container[2224:25082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 23:12:43 INFO - PROCESS | 2222 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:12:43 INFO - PROCESS | 2222 | 2017-03-06 23:12:43.629 plugin-container[2224:25082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:12:43 INFO - PROCESS | 2222 | __metrics Screen width/height:1600/1200 23:12:43 INFO - PROCESS | 2222 | colorDepth:24 23:12:43 INFO - PROCESS | 2222 | Browser inner width/height: 1024/689 23:12:43 INFO - PROCESS | 2222 | __metrics 23:12:43 ERROR - PROCESS | 2222 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 23:12:44 INFO - TEST-INFO | 2222: exit 0 23:12:44 INFO - Browser initialized. 23:12:44 INFO - Running cycle 1/1 for dromaeo_css test... 23:12:44 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpWixMxk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:46 INFO - PROCESS | 2225 | 2017-03-06 23:12:46.937 plugin-container[2226:25222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 23:12:46 INFO - PROCESS | 2225 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:12:46 INFO - PROCESS | 2225 | 2017-03-06 23:12:46.940 plugin-container[2226:25222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 23:12:46 INFO - PROCESS | 2225 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:12:46 INFO - PROCESS | 2225 | 2017-03-06 23:12:46.940 plugin-container[2226:25222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:12:56 INFO - PROCESS | 2225 | RSS: Main: 226619392 23:12:56 INFO - PROCESS | 2225 | 23:16:00 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58608/tests/dromaeo/cssquery-ext.html) 23:16:02 INFO - PROCESS | 2225 | RSS: Main: 249708544 23:16:02 INFO - PROCESS | 2225 | 23:17:44 INFO - PROCESS | 2225 | ************************* 23:17:44 INFO - PROCESS | 2225 | A coding exception was thrown and uncaught in a Task. 23:17:44 INFO - PROCESS | 2225 | 23:17:44 ERROR - PROCESS | 2225 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:17:44 INFO - PROCESS | 2225 | Full stack: 23:17:44 INFO - PROCESS | 2225 | ************************* 23:19:04 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-ext.html (next: http://localhost:58608/tests/dromaeo/cssquery-jquery.html) 23:19:05 INFO - PROCESS | 2225 | RSS: Main: 257261568 23:19:05 INFO - PROCESS | 2225 | 23:22:07 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58608/tests/dromaeo/cssquery-mootools.html) 23:22:07 INFO - PROCESS | 2225 | RSS: Main: 268251136 23:22:07 INFO - PROCESS | 2225 | 23:25:09 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58608/tests/dromaeo/cssquery-prototype.html) 23:25:10 INFO - PROCESS | 2225 | RSS: Main: 261144576 23:25:10 INFO - PROCESS | 2225 | 23:28:13 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58608/tests/dromaeo/cssquery-yui.html) 23:28:14 INFO - PROCESS | 2225 | RSS: Main: 271704064 23:28:14 INFO - PROCESS | 2225 | 23:31:16 INFO - PROCESS | 2225 | Cycle 1(1): loaded http://localhost:58608/tests/dromaeo/cssquery-yui.html (next: http://localhost:58608/tests/dromaeo/cssquery-dojo.html) 23:31:17 INFO - PROCESS | 2225 | RSS: Main: 266039296 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | __start_tp_report 23:31:17 INFO - PROCESS | 2225 | _x_x_mozilla_page_load 23:31:17 INFO - PROCESS | 2225 | _x_x_mozilla_page_load_details 23:31:17 INFO - PROCESS | 2225 | |i|pagename|runs| 23:31:17 INFO - PROCESS | 2225 | |0;dojo.html;3407.250404910919;3432.8283585820705;3451.085462352477;3451.3097380523886;3469.8785542506016;5808.854778630536;5920;5958.463738263556;5961.612495187821;6026.186464827247;5915.911261331084;5964.552658550604;6046.576739628217;6081.604695694777;6107.847303817407;5352.277442545265;5362.088444964346;5397.919031214535;5434.103372943459;5476.342838859323;5376.462353764612;5441.7279136043235;5494.230807686907;5521.061419558685;5526.723663816813;5230.979958908015;5481.424450432727;5513.117901135839;5538.280023596927;5545.556355491572;5310.017646735328;5324.148136298174;5366.382865970424;5398.838034858961;5400.297961265011;4496.887577810581;5342.679439233659;5349.411564727877;5388.515033646951;5420.186971954199;5309.522143007148;5519.116941289374;6134.141220229171;6146.139540464339;6147.661878596676;6181.07283907413;6214.533909956771;6277.843053923689;6342.460890824285;6368.904466433008;6451.7741879034475;6461.224653041665;6463.385978332051;6474.449671777905;6481.48148148147;5151.068349368987;6189.35011823761;6200.844978875519;6217.129601855744;6221.751129954751;6282.089097080989;6324.462420694293;6482.384173503509;6567.047778072249;6629.900551491732;6218.378162183745;6250.49996000328;6271.62370257786;6283.560150789493;6284.811455656246;6428.678566071678;6435.51733619971;6435.839104022437;6455.7740479083;6457.709403076873;6182.536309776785;6198.132261483396;6215.564910456224;6283.654399008007;6352.2694890087305;5509.47659972302;6313.515880836505;6338.904916426257;6362.872742545123;6367.872642547123;5150;5153.613478989091;5173.198154286091;5176.197689358156;5234.947650523446;4520.728756274634;4547.795349209294;4573;4604.930926036112;4617.376654151646;5160.716160611128;6184.5980011299125;6197.442230199214;6205.937940620536;6235.189425374672;6459.806205813833;6466.515011374166;6475.579087359307;6484.708188131545;6493.480521558264;5962.314333851576;6153.353897840751;6205.2553693557065;6221.4089661482085;6235;5582.162675598693;5603.299587551556;5616.438356164433;5617.41017193197;5625.09998400254;6450.645214513247;6593.307702691244;6638.634875081917;6651.43462805667;6697.196336439659;6487.675616219264;6491.383318584727;6497.18785151856;6501.187351581052;6529.7388104475285;6697.2633010367845;6747.055412242192;6757.628330441873;6758.155230596176;6762.966185169043;6150.261968563801;6182.783602573888;6288.434040936338;6318.147050148171;6332.52506062038;6443.484521238196;6480.287168411377;6487.708053137714;6509.804705858832;6550.410463058348;6305.842353941188;6317.7157027932935;6319.58922670025;6328.46208072319;6337.271213810471;6137.714085983886;6180.165677633462;6203.751849926133;6234.345393733593;6235.812925612239;4980.427250866197;6160.16837726919;6181.196444462191;6242.843928901813;6243.37566243372;6201.224846894138;6215.818994391051;6265.279492858197;6288.6855657217875;6298.71655775482;5595.68501402162;6215.906761398493;6221.626702397871;6242.344553821783;6290.528210384331 23:31:17 INFO - PROCESS | 2225 | |1;ext.html;702.2450865319779;731.593965349231;750.7409943569469;928.1414691410444;929.8047410043895;37420.06449838752;38150.04624884379;39617.40573891394;39690.80154599227;39778.60221397785;38703.806480967556;38925;38975.41536876949;39032.024199395026;39048;7711.3059824615775;7853.136155022943;10462.267641265114;10712.35725856451;10903.01872831445;10631.308964917256;10685.679429617085;10690;10698.411587362692;10729.517171727291;10701;10711.03600675939;10752.892471075307;10791.244612877103;10793;10684.145268378588;10685.038346548808;10713.785724285548;10715.83926241107;10732.570697172181;10281.177505799518;10323.896761032443;10343.431111288875;10351.223658225663;10355.48222588875;32912.835435822904;33011.504827427605;33086.17284567905;33103.172420689676;33374.49938250928;3264.3308121834966;3565.180008598037;3574.3566158091535;3626.5829429615405;3725.795081270529;22824.429389265235;24257.272281831396;24817;24830.130945416822;25551.361215969562;2460.3972026853357;3054.3585846972164;3055.5416687497054;3123.485109721783;3144.33968866536;289516;337484;340791;340946.59053409396;343000;43048.56951430446;43230.35154472685;43422.56577434185;43432.78283608625;43494.56505434905;45422.77288613536;45705.085898281846;45715.31427028597;45734;45912.770436147606;13057.412416441282;13074.411651475706;13173.209607423585;13177.275249861157;13180.143290686075;11115.055220306203;11143.888561114447;11170.832437513282;11188.66434006981;11195.720106997227;9078.319126065571;10250.794984100276;11811.519580887938;11943;11949.462274197682;5477.589180811376;5549.861253468614;5569.610127291132;5582.748776305076;5594.496495315441;930.3255140023564;1109.378747901178;1111.8888111188817;1117.6870476266495;1176.4235524592889;20761.273355432488;25378;25758.8408521617;25794.871025645127;25964.961401544104;1222.9694257643453;1245.0102168775845;1257.792464243408;1267.2269915351628;1270.4029106320015;10101.494925253708;11682.883171168349;13159.60521184366;13367;13372.130811497416;418691;426412;450863.7456812761;451538;454630;45800.083998319846;46801.765991170505;48203;49966;53057;1286.8648791876906;1408.4718230663502;1423.7864320351823;1432.7349440353569;1444.5088669852091;28098.71901280961;28130.859345703142;28554.14337569876;28695;28784.136475905758;44101.77949110234;44202.55797442113;44396.77801610971;44615.100018499994;44813.10373792571;433504;434386;436856;437305;437866;30305.69694303117;30866.0740177795;30890.0732978011;30909.227269318446;30911.381772364875;40443.3933490992;40676.38985415162;41086.589134108275;41681.16637667169;42086.78956605197;33599;33972.49041264334;34019.14952126217;34794;34870.30259394849;33994.66005339915;34929.47605785864;34963.300733984666;35032.474512882836;35041.29917401689;31133.53299700461;34310.42671799675;35354.29291414209;35394.29211415809;35410.46884296686;25339;26171;28872.711272887;30851.382972340878;31251.68748312488;20572.485687857323;23412.414689632213;23594.646080308466;23647;23812.88093559521 23:31:17 INFO - PROCESS | 2225 | |2;jquery.html;373.9813009349532;374.8388193076976;405.0966345050537;421.3195688962327;423.63144064663743;19629.31297404591;19788.208471661117;20546.589068218665;20886.06012729427;20954.95225238738;21299.25452609159;21705.565888682257;21867.234646787365;22232;22425.775742242615;6407.615543067431;6603.537752357337;6704.731810727565;6922.411595014405;6965.477589180807;6577.177852768404;6731.629760363178;6732.259451460335;6821.727130914757;6835.965820170917;6027.819165425044;6772.593644381328;6817.284185160561;6863.450923926099;6868.690908909086;6656.167979002625;6782.1183246177925;6803.319668033182;6842.213145488283;6855.965720171417;6646.368594983469;6648.202215734094;6678.666066696645;7123.074000379917;7132.5007249492555;25906.35234119162;26067.95728170871;26364.73635263642;26382.680865956816;26672.265882776428;2895.290653789835;2924.69290724473;2964.2537139801984;3000.6699263081046;3002.774791890617;15846.683066338725;15951.245363010059;16204;16363.34546618134;16772.664546709;1788.9284428622839;2008.3573256557906;2032.5121970727066;2055.2395613622907;2059.3718915730697;129888.35055824755;138224.30887845598;152465.23767381202;167640;169793.15103424527;19290.58867641545;20524.486887827923;20740.859252740942;20741.58516829685;20780.688289675665;30341.848290758404;30481.847590761903;30512.542311865338;30639.69360306413;30666.54000189999;9742.853857192147;9940.111378838143;10043.817643176442;10213.795724085625;10331.586736530455;8482.703105391405;8501.744947651581;8521;8658.090900455487;8735.43219690718;9545.949945505989;9579.808403831885;9647.565859536197;9659.130678238993;9703.223742100616;3954.268460334847;4013.177298653769;4023.657989070903;4040.919181616351;4056.1684854605323;863.3395452478859;873.6287077991828;879.7580665317089;903.661127077346;920.9309301802258;17065;20856.374308770763;21619.243326483498;21725.13099476003;21798;884.8318819424288;910.2081189365294;918.5453200665713;953.9236861051099;971.9805603887883;8230.917690823015;10024.649137280161;10747.086497647631;10802.89197108019;10931.945340273247;698536;699050;705019;710977;723626;131465;155898.22050889672;160347;161889;163383;1144.5250221158292;1148.8276758486295;1211.7879371110196;1248.7315227226009;1259.2066997791333;20035.699464507754;20062.398128056182;20446.182152713725;20472;20472.897635511727;52806;64256.35743642504;64634.67682661556;65268.673656631414;65976.34023659703;78635;88500.11499884918;91770;93223;93634;11435.599754008903;11543.229624890133;11653.359065251492;11656.475458604553;11718.472668730099;12840.807387889003;12870.356482176041;13197.472101116233;13203.735925281248;13237.40431680596;5565;8325.458845175044;10648.627298044532;10996.175286853679;11004.059323977886;9473.952630236805;10958.835617465904;11093.667189984313;11132.666020019413;11283.605073822377;11131.666050018512;11517.827232591686;11528.59649912249;11846.407679616157;11896.76206475883;11454.94272528632;11774;11791.528338866692;11856.70358241051;11889.702757431134;11347.48936297852;11378.886211137782;11586.015188709054;11670.649880503599;11777.234479758788 23:31:17 INFO - PROCESS | 2225 | |3;mootools.html;1439.100562148657;1494.7533656946605;1506.0662389318625;1575.306864979409;1581.7706432567275;10327.535260913259;10569.67879015123;10816.513256903438;10991.06575941045;10996.560137594506;9839.852402213955;10750.247482676215;10951.616693415732;11004.944975275112;11019.83470247947;2317.9453348726333;2341.1103780563362;2351.0830775997288;2352.3766201956496;2359.988200059006;2102.8843413612244;2208.763092668103;2275.601769690306;2315.90736370545;2316.6409206573007;2294.7819957104048;2308.7114110736156;2308.9422764430938;2310.1106074161403;2319.257837492003;2268.6937263469467;2268.9886550567307;2274.80664143548;2280.817534597228;2285.04028308111;2254.4251215939994;2310.5378924215224;2311.4799170186743;2318.826088043387;2324.732655744577;10691;10747.78504429907;10775;10778.622748203776;10799.974002469753;5543.085390910495;5649.378568357477;5683.630564013326;5693.971530142323;5713.542916566665;3293.5883014623173;3384.627690953993;3592.802395868226;3598.514200582914;3605.94591081134;1123.606707742621;1149.919505634606;1198.8261702053242;1211.5698926881007;1226.4480983557435;13279.73440531184;13466.25935573543;14200.934929880405;14666.413343466249;14728.116313021254;4481.843135490308;4498.167838949805;4640.582347588733;4665.346851440801;4675.368825208553;9000.784894039352;11071.612493562849;11349.432528373549;11354.26197297173;11358.716032099099;4780.235162374073;5658.943410565924;5692.943070569324;5704.401037891044;5716.42835716425;5124.538791508708;5143.254228136974;5175.223716442489;5222.294990176278;5228.9738551307;3783.5459744830796;3808.4668146459517;3829.119302560371;3897.4543563901075;3897.6881849451975;1036.8807587127426;1038.693585392311;1038.8182068138046;1051.894810518942;1051.9158467322595;774.9457537972401;856.5160684213399;908.5729707037682;920.4845286639503;952.7237101240563;5770.798022069207;6550.574212676161;6600.438962688129;6726.529142960044;7035.33164349386;1015.4509987593261;1028.341861208827;1033.6975410982066;1036.1451802263164;1077.0306723948509;4132.752034877917;4173;4193.580641935782;4287.828486860491;4324.13517296542;12812.935935320264;13145.21128732279;13151.473941042443;13151.539696110782;13193.20840749558;13046.152000119962;13046.934765326112;13058.477660893457;13145.73708525824;13188;8077.838443231102;8104.270615644738;9676.612935482643;9832;9856.21150307988;5206.973965130302;6177.876442471035;6479.4816414685765;6561.179852518435;6573.244076931022;11714.121440891797;11811.409429528661;12052.51789928393;12061.336626485627;12061.457234424297;11681.415929203336;11930.522779088738;12055.397230138633;12200.328981906081;12409.503619855104;7775.572343520935;8153.6330865112395;8961.641534338554;9147.451152930846;9197.724068277963;10048.145907597936;10269.332493387907;10282.588696452358;10315.17478601695;10315.226358023028;8715.520646364257;8808.427452215585;8816.735497935073;8847.42491738035;8887.777805554913;8669.783255418664;8679.17547832955;8730.257928076184;8734.65061397537;8766.298696104424;7641.694332226649;7754.147043825062;7767.572783496736;7826.452148349561;7835.177306382748;5830.2420685310635;5879.5002424794375;5885.058390657474;5930.7924222652;6253.843653908507;5638.88722225545;5934.821955341346;5946.940530594812;5959.91060134107;6117.3576774439825 23:31:17 INFO - PROCESS | 2225 | |4;prototype.html;310.65982748889985;339.42298093241493;374.79386337514376;378.92232092421057;385.7242071918578;4438.911221775566;4561.407017087774;5085.821996230133;5158.484151584849;5222.529972302492;4475.507694153641;4544.909461566133;4791.209450440673;5116.948830511694;5222;4165.66674666026;4328.199283132622;4347.043650396908;4379.64962802975;4391.297392417214;3518.665726755954;3549.852339334704;3814.4469051987444;3911.706622003347;4405.229084910144;3632.6367363263726;3844.2888065707934;4218.1563687262515;4350.695451318409;4350.825966961318;3893.6885049196;3973.4834471518516;4216.241076606209;4350.912981740347;4389.4513185851765;3534.929301413983;3560.7151427885965;3680;3834.811208525366;3886.455896174538;4344.370066340363;4474.798634061475;4661.766911654404;5059.519345662193;5108.981952128888;4203.957960420387;4796.52034796521;4892.828750993734;5026.798928042874;5050.84847454577;4876.317315575822;5342.946570534284;5402.594805389612;5481.409676661793;5621.8594535136535;3854.5908806995626;4354.325079612633;4409.206342858284;4420.58004489573;4466.285394336891;4964.1704228589715;5376.731163441812;5574.665520068768;5865.568642344234;5876.7355469004;4072.0023594219015;4340.65274778017;4477.306017567283;4517.977410112994;4523.5250298718165;4020.929221450955;4496.785867815737;4600.401947746772;4609.469910960215;4654.371659825948;4387.890302742393;4488.955110448854;4857.635677324144;4954.603631709527;4968.27959945813;4964.056829202512;5533.3083364579425;5569.693666848362;5575.414581468968;5605.243292155588;1363.8158848555317;1404.480342273345;1475.144416238579;1546.7239527389722;1547.9303431345616;992.6450395210486;994.8109859126743;1029.8352263637782;1085.913126949842;1163.6450882480924;4677.298405239173;4961.288355517341;5227.607929405233;5339.385970613352;5351.153851921081;4987.406548594783;5034.672746271481;5242.475752424727;5756.798512052142;5964.463198312173;4462.955370446319;5052.014857102829;5229;5236.397814251333;5305.734713264322;2603.609458581228;2678.504476671822;2740.615664372009;2832.971670283312;2844.6301980742783;5246.242508759532;5353.250544923636;5484;5790;5979.431953964367;5066.1761765764295;5110.718910459886;5383.569314454834;5554.111602055321;5812.418758124154;4912.806675660778;5790.449907258803;6033.431307859988;6068.423499767603;6113.724882380215;4968.826091086795;5300.496452836975;5778.595498315246;5833;5833.445822646841;5071.366079240095;5445.618806683494;5796.029350021013;5831.650100993954;5863.853403664943;5523.619095226193;5988.910166347421;6003.849903752441;6016.639001659914;6017.638941663514;3861.922761544697;3990.900227494336;4075;4137.234611596864;4485.304777759453;5068.467810879804;5070.416902056163;5268.446813084724;5465;5687.77248910039;5123.897522049464;5179.4779549906425;5195.818146364859;5543.307086614173;5582.911332290165;5079.358733458655;5126.667066562646;5217.321748172713;5255.448177941414;5529.972350138223;4650.023495066074;5404.811051524139;5421.647592906448;5469.699166545878;5478.561715062706;4778.713277203379;4986.501349864984;5070.391553013662;5561.721913904369;5574.470425309589;5255.185446255837;5426.860359324586;5431.972840135774;5463.344398672185;5575.804846830341 23:31:17 INFO - PROCESS | 2225 | |5;yui.html;704.471646265301;713.8893471511915;733.7982054934889;764.5565571968259;857.1471385970959;13867.23730194839;15180.165090919994;16485.670286594275;16491.58771030725;16504.092274924875;14688.853111468881;14996;15150.848491515082;15232.314545845436;15363.3086511107;898.1781669772167;918.4030380252856;918.4810582021137;919.2737737187647;920.2178148573726;892.8303622311772;926.272875792503;928.7353104365258;928.9860652090224;940.191435365585;1000.6947880896324;1001.5743309093642;1002.1581871228167;1004.6985904228738;1006.4665727164614;853.257665830725;996.9900300996969;997.1424574865646;999.7000899730052;1000.1598657127975;742.7289039500597;743.0897150990048;743.3050098158185;743.687651186503;745.7091734223657;16008.919955400264;16115;16472.67054658912;17039.57401064971;17337.04646244456;14036;14037.22795246261;14244.928775356057;14389.640258993504;14461.927690361586;10586.20603454744;11339.80932002136;11829.467673954692;11898.286102833858;11967.40162991847;3353.312570922955;3412.181076541637;3614.8192590370377;3641.217138315275;3773.64150405711;108376;109905.99494510522;110656;112395.43802281018;114500.28249576263;9205.171534561921;9789.265805064648;10702.25084244111;10788;11215.04672102864;22621.547569048857;22848.885755571115;22892.313230603108;22938.885305573367;23098.53802923932;6958;7296.197418283985;7459.702549222002;7484.738034168778;7702.037245344332;3949.565547789741;4087.6729861611;4131.111810960658;4165.97917010413;4169.624733773975;3411.7441191910707;3465.6361082086523;3489.616142224353;3499.1427100360065;3508.964910350915;612.5558969746897;615.2739767074805;633.6799916042369;634.7080343042252;637.6227348926296;654.5712558274338;680.323078536859;694.5554844899167;711.8006958051753;713.6859781696037;9404.24766018717;9986;10053.899461005443;10078.294519383719;10152.695419137437;635.6027482823235;680.534536138569;686.0977814174345;687.5324779150226;714.921358650548;4655.976720116378;4889.731064791399;4910.90178196434;4914.287428322873;4916.655834091651;97066;99374;100448;101593;101728.98271017196;86525.56737216399;88167;88471.23057538814;89260.5536972311;89317;69625.60748784996;70794.9380759289;70821.64589177021;78869.60565197137;81574.18425815666;8767.605457754544;10405.32365396277;10842.837357439803;10970.177236707119;11133.443327833738;48568;51854.22218666648;52193.7390313046;53304.46695532995;54699;53461.73269133629;63629;65795.67102164458;65878;67396.6630166846;13672.179669219879;13685.657858553615;13877.514286999907;14486.710265794836;14653.12081275127;14712;14745.92627036858;14772;14819.777703334243;14962.952593318798;13696.794548081587;13708.3831227593;13862.792058118934;14059.297035148405;14125.223112728898;13832.239226842621;13944.302784860514;13984.860151398356;14380.065295755743;14495.710085798435;13470.663233419244;13539.11995720275;13776.793348099585;13955.372008259856;14302;10854.240203185702;10948.288361256493;11051;11104.389258590854;11377.15327705537;6960.860782784214;7343.8898416522725;9223.815523689624;10140.54367553447;10569.365838049742 23:31:17 INFO - PROCESS | 2225 | __end_tp_report 23:31:17 INFO - PROCESS | 2225 | __start_cc_report 23:31:17 INFO - PROCESS | 2225 | _x_x_mozilla_cycle_collect,426 23:31:17 INFO - PROCESS | 2225 | __end_cc_report 23:31:17 INFO - PROCESS | 2225 | __startTimestamp1488871877040__endTimestamp 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | ------- Summary: start ------- 23:31:17 INFO - PROCESS | 2225 | Number of tests: 6 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#0] dojo.html Cycles:165 Average:5936.77 Median:6205.26 stddev:681.02 (11.0%) stddev-sans-first:653.55 23:31:17 INFO - PROCESS | 2225 | Values: 3407.3 3432.8 3451.1 3451.3 3469.9 5808.9 5920.0 5958.5 5961.6 6026.2 5915.9 5964.6 6046.6 6081.6 6107.8 5352.3 5362.1 5397.9 5434.1 5476.3 5376.5 5441.7 5494.2 5521.1 5526.7 5231.0 5481.4 5513.1 5538.3 5545.6 5310.0 5324.1 5366.4 5398.8 5400.3 4496.9 5342.7 5349.4 5388.5 5420.2 5309.5 5519.1 6134.1 6146.1 6147.7 6181.1 6214.5 6277.8 6342.5 6368.9 6451.8 6461.2 6463.4 6474.4 6481.5 5151.1 6189.4 6200.8 6217.1 6221.8 6282.1 6324.5 6482.4 6567.0 6629.9 6218.4 6250.5 6271.6 6283.6 6284.8 6428.7 6435.5 6435.8 6455.8 6457.7 6182.5 6198.1 6215.6 6283.7 6352.3 5509.5 6313.5 6338.9 6362.9 6367.9 5150.0 5153.6 5173.2 5176.2 5234.9 4520.7 4547.8 4573.0 4604.9 4617.4 5160.7 6184.6 6197.4 6205.9 6235.2 6459.8 6466.5 6475.6 6484.7 6493.5 5962.3 6153.4 6205.3 6221.4 6235.0 5582.2 5603.3 5616.4 5617.4 5625.1 6450.6 6593.3 6638.6 6651.4 6697.2 6487.7 6491.4 6497.2 6501.2 6529.7 6697.3 6747.1 6757.6 6758.2 6763.0 6150.3 6182.8 6288.4 6318.1 6332.5 6443.5 6480.3 6487.7 6509.8 6550.4 6305.8 6317.7 6319.6 6328.5 6337.3 6137.7 6180.2 6203.8 6234.3 6235.8 4980.4 6160.2 6181.2 6242.8 6243.4 6201.2 6215.8 6265.3 6288.7 6298.7 5595.7 6215.9 6221.6 6242.3 6290.5 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#1] ext.html Cycles:180 Average:53352.33 Median:17187.59 stddev:107610.76 (626.1%) stddev-sans-first:107840.03 23:31:17 INFO - PROCESS | 2225 | Values: 702.2 731.6 750.7 928.1 929.8 37420.1 38150.0 39617.4 39690.8 39778.6 38703.8 38925.0 38975.4 39032.0 39048.0 7711.3 7853.1 10462.3 10712.4 10903.0 10631.3 10685.7 10690.0 10698.4 10729.5 10701.0 10711.0 10752.9 10791.2 10793.0 10684.1 10685.0 10713.8 10715.8 10732.6 10281.2 10323.9 10343.4 10351.2 10355.5 32912.8 33011.5 33086.2 33103.2 33374.5 3264.3 3565.2 3574.4 3626.6 3725.8 22824.4 24257.3 24817.0 24830.1 25551.4 2460.4 3054.4 3055.5 3123.5 3144.3 289516.0 337484.0 340791.0 340946.6 343000.0 43048.6 43230.4 43422.6 43432.8 43494.6 45422.8 45705.1 45715.3 45734.0 45912.8 13057.4 13074.4 13173.2 13177.3 13180.1 11115.1 11143.9 11170.8 11188.7 11195.7 9078.3 10250.8 11811.5 11943.0 11949.5 5477.6 5549.9 5569.6 5582.7 5594.5 930.3 1109.4 1111.9 1117.7 1176.4 20761.3 25378.0 25758.8 25794.9 25965.0 1223.0 1245.0 1257.8 1267.2 1270.4 10101.5 11682.9 13159.6 13367.0 13372.1 418691.0 426412.0 450863.7 451538.0 454630.0 45800.1 46801.8 48203.0 49966.0 53057.0 1286.9 1408.5 1423.8 1432.7 1444.5 28098.7 28130.9 28554.1 28695.0 28784.1 44101.8 44202.6 44396.8 44615.1 44813.1 433504.0 434386.0 436856.0 437305.0 437866.0 30305.7 30866.1 30890.1 30909.2 30911.4 40443.4 40676.4 41086.6 41681.2 42086.8 33599.0 33972.5 34019.1 34794.0 34870.3 33994.7 34929.5 34963.3 35032.5 35041.3 31133.5 34310.4 35354.3 35394.3 35410.5 25339.0 26171.0 28872.7 30851.4 31251.7 20572.5 23412.4 23594.6 23647.0 23812.9 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#2] jquery.html Cycles:180 Average:41839.51 Median:26216.35 stddev:118453.88 (451.8%) stddev-sans-first:118745.25 23:31:17 INFO - PROCESS | 2225 | Values: 374.0 374.8 405.1 421.3 423.6 19629.3 19788.2 20546.6 20886.1 20955.0 21299.3 21705.6 21867.2 22232.0 22425.8 6407.6 6603.5 6704.7 6922.4 6965.5 6577.2 6731.6 6732.3 6821.7 6836.0 6027.8 6772.6 6817.3 6863.5 6868.7 6656.2 6782.1 6803.3 6842.2 6856.0 6646.4 6648.2 6678.7 7123.1 7132.5 25906.4 26068.0 26364.7 26382.7 26672.3 2895.3 2924.7 2964.3 3000.7 3002.8 15846.7 15951.2 16204.0 16363.3 16772.7 1788.9 2008.4 2032.5 2055.2 2059.4 129888.4 138224.3 152465.2 167640.0 169793.2 19290.6 20524.5 20740.9 20741.6 20780.7 30341.8 30481.8 30512.5 30639.7 30666.5 9742.9 9940.1 10043.8 10213.8 10331.6 8482.7 8501.7 8521.0 8658.1 8735.4 9545.9 9579.8 9647.6 9659.1 9703.2 3954.3 4013.2 4023.7 4040.9 4056.2 863.3 873.6 879.8 903.7 920.9 17065.0 20856.4 21619.2 21725.1 21798.0 884.8 910.2 918.5 953.9 972.0 8230.9 10024.6 10747.1 10802.9 10931.9 698536.0 699050.0 705019.0 710977.0 723626.0 131465.0 155898.2 160347.0 161889.0 163383.0 1144.5 1148.8 1211.8 1248.7 1259.2 20035.7 20062.4 20446.2 20472.0 20472.9 52806.0 64256.4 64634.7 65268.7 65976.3 78635.0 88500.1 91770.0 93223.0 93634.0 11435.6 11543.2 11653.4 11656.5 11718.5 12840.8 12870.4 13197.5 13203.7 13237.4 5565.0 8325.5 10648.6 10996.2 11004.1 9474.0 10958.8 11093.7 11132.7 11283.6 11131.7 11517.8 11528.6 11846.4 11896.8 11454.9 11774.0 11791.5 11856.7 11889.7 11347.5 11378.9 11586.0 11670.6 11777.2 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#3] mootools.html Cycles:180 Average:6523.42 Median:2351.73 stddev:4023.73 (171.1%) stddev-sans-first:4016.87 23:31:17 INFO - PROCESS | 2225 | Values: 1439.1 1494.8 1506.1 1575.3 1581.8 10327.5 10569.7 10816.5 10991.1 10996.6 9839.9 10750.2 10951.6 11004.9 11019.8 2317.9 2341.1 2351.1 2352.4 2360.0 2102.9 2208.8 2275.6 2315.9 2316.6 2294.8 2308.7 2308.9 2310.1 2319.3 2268.7 2269.0 2274.8 2280.8 2285.0 2254.4 2310.5 2311.5 2318.8 2324.7 10691.0 10747.8 10775.0 10778.6 10800.0 5543.1 5649.4 5683.6 5694.0 5713.5 3293.6 3384.6 3592.8 3598.5 3605.9 1123.6 1149.9 1198.8 1211.6 1226.4 13279.7 13466.3 14200.9 14666.4 14728.1 4481.8 4498.2 4640.6 4665.3 4675.4 9000.8 11071.6 11349.4 11354.3 11358.7 4780.2 5658.9 5692.9 5704.4 5716.4 5124.5 5143.3 5175.2 5222.3 5229.0 3783.5 3808.5 3829.1 3897.5 3897.7 1036.9 1038.7 1038.8 1051.9 1051.9 774.9 856.5 908.6 920.5 952.7 5770.8 6550.6 6600.4 6726.5 7035.3 1015.5 1028.3 1033.7 1036.1 1077.0 4132.8 4173.0 4193.6 4287.8 4324.1 12812.9 13145.2 13151.5 13151.5 13193.2 13046.2 13046.9 13058.5 13145.7 13188.0 8077.8 8104.3 9676.6 9832.0 9856.2 5207.0 6177.9 6479.5 6561.2 6573.2 11714.1 11811.4 12052.5 12061.3 12061.5 11681.4 11930.5 12055.4 12200.3 12409.5 7775.6 8153.6 8961.6 9147.5 9197.7 10048.1 10269.3 10282.6 10315.2 10315.2 8715.5 8808.4 8816.7 8847.4 8887.8 8669.8 8679.2 8730.3 8734.7 8766.3 7641.7 7754.1 7767.6 7826.5 7835.2 5830.2 5879.5 5885.1 5930.8 6253.8 5638.9 5934.8 5946.9 5959.9 6117.4 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#4] prototype.html Cycles:180 Average:4568.13 Median:5007.10 stddev:1310.74 (26.2%) stddev-sans-first:1274.87 23:31:17 INFO - PROCESS | 2225 | Values: 310.7 339.4 374.8 378.9 385.7 4438.9 4561.4 5085.8 5158.5 5222.5 4475.5 4544.9 4791.2 5116.9 5222.0 4165.7 4328.2 4347.0 4379.6 4391.3 3518.7 3549.9 3814.4 3911.7 4405.2 3632.6 3844.3 4218.2 4350.7 4350.8 3893.7 3973.5 4216.2 4350.9 4389.5 3534.9 3560.7 3680.0 3834.8 3886.5 4344.4 4474.8 4661.8 5059.5 5109.0 4204.0 4796.5 4892.8 5026.8 5050.8 4876.3 5342.9 5402.6 5481.4 5621.9 3854.6 4354.3 4409.2 4420.6 4466.3 4964.2 5376.7 5574.7 5865.6 5876.7 4072.0 4340.7 4477.3 4518.0 4523.5 4020.9 4496.8 4600.4 4609.5 4654.4 4387.9 4489.0 4857.6 4954.6 4968.3 4964.1 5533.3 5569.7 5575.4 5605.2 1363.8 1404.5 1475.1 1546.7 1547.9 992.6 994.8 1029.8 1085.9 1163.6 4677.3 4961.3 5227.6 5339.4 5351.2 4987.4 5034.7 5242.5 5756.8 5964.5 4463.0 5052.0 5229.0 5236.4 5305.7 2603.6 2678.5 2740.6 2833.0 2844.6 5246.2 5353.3 5484.0 5790.0 5979.4 5066.2 5110.7 5383.6 5554.1 5812.4 4912.8 5790.4 6033.4 6068.4 6113.7 4968.8 5300.5 5778.6 5833.0 5833.4 5071.4 5445.6 5796.0 5831.7 5863.9 5523.6 5988.9 6003.8 6016.6 6017.6 3861.9 3990.9 4075.0 4137.2 4485.3 5068.5 5070.4 5268.4 5465.0 5687.8 5123.9 5179.5 5195.8 5543.3 5582.9 5079.4 5126.7 5217.3 5255.4 5530.0 4650.0 5404.8 5421.6 5469.7 5478.6 4778.7 4986.5 5070.4 5561.7 5574.5 5255.2 5426.9 5432.0 5463.3 5575.8 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - PROCESS | 2225 | [#5] yui.html Cycles:180 Average:20479.82 Median:3494.38 stddev:29251.00 (837.1%) stddev-sans-first:29295.36 23:31:17 INFO - PROCESS | 2225 | Values: 704.5 713.9 733.8 764.6 857.1 13867.2 15180.2 16485.7 16491.6 16504.1 14688.9 14996.0 15150.8 15232.3 15363.3 898.2 918.4 918.5 919.3 920.2 892.8 926.3 928.7 929.0 940.2 1000.7 1001.6 1002.2 1004.7 1006.5 853.3 997.0 997.1 999.7 1000.2 742.7 743.1 743.3 743.7 745.7 16008.9 16115.0 16472.7 17039.6 17337.0 14036.0 14037.2 14244.9 14389.6 14461.9 10586.2 11339.8 11829.5 11898.3 11967.4 3353.3 3412.2 3614.8 3641.2 3773.6 108376.0 109906.0 110656.0 112395.4 114500.3 9205.2 9789.3 10702.3 10788.0 11215.0 22621.5 22848.9 22892.3 22938.9 23098.5 6958.0 7296.2 7459.7 7484.7 7702.0 3949.6 4087.7 4131.1 4166.0 4169.6 3411.7 3465.6 3489.6 3499.1 3509.0 612.6 615.3 633.7 634.7 637.6 654.6 680.3 694.6 711.8 713.7 9404.2 9986.0 10053.9 10078.3 10152.7 635.6 680.5 686.1 687.5 714.9 4656.0 4889.7 4910.9 4914.3 4916.7 97066.0 99374.0 100448.0 101593.0 101729.0 86525.6 88167.0 88471.2 89260.6 89317.0 69625.6 70794.9 70821.6 78869.6 81574.2 8767.6 10405.3 10842.8 10970.2 11133.4 48568.0 51854.2 52193.7 53304.5 54699.0 53461.7 63629.0 65795.7 65878.0 67396.7 13672.2 13685.7 13877.5 14486.7 14653.1 14712.0 14745.9 14772.0 14819.8 14963.0 13696.8 13708.4 13862.8 14059.3 14125.2 13832.2 13944.3 13984.9 14380.1 14495.7 13470.7 13539.1 13776.8 13955.4 14302.0 10854.2 10948.3 11051.0 11104.4 11377.2 6960.9 7343.9 9223.8 10140.5 10569.4 23:31:17 INFO - PROCESS | 2225 | -------- Summary: end -------- 23:31:17 INFO - PROCESS | 2225 | 23:31:17 INFO - TEST-INFO | 2225: exit 0 23:31:17 INFO - TEST-OK | dromaeo_css | took 1116698ms 23:31:17 INFO - TEST-START | kraken 23:31:17 INFO - operating with platform_type : mac_ 23:31:17 INFO - Initialising browser for kraken test... 23:31:17 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp4M2PRp/profile http://localhost:58608/getInfo.html) 23:31:20 INFO - PROCESS | 2230 | 2017-03-06 23:31:20.322 plugin-container[2231:27382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 23:31:20 INFO - PROCESS | 2230 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:20 INFO - PROCESS | 2230 | 2017-03-06 23:31:20.325 plugin-container[2231:27382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 23:31:20 INFO - PROCESS | 2230 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:20 INFO - PROCESS | 2230 | 2017-03-06 23:31:20.325 plugin-container[2231:27382] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:31:20 INFO - PROCESS | 2230 | __metrics Screen width/height:1600/1200 23:31:20 INFO - PROCESS | 2230 | colorDepth:24 23:31:20 INFO - PROCESS | 2230 | Browser inner width/height: 1024/689 23:31:20 INFO - PROCESS | 2230 | __metrics 23:31:20 ERROR - PROCESS | 2230 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 23:31:21 INFO - TEST-INFO | 2230: exit 0 23:31:21 INFO - Browser initialized. 23:31:21 INFO - Running cycle 1/1 for kraken test... 23:31:21 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmp4M2PRp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:31:23 INFO - PROCESS | 2232 | 2017-03-06 23:31:23.528 plugin-container[2233:27509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 23:31:23 INFO - PROCESS | 2232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:23 INFO - PROCESS | 2232 | 2017-03-06 23:31:23.529 plugin-container[2233:27509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 23:31:23 INFO - PROCESS | 2232 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:31:23 INFO - PROCESS | 2232 | 2017-03-06 23:31:23.529 plugin-container[2233:27509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:31:33 INFO - PROCESS | 2232 | RSS: Main: 226766848 23:31:33 INFO - PROCESS | 2232 | 23:31:36 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58608/tests/kraken/driver.html?testName=audio-beat-detection) 23:31:37 INFO - PROCESS | 2232 | RSS: Main: 241311744 23:31:37 INFO - PROCESS | 2232 | 23:31:39 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58608/tests/kraken/driver.html?testName=audio-dft) 23:31:40 INFO - PROCESS | 2232 | RSS: Main: 249491456 23:31:40 INFO - PROCESS | 2232 | 23:31:44 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58608/tests/kraken/driver.html?testName=audio-fft) 23:31:44 INFO - PROCESS | 2232 | RSS: Main: 252133376 23:31:44 INFO - PROCESS | 2232 | 23:31:46 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58608/tests/kraken/driver.html?testName=audio-oscillator) 23:31:47 INFO - PROCESS | 2232 | RSS: Main: 253325312 23:31:47 INFO - PROCESS | 2232 | 23:31:49 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58608/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:31:50 INFO - PROCESS | 2232 | RSS: Main: 253812736 23:31:50 INFO - PROCESS | 2232 | 23:31:53 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58608/tests/kraken/driver.html?testName=imaging-darkroom) 23:31:53 INFO - PROCESS | 2232 | RSS: Main: 259141632 23:31:53 INFO - PROCESS | 2232 | 23:31:57 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58608/tests/kraken/driver.html?testName=imaging-desaturate) 23:31:57 INFO - PROCESS | 2232 | RSS: Main: 259993600 23:31:57 INFO - PROCESS | 2232 | 23:32:01 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58608/tests/kraken/driver.html?testName=json-parse-financial) 23:32:01 INFO - PROCESS | 2232 | RSS: Main: 257007616 23:32:01 INFO - PROCESS | 2232 | 23:32:03 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58608/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:32:04 INFO - PROCESS | 2232 | RSS: Main: 258035712 23:32:04 INFO - PROCESS | 2232 | 23:32:06 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:32:06 INFO - PROCESS | 2232 | RSS: Main: 258420736 23:32:06 INFO - PROCESS | 2232 | 23:32:09 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:32:09 INFO - PROCESS | 2232 | RSS: Main: 258527232 23:32:09 INFO - PROCESS | 2232 | 23:32:12 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:32:13 INFO - PROCESS | 2232 | RSS: Main: 259284992 23:32:13 INFO - PROCESS | 2232 | 23:32:16 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:32:17 INFO - PROCESS | 2232 | RSS: Main: 258629632 23:32:17 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | Cycle 1(1): loaded http://localhost:58608/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58608/tests/kraken/driver.html?testName=ai-astar) 23:32:19 INFO - PROCESS | 2232 | RSS: Main: 262078464 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | __start_tp_report 23:32:19 INFO - PROCESS | 2232 | _x_x_mozilla_page_load 23:32:19 INFO - PROCESS | 2232 | _x_x_mozilla_page_load_details 23:32:19 INFO - PROCESS | 2232 | |i|pagename|runs| 23:32:19 INFO - PROCESS | 2232 | |0;ai-astar;105;118;121;119;113;118;121;120;117;120 23:32:19 INFO - PROCESS | 2232 | |1;audio-beat-detection;174;136;113;151;112;115;110;107;132;116 23:32:19 INFO - PROCESS | 2232 | |2;audio-dft;194;220;252;248;271;271;196;194;191;278 23:32:19 INFO - PROCESS | 2232 | |3;audio-fft;87;61;63;88;63;62;60;64;78;74 23:32:19 INFO - PROCESS | 2232 | |4;audio-oscillator;81;73;87;73;73;71;82;73;71;87 23:32:19 INFO - PROCESS | 2232 | |5;imaging-gaussian-blur;90;89;93;94;89;132;89;83;132;98 23:32:19 INFO - PROCESS | 2232 | |6;imaging-darkroom;99;146;98;98;130;98;97;98;97;98 23:32:19 INFO - PROCESS | 2232 | |7;imaging-desaturate;144;136;90;135;89;150;141;93;92;93 23:32:19 INFO - PROCESS | 2232 | |8;json-parse-financial;66;66;68;66;61;60;67;87;97;98 23:32:19 INFO - PROCESS | 2232 | |9;json-stringify-tinderbox;56;64;59;69;66;61;58;54;75;79 23:32:19 INFO - PROCESS | 2232 | |10;stanford-crypto-aes;92;97;91;105;105;108;90;87;94;91 23:32:19 INFO - PROCESS | 2232 | |11;stanford-crypto-ccm;167;154;152;163;157;156;145;152;212;158 23:32:19 INFO - PROCESS | 2232 | |12;stanford-crypto-pbkdf2;177;187;173;176;173;172;172;176;196;174 23:32:19 INFO - PROCESS | 2232 | |13;stanford-crypto-sha256-iterative;69;69;71;87;88;89;91;73;68;68 23:32:19 INFO - PROCESS | 2232 | __end_tp_report 23:32:19 INFO - PROCESS | 2232 | __start_cc_report 23:32:19 INFO - PROCESS | 2232 | _x_x_mozilla_cycle_collect,1010 23:32:19 INFO - PROCESS | 2232 | __end_cc_report 23:32:19 INFO - PROCESS | 2232 | __startTimestamp1488871939256__endTimestamp 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | ------- Summary: start ------- 23:32:19 INFO - PROCESS | 2232 | Number of tests: 14 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#0] ai-astar Cycles:10 Average:117.20 Median:119.50 stddev:4.89 (4.1%) stddev-sans-first:2.51 23:32:19 INFO - PROCESS | 2232 | Values: 105.0 118.0 121.0 119.0 113.0 118.0 121.0 120.0 117.0 120.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:124.00 stddev:21.77 (17.6%) stddev-sans-first:14.87 23:32:19 INFO - PROCESS | 2232 | Values: 174.0 136.0 113.0 151.0 112.0 115.0 110.0 107.0 132.0 116.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#2] audio-dft Cycles:10 Average:231.50 Median:250.00 stddev:36.24 (14.5%) stddev-sans-first:35.81 23:32:19 INFO - PROCESS | 2232 | Values: 194.0 220.0 252.0 248.0 271.0 271.0 196.0 194.0 191.0 278.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#3] audio-fft Cycles:10 Average:70.00 Median:69.00 stddev:10.91 (15.8%) stddev-sans-first:9.69 23:32:19 INFO - PROCESS | 2232 | Values: 87.0 61.0 63.0 88.0 63.0 62.0 60.0 64.0 78.0 74.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.00 stddev:6.47 (8.4%) stddev-sans-first:6.71 23:32:19 INFO - PROCESS | 2232 | Values: 81.0 73.0 87.0 73.0 73.0 71.0 82.0 73.0 71.0 87.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#5] imaging-gaussian-blur Cycles:10 Average:98.90 Median:89.50 stddev:17.88 (20.0%) stddev-sans-first:18.67 23:32:19 INFO - PROCESS | 2232 | Values: 90.0 89.0 93.0 94.0 89.0 132.0 89.0 83.0 132.0 98.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:98.00 stddev:17.34 (17.7%) stddev-sans-first:18.21 23:32:19 INFO - PROCESS | 2232 | Values: 99.0 146.0 98.0 98.0 130.0 98.0 97.0 98.0 97.0 98.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#7] imaging-desaturate Cycles:10 Average:116.30 Median:89.50 stddev:26.59 (29.7%) stddev-sans-first:26.25 23:32:19 INFO - PROCESS | 2232 | Values: 144.0 136.0 90.0 135.0 89.0 150.0 141.0 93.0 92.0 93.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#8] json-parse-financial Cycles:10 Average:73.60 Median:67.50 stddev:14.58 (21.6%) stddev-sans-first:15.21 23:32:19 INFO - PROCESS | 2232 | Values: 66.0 66.0 68.0 66.0 61.0 60.0 67.0 87.0 97.0 98.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:65.00 stddev:8.23 (12.7%) stddev-sans-first:8.19 23:32:19 INFO - PROCESS | 2232 | Values: 56.0 64.0 59.0 69.0 66.0 61.0 58.0 54.0 75.0 79.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#10] stanford-crypto-aes Cycles:10 Average:96.00 Median:91.00 stddev:7.41 (8.1%) stddev-sans-first:7.72 23:32:19 INFO - PROCESS | 2232 | Values: 92.0 97.0 91.0 105.0 105.0 108.0 90.0 87.0 94.0 91.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:157.50 stddev:18.72 (11.9%) stddev-sans-first:19.75 23:32:19 INFO - PROCESS | 2232 | Values: 167.0 154.0 152.0 163.0 157.0 156.0 145.0 152.0 212.0 158.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:176.00 stddev:7.82 (4.4%) stddev-sans-first:8.29 23:32:19 INFO - PROCESS | 2232 | Values: 177.0 187.0 173.0 176.0 173.0 172.0 172.0 176.0 196.0 174.0 23:32:19 INFO - PROCESS | 2232 | 23:32:19 INFO - PROCESS | 2232 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.30 Median:80.00 stddev:10.01 (12.5%) stddev-sans-first:10.16 23:32:19 INFO - PROCESS | 2232 | Values: 69.0 69.0 71.0 87.0 88.0 89.0 91.0 73.0 68.0 68.0 23:32:19 INFO - PROCESS | 2232 | -------- Summary: end -------- 23:32:19 INFO - PROCESS | 2232 | 23:32:20 INFO - TEST-INFO | 2232: exit 0 23:32:20 INFO - TEST-OK | kraken | took 62450ms 23:32:20 INFO - SUITE-END | took 1179s 23:32:20 INFO - Completed test suite (00:19:39) 23:32:20 INFO - javascript benchmark 23:32:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7603.4756044146925, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.250404910919, 3432.8283585820705, 3451.085462352477, 3451.3097380523886, 3469.8785542506016, 5808.854778630536, 5920.0, 5958.463738263556, 5961.612495187821, 6026.186464827247, 5915.911261331084, 5964.552658550604, 6046.576739628217, 6081.604695694777, 6107.847303817407, 5352.277442545265, 5362.088444964346, 5397.919031214535, 5434.103372943459, 5476.342838859323, 5376.462353764612, 5441.7279136043235, 5494.230807686907, 5521.061419558685, 5526.723663816813, 5230.979958908015, 5481.424450432727, 5513.117901135839, 5538.280023596927, 5545.556355491572, 5310.017646735328, 5324.148136298174, 5366.382865970424, 5398.838034858961, 5400.297961265011, 4496.887577810581, 5342.679439233659, 5349.411564727877, 5388.515033646951, 5420.186971954199, 5309.522143007148, 5519.116941289374, 6134.141220229171, 6146.139540464339, 6147.661878596676, 6181.07283907413, 6214.533909956771, 6277.843053923689, 6342.460890824285, 6368.904466433008, 6451.7741879034475, 6461.224653041665, 6463.385978332051, 6474.449671777905, 6481.48148148147, 5151.068349368987, 6189.35011823761, 6200.844978875519, 6217.129601855744, 6221.751129954751, 6282.089097080989, 6324.462420694293, 6482.384173503509, 6567.047778072249, 6629.900551491732, 6218.378162183745, 6250.49996000328, 6271.62370257786, 6283.560150789493, 6284.811455656246, 6428.678566071678, 6435.51733619971, 6435.839104022437, 6455.7740479083, 6457.709403076873, 6182.536309776785, 6198.132261483396, 6215.564910456224, 6283.654399008007, 6352.2694890087305, 5509.47659972302, 6313.515880836505, 6338.904916426257, 6362.872742545123, 6367.872642547123, 5150.0, 5153.613478989091, 5173.198154286091, 5176.197689358156, 5234.947650523446, 4520.728756274634, 4547.795349209294, 4573.0, 4604.930926036112, 4617.376654151646, 5160.716160611128, 6184.5980011299125, 6197.442230199214, 6205.937940620536, 6235.189425374672, 6459.806205813833, 6466.515011374166, 6475.579087359307, 6484.708188131545, 6493.480521558264, 5962.314333851576, 6153.353897840751, 6205.2553693557065, 6221.4089661482085, 6235.0, 5582.162675598693, 5603.299587551556, 5616.438356164433, 5617.41017193197, 5625.09998400254, 6450.645214513247, 6593.307702691244, 6638.634875081917, 6651.43462805667, 6697.196336439659, 6487.675616219264, 6491.383318584727, 6497.18785151856, 6501.187351581052, 6529.7388104475285, 6697.2633010367845, 6747.055412242192, 6757.628330441873, 6758.155230596176, 6762.966185169043, 6150.261968563801, 6182.783602573888, 6288.434040936338, 6318.147050148171, 6332.52506062038, 6443.484521238196, 6480.287168411377, 6487.708053137714, 6509.804705858832, 6550.410463058348, 6305.842353941188, 6317.7157027932935, 6319.58922670025, 6328.46208072319, 6337.271213810471, 6137.714085983886, 6180.165677633462, 6203.751849926133, 6234.345393733593, 6235.812925612239, 4980.427250866197, 6160.16837726919, 6181.196444462191, 6242.843928901813, 6243.37566243372, 6201.224846894138, 6215.818994391051, 6265.279492858197, 6288.6855657217875, 6298.71655775482, 5595.68501402162, 6215.906761398493, 6221.626702397871, 6242.344553821783, 6290.528210384331], "value": 5893.6152269086315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.2450865319779, 731.593965349231, 750.7409943569469, 928.1414691410444, 929.8047410043895, 37420.06449838752, 38150.04624884379, 39617.40573891394, 39690.80154599227, 39778.60221397785, 38703.806480967556, 38925.0, 38975.41536876949, 39032.024199395026, 39048.0, 7711.3059824615775, 7853.136155022943, 10462.267641265114, 10712.35725856451, 10903.01872831445, 10631.308964917256, 10685.679429617085, 10690.0, 10698.411587362692, 10729.517171727291, 10701.0, 10711.03600675939, 10752.892471075307, 10791.244612877103, 10793.0, 10684.145268378588, 10685.038346548808, 10713.785724285548, 10715.83926241107, 10732.570697172181, 10281.177505799518, 10323.896761032443, 10343.431111288875, 10351.223658225663, 10355.48222588875, 32912.835435822904, 33011.504827427605, 33086.17284567905, 33103.172420689676, 33374.49938250928, 3264.3308121834966, 3565.180008598037, 3574.3566158091535, 3626.5829429615405, 3725.795081270529, 22824.429389265235, 24257.272281831396, 24817.0, 24830.130945416822, 25551.361215969562, 2460.3972026853357, 3054.3585846972164, 3055.5416687497054, 3123.485109721783, 3144.33968866536, 289516.0, 337484.0, 340791.0, 340946.59053409396, 343000.0, 43048.56951430446, 43230.35154472685, 43422.56577434185, 43432.78283608625, 43494.56505434905, 45422.77288613536, 45705.085898281846, 45715.31427028597, 45734.0, 45912.770436147606, 13057.412416441282, 13074.411651475706, 13173.209607423585, 13177.275249861157, 13180.143290686075, 11115.055220306203, 11143.888561114447, 11170.832437513282, 11188.66434006981, 11195.720106997227, 9078.319126065571, 10250.794984100276, 11811.519580887938, 11943.0, 11949.462274197682, 5477.589180811376, 5549.861253468614, 5569.610127291132, 5582.748776305076, 5594.496495315441, 930.3255140023564, 1109.378747901178, 1111.8888111188817, 1117.6870476266495, 1176.4235524592889, 20761.273355432488, 25378.0, 25758.8408521617, 25794.871025645127, 25964.961401544104, 1222.9694257643453, 1245.0102168775845, 1257.792464243408, 1267.2269915351628, 1270.4029106320015, 10101.494925253708, 11682.883171168349, 13159.60521184366, 13367.0, 13372.130811497416, 418691.0, 426412.0, 450863.7456812761, 451538.0, 454630.0, 45800.083998319846, 46801.765991170505, 48203.0, 49966.0, 53057.0, 1286.8648791876906, 1408.4718230663502, 1423.7864320351823, 1432.7349440353569, 1444.5088669852091, 28098.71901280961, 28130.859345703142, 28554.14337569876, 28695.0, 28784.136475905758, 44101.77949110234, 44202.55797442113, 44396.77801610971, 44615.100018499994, 44813.10373792571, 433504.0, 434386.0, 436856.0, 437305.0, 437866.0, 30305.69694303117, 30866.0740177795, 30890.0732978011, 30909.227269318446, 30911.381772364875, 40443.3933490992, 40676.38985415162, 41086.589134108275, 41681.16637667169, 42086.78956605197, 33599.0, 33972.49041264334, 34019.14952126217, 34794.0, 34870.30259394849, 33994.66005339915, 34929.47605785864, 34963.300733984666, 35032.474512882836, 35041.29917401689, 31133.53299700461, 34310.42671799675, 35354.29291414209, 35394.29211415809, 35410.46884296686, 25339.0, 26171.0, 28872.711272887, 30851.382972340878, 31251.68748312488, 20572.485687857323, 23412.414689632213, 23594.646080308466, 23647.0, 23812.88093559521], "value": 18060.225162651386, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.9813009349532, 374.8388193076976, 405.0966345050537, 421.3195688962327, 423.63144064663743, 19629.31297404591, 19788.208471661117, 20546.589068218665, 20886.06012729427, 20954.95225238738, 21299.25452609159, 21705.565888682257, 21867.234646787365, 22232.0, 22425.775742242615, 6407.615543067431, 6603.537752357337, 6704.731810727565, 6922.411595014405, 6965.477589180807, 6577.177852768404, 6731.629760363178, 6732.259451460335, 6821.727130914757, 6835.965820170917, 6027.819165425044, 6772.593644381328, 6817.284185160561, 6863.450923926099, 6868.690908909086, 6656.167979002625, 6782.1183246177925, 6803.319668033182, 6842.213145488283, 6855.965720171417, 6646.368594983469, 6648.202215734094, 6678.666066696645, 7123.074000379917, 7132.5007249492555, 25906.35234119162, 26067.95728170871, 26364.73635263642, 26382.680865956816, 26672.265882776428, 2895.290653789835, 2924.69290724473, 2964.2537139801984, 3000.6699263081046, 3002.774791890617, 15846.683066338725, 15951.245363010059, 16204.0, 16363.34546618134, 16772.664546709, 1788.9284428622839, 2008.3573256557906, 2032.5121970727066, 2055.2395613622907, 2059.3718915730697, 129888.35055824755, 138224.30887845598, 152465.23767381202, 167640.0, 169793.15103424527, 19290.58867641545, 20524.486887827923, 20740.859252740942, 20741.58516829685, 20780.688289675665, 30341.848290758404, 30481.847590761903, 30512.542311865338, 30639.69360306413, 30666.54000189999, 9742.853857192147, 9940.111378838143, 10043.817643176442, 10213.795724085625, 10331.586736530455, 8482.703105391405, 8501.744947651581, 8521.0, 8658.090900455487, 8735.43219690718, 9545.949945505989, 9579.808403831885, 9647.565859536197, 9659.130678238993, 9703.223742100616, 3954.268460334847, 4013.177298653769, 4023.657989070903, 4040.919181616351, 4056.1684854605323, 863.3395452478859, 873.6287077991828, 879.7580665317089, 903.661127077346, 920.9309301802258, 17065.0, 20856.374308770763, 21619.243326483498, 21725.13099476003, 21798.0, 884.8318819424288, 910.2081189365294, 918.5453200665713, 953.9236861051099, 971.9805603887883, 8230.917690823015, 10024.649137280161, 10747.086497647631, 10802.89197108019, 10931.945340273247, 698536.0, 699050.0, 705019.0, 710977.0, 723626.0, 131465.0, 155898.22050889672, 160347.0, 161889.0, 163383.0, 1144.5250221158292, 1148.8276758486295, 1211.7879371110196, 1248.7315227226009, 1259.2066997791333, 20035.699464507754, 20062.398128056182, 20446.182152713725, 20472.0, 20472.897635511727, 52806.0, 64256.35743642504, 64634.67682661556, 65268.673656631414, 65976.34023659703, 78635.0, 88500.11499884918, 91770.0, 93223.0, 93634.0, 11435.599754008903, 11543.229624890133, 11653.359065251492, 11656.475458604553, 11718.472668730099, 12840.807387889003, 12870.356482176041, 13197.472101116233, 13203.735925281248, 13237.40431680596, 5565.0, 8325.458845175044, 10648.627298044532, 10996.175286853679, 11004.059323977886, 9473.952630236805, 10958.835617465904, 11093.667189984313, 11132.666020019413, 11283.605073822377, 11131.666050018512, 11517.827232591686, 11528.59649912249, 11846.407679616157, 11896.76206475883, 11454.94272528632, 11774.0, 11791.528338866692, 11856.70358241051, 11889.702757431134, 11347.48936297852, 11378.886211137782, 11586.015188709054, 11670.649880503599, 11777.234479758788], "value": 11428.767086734353, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.100562148657, 1494.7533656946605, 1506.0662389318625, 1575.306864979409, 1581.7706432567275, 10327.535260913259, 10569.67879015123, 10816.513256903438, 10991.06575941045, 10996.560137594506, 9839.852402213955, 10750.247482676215, 10951.616693415732, 11004.944975275112, 11019.83470247947, 2317.9453348726333, 2341.1103780563362, 2351.0830775997288, 2352.3766201956496, 2359.988200059006, 2102.8843413612244, 2208.763092668103, 2275.601769690306, 2315.90736370545, 2316.6409206573007, 2294.7819957104048, 2308.7114110736156, 2308.9422764430938, 2310.1106074161403, 2319.257837492003, 2268.6937263469467, 2268.9886550567307, 2274.80664143548, 2280.817534597228, 2285.04028308111, 2254.4251215939994, 2310.5378924215224, 2311.4799170186743, 2318.826088043387, 2324.732655744577, 10691.0, 10747.78504429907, 10775.0, 10778.622748203776, 10799.974002469753, 5543.085390910495, 5649.378568357477, 5683.630564013326, 5693.971530142323, 5713.542916566665, 3293.5883014623173, 3384.627690953993, 3592.802395868226, 3598.514200582914, 3605.94591081134, 1123.606707742621, 1149.919505634606, 1198.8261702053242, 1211.5698926881007, 1226.4480983557435, 13279.73440531184, 13466.25935573543, 14200.934929880405, 14666.413343466249, 14728.116313021254, 4481.843135490308, 4498.167838949805, 4640.582347588733, 4665.346851440801, 4675.368825208553, 9000.784894039352, 11071.612493562849, 11349.432528373549, 11354.26197297173, 11358.716032099099, 4780.235162374073, 5658.943410565924, 5692.943070569324, 5704.401037891044, 5716.42835716425, 5124.538791508708, 5143.254228136974, 5175.223716442489, 5222.294990176278, 5228.9738551307, 3783.5459744830796, 3808.4668146459517, 3829.119302560371, 3897.4543563901075, 3897.6881849451975, 1036.8807587127426, 1038.693585392311, 1038.8182068138046, 1051.894810518942, 1051.9158467322595, 774.9457537972401, 856.5160684213399, 908.5729707037682, 920.4845286639503, 952.7237101240563, 5770.798022069207, 6550.574212676161, 6600.438962688129, 6726.529142960044, 7035.33164349386, 1015.4509987593261, 1028.341861208827, 1033.6975410982066, 1036.1451802263164, 1077.0306723948509, 4132.752034877917, 4173.0, 4193.580641935782, 4287.828486860491, 4324.13517296542, 12812.935935320264, 13145.21128732279, 13151.473941042443, 13151.539696110782, 13193.20840749558, 13046.152000119962, 13046.934765326112, 13058.477660893457, 13145.73708525824, 13188.0, 8077.838443231102, 8104.270615644738, 9676.612935482643, 9832.0, 9856.21150307988, 5206.973965130302, 6177.876442471035, 6479.4816414685765, 6561.179852518435, 6573.244076931022, 11714.121440891797, 11811.409429528661, 12052.51789928393, 12061.336626485627, 12061.457234424297, 11681.415929203336, 11930.522779088738, 12055.397230138633, 12200.328981906081, 12409.503619855104, 7775.572343520935, 8153.6330865112395, 8961.641534338554, 9147.451152930846, 9197.724068277963, 10048.145907597936, 10269.332493387907, 10282.588696452358, 10315.17478601695, 10315.226358023028, 8715.520646364257, 8808.427452215585, 8816.735497935073, 8847.42491738035, 8887.777805554913, 8669.783255418664, 8679.17547832955, 8730.257928076184, 8734.65061397537, 8766.298696104424, 7641.694332226649, 7754.147043825062, 7767.572783496736, 7826.452148349561, 7835.177306382748, 5830.2420685310635, 5879.5002424794375, 5885.058390657474, 5930.7924222652, 6253.843653908507, 5638.88722225545, 5934.821955341346, 5946.940530594812, 5959.91060134107, 6117.3576774439825], "value": 5002.0533448563465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.65982748889985, 339.42298093241493, 374.79386337514376, 378.92232092421057, 385.7242071918578, 4438.911221775566, 4561.407017087774, 5085.821996230133, 5158.484151584849, 5222.529972302492, 4475.507694153641, 4544.909461566133, 4791.209450440673, 5116.948830511694, 5222.0, 4165.66674666026, 4328.199283132622, 4347.043650396908, 4379.64962802975, 4391.297392417214, 3518.665726755954, 3549.852339334704, 3814.4469051987444, 3911.706622003347, 4405.229084910144, 3632.6367363263726, 3844.2888065707934, 4218.1563687262515, 4350.695451318409, 4350.825966961318, 3893.6885049196, 3973.4834471518516, 4216.241076606209, 4350.912981740347, 4389.4513185851765, 3534.929301413983, 3560.7151427885965, 3680.0, 3834.811208525366, 3886.455896174538, 4344.370066340363, 4474.798634061475, 4661.766911654404, 5059.519345662193, 5108.981952128888, 4203.957960420387, 4796.52034796521, 4892.828750993734, 5026.798928042874, 5050.84847454577, 4876.317315575822, 5342.946570534284, 5402.594805389612, 5481.409676661793, 5621.8594535136535, 3854.5908806995626, 4354.325079612633, 4409.206342858284, 4420.58004489573, 4466.285394336891, 4964.1704228589715, 5376.731163441812, 5574.665520068768, 5865.568642344234, 5876.7355469004, 4072.0023594219015, 4340.65274778017, 4477.306017567283, 4517.977410112994, 4523.5250298718165, 4020.929221450955, 4496.785867815737, 4600.401947746772, 4609.469910960215, 4654.371659825948, 4387.890302742393, 4488.955110448854, 4857.635677324144, 4954.603631709527, 4968.27959945813, 4964.056829202512, 5533.3083364579425, 5569.693666848362, 5575.414581468968, 5605.243292155588, 1363.8158848555317, 1404.480342273345, 1475.144416238579, 1546.7239527389722, 1547.9303431345616, 992.6450395210486, 994.8109859126743, 1029.8352263637782, 1085.913126949842, 1163.6450882480924, 4677.298405239173, 4961.288355517341, 5227.607929405233, 5339.385970613352, 5351.153851921081, 4987.406548594783, 5034.672746271481, 5242.475752424727, 5756.798512052142, 5964.463198312173, 4462.955370446319, 5052.014857102829, 5229.0, 5236.397814251333, 5305.734713264322, 2603.609458581228, 2678.504476671822, 2740.615664372009, 2832.971670283312, 2844.6301980742783, 5246.242508759532, 5353.250544923636, 5484.0, 5790.0, 5979.431953964367, 5066.1761765764295, 5110.718910459886, 5383.569314454834, 5554.111602055321, 5812.418758124154, 4912.806675660778, 5790.449907258803, 6033.431307859988, 6068.423499767603, 6113.724882380215, 4968.826091086795, 5300.496452836975, 5778.595498315246, 5833.0, 5833.445822646841, 5071.366079240095, 5445.618806683494, 5796.029350021013, 5831.650100993954, 5863.853403664943, 5523.619095226193, 5988.910166347421, 6003.849903752441, 6016.639001659914, 6017.638941663514, 3861.922761544697, 3990.900227494336, 4075.0, 4137.234611596864, 4485.304777759453, 5068.467810879804, 5070.416902056163, 5268.446813084724, 5465.0, 5687.77248910039, 5123.897522049464, 5179.4779549906425, 5195.818146364859, 5543.307086614173, 5582.911332290165, 5079.358733458655, 5126.667066562646, 5217.321748172713, 5255.448177941414, 5529.972350138223, 4650.023495066074, 5404.811051524139, 5421.647592906448, 5469.699166545878, 5478.561715062706, 4778.713277203379, 4986.501349864984, 5070.391553013662, 5561.721913904369, 5574.470425309589, 5255.185446255837, 5426.860359324586, 5431.972840135774, 5463.344398672185, 5575.804846830341], "value": 4175.117611707243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.471646265301, 713.8893471511915, 733.7982054934889, 764.5565571968259, 857.1471385970959, 13867.23730194839, 15180.165090919994, 16485.670286594275, 16491.58771030725, 16504.092274924875, 14688.853111468881, 14996.0, 15150.848491515082, 15232.314545845436, 15363.3086511107, 898.1781669772167, 918.4030380252856, 918.4810582021137, 919.2737737187647, 920.2178148573726, 892.8303622311772, 926.272875792503, 928.7353104365258, 928.9860652090224, 940.191435365585, 1000.6947880896324, 1001.5743309093642, 1002.1581871228167, 1004.6985904228738, 1006.4665727164614, 853.257665830725, 996.9900300996969, 997.1424574865646, 999.7000899730052, 1000.1598657127975, 742.7289039500597, 743.0897150990048, 743.3050098158185, 743.687651186503, 745.7091734223657, 16008.919955400264, 16115.0, 16472.67054658912, 17039.57401064971, 17337.04646244456, 14036.0, 14037.22795246261, 14244.928775356057, 14389.640258993504, 14461.927690361586, 10586.20603454744, 11339.80932002136, 11829.467673954692, 11898.286102833858, 11967.40162991847, 3353.312570922955, 3412.181076541637, 3614.8192590370377, 3641.217138315275, 3773.64150405711, 108376.0, 109905.99494510522, 110656.0, 112395.43802281018, 114500.28249576263, 9205.171534561921, 9789.265805064648, 10702.25084244111, 10788.0, 11215.04672102864, 22621.547569048857, 22848.885755571115, 22892.313230603108, 22938.885305573367, 23098.53802923932, 6958.0, 7296.197418283985, 7459.702549222002, 7484.738034168778, 7702.037245344332, 3949.565547789741, 4087.6729861611, 4131.111810960658, 4165.97917010413, 4169.624733773975, 3411.7441191910707, 3465.6361082086523, 3489.616142224353, 3499.1427100360065, 3508.964910350915, 612.5558969746897, 615.2739767074805, 633.6799916042369, 634.7080343042252, 637.6227348926296, 654.5712558274338, 680.323078536859, 694.5554844899167, 711.8006958051753, 713.6859781696037, 9404.24766018717, 9986.0, 10053.899461005443, 10078.294519383719, 10152.695419137437, 635.6027482823235, 680.534536138569, 686.0977814174345, 687.5324779150226, 714.921358650548, 4655.976720116378, 4889.731064791399, 4910.90178196434, 4914.287428322873, 4916.655834091651, 97066.0, 99374.0, 100448.0, 101593.0, 101728.98271017196, 86525.56737216399, 88167.0, 88471.23057538814, 89260.5536972311, 89317.0, 69625.60748784996, 70794.9380759289, 70821.64589177021, 78869.60565197137, 81574.18425815666, 8767.605457754544, 10405.32365396277, 10842.837357439803, 10970.177236707119, 11133.443327833738, 48568.0, 51854.22218666648, 52193.7390313046, 53304.46695532995, 54699.0, 53461.73269133629, 63629.0, 65795.67102164458, 65878.0, 67396.6630166846, 13672.179669219879, 13685.657858553615, 13877.514286999907, 14486.710265794836, 14653.12081275127, 14712.0, 14745.92627036858, 14772.0, 14819.777703334243, 14962.952593318798, 13696.794548081587, 13708.3831227593, 13862.792058118934, 14059.297035148405, 14125.223112728898, 13832.239226842621, 13944.302784860514, 13984.860151398356, 14380.065295755743, 14495.710085798435, 13470.663233419244, 13539.11995720275, 13776.793348099585, 13955.372008259856, 14302.0, 10854.240203185702, 10948.288361256493, 11051.0, 11104.389258590854, 11377.15327705537, 6960.860782784214, 7343.8898416522725, 9223.815523689624, 10140.54367553447, 10569.365838049742], "value": 7605.199675987906, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.6999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 121.0, 119.0, 113.0, 118.0, 121.0, 120.0, 117.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 136.0, 113.0, 151.0, 112.0, 115.0, 110.0, 107.0, 132.0, 116.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 220.0, 252.0, 248.0, 271.0, 271.0, 196.0, 194.0, 191.0, 278.0], "value": 231.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 61.0, 63.0, 88.0, 63.0, 62.0, 60.0, 64.0, 78.0, 74.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 87.0, 73.0, 73.0, 71.0, 82.0, 73.0, 71.0, 87.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 93.0, 94.0, 89.0, 132.0, 89.0, 83.0, 132.0, 98.0], "value": 98.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 146.0, 98.0, 98.0, 130.0, 98.0, 97.0, 98.0, 97.0, 98.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 136.0, 90.0, 135.0, 89.0, 150.0, 141.0, 93.0, 92.0, 93.0], "value": 116.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 61.0, 60.0, 67.0, 87.0, 97.0, 98.0], "value": 73.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 59.0, 69.0, 66.0, 61.0, 58.0, 54.0, 75.0, 79.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 91.0, 105.0, 105.0, 108.0, 90.0, 87.0, 94.0, 91.0], "value": 96.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 154.0, 152.0, 163.0, 157.0, 156.0, 145.0, 152.0, 212.0, 158.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 187.0, 173.0, 176.0, 173.0, 172.0, 172.0, 176.0, 196.0, 174.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 87.0, 88.0, 89.0, 91.0, 73.0, 68.0, 68.0], "value": 77.3, "unit": "score"}]}]} 23:32:20 INFO - Return code: 0 23:32:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:32:20 ERROR - # TBPL SUCCESS # 23:32:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:32:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:32:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:32:20 INFO - 2017-03-06 23:32:20.579 screenresolution[2237:27906] starting screenresolution argv=screenresolution get 23:32:20 INFO - 2017-03-06 23:32:20.582 screenresolution[2237:27906] Display 0: 1600x1200x32@60 23:32:20 INFO - 2017-03-06 23:32:20.589 screenresolution[2238:27909] starting screenresolution argv=screenresolution list 23:32:20 INFO - Available Modes on Display 0 23:32:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:32:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:32:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:32:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:32:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:32:20 INFO - Intel Iris: 23:32:20 INFO - Chipset Model: Intel Iris 23:32:20 INFO - Type: GPU 23:32:20 INFO - Bus: Built-In 23:32:20 INFO - VRAM (Dynamic, Max): 1536 MB 23:32:20 INFO - Vendor: Intel (0x8086) 23:32:20 INFO - Device ID: 0x0a2e 23:32:20 INFO - Revision ID: 0x0009 23:32:20 INFO - Displays: 23:32:20 INFO - Display: 23:32:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:32:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:32:20 INFO - Display Serial Number: 1600x1200 60 23:32:20 INFO - Main Display: Yes 23:32:20 INFO - Mirror: Off 23:32:20 INFO - Online: Yes 23:32:20 INFO - Rotation: Supported 23:32:20 INFO - Return code: 0 23:32:20 INFO - Running post-action listener: _resource_record_post_action 23:32:20 INFO - [mozharness: 2017-03-07 07:32:20.828953Z] Finished run-tests step (success) 23:32:20 INFO - Running post-run listener: _resource_record_post_run 23:32:20 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 2355712; Write bytes: 512437248; Read time: 9; Write time: 1919 23:32:20 INFO - TinderboxPrint: CPU usage
55.0% 23:32:20 INFO - TinderboxPrint: I/O read bytes / time
2,355,712 / 9 23:32:20 INFO - TinderboxPrint: I/O write bytes / time
512,437,248 / 1,919 23:32:20 INFO - TinderboxPrint: CPU idle
2,162.1 (45.0%) 23:32:20 INFO - TinderboxPrint: CPU system
967.8 (20.2%) 23:32:20 INFO - TinderboxPrint: CPU user
1,671.9 (34.8%) 23:32:20 INFO - TinderboxPrint: Swap in / out
211,771,392 / 0 23:32:20 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200727040; Write bytes: 194951168; Read time: 15745; Write time: 439 23:32:20 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 2355200; Write bytes: 299774976; Read time: 9; Write time: 1431 23:32:21 INFO - Running post-run listener: _upload_blobber_files 23:32:21 INFO - Blob upload gear active. 23:32:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:32:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:32:21 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:32:21 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:32:21 INFO - (blobuploader) - INFO - Open directory for files ... 23:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:22 INFO - (blobuploader) - INFO - Done attempting. 23:32:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:32:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:24 INFO - (blobuploader) - INFO - Done attempting. 23:32:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:32:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:32:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:25 INFO - (blobuploader) - INFO - Done attempting. 23:32:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:32:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:32:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:32:28 INFO - (blobuploader) - INFO - Done attempting. 23:32:28 INFO - (blobuploader) - INFO - Iteration through files over. 23:32:28 INFO - Return code: 0 23:32:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:32:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:32:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27cc293cc4cf15ed1c807a68f096e502ab0c47725038012ee0141d79f43435fcdcb30a9b9a290797e37c10de66c40d8f4d077efe25035f5915391a3c672fabfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e337c5a2ef379831293d4c3c82877599c7ee59a3d7c24de0b3000ad9f5f72acce2f21a486738955ae469dc66673d158ae3c28424dd23e9f1d4665d0375508feb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76f1f4edb33a16625d1826aa5350277043967d7012af28931686c14f3091a7f070fc95c57fb5472dd0e70a7ad8c95924386248d248886281faa0857431552094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/644ecdaa28dcd067ddfa5dee444260df896ad3e46dc8fe7342dcced70958efb2dbeb5ad0fb59e34898f4475e77dd33542695b3ee5662c1b8ab2dc4449ed2bb23"} 23:32:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:32:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:32:28 INFO - Contents: 23:32:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27cc293cc4cf15ed1c807a68f096e502ab0c47725038012ee0141d79f43435fcdcb30a9b9a290797e37c10de66c40d8f4d077efe25035f5915391a3c672fabfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e337c5a2ef379831293d4c3c82877599c7ee59a3d7c24de0b3000ad9f5f72acce2f21a486738955ae469dc66673d158ae3c28424dd23e9f1d4665d0375508feb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76f1f4edb33a16625d1826aa5350277043967d7012af28931686c14f3091a7f070fc95c57fb5472dd0e70a7ad8c95924386248d248886281faa0857431552094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/644ecdaa28dcd067ddfa5dee444260df896ad3e46dc8fe7342dcced70958efb2dbeb5ad0fb59e34898f4475e77dd33542695b3ee5662c1b8ab2dc4449ed2bb23"} 23:32:28 INFO - Running post-run listener: copy_logs_to_upload_dir 23:32:28 INFO - Copying logs to upload dir... 23:32:28 INFO - mkdir: /builds/slave/test/build/upload/logs 23:32:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.807100 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2017-03-06 23:32:28.473291) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 23:32:28.479064) ========= 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.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27cc293cc4cf15ed1c807a68f096e502ab0c47725038012ee0141d79f43435fcdcb30a9b9a290797e37c10de66c40d8f4d077efe25035f5915391a3c672fabfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e337c5a2ef379831293d4c3c82877599c7ee59a3d7c24de0b3000ad9f5f72acce2f21a486738955ae469dc66673d158ae3c28424dd23e9f1d4665d0375508feb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76f1f4edb33a16625d1826aa5350277043967d7012af28931686c14f3091a7f070fc95c57fb5472dd0e70a7ad8c95924386248d248886281faa0857431552094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/644ecdaa28dcd067ddfa5dee444260df896ad3e46dc8fe7342dcced70958efb2dbeb5ad0fb59e34898f4475e77dd33542695b3ee5662c1b8ab2dc4449ed2bb23"} build_url:https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/cE07tISnTR2G4Tg4IqA6CA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/27cc293cc4cf15ed1c807a68f096e502ab0c47725038012ee0141d79f43435fcdcb30a9b9a290797e37c10de66c40d8f4d077efe25035f5915391a3c672fabfe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e337c5a2ef379831293d4c3c82877599c7ee59a3d7c24de0b3000ad9f5f72acce2f21a486738955ae469dc66673d158ae3c28424dd23e9f1d4665d0375508feb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/76f1f4edb33a16625d1826aa5350277043967d7012af28931686c14f3091a7f070fc95c57fb5472dd0e70a7ad8c95924386248d248886281faa0857431552094", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/644ecdaa28dcd067ddfa5dee444260df896ad3e46dc8fe7342dcced70958efb2dbeb5ad0fb59e34898f4475e77dd33542695b3ee5662c1b8ab2dc4449ed2bb23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 23:32:28.514098) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:32:28.514472) ========= 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.Modogb4dlE/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.SXyS1lFyUe/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 23:32:28.563506) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 23:32:28.563829) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 23:32:28.564140) ========= ========= Total master_lag: 0.18 =========