builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0100 starttime: 1492776268.01 results: success (0) buildid: 20170421034727 builduid: 15db89e7e2fd4cbbb2e8d97b13fae9d4 revision: 84360b114045c9b05909658f044fa956da2587ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.005441) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.005899) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.006202) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.030811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.031127) ========= 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.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-04-21 05:04:28-- 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% 8.02M=0.001s 2017-04-21 05:04:28 (8.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089657 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.144056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.144379) ========= 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.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.022746 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.213633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:28.213996) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 84360b114045c9b05909658f044fa956da2587ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 84360b114045c9b05909658f044fa956da2587ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-04-21 05:04:28,301 truncating revision to first 12 chars 2017-04-21 05:04:28,301 Setting DEBUG logging. 2017-04-21 05:04:28,301 attempt 1/10 2017-04-21 05:04:28,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/84360b114045?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 05:04:28,944 unpacking tar archive at: mozilla-beta-84360b114045/testing/mozharness/ program finished with exit code 0 elapsedTime=0.908861 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:29.150617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:29.151283) ========= script_repo_revision: 84360b114045c9b05909658f044fa956da2587ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:29.151994) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:29.152569) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 05:04:29.174164) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-04-21 05:04:29.174890) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:04:29 INFO - MultiFileLogger online at 20170421 05:04:29 in /builds/slave/test 05:04:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:04:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:29 INFO - {'append_to_log': False, 05:04:29 INFO - 'base_work_dir': '/builds/slave/test', 05:04:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:04:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:29 INFO - 'branch': 'Mozilla-Beta', 05:04:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:29 INFO - 'config_files': ('talos/mac_config.py',), 05:04:29 INFO - 'default_actions': ('clobber', 05:04:29 INFO - 'read-buildbot-config', 05:04:29 INFO - 'download-and-extract', 05:04:29 INFO - 'populate-webroot', 05:04:29 INFO - 'create-virtualenv', 05:04:29 INFO - 'install', 05:04:29 INFO - 'run-tests'), 05:04:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:29 INFO - 'download_minidump_stackwalk': True, 05:04:29 INFO - 'download_symbols': 'ondemand', 05:04:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:29 INFO - 'gecko_profile': False, 05:04:29 INFO - 'gecko_profile_interval': 0, 05:04:29 INFO - 'installer_path': 'installer.exe', 05:04:29 INFO - 'log_level': 'info', 05:04:29 INFO - 'log_name': 'talos', 05:04:29 INFO - 'log_to_console': True, 05:04:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:04:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:04:29 INFO - 'opt_config_files': (), 05:04:29 INFO - 'pip_index': False, 05:04:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:29 INFO - 'cmd': ('bash', 05:04:29 INFO - '-c', 05:04:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:29 INFO - 'enabled': True, 05:04:29 INFO - 'halt_on_failure': False, 05:04:29 INFO - 'name': 'check_screen_resolution'},), 05:04:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:29 INFO - 'cmd': ('bash', 05:04:29 INFO - '-c', 05:04:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:29 INFO - 'enabled': True, 05:04:29 INFO - 'halt_on_failure': False, 05:04:29 INFO - 'name': 'check_screen_resolution'},), 05:04:29 INFO - 'run_cmd_checks_enabled': True, 05:04:29 INFO - 'suite': 'dromaeojs-e10s', 05:04:29 INFO - 'system_bits': '32', 05:04:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:29 INFO - 'title': 't-yosemite-r7-0100', 05:04:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:29 INFO - 'use_talos_json': True, 05:04:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:29 INFO - 'work_dir': 'build'} 05:04:29 INFO - [mozharness: 2017-04-21 12:04:29.390051Z] Running clobber step. 05:04:29 INFO - Running pre-action listener: _resource_record_pre_action 05:04:29 INFO - Running main action method: clobber 05:04:29 INFO - rmtree: /builds/slave/test/build 05:04:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:32 INFO - Running post-action listener: _resource_record_post_action 05:04:32 INFO - [mozharness: 2017-04-21 12:04:32.525639Z] Finished clobber step (success) 05:04:32 INFO - [mozharness: 2017-04-21 12:04:32.525812Z] Running read-buildbot-config step. 05:04:32 INFO - Running pre-action listener: _resource_record_pre_action 05:04:32 INFO - Running main action method: read_buildbot_config 05:04:32 INFO - Using buildbot properties: 05:04:32 INFO - { 05:04:32 INFO - "project": "", 05:04:32 INFO - "product": "firefox", 05:04:32 INFO - "script_repo_revision": "production", 05:04:32 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:04:32 INFO - "repository": "", 05:04:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:04:32 INFO - "buildid": "20170421034727", 05:04:32 INFO - "pgo_build": "False", 05:04:32 INFO - "basedir": "/builds/slave/test", 05:04:32 INFO - "buildnumber": 24, 05:04:32 INFO - "slavename": "t-yosemite-r7-0100", 05:04:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:04:32 INFO - "platform": "yosemite_r7", 05:04:32 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:04:32 INFO - "branch": "mozilla-beta", 05:04:32 INFO - "repo_path": "releases/mozilla-beta", 05:04:32 INFO - "revision": "84360b114045c9b05909658f044fa956da2587ef", 05:04:32 INFO - "stage_platform": "macosx64", 05:04:32 INFO - "builduid": "15db89e7e2fd4cbbb2e8d97b13fae9d4", 05:04:32 INFO - "slavebuilddir": "test" 05:04:32 INFO - } 05:04:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 05:04:32 INFO - Running post-action listener: _resource_record_post_action 05:04:32 INFO - [mozharness: 2017-04-21 12:04:32.529282Z] Finished read-buildbot-config step (success) 05:04:32 INFO - [mozharness: 2017-04-21 12:04:32.529416Z] Running download-and-extract step. 05:04:32 INFO - Running pre-action listener: _resource_record_pre_action 05:04:32 INFO - Running main action method: download_and_extract 05:04:32 INFO - mkdir: /builds/slave/test/build/tests 05:04:32 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')]} 05:04:32 INFO - trying https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 05:04:32 INFO - Downloading https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 05:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 05:04:33 INFO - Downloaded 1165 bytes. 05:04:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 05:04:33 INFO - Using the following test package requirements: 05:04:33 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 05:04:33 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 05:04:33 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 05:04:33 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:04:33 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 05:04:33 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 05:04:33 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 05:04:33 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 05:04:33 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 05:04:33 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 05:04:33 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 05:04:33 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 05:04:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 05:04:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 05:04:33 INFO - Fetch https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 05:04:35 INFO - Expected file size: 51223835 05:04:35 INFO - Obtained file size: 51223835 05:04:51 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:04:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 05:04:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 05:04:51 INFO - Fetch https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 05:04:52 INFO - Expected file size: 51223835 05:04:52 INFO - Obtained file size: 51223835 05:05:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 05:05:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 05:05:08 INFO - Fetch https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 05:05:09 INFO - Expected file size: 14404424 05:05:09 INFO - Obtained file size: 14404424 05:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:09 INFO - trying https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 05:05:09 INFO - Downloading https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 05:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 05:05:11 INFO - Downloaded 59545337 bytes. 05:05:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 05:05:11 INFO - mkdir: /builds/slave/test/properties 05:05:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:11 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:11 INFO - Contents: 05:05:11 INFO - build_url:https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 05:05:12 INFO - Running post-action listener: _resource_record_post_action 05:05:12 INFO - Running post-action listener: set_extra_try_arguments 05:05:12 INFO - [mozharness: 2017-04-21 12:05:12.334382Z] Finished download-and-extract step (success) 05:05:12 INFO - [mozharness: 2017-04-21 12:05:12.334553Z] Running populate-webroot step. 05:05:12 INFO - Running pre-action listener: _resource_record_pre_action 05:05:12 INFO - Running main action method: populate_webroot 05:05:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:12 INFO - '--tppagecycles', 05:05:12 INFO - '7'], 05:05:12 INFO - 'tests': ['tsvgm']}}, 05:05:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['damp', 'tps']}, 05:05:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'tests': ['damp', 'tps']}, 05:05:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['dromaeo_dom']}, 05:05:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['basic_compositor_video']}, 05:05:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['a11yr', 05:05:12 INFO - 'ts_paint', 05:05:12 INFO - 'tpaint', 05:05:12 INFO - 'sessionrestore', 05:05:12 INFO - 'sessionrestore_no_auto_restore', 05:05:12 INFO - 'tabpaint']}, 05:05:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['tsvgx', 05:05:12 INFO - 'tsvgr_opacity', 05:05:12 INFO - 'tart', 05:05:12 INFO - 'tscrollx', 05:05:12 INFO - 'cart', 05:05:12 INFO - 'tsvg_static']}, 05:05:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:12 INFO - 'tsvgr_opacity', 05:05:12 INFO - 'tart', 05:05:12 INFO - 'tscrollx', 05:05:12 INFO - 'cart', 05:05:12 INFO - 'tsvg_static']}, 05:05:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'talos_options': ['--disable-e10s'], 05:05:12 INFO - 'tests': ['tp5o']}, 05:05:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:05:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'talos_options': ['--disable-e10s', 05:05:12 INFO - '--xperf_path', 05:05:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:12 INFO - 'tests': ['tp5n']}, 05:05:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:05:12 INFO - 'talos_options': ['--xperf_path', 05:05:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:12 INFO - 'tests': ['tp5n']}}, 05:05:12 INFO - 'talos.zip': {'path': '', 05:05:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:12 INFO - Running post-action listener: _resource_record_post_action 05:05:12 INFO - [mozharness: 2017-04-21 12:05:12.353073Z] Finished populate-webroot step (success) 05:05:12 INFO - [mozharness: 2017-04-21 12:05:12.353200Z] Running create-virtualenv step. 05:05:12 INFO - Running pre-action listener: _resource_record_pre_action 05:05:12 INFO - Running main action method: create_virtualenv 05:05:12 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:12 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 05:05:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:12 INFO - Using real prefix '/tools/python27' 05:05:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:13 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:16 INFO - Installing pip.................done. 05:05:16 INFO - Return code: 0 05:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:05:16 INFO - Reading from file tmpfile_stdout 05:05:16 INFO - Output received: 05:05:16 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) 05:05:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:16 INFO - 'HOME': '/Users/cltbld', 05:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:16 INFO - 'LOGNAME': 'cltbld', 05:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:16 INFO - 'MOZ_NO_REMOTE': '1', 05:05:16 INFO - 'NO_EM_RESTART': '1', 05:05:16 INFO - 'PAGER': '/bin/cat', 05:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:16 INFO - 'PWD': '/builds/slave/test', 05:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:16 INFO - 'SHELL': '/bin/bash', 05:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:16 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:16 INFO - 'USER': 'cltbld', 05:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:16 INFO - 'XPC_FLAGS': '0x0', 05:05:16 INFO - 'XPC_SERVICE_NAME': '0', 05:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:16 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 05:05:16 INFO - Cleaning up... 05:05:16 INFO - Return code: 0 05:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:16 INFO - 'HOME': '/Users/cltbld', 05:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:16 INFO - 'LOGNAME': 'cltbld', 05:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:16 INFO - 'MOZ_NO_REMOTE': '1', 05:05:16 INFO - 'NO_EM_RESTART': '1', 05:05:16 INFO - 'PAGER': '/bin/cat', 05:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:16 INFO - 'PWD': '/builds/slave/test', 05:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:16 INFO - 'SHELL': '/bin/bash', 05:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:16 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:16 INFO - 'USER': 'cltbld', 05:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:16 INFO - 'XPC_FLAGS': '0x0', 05:05:16 INFO - 'XPC_SERVICE_NAME': '0', 05:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:16 INFO - Downloading/unpacking psutil>=3.1.1 05:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:20 INFO - Installing collected packages: psutil 05:05:20 INFO - Running setup.py install for psutil 05:05:20 INFO - building 'psutil._psutil_osx' extension 05:05:20 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 05:05:20 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 05:05:20 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 05:05:20 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 05:05:20 INFO - building 'psutil._psutil_posix' extension 05:05:20 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 05:05:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:20 INFO - ^ 05:05:20 INFO - 1 warning generated. 05:05:20 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 05:05:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:21 INFO - Successfully installed psutil 05:05:21 INFO - Cleaning up... 05:05:21 INFO - Return code: 0 05:05:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:21 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')]} 05:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:21 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')]} 05:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:21 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 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:21 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 05:05:21 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 05:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:21 INFO - 'HOME': '/Users/cltbld', 05:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:21 INFO - 'LOGNAME': 'cltbld', 05:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:21 INFO - 'MOZ_NO_REMOTE': '1', 05:05:21 INFO - 'NO_EM_RESTART': '1', 05:05:21 INFO - 'PAGER': '/bin/cat', 05:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:21 INFO - 'PWD': '/builds/slave/test', 05:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:21 INFO - 'SHELL': '/bin/bash', 05:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:21 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:21 INFO - 'USER': 'cltbld', 05:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:21 INFO - 'XPC_FLAGS': '0x0', 05:05:21 INFO - 'XPC_SERVICE_NAME': '0', 05:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:21 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 05:05:21 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 05:05:21 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 05:05:21 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 05:05:21 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 05:05:21 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 05:05:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:24 INFO - Installing collected packages: mozsystemmonitor 05:05:24 INFO - Running setup.py install for mozsystemmonitor 05:05:24 INFO - Successfully installed mozsystemmonitor 05:05:24 INFO - Cleaning up... 05:05:24 INFO - Return code: 0 05:05:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:24 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')]} 05:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:25 INFO - 'HOME': '/Users/cltbld', 05:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:25 INFO - 'LOGNAME': 'cltbld', 05:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:25 INFO - 'MOZ_NO_REMOTE': '1', 05:05:25 INFO - 'NO_EM_RESTART': '1', 05:05:25 INFO - 'PAGER': '/bin/cat', 05:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:25 INFO - 'PWD': '/builds/slave/test', 05:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:25 INFO - 'SHELL': '/bin/bash', 05:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:25 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:25 INFO - 'USER': 'cltbld', 05:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:25 INFO - 'XPC_FLAGS': '0x0', 05:05:25 INFO - 'XPC_SERVICE_NAME': '0', 05:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:25 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:28 INFO - warning: no files found matching '*.txt' 05:05:28 INFO - no previously-included directories found matching 'build' 05:05:28 INFO - no previously-included directories found matching 'dist' 05:05:28 INFO - no previously-included directories found matching '.git*' 05:05:28 INFO - Installing collected packages: jsonschema, functools32 05:05:29 INFO - Running setup.py install for functools32 05:05:29 INFO - warning: no files found matching '*.txt' 05:05:29 INFO - no previously-included directories found matching 'build' 05:05:29 INFO - no previously-included directories found matching 'dist' 05:05:29 INFO - no previously-included directories found matching '.git*' 05:05:29 INFO - Successfully installed jsonschema functools32 05:05:29 INFO - Cleaning up... 05:05:29 INFO - Return code: 0 05:05:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:29 INFO - 'HOME': '/Users/cltbld', 05:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:29 INFO - 'LOGNAME': 'cltbld', 05:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:29 INFO - 'MOZ_NO_REMOTE': '1', 05:05:29 INFO - 'NO_EM_RESTART': '1', 05:05:29 INFO - 'PAGER': '/bin/cat', 05:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:29 INFO - 'PWD': '/builds/slave/test', 05:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:29 INFO - 'SHELL': '/bin/bash', 05:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:29 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:29 INFO - 'USER': 'cltbld', 05:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:29 INFO - 'XPC_FLAGS': '0x0', 05:05:29 INFO - 'XPC_SERVICE_NAME': '0', 05:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:29 INFO - Cleaning up... 05:05:29 INFO - Return code: 0 05:05:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:05:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:05:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:29 INFO - 'HOME': '/Users/cltbld', 05:05:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:29 INFO - 'LOGNAME': 'cltbld', 05:05:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:29 INFO - 'MOZ_NO_REMOTE': '1', 05:05:29 INFO - 'NO_EM_RESTART': '1', 05:05:29 INFO - 'PAGER': '/bin/cat', 05:05:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:29 INFO - 'PWD': '/builds/slave/test', 05:05:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:29 INFO - 'SHELL': '/bin/bash', 05:05:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:29 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:29 INFO - 'USER': 'cltbld', 05:05:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:29 INFO - 'XPC_FLAGS': '0x0', 05:05:29 INFO - 'XPC_SERVICE_NAME': '0', 05:05:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:33 INFO - Downloading docopt-0.6.1.tar.gz 05:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:33 INFO - Running setup.py install for blobuploader 05:05:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:33 INFO - Running setup.py install for requests 05:05:34 INFO - Running setup.py install for docopt 05:05:34 INFO - Successfully installed blobuploader requests docopt 05:05:34 INFO - Cleaning up... 05:05:34 INFO - Return code: 0 05:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:34 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 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:34 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 05:05:34 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 05:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:34 INFO - 'HOME': '/Users/cltbld', 05:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:34 INFO - 'LOGNAME': 'cltbld', 05:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:34 INFO - 'MOZ_NO_REMOTE': '1', 05:05:34 INFO - 'NO_EM_RESTART': '1', 05:05:34 INFO - 'PAGER': '/bin/cat', 05:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:34 INFO - 'PWD': '/builds/slave/test', 05:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:34 INFO - 'SHELL': '/bin/bash', 05:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:34 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:34 INFO - 'USER': 'cltbld', 05:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:34 INFO - 'XPC_FLAGS': '0x0', 05:05:34 INFO - 'XPC_SERVICE_NAME': '0', 05:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:34 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-QxRtG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-KVvBAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-tQzIIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-2Asfr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-LBkQaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-bG6WBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Xh7bQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-2AOdTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-VCNfeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-KtJN1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:35 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-4si_Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-3Jk4Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-SmpAxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-AJkREs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-UjNtNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-0FdJbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:36 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-dxhgQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:36 INFO - Running setup.py install for manifestparser 05:05:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:36 INFO - Running setup.py install for mozcrash 05:05:36 INFO - Running setup.py install for mozdebug 05:05:37 INFO - Running setup.py install for mozdevice 05:05:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Running setup.py install for mozfile 05:05:37 INFO - Running setup.py install for mozhttpd 05:05:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Running setup.py install for mozinfo 05:05:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Running setup.py install for mozInstall 05:05:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:37 INFO - Running setup.py install for mozleak 05:05:37 INFO - Running setup.py install for mozlog 05:05:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Running setup.py install for moznetwork 05:05:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Running setup.py install for mozprocess 05:05:38 INFO - Running setup.py install for mozprofile 05:05:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Running setup.py install for mozrunner 05:05:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:38 INFO - Running setup.py install for mozscreenshot 05:05:38 INFO - Running setup.py install for moztest 05:05:39 INFO - Running setup.py install for mozversion 05:05:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:39 INFO - Cleaning up... 05:05:39 INFO - Return code: 0 05:05:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:39 INFO - 'HOME': '/Users/cltbld', 05:05:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:39 INFO - 'LOGNAME': 'cltbld', 05:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:39 INFO - 'MOZ_NO_REMOTE': '1', 05:05:39 INFO - 'NO_EM_RESTART': '1', 05:05:39 INFO - 'PAGER': '/bin/cat', 05:05:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:39 INFO - 'PWD': '/builds/slave/test', 05:05:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:39 INFO - 'SHELL': '/bin/bash', 05:05:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:39 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:39 INFO - 'USER': 'cltbld', 05:05:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:39 INFO - 'XPC_FLAGS': '0x0', 05:05:39 INFO - 'XPC_SERVICE_NAME': '0', 05:05:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:39 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-p6QJFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:39 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)) 05:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:39 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-7d15JC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:39 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)) 05:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:39 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-p8uPmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:39 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)) 05:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:39 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-IMxp34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:40 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)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-l2BCUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1BOCxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-PHIK8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-UahH3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-WzOSbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-WpIE__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:40 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)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-r8cXeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:40 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)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-DyLIpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:40 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)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-nO87fB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:40 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)) 05:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:40 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-SUHyi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:41 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)) 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:41 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-vhuuqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:41 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-DLR3B5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:41 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)) 05:05:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:41 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-wQ6iz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:05:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:44 INFO - Downloading blessings-1.6.tar.gz 05:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:44 INFO - Installing collected packages: blessings 05:05:44 INFO - Running setup.py install for blessings 05:05:44 INFO - Successfully installed blessings 05:05:44 INFO - Cleaning up... 05:05:44 INFO - Return code: 0 05:05:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:44 INFO - Reading from file tmpfile_stdout 05:05:44 INFO - Current package versions: 05:05:44 INFO - blessings == 1.6 05:05:44 INFO - blobuploader == 1.2.4 05:05:44 INFO - docopt == 0.6.1 05:05:44 INFO - functools32 == 3.2.3-2 05:05:44 INFO - jsonschema == 2.5.1 05:05:44 INFO - manifestparser == 1.1 05:05:44 INFO - mozInstall == 1.12 05:05:44 INFO - mozcrash == 1.0 05:05:44 INFO - mozdebug == 0.1 05:05:44 INFO - mozdevice == 0.49 05:05:44 INFO - mozfile == 1.2 05:05:44 INFO - mozhttpd == 0.7 05:05:44 INFO - mozinfo == 0.9 05:05:44 INFO - mozleak == 0.1 05:05:44 INFO - mozlog == 3.4 05:05:44 INFO - moznetwork == 0.27 05:05:44 INFO - mozprocess == 0.25 05:05:44 INFO - mozprofile == 0.28 05:05:44 INFO - mozrunner == 6.13 05:05:44 INFO - mozscreenshot == 0.1 05:05:44 INFO - mozsystemmonitor == 0.3 05:05:44 INFO - moztest == 0.8 05:05:44 INFO - mozversion == 1.4 05:05:44 INFO - psutil == 3.1.1 05:05:44 INFO - requests == 1.2.3 05:05:44 INFO - wsgiref == 0.1.2 05:05:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:44 INFO - 'HOME': '/Users/cltbld', 05:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:44 INFO - 'LOGNAME': 'cltbld', 05:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:44 INFO - 'MOZ_NO_REMOTE': '1', 05:05:44 INFO - 'NO_EM_RESTART': '1', 05:05:44 INFO - 'PAGER': '/bin/cat', 05:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:44 INFO - 'PWD': '/builds/slave/test', 05:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:44 INFO - 'SHELL': '/bin/bash', 05:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:44 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:44 INFO - 'USER': 'cltbld', 05:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:44 INFO - 'XPC_FLAGS': '0x0', 05:05:44 INFO - 'XPC_SERVICE_NAME': '0', 05:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 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)) 05:05:44 INFO - Cleaning up... 05:05:44 INFO - Return code: 0 05:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 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 0x1093f2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093f5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fe9dd0d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, '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.raHmySM52L/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.kbOgeiOSAq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 05:05:44 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 05:05:44 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 05:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:44 INFO - 'HOME': '/Users/cltbld', 05:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:44 INFO - 'LOGNAME': 'cltbld', 05:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:44 INFO - 'MOZ_NO_REMOTE': '1', 05:05:44 INFO - 'NO_EM_RESTART': '1', 05:05:44 INFO - 'PAGER': '/bin/cat', 05:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:44 INFO - 'PWD': '/builds/slave/test', 05:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:44 INFO - 'SHELL': '/bin/bash', 05:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:05:44 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:44 INFO - 'USER': 'cltbld', 05:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:44 INFO - 'XPC_FLAGS': '0x0', 05:05:44 INFO - 'XPC_SERVICE_NAME': '0', 05:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:45 INFO - Cleaning up... 05:05:45 INFO - Return code: 0 05:05:45 INFO - Running post-action listener: _resource_record_post_action 05:05:45 INFO - Running post-action listener: _start_resource_monitoring 05:05:45 INFO - Starting resource monitoring. 05:05:45 INFO - [mozharness: 2017-04-21 12:05:45.259491Z] Finished create-virtualenv step (success) 05:05:45 INFO - [mozharness: 2017-04-21 12:05:45.260569Z] Running install step. 05:05:45 INFO - Running pre-action listener: _resource_record_pre_action 05:05:45 INFO - Running main action method: install 05:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:45 INFO - Reading from file tmpfile_stdout 05:05:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:45 INFO - Reading from file tmpfile_stdout 05:05:45 INFO - Output received: 05:05:45 INFO - Usage: mozinstall [options] installer 05:05:45 INFO - Options: 05:05:45 INFO - -h, --help show this help message and exit 05:05:45 INFO - -d DEST, --destination=DEST 05:05:45 INFO - Directory to install application into. [default: 05:05:45 INFO - "/builds/slave/test"] 05:05:45 INFO - --app=APP Application being installed. [default: firefox] 05:05:45 INFO - mkdir: /builds/slave/test/build/application 05:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:06:00 INFO - Reading from file tmpfile_stdout 05:06:00 INFO - Output received: 05:06:00 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:06:00 INFO - Running post-action listener: _resource_record_post_action 05:06:00 INFO - [mozharness: 2017-04-21 12:06:00.549347Z] Finished install step (success) 05:06:00 INFO - [mozharness: 2017-04-21 12:06:00.549496Z] Running run-tests step. 05:06:00 INFO - Running pre-action listener: _resource_record_pre_action 05:06:00 INFO - Running main action method: run_tests 05:06:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:00 INFO - 2017-04-21 05:06:00.564 screenresolution[30484:280068] starting screenresolution argv=screenresolution get 05:06:00 INFO - 2017-04-21 05:06:00.567 screenresolution[30484:280068] Display 0: 1600x1200x32@60 05:06:00 INFO - 2017-04-21 05:06:00.573 screenresolution[30485:280070] starting screenresolution argv=screenresolution list 05:06:00 INFO - Available Modes on Display 0 05:06:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:00 INFO - Intel Iris: 05:06:00 INFO - Chipset Model: Intel Iris 05:06:00 INFO - Type: GPU 05:06:00 INFO - Bus: Built-In 05:06:00 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:00 INFO - Vendor: Intel (0x8086) 05:06:00 INFO - Device ID: 0x0a2e 05:06:00 INFO - Revision ID: 0x0009 05:06:00 INFO - Displays: 05:06:00 INFO - Display: 05:06:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:00 INFO - Display Serial Number: 1600x1200 60 05:06:00 INFO - Main Display: Yes 05:06:00 INFO - Mirror: Off 05:06:00 INFO - Online: Yes 05:06:00 INFO - Rotation: Supported 05:06:00 INFO - Return code: 0 05:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:00 INFO - Python 2.7.3 05:06:00 INFO - Return code: 0 05:06:00 INFO - grabbing minidump binary from tooltool 05:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:00 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 0x1091d0d30>, '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 0x1090f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091c5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:00 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 05:06:00 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 05:06:00 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 05:06:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:00 INFO - Return code: 0 05:06:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:00 INFO - ENV: RUST_BACKTRACE is now 1 05:06:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 05:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --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 05:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.raHmySM52L/Render', 05:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:00 INFO - 'HOME': '/Users/cltbld', 05:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:00 INFO - 'LOGNAME': 'cltbld', 05:06:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:00 INFO - 'MOZ_NO_REMOTE': '1', 05:06:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:00 INFO - 'NO_EM_RESTART': '1', 05:06:00 INFO - 'PAGER': '/bin/cat', 05:06:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:00 INFO - 'PWD': '/builds/slave/test', 05:06:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:00 INFO - 'RUST_BACKTRACE': '1', 05:06:00 INFO - 'SHELL': '/bin/bash', 05:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kbOgeiOSAq/Listeners', 05:06:00 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 05:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:00 INFO - 'USER': 'cltbld', 05:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:00 INFO - 'XPC_FLAGS': '0x0', 05:06:00 INFO - 'XPC_SERVICE_NAME': '0', 05:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--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 05:06:01 INFO - mozversion application_buildid: 20170421034727 05:06:01 INFO - mozversion application_changeset: 84360b114045c9b05909658f044fa956da2587ef 05:06:01 INFO - mozversion application_display_name: Firefox 05:06:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:01 INFO - mozversion application_name: Firefox 05:06:01 INFO - mozversion application_remotingname: firefox 05:06:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:06:01 INFO - mozversion application_vendor: Mozilla 05:06:01 INFO - mozversion application_version: 54.0 05:06:01 INFO - mozversion platform_buildid: 20170421034727 05:06:01 INFO - mozversion platform_changeset: 84360b114045c9b05909658f044fa956da2587ef 05:06:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:06:01 INFO - mozversion platform_version: 54.0 05:06:01 INFO - using testdate: 1492776361 05:06:01 INFO - actual date: 1492776361 05:06:01 INFO - starting webserver on 'localhost:49528' 05:06:01 INFO - SUITE-START | Running 2 tests 05:06:01 INFO - TEST-START | dromaeo_css 05:06:01 INFO - operating with platform_type : mac_ 05:06:01 INFO - Initialising browser for dromaeo_css test... 05:06:01 INFO - TEST-INFO | started process 30495 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp9cHdco/profile http://localhost:49528/getInfo.html) 05:06:04 INFO - PROCESS | 30495 | 2017-04-21 05:06:04.439 plugin-container[30498:280221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c37, name = 'com.apple.tsm.portname' 05:06:04 INFO - PROCESS | 30495 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:04 INFO - PROCESS | 30495 | 2017-04-21 05:06:04.439 plugin-container[30498:280221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 05:06:04 INFO - PROCESS | 30495 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:04 INFO - PROCESS | 30495 | 2017-04-21 05:06:04.439 plugin-container[30498:280221] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:04 INFO - PROCESS | 30495 | __metrics Screen width/height:1600/1200 05:06:04 INFO - PROCESS | 30495 | colorDepth:24 05:06:04 INFO - PROCESS | 30495 | Browser inner width/height: 1024/689 05:06:04 INFO - PROCESS | 30495 | __metrics 05:06:04 ERROR - PROCESS | 30495 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 05:06:05 INFO - TEST-INFO | 30495: exit 0 05:06:05 INFO - Browser initialized. 05:06:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:05 INFO - TEST-INFO | started process 30499 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp9cHdco/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:07 INFO - PROCESS | 30499 | 2017-04-21 05:06:07.436 plugin-container[30500:280351] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 05:06:07 INFO - PROCESS | 30499 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:07 INFO - PROCESS | 30499 | 2017-04-21 05:06:07.437 plugin-container[30500:280351] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 05:06:07 INFO - PROCESS | 30499 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:06:07 INFO - PROCESS | 30499 | 2017-04-21 05:06:07.437 plugin-container[30500:280351] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:06:17 INFO - PROCESS | 30499 | RSS: Main: 174178304 05:06:17 INFO - PROCESS | 30499 | 05:09:20 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49528/tests/dromaeo/cssquery-ext.html) 05:09:21 INFO - PROCESS | 30499 | RSS: Main: 191664128 05:09:21 INFO - PROCESS | 30499 | 05:12:23 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-ext.html (next: http://localhost:49528/tests/dromaeo/cssquery-jquery.html) 05:12:24 INFO - PROCESS | 30499 | RSS: Main: 201715712 05:12:24 INFO - PROCESS | 30499 | 05:15:26 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49528/tests/dromaeo/cssquery-mootools.html) 05:15:27 INFO - PROCESS | 30499 | RSS: Main: 205721600 05:15:27 INFO - PROCESS | 30499 | 05:18:29 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49528/tests/dromaeo/cssquery-prototype.html) 05:18:30 INFO - PROCESS | 30499 | RSS: Main: 208789504 05:18:30 INFO - PROCESS | 30499 | 05:21:32 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49528/tests/dromaeo/cssquery-yui.html) 05:21:33 INFO - PROCESS | 30499 | RSS: Main: 211603456 05:21:33 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | Cycle 1(1): loaded http://localhost:49528/tests/dromaeo/cssquery-yui.html (next: http://localhost:49528/tests/dromaeo/cssquery-dojo.html) 05:24:36 INFO - PROCESS | 30499 | RSS: Main: 209825792 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | __start_tp_report 05:24:36 INFO - PROCESS | 30499 | _x_x_mozilla_page_load 05:24:36 INFO - PROCESS | 30499 | _x_x_mozilla_page_load_details 05:24:36 INFO - PROCESS | 30499 | |i|pagename|runs| 05:24:36 INFO - PROCESS | 30499 | |0;dojo.html;3375.4430518964364;3607.3326434609617;3659.981700091499;3678.485012098307;3680.4479328100783;6172.382761723826;6174.598651087676;6215.906761398572;6231.968840155793;6237.3450787667325;6188.133661287423;6196.78311259106;6208.1308616793685;6230.688465576714;6234.7506099756065;5589.077802162648;5657.717114144297;5674.3190817102;5692.203091567184;5717.685527295997;5717.799877004306;5742.540596752271;5756.194132821408;5758.539316854642;5759.424057594249;5500.972495137518;5503.064479038553;5721.256236689224;5734.598578099534;5758.798442054529;5652.547796176316;5660.405657405954;5689.715514224293;5694.544436445074;5735.4551317624755;5556.722163891789;5676.914846277309;5689.402612725687;5694.345150307726;5726.0838265877255;6220.782272620437;6221;6235.96882015587;6242.594231374991;6246.344133865924;4872.269159626049;6077.0276755719315;6269.185005949243;6287.748490060392;6495.610263384212;6656.434203092695;6660.134182556285;6664.166979127609;6669.399754022113;6705.463562915004;6267.9373206268265;6283.968580157116;6323.494120470351;6324.71538780756;6332.841678958063;5784.987627165229;6226.906596401058;6473.676316184266;6715.865682686319;6823.658817059128;5321.521063104339;6420.133282006869;6420.807375778734;6422.807315780534;6456.322086180978;6651.368120028589;6666.83332916681;6672.6997285121315;6686.465082793462;6687.765928192587;6284.088807122942;6418.261899881573;6577.440917521969;6578.868422631616;6579.671016449254;6387.489000879918;6512.251091124582;6514.804555863332;6521.08704781331;6523.902141467882;5229.032628963654;5250.4749525047955;5333.986542556901;5347.0909945309395;5371.274877891513;4721.905561888743;4743.644226682944;4755.738434386142;4763.7141771493825;4805.711657300573;6252;6284.183056202664;6291.591046581958;6311.337309582428;6354.205724284465;6646.26891041985;6731.394174524316;6759.797206083826;6760.763373281911;6765.492588055916;6381.904271435932;6393.296737358887;6397.4562162216735;6407.935920640827;6431.5819471734185;5730.19777231188;5748.338941021752;5994.790182343684;5995;5999.760009599567;6822.590644561342;6825.385715285648;6939.236683964659;6955.061066755923;6957.512974091765;5026.472220416804;5449.128139497661;6571.835704107436;6598.175228096488;6610.9669451652435;5922.437368449991;6653.367930046637;6831.11195544576;6898.344657257552;6926.44588432914;6386.808395748135;6476.384743449365;6521.152250207443;6525.967370163119;6541.6075035498025;5285.9471405286995;6226.25284965807;6705.228898676714;6717.1267735194115;6732.899006514808;5398.811041613525;6482.254540727877;6521.478281737544;6551.868962620625;6555.147830781968;6240.563160578716;6256.874862502816;6389.584676995981;6443.67781610927;6535.3464653534265;6344.777932772516;6402.90395644075;6438.420542151229;6441.838954026187;6443.613383197023;5961.9864623015055;6157.568970172045;6501.414872661483;6529;6552.803415897531;5209.583233341399;5390.811321603725;6184.288806787277;6284.591501552567;6303.991361382157 05:24:36 INFO - PROCESS | 30499 | |1;ext.html;778.6612823421812;827.3257295304327;855.3072011670547;865.8744482050107;989.1641562879369;40922;41616.37575436367;43083;43177.784111079476;43216;41250;41523.58476415235;41550;41553.75338739835;41651.37522937158;8305.211004954519;8472.576371181416;11237.662870113909;11516.309021458697;11718.121140914423;11412.258203216807;11510.769784604303;11516.71208219797;11539.019183369424;11546.07631389491;11465.082793376552;11483.59807406741;11487.59793407231;11522.251053681526;11532;11434.942825285903;11455.369954652491;11482.966533011986;11497.195196336259;11509.539618415254;11165.38590377529;11216.383098929555;11218.551257949672;11233.04519115876;11248;35026;35205.29589408197;35224.591016359314;35313.29373412543;35371;3904.082540602956;3914.432407300954;3939.527256729182;3961.881143565669;4039.838406463738;26366.945322187283;26505;26590.335241619116;27940.301492462648;27945.301367465774;3168.2712976015414;3230.628477725045;3233.1917020744813;3295.736341092707;3301.834908254578;383400;386414;386760;386763;388096;45394.31908521375;45457.77271113623;45495.09009819785;45556.4055258071;45565.544344556794;47447.525524745004;47742.80642983953;47835;47856.282155767694;47919.76040119777;14855;14889.404423822925;14889.404423823142;15023;15027.849721502864;12904.77404646557;12906.419211135333;12909.031822613342;12919.612411627666;12922.612321630366;11376.374299413612;12393.194442361399;12461.439235234437;12500.24998500093;12554.874451255371;5014.548690617862;5624.6625202487985;5689.6586204827845;5726.627769194989;5730.684812335278;1130.304862509567;1140.1050175612104;1176.4999875053068;1177.8527684039495;1199.7420554580633;28199.43601127966;28472.572911406347;28575.255481694367;28656.570151447744;28893.42213155725;1283.1210620724833;1307.5358247822076;1314.769915264825;1318.4462525739214;1344.717609302038;13281.335933203301;13591.592252232449;14093.365798539091;14575.489857854924;14810.481633143003;444283;478387;481999;485417;486152;51943.48056519462;61757.38242617606;62247.6887615559;62256;62372.37627623756;1244.4337826289197;1255.234307072685;1453.9200343980972;1491.2767307855897;1494.1558019718825;21389.35831925045;27629.447411052068;28132;29041;29486.262843428227;39506.802465987486;39662.008449788984;39711.80144099261;39787;39881;497488;497549;497698;498113;504982;30751.2312192197;30930.226744331572;31360.215994600316;32706.345873082882;32733;40932;41593.168136636494;42029.579704202566;42303.3654495189;42811.7859410701;28995;29550.556741648463;29746;30140.84929575338;30335.938242161417;23769.52460950806;24195.15316963898;24214.757852421953;24515.387115321548;25355;34428.79499217516;34679.13302167466;35205;35223.82388088043;35274.294514110086;20288.391348259574;29015.12954611365;29527.409451810414;29621;30071.69928300689;18803.811961880205;22813.885930570243;23787.762122378554;23897.880510597337;24187.15344962918 05:24:36 INFO - PROCESS | 30499 | |2;jquery.html;366.2601544879343;373.7290464413299;420.6529613069218;429.2488145744944;436.9491373247341;21187.68218476722;21565.259078742383;22498.55002899941;22618.095276188953;22645.320640380774;22130.44673883154;22847.657285140736;23240.140788736902;23419.180328688457;23948.8802555987;6673.0657707921055;6939.861202775942;6977.092977912865;6999.510034297601;7008.824779380505;7541.321281084702;7879.921200788005;8012.3189528889825;8026.638801253928;8049.993750781152;6893.758718444856;7815.140334563193;7946.483478573903;8046.557439340834;8059.516429014248;3637.0361854108683;3670.4494325851338;3676.8161591920298;3917.001164703011;8013.919860801405;7491.213422590604;7836.804079897991;7914.683412663487;7922.564258965755;7930.008748906387;26283.60574591383;27242.5913611296;27653.170404887886;27672.308192295353;28016;3020.154356780105;3389.813560254185;3486.337595856779;3540.539729835131;3724.5902950675404;16197.51407457778;16501.58746031347;16706.582335441588;16736.83263167365;17266.91366543172;2188.9343319700433;2218.5784700906775;2223.788740069691;2232.2521955145044;2277.6811246425514;167647.1617641904;176731;177200;177411.11294443574;178639;20597.352211823018;21372.572548548942;21375.358739237538;21403.357899263046;21558.676619850714;33125.66874331226;33333.66666333354;33392.66607333944;33419.83030593918;33620.66379336175;10554.311173574248;10862.131029517619;11044.834327485094;11067.72330691723;11308.604198853001;9247.029061948399;9262.814743705088;9309.767255818659;9426.905730942603;9437.528123593793;7941.682332706742;9688.273379496424;9814.75463113428;9896.55465504054;10262.846057309147;3442.4836274559016;3946.3685810270795;4013.7391069580967;4034.8587799426878;4052.392141178847;892.7455675132557;901.4861528928448;926.3376685669779;951.8619800129081;963.5423173992298;16851.831481685273;21194;21544.676829847253;21648.567028659654;21742.456438588844;954.8758661374115;973.5083782689696;982.8991529487021;993.8012397520525;995.5470261031213;10965.50655220517;11386;11426.143039272089;11472.565929258843;11480;740516;744011;762545.1872740712;762745;773124.1343793245;109530;113873.54629620402;115578;135452;166021.16989414976;1243.639344590077;1259;1281.3721276574597;1281.9743605127844;1305.1777380250383;17011.404600838912;20923.790762092183;21070.051847666586;21166.576668466852;21177.682334764682;67177.66411167913;67369.66315168393;67742.3225767736;68089.97865032128;69073;90443.0955690461;94900.62748431068;95144;96351;98528.50735746276;11556;12024.218425802295;12109.394530273275;12132.332721700392;12175.33035683046;13481.325933703472;13547.661308467366;13579.45682172702;13936.512222072179;14007;9112.690837805287;11315.490802913724;11809.881901180877;11928.165028447926;11992.220505667105;9713.320067595201;10910.727231819268;11175.161862860155;11275.774484510428;11342.489587968395;11708.88291117078;11920.225185362924;12054.638360849189;12144.332061736335;12306.938465307616;12086.15396922207;12091;12165.878341216474;12432.508099028175;12541.874581254071;11472.139589530652;11570.59502917394;11662.416879155839;11867.287962722265;11897.286162830258 05:24:36 INFO - PROCESS | 30499 | |3;mootools.html;1412.2091628687936;1454.7236025155219;1606.5421354913851;1627.1457484820476;1637.7297745871929;10744.033037026666;11358.26171298865;11370.658880233586;11735.178537502377;12144.635660930186;11554;11690.532378704864;11756.7648647027;11790.115741319392;11804.114691398157;2436.1229957215387;2485.8508489490596;2485.9005639774387;2486.2168416948693;2499.712533058694;2447.559439300925;2450.13020377766;2482.677251957252;2486.080150344366;2494.7505249475;2458.4468494588755;2463.864487453189;2476;2477.4797292568583;2483.1557270528015;2417.045267119478;2441.6093425051995;2452.1907770435714;2459.7786199242064;2461.716902556193;2370.9051637934463;2391.9521609567887;2392.007316963458;2395.748446413136;2396.04158336665;11399.088072954226;11629.418529073597;11651.708707282301;11660.766784664344;11672.007879330267;5904.409559044104;6072.119542666333;6074.210352654127;6080.270367555878;6096.603720758181;3601.225736466672;3675.4119340905327;3682.0978860179425;3692.5199724035974;3750.1562148516637;1243.7388148488828;1245.2901845947813;1286.8198452216698;1299.6296055624102;1314.539911031141;14119.152850828983;14492.782608260779;15284.770728439082;16124.193790310555;16224.513264601963;4120.690948178899;4244.405783190356;4583.083383323348;4821.132196204717;4836.419629644395;11737;11793;11829.053675705924;11843.407829608657;11854.762904741858;5913.940860591425;5967.313758917693;5975.641461512323;5976.282846058414;5989.131575921467;5341.813036543703;5357.8392648220615;5411.404745477994;5439.864003399867;5499.945000550023;3969.861054863066;4062.9593704063172;4068.6745060395097;4094.9590504094576;4128.752274863517;998.4907697074544;1010.5301035018605;1012.6607586458618;1020.285799940045;1021.7445638590352;744.6239648977232;844.1305455380967;851.5401683091061;861.1431625532636;865.536937738307;6772.356626120511;6788.8302792429595;6852.828679283058;6918.757843475555;7037.859242815115;945.2485274207022;976.4434272464769;1017.9032991865761;1019.898010198989;1023.9539220735085;4632.374629425052;4684.672072954861;4750.287456881495;4880.585150262196;4929.112759703216;14063.648408789863;14130.85869141316;14236.85763142376;14461.85538144605;14592;14002;14380.424783008772;14385.712285754436;14453.421863125357;14507.854921450651;8793.428427152214;10782;10783.191260655325;10947.56209751617;10950.06924411434;5926.67403292823;7155.356017958408;7269.454790890599;7295;7489.13874904393;12865.421056052688;12923.870761292266;13076.150050246704;13107.344632768514;13173.473061077833;12555.309457979898;12901.225926444067;13069.869301306866;13118.278494682503;13417.329133543479;9434.716958491255;9459.38513996588;9716.368436051634;9718.514074296116;9723.416595004323;10668.679939601825;10671.679849604525;10739.731506712395;10774.084202842532;10783.892161078602;9006.684766033419;9213.953930230306;9223.907760922304;9314;9334.53327333617;8963.865542017005;9011.279097672039;9051.909480905106;9071.183593476619;9073.637054517745;8377.204165604258;8387.245147936716;8469.407141500036;8487.490750554987;8520.3183745299;6071.301800292847;6163.3528479509005;6178.567500274938;6205.937940620536;6297.149884765498;6126.724297406717;6151.661658608819;6293.716782744879;6322.0200868865395;6325.399087086904 05:24:36 INFO - PROCESS | 30499 | |4;prototype.html;352.49417086480895;385.21992964247397;399.2195258270082;400.70347942522534;403.66495808478965;5128.973755601854;5224.9622647167635;5269.420363759983;5353.554540274128;5361.624686271957;4944.99455441093;5068.923966140511;5125.923111153335;5143.897122057557;5146;3369.6580876290986;4120.381942708587;4220.7256528325715;4496.505384407713;4554.316852472122;3832.9425058624;4299.634531064848;4344.739315641056;4417.779111044451;4546.658735672968;3904.7657140571655;4121.464209652741;4472.172648060103;4496.707713998596;4497;3624.8762883505933;3884.5921178276158;4022.896645421668;4259.7657128857745;4547.704399214073;3601.657842504958;3850.537935447764;4318.352247162919;4330.848420305274;4367.410399596046;3973.3443981742976;4665.4168228971375;5022.668992716933;5049.394072711261;5179.300794392747;4640.930386044212;4834;4967.801287948478;5064.290999260106;5065.049261575977;5215.556677682363;5605.635633683797;5623.831285061455;5625.71871406428;5634.408387119334;3970.841166353371;4468.932966005513;4476.567498400513;4493.640508759291;4509.436320459942;5239.707895026013;5463.289772329572;5886.6468011919;5912.881742365172;5966.3138739045135;3822.9295797176833;4000.3999400089638;4135.77253251074;4536.750478723636;4642.976785116053;4249.2988656871885;4443.911121777546;4522.3668686383935;4600.332951721982;4695.483496815347;4677.269410318204;4990.950090499121;5029.899402011939;5048.469910659328;5073.670211436245;4631.3516107744945;4794.633529444091;5315.730825542264;5651.745671444794;5657.377688454267;1349.663832805535;1407.6340151560669;1411.336671764269;1518.360171015301;1610.428297954233;983.6262220356219;1144.1658990764122;1202;1206.8715750773056;1219.2440686774257;4606.447226332862;4618.330342100444;5340.946590534123;5347.679139251657;5456.399796022434;5116.616253780981;5182.637215394886;5344.839654810362;5758.3223800932765;5845.88308233833;5039.244113382949;5195.428263009834;5236.73816309183;5329.789466321563;5411.323584551931;2445.7065152181854;2469.40734223785;2911.427828972331;2911.985440072786;3004.9549256761165;4893.510648935078;5175.844724658266;5205.5315021647475;5385.515303622693;5439.428859969726;5601;5608.775648974077;5871.882562348729;5944.314524994531;6015.263055309823;5156.458571849902;5468.632841789553;5624.128260119688;6015.729292181863;6075.33171351151;4730.124926888537;5293.841184764463;5544.944550554443;5551.528120109836;5695.971520142372;4875.390576177978;5019;5195.870103247449;5664.971675141763;5697.202391665253;5077.898442031064;5597.916031259453;5947.405259474018;6014.759409623566;6026;4008.2785098682516;4061.7611628453606;4096.897577560585;4339.609435150852;4747.501512341154;5068.670536415121;5800.390958949419;5836.212111364912;5874.237728681293;5888.851673923544;4910.729909854849;4987.276844857548;5245.501677340647;5436.53789427903;5561.221429000024;5062.620303477328;5225.16397376418;5281.540333831331;5344.35867695879;5537.667739935617;4711.78796954131;4801.783919723691;5252.002119597264;5331.040412725747;5499;4806.399200099988;5066.620003499678;5514.944850551443;5578.875829535473;5790;5143.3570803649545;5495.807646732345;5500.1749737539685;5511.889762204653;5583.385827559046 05:24:36 INFO - PROCESS | 30499 | |5;yui.html;715.0624934825034;770.4876257288903;821.9479066794502;826.9993308097202;878.912108789121;12227.266364018165;16596.83403165968;16788.740844436656;16915;17143.742843857322;15293;15406.922965385158;15416.152111633855;15429.45996890109;15435.922820385911;992.6327258914193;1006.6275478073123;1046.215338496128;1046.8272734998754;1049.9737506562358;1029.840374741916;1042.93220940639;1043.0508237503873;1043.415687215162;1044.5195210203315;1065.3394895165009;1079.3092420850662;1087.9728006799814;1089.2484185911696;1089.9782004359947;1075.1398880895254;1075.3225467955217;1076.165971372185;1076.6662334676237;1077.3266708307308;797.0554892452404;799.4803377804416;811.8782182672587;811.9269265766051;814.9307308878693;17250.30998760048;17605.823941760547;17989.7301540477;18321.725174122264;18501.35245266409;14565.198914059722;14743.705125897422;14785.186814725186;14872.256387180598;15005.69988600222;11802.881971180264;12215.144939854214;12229.449674764657;12244.510219591206;12439.626811195678;3853.556840963297;3868.303705333039;3871.9612803871883;4004.519457665099;4108.67130629549;112005.87994120036;117221;121412;121637;124052;11126.721831954266;11283.830742538868;11404.600838970762;11936.701582460508;12062.155649104478;19784.406467805988;23628.88185559061;23981.280561583182;24375.634365484533;24463.877680611484;7678.923210767821;8013.639386227634;8018.719344822904;8188.631511581993;8367.623456944451;4493.73037617744;4519.593236608721;4568.840090596813;4579.771011449414;4631.768411579475;3249.1552196428624;3336.9165770855925;3354.5135955286346;3356.6643335666727;3546.3793836078585;564.1312378936474;629.6820105846518;632.0897907013886;640.1741753138399;648.9188851393576;697.5256825358704;704.9224585295614;729.1396152539947;733.9009233753375;754.3135746470685;10634.73413164677;10825.566977320976;11049.613263535737;11093.722656933642;11310.660680179608;679.8776220280397;688.7980474072775;700.629969845047;722.9060222171084;726.5822152262471;4532.138893610204;4975.800967961241;5028.220625803006;5030.798768049237;5042.672226305279;85167;93649;110432.44783776191;113636.43181784038;113831;74950;99262;99361;99658.50512242322;100491;76863.61568192123;82494.92555818975;83150;83856.58071709602;87110.56444717736;10673.626423075155;10714.678559643224;11236.602970710386;11326.773464530828;11642.185047046625;32298.515522267655;47455.762721186176;54948.45051549433;55112.44887551073;55873.44126558845;49961;67101.3289867095;67365;69426;71835.64082179556;13650.317484125953;13736.793948090986;13793.034487585772;14106.717865642424;14371;15239.923800380928;15273.62763572002;15909.68180636404;15961.600959976093;16560.089195094384;11708.765824683629;14353.856461435253;14556.272186390426;14724.969252152676;15095.698086037997;14150.292485375485;14213.64465888361;14511.201883896487;15160;15285.617859553155;14005.43978240859;14031.649208769863;14306.140201774118;14681.119132852064;14825.777613335593;9962.402255864672;10381.325213861075;10787;10833.837492437777;10834.187435942178;10534.841977370499;10707.357558546511;11560.47977841016;11805.586804461802;11826.645200643994 05:24:36 INFO - PROCESS | 30499 | __end_tp_report 05:24:36 INFO - PROCESS | 30499 | __start_cc_report 05:24:36 INFO - PROCESS | 30499 | _x_x_mozilla_cycle_collect,393 05:24:36 INFO - PROCESS | 30499 | __end_cc_report 05:24:36 INFO - PROCESS | 30499 | __startTimestamp1492777476610__endTimestamp 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | ------- Summary: start ------- 05:24:36 INFO - PROCESS | 30499 | Number of tests: 6 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#0] dojo.html Cycles:165 Average:6091.70 Median:6284.09 stddev:674.46 (10.7%) stddev-sans-first:641.98 05:24:36 INFO - PROCESS | 30499 | Values: 3375.4 3607.3 3660.0 3678.5 3680.4 6172.4 6174.6 6215.9 6232.0 6237.3 6188.1 6196.8 6208.1 6230.7 6234.8 5589.1 5657.7 5674.3 5692.2 5717.7 5717.8 5742.5 5756.2 5758.5 5759.4 5501.0 5503.1 5721.3 5734.6 5758.8 5652.5 5660.4 5689.7 5694.5 5735.5 5556.7 5676.9 5689.4 5694.3 5726.1 6220.8 6221.0 6236.0 6242.6 6246.3 4872.3 6077.0 6269.2 6287.7 6495.6 6656.4 6660.1 6664.2 6669.4 6705.5 6267.9 6284.0 6323.5 6324.7 6332.8 5785.0 6226.9 6473.7 6715.9 6823.7 5321.5 6420.1 6420.8 6422.8 6456.3 6651.4 6666.8 6672.7 6686.5 6687.8 6284.1 6418.3 6577.4 6578.9 6579.7 6387.5 6512.3 6514.8 6521.1 6523.9 5229.0 5250.5 5334.0 5347.1 5371.3 4721.9 4743.6 4755.7 4763.7 4805.7 6252.0 6284.2 6291.6 6311.3 6354.2 6646.3 6731.4 6759.8 6760.8 6765.5 6381.9 6393.3 6397.5 6407.9 6431.6 5730.2 5748.3 5994.8 5995.0 5999.8 6822.6 6825.4 6939.2 6955.1 6957.5 5026.5 5449.1 6571.8 6598.2 6611.0 5922.4 6653.4 6831.1 6898.3 6926.4 6386.8 6476.4 6521.2 6526.0 6541.6 5285.9 6226.3 6705.2 6717.1 6732.9 5398.8 6482.3 6521.5 6551.9 6555.1 6240.6 6256.9 6389.6 6443.7 6535.3 6344.8 6402.9 6438.4 6441.8 6443.6 5962.0 6157.6 6501.4 6529.0 6552.8 5209.6 5390.8 6184.3 6284.6 6304.0 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#1] ext.html Cycles:180 Average:58234.64 Median:30106.27 stddev:121355.43 (403.1%) stddev-sans-first:121619.19 05:24:36 INFO - PROCESS | 30499 | Values: 778.7 827.3 855.3 865.9 989.2 40922.0 41616.4 43083.0 43177.8 43216.0 41250.0 41523.6 41550.0 41553.8 41651.4 8305.2 8472.6 11237.7 11516.3 11718.1 11412.3 11510.8 11516.7 11539.0 11546.1 11465.1 11483.6 11487.6 11522.3 11532.0 11434.9 11455.4 11483.0 11497.2 11509.5 11165.4 11216.4 11218.6 11233.0 11248.0 35026.0 35205.3 35224.6 35313.3 35371.0 3904.1 3914.4 3939.5 3961.9 4039.8 26366.9 26505.0 26590.3 27940.3 27945.3 3168.3 3230.6 3233.2 3295.7 3301.8 383400.0 386414.0 386760.0 386763.0 388096.0 45394.3 45457.8 45495.1 45556.4 45565.5 47447.5 47742.8 47835.0 47856.3 47919.8 14855.0 14889.4 14889.4 15023.0 15027.8 12904.8 12906.4 12909.0 12919.6 12922.6 11376.4 12393.2 12461.4 12500.2 12554.9 5014.5 5624.7 5689.7 5726.6 5730.7 1130.3 1140.1 1176.5 1177.9 1199.7 28199.4 28472.6 28575.3 28656.6 28893.4 1283.1 1307.5 1314.8 1318.4 1344.7 13281.3 13591.6 14093.4 14575.5 14810.5 444283.0 478387.0 481999.0 485417.0 486152.0 51943.5 61757.4 62247.7 62256.0 62372.4 1244.4 1255.2 1453.9 1491.3 1494.2 21389.4 27629.4 28132.0 29041.0 29486.3 39506.8 39662.0 39711.8 39787.0 39881.0 497488.0 497549.0 497698.0 498113.0 504982.0 30751.2 30930.2 31360.2 32706.3 32733.0 40932.0 41593.2 42029.6 42303.4 42811.8 28995.0 29550.6 29746.0 30140.8 30335.9 23769.5 24195.2 24214.8 24515.4 25355.0 34428.8 34679.1 35205.0 35223.8 35274.3 20288.4 29015.1 29527.4 29621.0 30071.7 18803.8 22813.9 23787.8 23897.9 24187.2 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#2] jquery.html Cycles:180 Average:43918.13 Median:23043.90 stddev:126254.30 (547.9%) stddev-sans-first:126566.13 05:24:36 INFO - PROCESS | 30499 | Values: 366.3 373.7 420.7 429.2 436.9 21187.7 21565.3 22498.6 22618.1 22645.3 22130.4 22847.7 23240.1 23419.2 23948.9 6673.1 6939.9 6977.1 6999.5 7008.8 7541.3 7879.9 8012.3 8026.6 8050.0 6893.8 7815.1 7946.5 8046.6 8059.5 3637.0 3670.4 3676.8 3917.0 8013.9 7491.2 7836.8 7914.7 7922.6 7930.0 26283.6 27242.6 27653.2 27672.3 28016.0 3020.2 3389.8 3486.3 3540.5 3724.6 16197.5 16501.6 16706.6 16736.8 17266.9 2188.9 2218.6 2223.8 2232.3 2277.7 167647.2 176731.0 177200.0 177411.1 178639.0 20597.4 21372.6 21375.4 21403.4 21558.7 33125.7 33333.7 33392.7 33419.8 33620.7 10554.3 10862.1 11044.8 11067.7 11308.6 9247.0 9262.8 9309.8 9426.9 9437.5 7941.7 9688.3 9814.8 9896.6 10262.8 3442.5 3946.4 4013.7 4034.9 4052.4 892.7 901.5 926.3 951.9 963.5 16851.8 21194.0 21544.7 21648.6 21742.5 954.9 973.5 982.9 993.8 995.5 10965.5 11386.0 11426.1 11472.6 11480.0 740516.0 744011.0 762545.2 762745.0 773124.1 109530.0 113873.5 115578.0 135452.0 166021.2 1243.6 1259.0 1281.4 1282.0 1305.2 17011.4 20923.8 21070.1 21166.6 21177.7 67177.7 67369.7 67742.3 68090.0 69073.0 90443.1 94900.6 95144.0 96351.0 98528.5 11556.0 12024.2 12109.4 12132.3 12175.3 13481.3 13547.7 13579.5 13936.5 14007.0 9112.7 11315.5 11809.9 11928.2 11992.2 9713.3 10910.7 11175.2 11275.8 11342.5 11708.9 11920.2 12054.6 12144.3 12306.9 12086.2 12091.0 12165.9 12432.5 12541.9 11472.1 11570.6 11662.4 11867.3 11897.3 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#3] mootools.html Cycles:180 Average:7020.71 Median:2485.88 stddev:4398.89 (177.0%) stddev-sans-first:4391.04 05:24:36 INFO - PROCESS | 30499 | Values: 1412.2 1454.7 1606.5 1627.1 1637.7 10744.0 11358.3 11370.7 11735.2 12144.6 11554.0 11690.5 11756.8 11790.1 11804.1 2436.1 2485.9 2485.9 2486.2 2499.7 2447.6 2450.1 2482.7 2486.1 2494.8 2458.4 2463.9 2476.0 2477.5 2483.2 2417.0 2441.6 2452.2 2459.8 2461.7 2370.9 2392.0 2392.0 2395.7 2396.0 11399.1 11629.4 11651.7 11660.8 11672.0 5904.4 6072.1 6074.2 6080.3 6096.6 3601.2 3675.4 3682.1 3692.5 3750.2 1243.7 1245.3 1286.8 1299.6 1314.5 14119.2 14492.8 15284.8 16124.2 16224.5 4120.7 4244.4 4583.1 4821.1 4836.4 11737.0 11793.0 11829.1 11843.4 11854.8 5913.9 5967.3 5975.6 5976.3 5989.1 5341.8 5357.8 5411.4 5439.9 5499.9 3969.9 4063.0 4068.7 4095.0 4128.8 998.5 1010.5 1012.7 1020.3 1021.7 744.6 844.1 851.5 861.1 865.5 6772.4 6788.8 6852.8 6918.8 7037.9 945.2 976.4 1017.9 1019.9 1024.0 4632.4 4684.7 4750.3 4880.6 4929.1 14063.6 14130.9 14236.9 14461.9 14592.0 14002.0 14380.4 14385.7 14453.4 14507.9 8793.4 10782.0 10783.2 10947.6 10950.1 5926.7 7155.4 7269.5 7295.0 7489.1 12865.4 12923.9 13076.2 13107.3 13173.5 12555.3 12901.2 13069.9 13118.3 13417.3 9434.7 9459.4 9716.4 9718.5 9723.4 10668.7 10671.7 10739.7 10774.1 10783.9 9006.7 9214.0 9223.9 9314.0 9334.5 8963.9 9011.3 9051.9 9071.2 9073.6 8377.2 8387.2 8469.4 8487.5 8520.3 6071.3 6163.4 6178.6 6205.9 6297.1 6126.7 6151.7 6293.7 6322.0 6325.4 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#4] prototype.html Cycles:180 Average:4623.52 Median:5063.46 stddev:1299.25 (25.7%) stddev-sans-first:1262.73 05:24:36 INFO - PROCESS | 30499 | Values: 352.5 385.2 399.2 400.7 403.7 5129.0 5225.0 5269.4 5353.6 5361.6 4945.0 5068.9 5125.9 5143.9 5146.0 3369.7 4120.4 4220.7 4496.5 4554.3 3832.9 4299.6 4344.7 4417.8 4546.7 3904.8 4121.5 4472.2 4496.7 4497.0 3624.9 3884.6 4022.9 4259.8 4547.7 3601.7 3850.5 4318.4 4330.8 4367.4 3973.3 4665.4 5022.7 5049.4 5179.3 4640.9 4834.0 4967.8 5064.3 5065.0 5215.6 5605.6 5623.8 5625.7 5634.4 3970.8 4468.9 4476.6 4493.6 4509.4 5239.7 5463.3 5886.6 5912.9 5966.3 3822.9 4000.4 4135.8 4536.8 4643.0 4249.3 4443.9 4522.4 4600.3 4695.5 4677.3 4991.0 5029.9 5048.5 5073.7 4631.4 4794.6 5315.7 5651.7 5657.4 1349.7 1407.6 1411.3 1518.4 1610.4 983.6 1144.2 1202.0 1206.9 1219.2 4606.4 4618.3 5340.9 5347.7 5456.4 5116.6 5182.6 5344.8 5758.3 5845.9 5039.2 5195.4 5236.7 5329.8 5411.3 2445.7 2469.4 2911.4 2912.0 3005.0 4893.5 5175.8 5205.5 5385.5 5439.4 5601.0 5608.8 5871.9 5944.3 6015.3 5156.5 5468.6 5624.1 6015.7 6075.3 4730.1 5293.8 5544.9 5551.5 5696.0 4875.4 5019.0 5195.9 5665.0 5697.2 5077.9 5597.9 5947.4 6014.8 6026.0 4008.3 4061.8 4096.9 4339.6 4747.5 5068.7 5800.4 5836.2 5874.2 5888.9 4910.7 4987.3 5245.5 5436.5 5561.2 5062.6 5225.2 5281.5 5344.4 5537.7 4711.8 4801.8 5252.0 5331.0 5499.0 4806.4 5066.6 5514.9 5578.9 5790.0 5143.4 5495.8 5500.2 5511.9 5583.4 05:24:36 INFO - PROCESS | 30499 | 05:24:36 INFO - PROCESS | 30499 | [#5] yui.html Cycles:180 Average:21525.06 Median:15935.64 stddev:31113.90 (195.2%) stddev-sans-first:31161.95 05:24:36 INFO - PROCESS | 30499 | Values: 715.1 770.5 821.9 827.0 878.9 12227.3 16596.8 16788.7 16915.0 17143.7 15293.0 15406.9 15416.2 15429.5 15435.9 992.6 1006.6 1046.2 1046.8 1050.0 1029.8 1042.9 1043.1 1043.4 1044.5 1065.3 1079.3 1088.0 1089.2 1090.0 1075.1 1075.3 1076.2 1076.7 1077.3 797.1 799.5 811.9 811.9 814.9 17250.3 17605.8 17989.7 18321.7 18501.4 14565.2 14743.7 14785.2 14872.3 15005.7 11802.9 12215.1 12229.4 12244.5 12439.6 3853.6 3868.3 3872.0 4004.5 4108.7 112005.9 117221.0 121412.0 121637.0 124052.0 11126.7 11283.8 11404.6 11936.7 12062.2 19784.4 23628.9 23981.3 24375.6 24463.9 7678.9 8013.6 8018.7 8188.6 8367.6 4493.7 4519.6 4568.8 4579.8 4631.8 3249.2 3336.9 3354.5 3356.7 3546.4 564.1 629.7 632.1 640.2 648.9 697.5 704.9 729.1 733.9 754.3 10634.7 10825.6 11049.6 11093.7 11310.7 679.9 688.8 700.6 722.9 726.6 4532.1 4975.8 5028.2 5030.8 5042.7 85167.0 93649.0 110432.4 113636.4 113831.0 74950.0 99262.0 99361.0 99658.5 100491.0 76863.6 82494.9 83150.0 83856.6 87110.6 10673.6 10714.7 11236.6 11326.8 11642.2 32298.5 47455.8 54948.5 55112.4 55873.4 49961.0 67101.3 67365.0 69426.0 71835.6 13650.3 13736.8 13793.0 14106.7 14371.0 15239.9 15273.6 15909.7 15961.6 16560.1 11708.8 14353.9 14556.3 14725.0 15095.7 14150.3 14213.6 14511.2 15160.0 15285.6 14005.4 14031.6 14306.1 14681.1 14825.8 9962.4 10381.3 10787.0 10833.8 10834.2 10534.8 10707.4 11560.5 11805.6 11826.6 05:24:36 INFO - PROCESS | 30499 | -------- Summary: end -------- 05:24:36 INFO - PROCESS | 30499 | 05:24:37 INFO - TEST-INFO | 30499: exit 0 05:24:37 INFO - TEST-OK | dromaeo_css | took 1115996ms 05:24:37 INFO - TEST-START | kraken 05:24:37 INFO - operating with platform_type : mac_ 05:24:37 INFO - Initialising browser for kraken test... 05:24:37 INFO - TEST-INFO | started process 30503 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp9mb8Sx/profile http://localhost:49528/getInfo.html) 05:24:39 INFO - PROCESS | 30503 | 2017-04-21 05:24:39.732 plugin-container[30505:282626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 05:24:39 INFO - PROCESS | 30503 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:39 INFO - PROCESS | 30503 | 2017-04-21 05:24:39.733 plugin-container[30505:282626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864f, name = 'com.apple.CFPasteboardClient' 05:24:39 INFO - PROCESS | 30503 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:39 INFO - PROCESS | 30503 | 2017-04-21 05:24:39.733 plugin-container[30505:282626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:39 INFO - PROCESS | 30503 | __metrics Screen width/height:1600/1200 05:24:39 INFO - PROCESS | 30503 | colorDepth:24 05:24:39 INFO - PROCESS | 30503 | Browser inner width/height: 1024/689 05:24:39 INFO - PROCESS | 30503 | __metrics 05:24:39 ERROR - PROCESS | 30503 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 05:24:40 INFO - TEST-INFO | 30503: exit 0 05:24:40 INFO - Browser initialized. 05:24:40 INFO - Running cycle 1/1 for kraken test... 05:24:40 INFO - TEST-INFO | started process 30506 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmp9mb8Sx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:42 INFO - PROCESS | 30506 | 2017-04-21 05:24:42.596 plugin-container[30507:282750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8343, name = 'com.apple.tsm.portname' 05:24:42 INFO - PROCESS | 30506 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:42 INFO - PROCESS | 30506 | 2017-04-21 05:24:42.596 plugin-container[30507:282750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 05:24:42 INFO - PROCESS | 30506 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:42 INFO - PROCESS | 30506 | 2017-04-21 05:24:42.596 plugin-container[30507:282750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:52 INFO - PROCESS | 30506 | RSS: Main: 174645248 05:24:52 INFO - PROCESS | 30506 | 05:24:55 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49528/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:56 INFO - PROCESS | 30506 | RSS: Main: 188104704 05:24:56 INFO - PROCESS | 30506 | 05:24:58 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49528/tests/kraken/driver.html?testName=audio-dft) 05:24:59 INFO - PROCESS | 30506 | RSS: Main: 196042752 05:24:59 INFO - PROCESS | 30506 | 05:25:02 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49528/tests/kraken/driver.html?testName=audio-fft) 05:25:03 INFO - PROCESS | 30506 | RSS: Main: 199704576 05:25:03 INFO - PROCESS | 30506 | 05:25:05 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49528/tests/kraken/driver.html?testName=audio-oscillator) 05:25:06 INFO - PROCESS | 30506 | RSS: Main: 201076736 05:25:06 INFO - PROCESS | 30506 | 05:25:08 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49528/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:09 INFO - PROCESS | 30506 | RSS: Main: 201105408 05:25:09 INFO - PROCESS | 30506 | 05:25:12 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49528/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:12 INFO - PROCESS | 30506 | RSS: Main: 205508608 05:25:12 INFO - PROCESS | 30506 | 05:25:16 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49528/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:16 INFO - PROCESS | 30506 | RSS: Main: 204787712 05:25:16 INFO - PROCESS | 30506 | 05:25:19 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49528/tests/kraken/driver.html?testName=json-parse-financial) 05:25:20 INFO - PROCESS | 30506 | RSS: Main: 204668928 05:25:20 INFO - PROCESS | 30506 | 05:25:21 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49528/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:22 INFO - PROCESS | 30506 | RSS: Main: 204374016 05:25:22 INFO - PROCESS | 30506 | 05:25:24 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:25 INFO - PROCESS | 30506 | RSS: Main: 204333056 05:25:25 INFO - PROCESS | 30506 | 05:25:27 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:27 INFO - PROCESS | 30506 | RSS: Main: 204378112 05:25:27 INFO - PROCESS | 30506 | 05:25:30 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:31 INFO - PROCESS | 30506 | RSS: Main: 204824576 05:25:31 INFO - PROCESS | 30506 | 05:25:34 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:35 INFO - PROCESS | 30506 | RSS: Main: 204570624 05:25:35 INFO - PROCESS | 30506 | 05:25:36 INFO - PROCESS | 30506 | Cycle 1(1): loaded http://localhost:49528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49528/tests/kraken/driver.html?testName=ai-astar) 05:25:37 INFO - PROCESS | 30506 | RSS: Main: 206688256 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | __start_tp_report 05:25:37 INFO - PROCESS | 30506 | _x_x_mozilla_page_load 05:25:37 INFO - PROCESS | 30506 | _x_x_mozilla_page_load_details 05:25:37 INFO - PROCESS | 30506 | |i|pagename|runs| 05:25:37 INFO - PROCESS | 30506 | |0;ai-astar;85;101;137;179;104;181;179;170;121;120 05:25:37 INFO - PROCESS | 30506 | |1;audio-beat-detection;172;139;147;144;108;115;134;140;154;112 05:25:37 INFO - PROCESS | 30506 | |2;audio-dft;222;199;221;281;206;203;201;203;203;201 05:25:37 INFO - PROCESS | 30506 | |3;audio-fft;87;87;84;112;100;60;60;65;88;85 05:25:37 INFO - PROCESS | 30506 | |4;audio-oscillator;84;72;84;71;74;57;93;102;101;112 05:25:37 INFO - PROCESS | 30506 | |5;imaging-gaussian-blur;136;100;128;132;104;92;134;88;90;81 05:25:37 INFO - PROCESS | 30506 | |6;imaging-darkroom;95;96;129;102;153;152;95;96;95;94 05:25:37 INFO - PROCESS | 30506 | |7;imaging-desaturate;115;109;90;92;92;92;92;90;91;89 05:25:37 INFO - PROCESS | 30506 | |8;json-parse-financial;65;65;64;67;66;67;66;68;64;67 05:25:37 INFO - PROCESS | 30506 | |9;json-stringify-tinderbox;64;57;73;92;98;88;86;59;60;60 05:25:37 INFO - PROCESS | 30506 | |10;stanford-crypto-aes;85;85;83;81;86;85;82;86;83;87 05:25:37 INFO - PROCESS | 30506 | |11;stanford-crypto-ccm;136;155;149;142;151;148;161;140;190;155 05:25:37 INFO - PROCESS | 30506 | |12;stanford-crypto-pbkdf2;164;186;157;145;222;241;210;170;180;223 05:25:37 INFO - PROCESS | 30506 | |13;stanford-crypto-sha256-iterative;66;63;64;66;62;64;64;65;63;64 05:25:37 INFO - PROCESS | 30506 | __end_tp_report 05:25:37 INFO - PROCESS | 30506 | __start_cc_report 05:25:37 INFO - PROCESS | 30506 | _x_x_mozilla_cycle_collect,986 05:25:37 INFO - PROCESS | 30506 | __end_cc_report 05:25:37 INFO - PROCESS | 30506 | __startTimestamp1492777537248__endTimestamp 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | ------- Summary: start ------- 05:25:37 INFO - PROCESS | 30506 | Number of tests: 14 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#0] ai-astar Cycles:10 Average:137.70 Median:174.50 stddev:36.79 (21.1%) stddev-sans-first:33.72 05:25:37 INFO - PROCESS | 30506 | Values: 85.0 101.0 137.0 179.0 104.0 181.0 179.0 170.0 121.0 120.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:142.00 stddev:20.09 (14.1%) stddev-sans-first:16.70 05:25:37 INFO - PROCESS | 30506 | Values: 172.0 139.0 147.0 144.0 108.0 115.0 134.0 140.0 154.0 112.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#2] audio-dft Cycles:10 Average:214.00 Median:204.50 stddev:24.93 (12.2%) stddev-sans-first:26.27 05:25:37 INFO - PROCESS | 30506 | Values: 222.0 199.0 221.0 281.0 206.0 203.0 201.0 203.0 203.0 201.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#3] audio-fft Cycles:10 Average:82.80 Median:84.50 stddev:16.91 (20.0%) stddev-sans-first:17.87 05:25:37 INFO - PROCESS | 30506 | Values: 87.0 87.0 84.0 112.0 100.0 60.0 60.0 65.0 88.0 85.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#4] audio-oscillator Cycles:10 Average:85.00 Median:73.00 stddev:17.03 (23.3%) stddev-sans-first:18.06 05:25:37 INFO - PROCESS | 30506 | Values: 84.0 72.0 84.0 71.0 74.0 57.0 93.0 102.0 101.0 112.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#5] imaging-gaussian-blur Cycles:10 Average:108.50 Median:108.50 stddev:21.66 (20.0%) stddev-sans-first:20.56 05:25:37 INFO - PROCESS | 30506 | Values: 136.0 100.0 128.0 132.0 104.0 92.0 134.0 88.0 90.0 81.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:95.00 stddev:24.40 (25.7%) stddev-sans-first:25.20 05:25:37 INFO - PROCESS | 30506 | Values: 95.0 96.0 129.0 102.0 153.0 152.0 95.0 96.0 95.0 94.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:91.50 stddev:9.03 (9.9%) stddev-sans-first:6.10 05:25:37 INFO - PROCESS | 30506 | Values: 115.0 109.0 90.0 92.0 92.0 92.0 92.0 90.0 91.0 89.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#8] json-parse-financial Cycles:10 Average:65.90 Median:66.50 stddev:1.37 (2.1%) stddev-sans-first:1.41 05:25:37 INFO - PROCESS | 30506 | Values: 65.0 65.0 64.0 67.0 66.0 67.0 66.0 68.0 64.0 67.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:79.50 stddev:15.80 (19.9%) stddev-sans-first:16.36 05:25:37 INFO - PROCESS | 30506 | Values: 64.0 57.0 73.0 92.0 98.0 88.0 86.0 59.0 60.0 60.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.95 (2.3%) stddev-sans-first:2.05 05:25:37 INFO - PROCESS | 30506 | Values: 85.0 85.0 83.0 81.0 86.0 85.0 82.0 86.0 83.0 87.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:153.00 stddev:15.14 (9.9%) stddev-sans-first:14.81 05:25:37 INFO - PROCESS | 30506 | Values: 136.0 155.0 149.0 142.0 151.0 148.0 161.0 140.0 190.0 155.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:198.00 stddev:32.35 (16.3%) stddev-sans-first:32.94 05:25:37 INFO - PROCESS | 30506 | Values: 164.0 186.0 157.0 145.0 222.0 241.0 210.0 170.0 180.0 223.0 05:25:37 INFO - PROCESS | 30506 | 05:25:37 INFO - PROCESS | 30506 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:1.29 (2.0%) stddev-sans-first:1.17 05:25:37 INFO - PROCESS | 30506 | Values: 66.0 63.0 64.0 66.0 62.0 64.0 64.0 65.0 63.0 64.0 05:25:37 INFO - PROCESS | 30506 | -------- Summary: end -------- 05:25:37 INFO - PROCESS | 30506 | 05:25:38 INFO - TEST-INFO | 30506: exit 0 05:25:38 INFO - TEST-OK | kraken | took 60939ms 05:25:38 INFO - SUITE-END | took 1176s 05:25:38 INFO - Completed test suite (00:19:37) 05:25:38 INFO - javascript benchmark 05:25:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7930.165347502562, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.4430518964364, 3607.3326434609617, 3659.981700091499, 3678.485012098307, 3680.4479328100783, 6172.382761723826, 6174.598651087676, 6215.906761398572, 6231.968840155793, 6237.3450787667325, 6188.133661287423, 6196.78311259106, 6208.1308616793685, 6230.688465576714, 6234.7506099756065, 5589.077802162648, 5657.717114144297, 5674.3190817102, 5692.203091567184, 5717.685527295997, 5717.799877004306, 5742.540596752271, 5756.194132821408, 5758.539316854642, 5759.424057594249, 5500.972495137518, 5503.064479038553, 5721.256236689224, 5734.598578099534, 5758.798442054529, 5652.547796176316, 5660.405657405954, 5689.715514224293, 5694.544436445074, 5735.4551317624755, 5556.722163891789, 5676.914846277309, 5689.402612725687, 5694.345150307726, 5726.0838265877255, 6220.782272620437, 6221.0, 6235.96882015587, 6242.594231374991, 6246.344133865924, 4872.269159626049, 6077.0276755719315, 6269.185005949243, 6287.748490060392, 6495.610263384212, 6656.434203092695, 6660.134182556285, 6664.166979127609, 6669.399754022113, 6705.463562915004, 6267.9373206268265, 6283.968580157116, 6323.494120470351, 6324.71538780756, 6332.841678958063, 5784.987627165229, 6226.906596401058, 6473.676316184266, 6715.865682686319, 6823.658817059128, 5321.521063104339, 6420.133282006869, 6420.807375778734, 6422.807315780534, 6456.322086180978, 6651.368120028589, 6666.83332916681, 6672.6997285121315, 6686.465082793462, 6687.765928192587, 6284.088807122942, 6418.261899881573, 6577.440917521969, 6578.868422631616, 6579.671016449254, 6387.489000879918, 6512.251091124582, 6514.804555863332, 6521.08704781331, 6523.902141467882, 5229.032628963654, 5250.4749525047955, 5333.986542556901, 5347.0909945309395, 5371.274877891513, 4721.905561888743, 4743.644226682944, 4755.738434386142, 4763.7141771493825, 4805.711657300573, 6252.0, 6284.183056202664, 6291.591046581958, 6311.337309582428, 6354.205724284465, 6646.26891041985, 6731.394174524316, 6759.797206083826, 6760.763373281911, 6765.492588055916, 6381.904271435932, 6393.296737358887, 6397.4562162216735, 6407.935920640827, 6431.5819471734185, 5730.19777231188, 5748.338941021752, 5994.790182343684, 5995.0, 5999.760009599567, 6822.590644561342, 6825.385715285648, 6939.236683964659, 6955.061066755923, 6957.512974091765, 5026.472220416804, 5449.128139497661, 6571.835704107436, 6598.175228096488, 6610.9669451652435, 5922.437368449991, 6653.367930046637, 6831.11195544576, 6898.344657257552, 6926.44588432914, 6386.808395748135, 6476.384743449365, 6521.152250207443, 6525.967370163119, 6541.6075035498025, 5285.9471405286995, 6226.25284965807, 6705.228898676714, 6717.1267735194115, 6732.899006514808, 5398.811041613525, 6482.254540727877, 6521.478281737544, 6551.868962620625, 6555.147830781968, 6240.563160578716, 6256.874862502816, 6389.584676995981, 6443.67781610927, 6535.3464653534265, 6344.777932772516, 6402.90395644075, 6438.420542151229, 6441.838954026187, 6443.613383197023, 5961.9864623015055, 6157.568970172045, 6501.414872661483, 6529.0, 6552.803415897531, 5209.583233341399, 5390.811321603725, 6184.288806787277, 6284.591501552567, 6303.991361382157], "value": 6053.363546656856, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.6612823421812, 827.3257295304327, 855.3072011670547, 865.8744482050107, 989.1641562879369, 40922.0, 41616.37575436367, 43083.0, 43177.784111079476, 43216.0, 41250.0, 41523.58476415235, 41550.0, 41553.75338739835, 41651.37522937158, 8305.211004954519, 8472.576371181416, 11237.662870113909, 11516.309021458697, 11718.121140914423, 11412.258203216807, 11510.769784604303, 11516.71208219797, 11539.019183369424, 11546.07631389491, 11465.082793376552, 11483.59807406741, 11487.59793407231, 11522.251053681526, 11532.0, 11434.942825285903, 11455.369954652491, 11482.966533011986, 11497.195196336259, 11509.539618415254, 11165.38590377529, 11216.383098929555, 11218.551257949672, 11233.04519115876, 11248.0, 35026.0, 35205.29589408197, 35224.591016359314, 35313.29373412543, 35371.0, 3904.082540602956, 3914.432407300954, 3939.527256729182, 3961.881143565669, 4039.838406463738, 26366.945322187283, 26505.0, 26590.335241619116, 27940.301492462648, 27945.301367465774, 3168.2712976015414, 3230.628477725045, 3233.1917020744813, 3295.736341092707, 3301.834908254578, 383400.0, 386414.0, 386760.0, 386763.0, 388096.0, 45394.31908521375, 45457.77271113623, 45495.09009819785, 45556.4055258071, 45565.544344556794, 47447.525524745004, 47742.80642983953, 47835.0, 47856.282155767694, 47919.76040119777, 14855.0, 14889.404423822925, 14889.404423823142, 15023.0, 15027.849721502864, 12904.77404646557, 12906.419211135333, 12909.031822613342, 12919.612411627666, 12922.612321630366, 11376.374299413612, 12393.194442361399, 12461.439235234437, 12500.24998500093, 12554.874451255371, 5014.548690617862, 5624.6625202487985, 5689.6586204827845, 5726.627769194989, 5730.684812335278, 1130.304862509567, 1140.1050175612104, 1176.4999875053068, 1177.8527684039495, 1199.7420554580633, 28199.43601127966, 28472.572911406347, 28575.255481694367, 28656.570151447744, 28893.42213155725, 1283.1210620724833, 1307.5358247822076, 1314.769915264825, 1318.4462525739214, 1344.717609302038, 13281.335933203301, 13591.592252232449, 14093.365798539091, 14575.489857854924, 14810.481633143003, 444283.0, 478387.0, 481999.0, 485417.0, 486152.0, 51943.48056519462, 61757.38242617606, 62247.6887615559, 62256.0, 62372.37627623756, 1244.4337826289197, 1255.234307072685, 1453.9200343980972, 1491.2767307855897, 1494.1558019718825, 21389.35831925045, 27629.447411052068, 28132.0, 29041.0, 29486.262843428227, 39506.802465987486, 39662.008449788984, 39711.80144099261, 39787.0, 39881.0, 497488.0, 497549.0, 497698.0, 498113.0, 504982.0, 30751.2312192197, 30930.226744331572, 31360.215994600316, 32706.345873082882, 32733.0, 40932.0, 41593.168136636494, 42029.579704202566, 42303.3654495189, 42811.7859410701, 28995.0, 29550.556741648463, 29746.0, 30140.84929575338, 30335.938242161417, 23769.52460950806, 24195.15316963898, 24214.757852421953, 24515.387115321548, 25355.0, 34428.79499217516, 34679.13302167466, 35205.0, 35223.82388088043, 35274.294514110086, 20288.391348259574, 29015.12954611365, 29527.409451810414, 29621.0, 30071.69928300689, 18803.811961880205, 22813.885930570243, 23787.762122378554, 23897.880510597337, 24187.15344962918], "value": 18956.928256774856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.2601544879343, 373.7290464413299, 420.6529613069218, 429.2488145744944, 436.9491373247341, 21187.68218476722, 21565.259078742383, 22498.55002899941, 22618.095276188953, 22645.320640380774, 22130.44673883154, 22847.657285140736, 23240.140788736902, 23419.180328688457, 23948.8802555987, 6673.0657707921055, 6939.861202775942, 6977.092977912865, 6999.510034297601, 7008.824779380505, 7541.321281084702, 7879.921200788005, 8012.3189528889825, 8026.638801253928, 8049.993750781152, 6893.758718444856, 7815.140334563193, 7946.483478573903, 8046.557439340834, 8059.516429014248, 3637.0361854108683, 3670.4494325851338, 3676.8161591920298, 3917.001164703011, 8013.919860801405, 7491.213422590604, 7836.804079897991, 7914.683412663487, 7922.564258965755, 7930.008748906387, 26283.60574591383, 27242.5913611296, 27653.170404887886, 27672.308192295353, 28016.0, 3020.154356780105, 3389.813560254185, 3486.337595856779, 3540.539729835131, 3724.5902950675404, 16197.51407457778, 16501.58746031347, 16706.582335441588, 16736.83263167365, 17266.91366543172, 2188.9343319700433, 2218.5784700906775, 2223.788740069691, 2232.2521955145044, 2277.6811246425514, 167647.1617641904, 176731.0, 177200.0, 177411.11294443574, 178639.0, 20597.352211823018, 21372.572548548942, 21375.358739237538, 21403.357899263046, 21558.676619850714, 33125.66874331226, 33333.66666333354, 33392.66607333944, 33419.83030593918, 33620.66379336175, 10554.311173574248, 10862.131029517619, 11044.834327485094, 11067.72330691723, 11308.604198853001, 9247.029061948399, 9262.814743705088, 9309.767255818659, 9426.905730942603, 9437.528123593793, 7941.682332706742, 9688.273379496424, 9814.75463113428, 9896.55465504054, 10262.846057309147, 3442.4836274559016, 3946.3685810270795, 4013.7391069580967, 4034.8587799426878, 4052.392141178847, 892.7455675132557, 901.4861528928448, 926.3376685669779, 951.8619800129081, 963.5423173992298, 16851.831481685273, 21194.0, 21544.676829847253, 21648.567028659654, 21742.456438588844, 954.8758661374115, 973.5083782689696, 982.8991529487021, 993.8012397520525, 995.5470261031213, 10965.50655220517, 11386.0, 11426.143039272089, 11472.565929258843, 11480.0, 740516.0, 744011.0, 762545.1872740712, 762745.0, 773124.1343793245, 109530.0, 113873.54629620402, 115578.0, 135452.0, 166021.16989414976, 1243.639344590077, 1259.0, 1281.3721276574597, 1281.9743605127844, 1305.1777380250383, 17011.404600838912, 20923.790762092183, 21070.051847666586, 21166.576668466852, 21177.682334764682, 67177.66411167913, 67369.66315168393, 67742.3225767736, 68089.97865032128, 69073.0, 90443.0955690461, 94900.62748431068, 95144.0, 96351.0, 98528.50735746276, 11556.0, 12024.218425802295, 12109.394530273275, 12132.332721700392, 12175.33035683046, 13481.325933703472, 13547.661308467366, 13579.45682172702, 13936.512222072179, 14007.0, 9112.690837805287, 11315.490802913724, 11809.881901180877, 11928.165028447926, 11992.220505667105, 9713.320067595201, 10910.727231819268, 11175.161862860155, 11275.774484510428, 11342.489587968395, 11708.88291117078, 11920.225185362924, 12054.638360849189, 12144.332061736335, 12306.938465307616, 12086.15396922207, 12091.0, 12165.878341216474, 12432.508099028175, 12541.874581254071, 11472.139589530652, 11570.59502917394, 11662.416879155839, 11867.287962722265, 11897.286162830258], "value": 11956.77308576512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.2091628687936, 1454.7236025155219, 1606.5421354913851, 1627.1457484820476, 1637.7297745871929, 10744.033037026666, 11358.26171298865, 11370.658880233586, 11735.178537502377, 12144.635660930186, 11554.0, 11690.532378704864, 11756.7648647027, 11790.115741319392, 11804.114691398157, 2436.1229957215387, 2485.8508489490596, 2485.9005639774387, 2486.2168416948693, 2499.712533058694, 2447.559439300925, 2450.13020377766, 2482.677251957252, 2486.080150344366, 2494.7505249475, 2458.4468494588755, 2463.864487453189, 2476.0, 2477.4797292568583, 2483.1557270528015, 2417.045267119478, 2441.6093425051995, 2452.1907770435714, 2459.7786199242064, 2461.716902556193, 2370.9051637934463, 2391.9521609567887, 2392.007316963458, 2395.748446413136, 2396.04158336665, 11399.088072954226, 11629.418529073597, 11651.708707282301, 11660.766784664344, 11672.007879330267, 5904.409559044104, 6072.119542666333, 6074.210352654127, 6080.270367555878, 6096.603720758181, 3601.225736466672, 3675.4119340905327, 3682.0978860179425, 3692.5199724035974, 3750.1562148516637, 1243.7388148488828, 1245.2901845947813, 1286.8198452216698, 1299.6296055624102, 1314.539911031141, 14119.152850828983, 14492.782608260779, 15284.770728439082, 16124.193790310555, 16224.513264601963, 4120.690948178899, 4244.405783190356, 4583.083383323348, 4821.132196204717, 4836.419629644395, 11737.0, 11793.0, 11829.053675705924, 11843.407829608657, 11854.762904741858, 5913.940860591425, 5967.313758917693, 5975.641461512323, 5976.282846058414, 5989.131575921467, 5341.813036543703, 5357.8392648220615, 5411.404745477994, 5439.864003399867, 5499.945000550023, 3969.861054863066, 4062.9593704063172, 4068.6745060395097, 4094.9590504094576, 4128.752274863517, 998.4907697074544, 1010.5301035018605, 1012.6607586458618, 1020.285799940045, 1021.7445638590352, 744.6239648977232, 844.1305455380967, 851.5401683091061, 861.1431625532636, 865.536937738307, 6772.356626120511, 6788.8302792429595, 6852.828679283058, 6918.757843475555, 7037.859242815115, 945.2485274207022, 976.4434272464769, 1017.9032991865761, 1019.898010198989, 1023.9539220735085, 4632.374629425052, 4684.672072954861, 4750.287456881495, 4880.585150262196, 4929.112759703216, 14063.648408789863, 14130.85869141316, 14236.85763142376, 14461.85538144605, 14592.0, 14002.0, 14380.424783008772, 14385.712285754436, 14453.421863125357, 14507.854921450651, 8793.428427152214, 10782.0, 10783.191260655325, 10947.56209751617, 10950.06924411434, 5926.67403292823, 7155.356017958408, 7269.454790890599, 7295.0, 7489.13874904393, 12865.421056052688, 12923.870761292266, 13076.150050246704, 13107.344632768514, 13173.473061077833, 12555.309457979898, 12901.225926444067, 13069.869301306866, 13118.278494682503, 13417.329133543479, 9434.716958491255, 9459.38513996588, 9716.368436051634, 9718.514074296116, 9723.416595004323, 10668.679939601825, 10671.679849604525, 10739.731506712395, 10774.084202842532, 10783.892161078602, 9006.684766033419, 9213.953930230306, 9223.907760922304, 9314.0, 9334.53327333617, 8963.865542017005, 9011.279097672039, 9051.909480905106, 9071.183593476619, 9073.637054517745, 8377.204165604258, 8387.245147936716, 8469.407141500036, 8487.490750554987, 8520.3183745299, 6071.301800292847, 6163.3528479509005, 6178.567500274938, 6205.937940620536, 6297.149884765498, 6126.724297406717, 6151.661658608819, 6293.716782744879, 6322.0200868865395, 6325.399087086904], "value": 5312.028338713411, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.49417086480895, 385.21992964247397, 399.2195258270082, 400.70347942522534, 403.66495808478965, 5128.973755601854, 5224.9622647167635, 5269.420363759983, 5353.554540274128, 5361.624686271957, 4944.99455441093, 5068.923966140511, 5125.923111153335, 5143.897122057557, 5146.0, 3369.6580876290986, 4120.381942708587, 4220.7256528325715, 4496.505384407713, 4554.316852472122, 3832.9425058624, 4299.634531064848, 4344.739315641056, 4417.779111044451, 4546.658735672968, 3904.7657140571655, 4121.464209652741, 4472.172648060103, 4496.707713998596, 4497.0, 3624.8762883505933, 3884.5921178276158, 4022.896645421668, 4259.7657128857745, 4547.704399214073, 3601.657842504958, 3850.537935447764, 4318.352247162919, 4330.848420305274, 4367.410399596046, 3973.3443981742976, 4665.4168228971375, 5022.668992716933, 5049.394072711261, 5179.300794392747, 4640.930386044212, 4834.0, 4967.801287948478, 5064.290999260106, 5065.049261575977, 5215.556677682363, 5605.635633683797, 5623.831285061455, 5625.71871406428, 5634.408387119334, 3970.841166353371, 4468.932966005513, 4476.567498400513, 4493.640508759291, 4509.436320459942, 5239.707895026013, 5463.289772329572, 5886.6468011919, 5912.881742365172, 5966.3138739045135, 3822.9295797176833, 4000.3999400089638, 4135.77253251074, 4536.750478723636, 4642.976785116053, 4249.2988656871885, 4443.911121777546, 4522.3668686383935, 4600.332951721982, 4695.483496815347, 4677.269410318204, 4990.950090499121, 5029.899402011939, 5048.469910659328, 5073.670211436245, 4631.3516107744945, 4794.633529444091, 5315.730825542264, 5651.745671444794, 5657.377688454267, 1349.663832805535, 1407.6340151560669, 1411.336671764269, 1518.360171015301, 1610.428297954233, 983.6262220356219, 1144.1658990764122, 1202.0, 1206.8715750773056, 1219.2440686774257, 4606.447226332862, 4618.330342100444, 5340.946590534123, 5347.679139251657, 5456.399796022434, 5116.616253780981, 5182.637215394886, 5344.839654810362, 5758.3223800932765, 5845.88308233833, 5039.244113382949, 5195.428263009834, 5236.73816309183, 5329.789466321563, 5411.323584551931, 2445.7065152181854, 2469.40734223785, 2911.427828972331, 2911.985440072786, 3004.9549256761165, 4893.510648935078, 5175.844724658266, 5205.5315021647475, 5385.515303622693, 5439.428859969726, 5601.0, 5608.775648974077, 5871.882562348729, 5944.314524994531, 6015.263055309823, 5156.458571849902, 5468.632841789553, 5624.128260119688, 6015.729292181863, 6075.33171351151, 4730.124926888537, 5293.841184764463, 5544.944550554443, 5551.528120109836, 5695.971520142372, 4875.390576177978, 5019.0, 5195.870103247449, 5664.971675141763, 5697.202391665253, 5077.898442031064, 5597.916031259453, 5947.405259474018, 6014.759409623566, 6026.0, 4008.2785098682516, 4061.7611628453606, 4096.897577560585, 4339.609435150852, 4747.501512341154, 5068.670536415121, 5800.390958949419, 5836.212111364912, 5874.237728681293, 5888.851673923544, 4910.729909854849, 4987.276844857548, 5245.501677340647, 5436.53789427903, 5561.221429000024, 5062.620303477328, 5225.16397376418, 5281.540333831331, 5344.35867695879, 5537.667739935617, 4711.78796954131, 4801.783919723691, 5252.002119597264, 5331.040412725747, 5499.0, 4806.399200099988, 5066.620003499678, 5514.944850551443, 5578.875829535473, 5790.0, 5143.3570803649545, 5495.807646732345, 5500.1749737539685, 5511.889762204653, 5583.385827559046], "value": 4243.765557759333, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.0624934825034, 770.4876257288903, 821.9479066794502, 826.9993308097202, 878.912108789121, 12227.266364018165, 16596.83403165968, 16788.740844436656, 16915.0, 17143.742843857322, 15293.0, 15406.922965385158, 15416.152111633855, 15429.45996890109, 15435.922820385911, 992.6327258914193, 1006.6275478073123, 1046.215338496128, 1046.8272734998754, 1049.9737506562358, 1029.840374741916, 1042.93220940639, 1043.0508237503873, 1043.415687215162, 1044.5195210203315, 1065.3394895165009, 1079.3092420850662, 1087.9728006799814, 1089.2484185911696, 1089.9782004359947, 1075.1398880895254, 1075.3225467955217, 1076.165971372185, 1076.6662334676237, 1077.3266708307308, 797.0554892452404, 799.4803377804416, 811.8782182672587, 811.9269265766051, 814.9307308878693, 17250.30998760048, 17605.823941760547, 17989.7301540477, 18321.725174122264, 18501.35245266409, 14565.198914059722, 14743.705125897422, 14785.186814725186, 14872.256387180598, 15005.69988600222, 11802.881971180264, 12215.144939854214, 12229.449674764657, 12244.510219591206, 12439.626811195678, 3853.556840963297, 3868.303705333039, 3871.9612803871883, 4004.519457665099, 4108.67130629549, 112005.87994120036, 117221.0, 121412.0, 121637.0, 124052.0, 11126.721831954266, 11283.830742538868, 11404.600838970762, 11936.701582460508, 12062.155649104478, 19784.406467805988, 23628.88185559061, 23981.280561583182, 24375.634365484533, 24463.877680611484, 7678.923210767821, 8013.639386227634, 8018.719344822904, 8188.631511581993, 8367.623456944451, 4493.73037617744, 4519.593236608721, 4568.840090596813, 4579.771011449414, 4631.768411579475, 3249.1552196428624, 3336.9165770855925, 3354.5135955286346, 3356.6643335666727, 3546.3793836078585, 564.1312378936474, 629.6820105846518, 632.0897907013886, 640.1741753138399, 648.9188851393576, 697.5256825358704, 704.9224585295614, 729.1396152539947, 733.9009233753375, 754.3135746470685, 10634.73413164677, 10825.566977320976, 11049.613263535737, 11093.722656933642, 11310.660680179608, 679.8776220280397, 688.7980474072775, 700.629969845047, 722.9060222171084, 726.5822152262471, 4532.138893610204, 4975.800967961241, 5028.220625803006, 5030.798768049237, 5042.672226305279, 85167.0, 93649.0, 110432.44783776191, 113636.43181784038, 113831.0, 74950.0, 99262.0, 99361.0, 99658.50512242322, 100491.0, 76863.61568192123, 82494.92555818975, 83150.0, 83856.58071709602, 87110.56444717736, 10673.626423075155, 10714.678559643224, 11236.602970710386, 11326.773464530828, 11642.185047046625, 32298.515522267655, 47455.762721186176, 54948.45051549433, 55112.44887551073, 55873.44126558845, 49961.0, 67101.3289867095, 67365.0, 69426.0, 71835.64082179556, 13650.317484125953, 13736.793948090986, 13793.034487585772, 14106.717865642424, 14371.0, 15239.923800380928, 15273.62763572002, 15909.68180636404, 15961.600959976093, 16560.089195094384, 11708.765824683629, 14353.856461435253, 14556.272186390426, 14724.969252152676, 15095.698086037997, 14150.292485375485, 14213.64465888361, 14511.201883896487, 15160.0, 15285.617859553155, 14005.43978240859, 14031.649208769863, 14306.140201774118, 14681.119132852064, 14825.777613335593, 9962.402255864672, 10381.325213861075, 10787.0, 10833.837492437777, 10834.187435942178, 10534.841977370499, 10707.357558546511, 11560.47977841016, 11805.586804461802, 11826.645200643994], "value": 8040.119281769479, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 101.0, 137.0, 179.0, 104.0, 181.0, 179.0, 170.0, 121.0, 120.0], "value": 137.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 139.0, 147.0, 144.0, 108.0, 115.0, 134.0, 140.0, 154.0, 112.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 199.0, 221.0, 281.0, 206.0, 203.0, 201.0, 203.0, 203.0, 201.0], "value": 214.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 112.0, 100.0, 60.0, 60.0, 65.0, 88.0, 85.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 84.0, 71.0, 74.0, 57.0, 93.0, 102.0, 101.0, 112.0], "value": 85.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 100.0, 128.0, 132.0, 104.0, 92.0, 134.0, 88.0, 90.0, 81.0], "value": 108.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 129.0, 102.0, 153.0, 152.0, 95.0, 96.0, 95.0, 94.0], "value": 110.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 90.0, 92.0, 92.0, 92.0, 92.0, 90.0, 91.0, 89.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 67.0, 66.0, 67.0, 66.0, 68.0, 64.0, 67.0], "value": 65.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 57.0, 73.0, 92.0, 98.0, 88.0, 86.0, 59.0, 60.0, 60.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 81.0, 86.0, 85.0, 82.0, 86.0, 83.0, 87.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 155.0, 149.0, 142.0, 151.0, 148.0, 161.0, 140.0, 190.0, 155.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 186.0, 157.0, 145.0, 222.0, 241.0, 210.0, 170.0, 180.0, 223.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 64.0, 66.0, 62.0, 64.0, 64.0, 65.0, 63.0, 64.0], "value": 64.1, "unit": "score"}]}]} 05:25:38 INFO - Return code: 0 05:25:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:38 ERROR - # TBPL SUCCESS # 05:25:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:38 INFO - 2017-04-21 05:25:38.586 screenresolution[30512:283177] starting screenresolution argv=screenresolution get 05:25:38 INFO - 2017-04-21 05:25:38.589 screenresolution[30512:283177] Display 0: 1600x1200x32@60 05:25:38 INFO - 2017-04-21 05:25:38.595 screenresolution[30513:283179] starting screenresolution argv=screenresolution list 05:25:38 INFO - Available Modes on Display 0 05:25:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:38 INFO - Intel Iris: 05:25:38 INFO - Chipset Model: Intel Iris 05:25:38 INFO - Type: GPU 05:25:38 INFO - Bus: Built-In 05:25:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:38 INFO - Vendor: Intel (0x8086) 05:25:38 INFO - Device ID: 0x0a2e 05:25:38 INFO - Revision ID: 0x0009 05:25:38 INFO - Displays: 05:25:38 INFO - Display: 05:25:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:38 INFO - Display Serial Number: 1600x1200 60 05:25:38 INFO - Main Display: Yes 05:25:38 INFO - Mirror: Off 05:25:38 INFO - Online: Yes 05:25:38 INFO - Rotation: Supported 05:25:38 INFO - Return code: 0 05:25:38 INFO - Running post-action listener: _resource_record_post_action 05:25:38 INFO - [mozharness: 2017-04-21 12:25:38.833492Z] Finished run-tests step (success) 05:25:38 INFO - Running post-run listener: _resource_record_post_run 05:25:38 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 815616; Write bytes: 425809920; Read time: 8; Write time: 1578 05:25:38 INFO - TinderboxPrint: CPU usage
54.4% 05:25:38 INFO - TinderboxPrint: I/O read bytes / time
815,616 / 8 05:25:38 INFO - TinderboxPrint: I/O write bytes / time
425,809,920 / 1,578 05:25:38 INFO - TinderboxPrint: CPU idle
2,176.8 (45.6%) 05:25:38 INFO - TinderboxPrint: CPU system
959.3 (20.1%) 05:25:38 INFO - TinderboxPrint: CPU user
1,637.7 (34.3%) 05:25:38 INFO - TinderboxPrint: Swap in / out
122,155,008 / 0 05:25:38 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119298560; Write bytes: 114554880; Read time: 10625; Write time: 302 05:25:38 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 765952; Write bytes: 300183552; Read time: 7; Write time: 1239 05:25:39 INFO - Running post-run listener: _upload_blobber_files 05:25:39 INFO - Blob upload gear active. 05:25:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:39 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 05:25:39 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:25:39 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:25:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:40 INFO - (blobuploader) - INFO - Done attempting. 05:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:41 INFO - (blobuploader) - INFO - Done attempting. 05:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:42 INFO - (blobuploader) - INFO - Done attempting. 05:25:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:44 INFO - (blobuploader) - INFO - Done attempting. 05:25:44 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:44 INFO - Return code: 0 05:25:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/526187e530ac5a598f8eef847bd1e12b1188d216bd95387803c7cc4860fb48f9b89d5827d022249a9aa31a3e2af1d61d8738a40ae92ef5087f79d0aed083dc87", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f5501c2382bb0293d66016227f42ec95a5462c0598d7647ee609e0ee2cbc9c2b491d88907691116f6f28c6a70fdd0f76312820f13e165db7292f9e29ca3eb146", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/085290de09d1eac5a018858eca0cdc95362aba01781909a71b63acc46126bf505501a79ed82d70354c8db749087bbaf5f4d08390e38fdff8d1aae21d9d48a1ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94ddb70b8bfd61ca33d9527f58eaef65f8d3eefdc310447700e9b405d53075084aac75a353506ee9bc5a3b438b1f1522a81cac9ac18df49e97bc3c5af9fda25"} 05:25:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:44 INFO - Contents: 05:25:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/526187e530ac5a598f8eef847bd1e12b1188d216bd95387803c7cc4860fb48f9b89d5827d022249a9aa31a3e2af1d61d8738a40ae92ef5087f79d0aed083dc87", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f5501c2382bb0293d66016227f42ec95a5462c0598d7647ee609e0ee2cbc9c2b491d88907691116f6f28c6a70fdd0f76312820f13e165db7292f9e29ca3eb146", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/085290de09d1eac5a018858eca0cdc95362aba01781909a71b63acc46126bf505501a79ed82d70354c8db749087bbaf5f4d08390e38fdff8d1aae21d9d48a1ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94ddb70b8bfd61ca33d9527f58eaef65f8d3eefdc310447700e9b405d53075084aac75a353506ee9bc5a3b438b1f1522a81cac9ac18df49e97bc3c5af9fda25"} 05:25:44 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:44 INFO - Copying logs to upload dir... 05:25:44 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.331115 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-04-21 05:25:44.548246) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 05:25:44.551526) ========= 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.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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/Mozilla-Beta/sha512/526187e530ac5a598f8eef847bd1e12b1188d216bd95387803c7cc4860fb48f9b89d5827d022249a9aa31a3e2af1d61d8738a40ae92ef5087f79d0aed083dc87", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f5501c2382bb0293d66016227f42ec95a5462c0598d7647ee609e0ee2cbc9c2b491d88907691116f6f28c6a70fdd0f76312820f13e165db7292f9e29ca3eb146", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/085290de09d1eac5a018858eca0cdc95362aba01781909a71b63acc46126bf505501a79ed82d70354c8db749087bbaf5f4d08390e38fdff8d1aae21d9d48a1ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94ddb70b8bfd61ca33d9527f58eaef65f8d3eefdc310447700e9b405d53075084aac75a353506ee9bc5a3b438b1f1522a81cac9ac18df49e97bc3c5af9fda25"} build_url:https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011564 build_url: 'https://queue.taskcluster.net/v1/task/aR5gme7ySNCGlEhdUMwFmw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/526187e530ac5a598f8eef847bd1e12b1188d216bd95387803c7cc4860fb48f9b89d5827d022249a9aa31a3e2af1d61d8738a40ae92ef5087f79d0aed083dc87", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f5501c2382bb0293d66016227f42ec95a5462c0598d7647ee609e0ee2cbc9c2b491d88907691116f6f28c6a70fdd0f76312820f13e165db7292f9e29ca3eb146", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/085290de09d1eac5a018858eca0cdc95362aba01781909a71b63acc46126bf505501a79ed82d70354c8db749087bbaf5f4d08390e38fdff8d1aae21d9d48a1ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d94ddb70b8bfd61ca33d9527f58eaef65f8d3eefdc310447700e9b405d53075084aac75a353506ee9bc5a3b438b1f1522a81cac9ac18df49e97bc3c5af9fda25"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 05:25:44.589058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:25:44.589552) ========= 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.raHmySM52L/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.kbOgeiOSAq/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.005311 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 05:25:44.639043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 05:25:44.639362) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 05:25:44.639701) ========= ========= Total master_lag: 0.21 =========