{"level":"warn","ts":"2026-04-18T09:02:26.610787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T09:02:26.610889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T09:02:26.610971Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T09:02:26.61099Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-18T09:02:26.611017Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T09:02:26.611867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-18T09:02:26.612017Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T09:02:26.615496Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.233061ms"} {"level":"info","ts":"2026-04-18T09:02:26.620564Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-18T09:02:26.620677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:02:26.620706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-18T09:02:26.62073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:02:26.620748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-18T09:02:26.620799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-18T09:02:26.626575Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:02:26.627779Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:02:26.62886Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T09:02:26.63218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:02:26.632415Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:02:26.632477Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:02:26.63249Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:02:26.632487Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:02:26.633261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-18T09:02:26.634417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-18T09:02:26.636722Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T09:02:26.636843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-18T09:02:26.636874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-18T09:02:26.63771Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:02:26.637749Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:02:27.021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:02:27.021059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:02:27.021082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-18T09:02:27.021141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:02:27.021161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-18T09:02:27.021177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-18T09:02:27.021228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-18T09:02:27.026244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:02:27.026291Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:02:27.026274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:02:27.026412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:02:27.026723Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:02:27.026762Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T09:02:27.027103Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:02:27.027212Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:02:27.027251Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:02:27.029819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:02:27.030275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-18T09:02:34.065785Z","caller":"traceutil/trace.go:171","msg":"trace[128948141] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"294.500481ms","start":"2026-04-18T09:02:33.771258Z","end":"2026-04-18T09:02:34.065758Z","steps":["trace[128948141] 'process raft request' (duration: 294.301659ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:34.344174Z","caller":"traceutil/trace.go:171","msg":"trace[506900535] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"268.409289ms","start":"2026-04-18T09:02:34.075729Z","end":"2026-04-18T09:02:34.344138Z","steps":["trace[506900535] 'process raft request' (duration: 212.850596ms)","trace[506900535] 'compare' (duration: 55.442442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:02:34.67879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.952234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:02:34.67894Z","caller":"traceutil/trace.go:171","msg":"trace[1353591805] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"127.111756ms","start":"2026-04-18T09:02:34.551806Z","end":"2026-04-18T09:02:34.678918Z","steps":["trace[1353591805] 'read index received' (duration: 55.9µs)","trace[1353591805] 'applied index is now lower than readState.Index' (duration: 127.054376ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:02:34.679001Z","caller":"traceutil/trace.go:171","msg":"trace[348384699] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"325.014981ms","start":"2026-04-18T09:02:34.353939Z","end":"2026-04-18T09:02:34.678954Z","steps":["trace[348384699] 'process raft request' (duration: 187.28078ms)","trace[348384699] 'compare' (duration: 136.711272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:02:34.6792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.386299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:02:34.679263Z","caller":"traceutil/trace.go:171","msg":"trace[1963972003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:328; }","duration":"127.49133ms","start":"2026-04-18T09:02:34.551757Z","end":"2026-04-18T09:02:34.679248Z","steps":["trace[1963972003] 'agreement among raft nodes before linearized reading' (duration: 127.279788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:02:34.679391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:02:34.353918Z","time spent":"325.187842ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:02:54.945421Z","caller":"traceutil/trace.go:171","msg":"trace[392264490] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"274.933397ms","start":"2026-04-18T09:02:54.670437Z","end":"2026-04-18T09:02:54.94537Z","steps":["trace[392264490] 'process raft request' (duration: 274.753895ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.956629Z","caller":"traceutil/trace.go:171","msg":"trace[1019835308] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:520; }","duration":"187.041962ms","start":"2026-04-18T09:02:54.769545Z","end":"2026-04-18T09:02:54.956587Z","steps":["trace[1019835308] 'read index received' (duration: 175.267984ms)","trace[1019835308] 'applied index is now lower than readState.Index' (duration: 11.773168ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:02:54.956831Z","caller":"traceutil/trace.go:171","msg":"trace[89310530] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"286.221972ms","start":"2026-04-18T09:02:54.670593Z","end":"2026-04-18T09:02:54.956815Z","steps":["trace[89310530] 'process raft request' (duration: 285.370056ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957023Z","caller":"traceutil/trace.go:171","msg":"trace[1590781487] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"285.016253ms","start":"2026-04-18T09:02:54.671996Z","end":"2026-04-18T09:02:54.957013Z","steps":["trace[1590781487] 'process raft request' (duration: 284.079166ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957054Z","caller":"traceutil/trace.go:171","msg":"trace[1048443374] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"251.907083ms","start":"2026-04-18T09:02:54.705116Z","end":"2026-04-18T09:02:54.957023Z","steps":["trace[1048443374] 'process raft request' (duration: 251.213817ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957144Z","caller":"traceutil/trace.go:171","msg":"trace[2107703397] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"247.987293ms","start":"2026-04-18T09:02:54.709146Z","end":"2026-04-18T09:02:54.957133Z","steps":["trace[2107703397] 'process raft request' (duration: 247.252887ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957219Z","caller":"traceutil/trace.go:171","msg":"trace[527484023] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"224.214943ms","start":"2026-04-18T09:02:54.732997Z","end":"2026-04-18T09:02:54.957212Z","steps":["trace[527484023] 'process raft request' (duration: 223.468168ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957282Z","caller":"traceutil/trace.go:171","msg":"trace[847766854] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"284.090936ms","start":"2026-04-18T09:02:54.673181Z","end":"2026-04-18T09:02:54.957272Z","steps":["trace[847766854] 'process raft request' (duration: 282.927767ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957346Z","caller":"traceutil/trace.go:171","msg":"trace[122431293] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"131.473853ms","start":"2026-04-18T09:02:54.825863Z","end":"2026-04-18T09:02:54.957336Z","steps":["trace[122431293] 'process raft request' (duration: 130.667217ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957374Z","caller":"traceutil/trace.go:171","msg":"trace[1346072208] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"278.492203ms","start":"2026-04-18T09:02:54.678873Z","end":"2026-04-18T09:02:54.957365Z","steps":["trace[1346072208] 'process raft request' (duration: 277.272044ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957431Z","caller":"traceutil/trace.go:171","msg":"trace[440133061] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"278.526154ms","start":"2026-04-18T09:02:54.678898Z","end":"2026-04-18T09:02:54.957424Z","steps":["trace[440133061] 'process raft request' (duration: 277.288214ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.95748Z","caller":"traceutil/trace.go:171","msg":"trace[1055862416] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"278.580254ms","start":"2026-04-18T09:02:54.678894Z","end":"2026-04-18T09:02:54.957474Z","steps":["trace[1055862416] 'process raft request' (duration: 277.331015ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:02:54.957555Z","caller":"traceutil/trace.go:171","msg":"trace[2014204685] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"271.139228ms","start":"2026-04-18T09:02:54.686411Z","end":"2026-04-18T09:02:54.95755Z","steps":["trace[2014204685] 'process raft request' (duration: 269.850088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:02:54.957591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.03149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:02:54.957628Z","caller":"traceutil/trace.go:171","msg":"trace[1594744312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"188.126291ms","start":"2026-04-18T09:02:54.769493Z","end":"2026-04-18T09:02:54.957619Z","steps":["trace[1594744312] 'agreement among raft nodes before linearized reading' (duration: 188.02468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:02:54.962397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.132671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:02:54.962458Z","caller":"traceutil/trace.go:171","msg":"trace[666478308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"192.249341ms","start":"2026-04-18T09:02:54.770194Z","end":"2026-04-18T09:02:54.962444Z","steps":["trace[666478308] 'agreement among raft nodes before linearized reading' (duration: 192.136451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:02:55.635301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.822202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:02:55.635631Z","caller":"traceutil/trace.go:171","msg":"trace[90339725] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"432.461966ms","start":"2026-04-18T09:02:55.203134Z","end":"2026-04-18T09:02:55.635596Z","steps":["trace[90339725] 'process raft request' (duration: 257.08105ms)","trace[90339725] 'compare' (duration: 174.58196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:02:55.635796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:02:55.203115Z","time spent":"432.595347ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:02:55.635938Z","caller":"traceutil/trace.go:171","msg":"trace[769655588] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"377.03299ms","start":"2026-04-18T09:02:55.258884Z","end":"2026-04-18T09:02:55.635917Z","steps":["trace[769655588] 'process raft request' (duration: 376.688968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:02:55.636029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:02:55.258861Z","time spent":"377.127791ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:02:55.636322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.856643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T09:02:55.635812Z","caller":"traceutil/trace.go:171","msg":"trace[84048120] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"290.348929ms","start":"2026-04-18T09:02:55.345445Z","end":"2026-04-18T09:02:55.635794Z","steps":["trace[84048120] 'read index received' (duration: 114.771202ms)","trace[84048120] 'applied index is now lower than readState.Index' (duration: 175.576737ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:02:55.636767Z","caller":"traceutil/trace.go:171","msg":"trace[2015136507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"291.346877ms","start":"2026-04-18T09:02:55.345396Z","end":"2026-04-18T09:02:55.636743Z","steps":["trace[2015136507] 'agreement among raft nodes before linearized reading' (duration: 290.695892ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:03:01.185191Z","caller":"traceutil/trace.go:171","msg":"trace[1247615008] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"168.015069ms","start":"2026-04-18T09:03:01.017151Z","end":"2026-04-18T09:03:01.185166Z","steps":["trace[1247615008] 'read index received' (duration: 167.815948ms)","trace[1247615008] 'applied index is now lower than readState.Index' (duration: 197.911µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:03:01.185562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.391242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:03:01.185632Z","caller":"traceutil/trace.go:171","msg":"trace[1210107479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"168.500782ms","start":"2026-04-18T09:03:01.017114Z","end":"2026-04-18T09:03:01.185614Z","steps":["trace[1210107479] 'agreement among raft nodes before linearized reading' (duration: 168.17351ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:03:01.18595Z","caller":"traceutil/trace.go:171","msg":"trace[1067454743] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"271.210352ms","start":"2026-04-18T09:03:00.914726Z","end":"2026-04-18T09:03:01.185936Z","steps":["trace[1067454743] 'process raft request' (duration: 270.224555ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:03:01.215895Z","caller":"traceutil/trace.go:171","msg":"trace[1842668684] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"179.954706ms","start":"2026-04-18T09:03:01.035914Z","end":"2026-04-18T09:03:01.215868Z","steps":["trace[1842668684] 'process raft request' (duration: 179.855465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:01.216481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.401765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T09:03:01.21654Z","caller":"traceutil/trace.go:171","msg":"trace[1158334691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"196.497166ms","start":"2026-04-18T09:03:01.020026Z","end":"2026-04-18T09:03:01.216523Z","steps":["trace[1158334691] 'agreement among raft nodes before linearized reading' (duration: 196.328764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:02.670786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.890494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:03:02.670866Z","caller":"traceutil/trace.go:171","msg":"trace[264826894] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.4377034s","start":"2026-04-18T09:03:01.233149Z","end":"2026-04-18T09:03:02.670852Z","steps":["trace[264826894] 'process raft request' (duration: 845.684165ms)","trace[264826894] 'compare' (duration: 591.807874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:03:02.670918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:01.233137Z","time spent":"1.43775574s","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:03:02.676309Z","caller":"traceutil/trace.go:171","msg":"trace[428357203] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"1.018648919s","start":"2026-04-18T09:03:01.657634Z","end":"2026-04-18T09:03:02.676283Z","steps":["trace[428357203] 'read index received' (duration: 421.106304ms)","trace[428357203] 'applied index is now lower than readState.Index' (duration: 597.541305ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:03:02.676388Z","caller":"traceutil/trace.go:171","msg":"trace[523516654] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.027910536s","start":"2026-04-18T09:03:01.648457Z","end":"2026-04-18T09:03:02.676367Z","steps":["trace[523516654] 'process raft request' (duration: 1.027262671s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:02.676565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018901471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-18T09:03:02.676579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:01.648428Z","time spent":"1.028107217s","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:03:02.676609Z","caller":"traceutil/trace.go:171","msg":"trace[449995519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.018986622s","start":"2026-04-18T09:03:01.657612Z","end":"2026-04-18T09:03:02.676599Z","steps":["trace[449995519] 'agreement among raft nodes before linearized reading' (duration: 1.01879211s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:02.676651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:01.657604Z","time spent":"1.019037902s","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T09:03:02.686452Z","caller":"traceutil/trace.go:171","msg":"trace[1687942124] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"448.777521ms","start":"2026-04-18T09:03:02.237656Z","end":"2026-04-18T09:03:02.686434Z","steps":["trace[1687942124] 'process raft request' (duration: 448.63328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:02.686558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:02.237616Z","time spent":"448.885181ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:03:02.686616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.638722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:03:02.687838Z","caller":"traceutil/trace.go:171","msg":"trace[1718798272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"567.907482ms","start":"2026-04-18T09:03:02.119914Z","end":"2026-04-18T09:03:02.687822Z","steps":["trace[1718798272] 'agreement among raft nodes before linearized reading' (duration: 566.631122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:02.687899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:02.119892Z","time spent":"567.975242ms","remote":"127.0.0.1:34450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:03:03.093239Z","caller":"traceutil/trace.go:171","msg":"trace[1076597072] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"134.570678ms","start":"2026-04-18T09:03:02.958578Z","end":"2026-04-18T09:03:03.093148Z","steps":["trace[1076597072] 'process raft request' (duration: 128.641506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.500883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.187665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:03:04.501Z","caller":"traceutil/trace.go:171","msg":"trace[2100503504] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"484.489003ms","start":"2026-04-18T09:03:04.016493Z","end":"2026-04-18T09:03:04.500982Z","steps":["trace[2100503504] 'read index received' (duration: 268.214138ms)","trace[2100503504] 'applied index is now lower than readState.Index' (duration: 216.273535ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:03:04.501236Z","caller":"traceutil/trace.go:171","msg":"trace[839180407] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"485.470411ms","start":"2026-04-18T09:03:04.015732Z","end":"2026-04-18T09:03:04.501202Z","steps":["trace[839180407] 'process raft request' (duration: 268.889163ms)","trace[839180407] 'compare' (duration: 215.891013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:03:04.501348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.732186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:03:04.501381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:04.015724Z","time spent":"485.588811ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:03:04.501439Z","caller":"traceutil/trace.go:171","msg":"trace[1691652615] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"487.896367ms","start":"2026-04-18T09:03:04.013524Z","end":"2026-04-18T09:03:04.50142Z","steps":["trace[1691652615] 'agreement among raft nodes before linearized reading' (duration: 487.682706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.501523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:04.013509Z","time spent":"488.001989ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-18T09:03:04.501429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.990311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:03:04.501783Z","caller":"traceutil/trace.go:171","msg":"trace[420060968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"487.393895ms","start":"2026-04-18T09:03:04.014357Z","end":"2026-04-18T09:03:04.501751Z","steps":["trace[420060968] 'agreement among raft nodes before linearized reading' (duration: 486.845931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.501859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:04.01435Z","time spent":"487.495245ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-18T09:03:04.515552Z","caller":"traceutil/trace.go:171","msg":"trace[471022002] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"306.798882ms","start":"2026-04-18T09:03:04.208733Z","end":"2026-04-18T09:03:04.515531Z","steps":["trace[471022002] 'process raft request' (duration: 306.700781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.515746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:04.208717Z","time spent":"306.966063ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T09:03:04.52513Z","caller":"traceutil/trace.go:171","msg":"trace[1210735287] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"117.844413ms","start":"2026-04-18T09:03:04.40726Z","end":"2026-04-18T09:03:04.525105Z","steps":["trace[1210735287] 'process raft request' (duration: 117.622661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.525836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.959592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:03:04.525907Z","caller":"traceutil/trace.go:171","msg":"trace[585615160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"406.060762ms","start":"2026-04-18T09:03:04.119828Z","end":"2026-04-18T09:03:04.525889Z","steps":["trace[585615160] 'agreement among raft nodes before linearized reading' (duration: 405.832551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:03:04.526079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:04.119814Z","time spent":"406.252874ms","remote":"127.0.0.1:34444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:03:16.820378Z","caller":"traceutil/trace.go:171","msg":"trace[895181452] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"631.856751ms","start":"2026-04-18T09:03:16.188492Z","end":"2026-04-18T09:03:16.820348Z","steps":["trace[895181452] 'process raft request' (duration: 573.988535ms)","trace[895181452] 'compare' (duration: 57.49008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:03:16.820563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:03:16.188475Z","time spent":"631.988496ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:03:17.050332Z","caller":"traceutil/trace.go:171","msg":"trace[430494758] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"225.670985ms","start":"2026-04-18T09:03:16.824628Z","end":"2026-04-18T09:03:17.050299Z","steps":["trace[430494758] 'process raft request' (duration: 223.650662ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:03:17.060884Z","caller":"traceutil/trace.go:171","msg":"trace[1504919265] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"235.50329ms","start":"2026-04-18T09:03:16.825341Z","end":"2026-04-18T09:03:17.060844Z","steps":["trace[1504919265] 'process raft request' (duration: 235.386955ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:01.66124Z","caller":"traceutil/trace.go:171","msg":"trace[1858065825] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"132.05075ms","start":"2026-04-18T09:04:01.529161Z","end":"2026-04-18T09:04:01.661211Z","steps":["trace[1858065825] 'process raft request' (duration: 118.41822ms)","trace[1858065825] 'compare' (duration: 13.377584ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:04:44.263521Z","caller":"traceutil/trace.go:171","msg":"trace[1108991618] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"126.988493ms","start":"2026-04-18T09:04:44.136511Z","end":"2026-04-18T09:04:44.263499Z","steps":["trace[1108991618] 'process raft request' (duration: 126.83939ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:53.890849Z","caller":"traceutil/trace.go:171","msg":"trace[1325699154] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"143.086246ms","start":"2026-04-18T09:04:53.747736Z","end":"2026-04-18T09:04:53.890822Z","steps":["trace[1325699154] 'process raft request' (duration: 142.555957ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:54.360614Z","caller":"traceutil/trace.go:171","msg":"trace[179935898] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"464.207168ms","start":"2026-04-18T09:04:53.896381Z","end":"2026-04-18T09:04:54.360588Z","steps":["trace[179935898] 'process raft request' (duration: 464.053365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:54.360778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:53.896351Z","time spent":"464.34126ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:04:54.361067Z","caller":"traceutil/trace.go:171","msg":"trace[1269117793] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1293; }","duration":"449.303223ms","start":"2026-04-18T09:04:53.911735Z","end":"2026-04-18T09:04:54.361038Z","steps":["trace[1269117793] 'read index received' (duration: 449.290663ms)","trace[1269117793] 'applied index is now lower than readState.Index' (duration: 10.65µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:04:54.361388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.631569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:04:54.361434Z","caller":"traceutil/trace.go:171","msg":"trace[1252288530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"449.7429ms","start":"2026-04-18T09:04:53.911681Z","end":"2026-04-18T09:04:54.361424Z","steps":["trace[1252288530] 'agreement among raft nodes before linearized reading' (duration: 449.530827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:54.361475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:53.91166Z","time spent":"449.807742ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:04:54.362028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.678799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:04:54.362445Z","caller":"traceutil/trace.go:171","msg":"trace[1795556963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"371.162277ms","start":"2026-04-18T09:04:53.991254Z","end":"2026-04-18T09:04:54.362417Z","steps":["trace[1795556963] 'agreement among raft nodes before linearized reading' (duration: 370.555847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:54.362527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:53.991232Z","time spent":"371.269819ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T09:04:54.362262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.981994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:04:54.362807Z","caller":"traceutil/trace.go:171","msg":"trace[1966657645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"241.575565ms","start":"2026-04-18T09:04:54.121216Z","end":"2026-04-18T09:04:54.362791Z","steps":["trace[1966657645] 'agreement among raft nodes before linearized reading' (duration: 241.017225ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:54.530211Z","caller":"traceutil/trace.go:171","msg":"trace[404562865] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"160.413065ms","start":"2026-04-18T09:04:54.36977Z","end":"2026-04-18T09:04:54.530183Z","steps":["trace[404562865] 'process raft request' (duration: 160.278793ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:54.530241Z","caller":"traceutil/trace.go:171","msg":"trace[1764799008] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"164.023837ms","start":"2026-04-18T09:04:54.366184Z","end":"2026-04-18T09:04:54.530207Z","steps":["trace[1764799008] 'process raft request' (duration: 67.272013ms)","trace[1764799008] 'compare' (duration: 96.433748ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:04:54.530282Z","caller":"traceutil/trace.go:171","msg":"trace[1865733816] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"111.465854ms","start":"2026-04-18T09:04:54.418791Z","end":"2026-04-18T09:04:54.530257Z","steps":["trace[1865733816] 'process raft request' (duration: 111.354132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:55.182906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.424102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:04:55.183017Z","caller":"traceutil/trace.go:171","msg":"trace[1708501886] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"542.443837ms","start":"2026-04-18T09:04:54.640558Z","end":"2026-04-18T09:04:55.183001Z","steps":["trace[1708501886] 'read index received' (duration: 22.051µs)","trace[1708501886] 'applied index is now lower than readState.Index' (duration: 542.420616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:04:55.183315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.732262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38560a65-cb5a-431d-9b0e-215085b1fb2f\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-18T09:04:55.183414Z","caller":"traceutil/trace.go:171","msg":"trace[1662796403] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38560a65-cb5a-431d-9b0e-215085b1fb2f; range_end:; response_count:1; response_revision:1247; }","duration":"542.871684ms","start":"2026-04-18T09:04:54.64052Z","end":"2026-04-18T09:04:55.183392Z","steps":["trace[1662796403] 'agreement among raft nodes before linearized reading' (duration: 542.582799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:55.183464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:54.640502Z","time spent":"542.949456ms","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38560a65-cb5a-431d-9b0e-215085b1fb2f\" "} {"level":"info","ts":"2026-04-18T09:04:55.183567Z","caller":"traceutil/trace.go:171","msg":"trace[333319919] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"636.015696ms","start":"2026-04-18T09:04:54.547526Z","end":"2026-04-18T09:04:55.183541Z","steps":["trace[333319919] 'process raft request' (duration: 87.859423ms)","trace[333319919] 'compare' (duration: 547.136356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:04:55.18372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:54.547496Z","time spent":"636.126488ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:04:55.847002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.724586ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-18T09:04:55.847118Z","caller":"traceutil/trace.go:171","msg":"trace[1576125793] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"457.490894ms","start":"2026-04-18T09:04:55.389608Z","end":"2026-04-18T09:04:55.847099Z","steps":["trace[1576125793] 'read index received' (duration: 27.701717ms)","trace[1576125793] 'applied index is now lower than readState.Index' (duration: 429.787527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:04:55.847284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.668558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T09:04:55.847321Z","caller":"traceutil/trace.go:171","msg":"trace[2070789440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"457.777739ms","start":"2026-04-18T09:04:55.389532Z","end":"2026-04-18T09:04:55.84731Z","steps":["trace[2070789440] 'agreement among raft nodes before linearized reading' (duration: 457.615437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:55.847359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:55.389495Z","time spent":"457.8556ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-18T09:04:55.996165Z","caller":"traceutil/trace.go:171","msg":"trace[1105350952] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"100.374445ms","start":"2026-04-18T09:04:55.895746Z","end":"2026-04-18T09:04:55.996121Z","steps":["trace[1105350952] 'read index received' (duration: 100.0572ms)","trace[1105350952] 'applied index is now lower than readState.Index' (duration: 314.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:04:55.996222Z","caller":"traceutil/trace.go:171","msg":"trace[1047074332] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"143.804108ms","start":"2026-04-18T09:04:55.852385Z","end":"2026-04-18T09:04:55.996189Z","steps":["trace[1047074332] 'process raft request' (duration: 143.32361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:55.996417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.655479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T09:04:55.996502Z","caller":"traceutil/trace.go:171","msg":"trace[385913806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"100.788622ms","start":"2026-04-18T09:04:55.895694Z","end":"2026-04-18T09:04:55.996483Z","steps":["trace[385913806] 'agreement among raft nodes before linearized reading' (duration: 100.510437ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:01.246961Z","caller":"traceutil/trace.go:171","msg":"trace[1364503721] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"126.432439ms","start":"2026-04-18T09:05:01.120502Z","end":"2026-04-18T09:05:01.246935Z","steps":["trace[1364503721] 'read index received' (duration: 126.389748ms)","trace[1364503721] 'applied index is now lower than readState.Index' (duration: 41.611µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:01.24713Z","caller":"traceutil/trace.go:171","msg":"trace[1154913391] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"162.588948ms","start":"2026-04-18T09:05:01.08448Z","end":"2026-04-18T09:05:01.247069Z","steps":["trace[1154913391] 'process raft request' (duration: 162.301843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:01.24717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.630773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:01.247558Z","caller":"traceutil/trace.go:171","msg":"trace[2081385799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"127.036999ms","start":"2026-04-18T09:05:01.120457Z","end":"2026-04-18T09:05:01.247494Z","steps":["trace[2081385799] 'agreement among raft nodes before linearized reading' (duration: 126.589232ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:02.137753Z","caller":"traceutil/trace.go:171","msg":"trace[1859300920] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"109.220028ms","start":"2026-04-18T09:05:02.028465Z","end":"2026-04-18T09:05:02.137685Z","steps":["trace[1859300920] 'process raft request' (duration: 109.030655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:02.676723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.088988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:05:02.676906Z","caller":"traceutil/trace.go:171","msg":"trace[1269236412] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"107.580102ms","start":"2026-04-18T09:05:02.569299Z","end":"2026-04-18T09:05:02.676879Z","steps":["trace[1269236412] 'read index received' (duration: 82.742µs)","trace[1269236412] 'applied index is now lower than readState.Index' (duration: 107.49467ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:02.676969Z","caller":"traceutil/trace.go:171","msg":"trace[1558376420] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"222.568013ms","start":"2026-04-18T09:05:02.454364Z","end":"2026-04-18T09:05:02.676932Z","steps":["trace[1558376420] 'process raft request' (duration: 74.133839ms)","trace[1558376420] 'compare' (duration: 147.773033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:02.677227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.915657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:05:02.677342Z","caller":"traceutil/trace.go:171","msg":"trace[1335568589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"108.077469ms","start":"2026-04-18T09:05:02.569198Z","end":"2026-04-18T09:05:02.677275Z","steps":["trace[1335568589] 'agreement among raft nodes before linearized reading' (duration: 107.764914ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:03.366189Z","caller":"traceutil/trace.go:171","msg":"trace[36649655] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"100.102162ms","start":"2026-04-18T09:05:03.266062Z","end":"2026-04-18T09:05:03.366164Z","steps":["trace[36649655] 'process raft request' (duration: 99.904219ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:04.627534Z","caller":"traceutil/trace.go:171","msg":"trace[1021568521] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"177.918319ms","start":"2026-04-18T09:05:04.449596Z","end":"2026-04-18T09:05:04.627514Z","steps":["trace[1021568521] 'process raft request' (duration: 177.814527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:04.931201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.13445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:05:04.931268Z","caller":"traceutil/trace.go:171","msg":"trace[1494435467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"166.237881ms","start":"2026-04-18T09:05:04.765015Z","end":"2026-04-18T09:05:04.931253Z","steps":["trace[1494435467] 'range keys from in-memory index tree' (duration: 165.898796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:04.931262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.332123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:05:04.931364Z","caller":"traceutil/trace.go:171","msg":"trace[101285602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"166.437194ms","start":"2026-04-18T09:05:04.76489Z","end":"2026-04-18T09:05:04.931327Z","steps":["trace[101285602] 'range keys from in-memory index tree' (duration: 166.015758ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:06.822477Z","caller":"traceutil/trace.go:171","msg":"trace[1995076545] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"186.810088ms","start":"2026-04-18T09:05:06.635635Z","end":"2026-04-18T09:05:06.822446Z","steps":["trace[1995076545] 'process raft request' (duration: 186.644235ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:07.124357Z","caller":"traceutil/trace.go:171","msg":"trace[2042690938] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"118.12422ms","start":"2026-04-18T09:05:07.006212Z","end":"2026-04-18T09:05:07.124336Z","steps":["trace[2042690938] 'process raft request' (duration: 117.985817ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:07.490554Z","caller":"traceutil/trace.go:171","msg":"trace[567185623] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"370.588035ms","start":"2026-04-18T09:05:07.119944Z","end":"2026-04-18T09:05:07.490532Z","steps":["trace[567185623] 'read index received' (duration: 4.287838ms)","trace[567185623] 'applied index is now lower than readState.Index' (duration: 366.298787ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:07.490644Z","caller":"traceutil/trace.go:171","msg":"trace[627511067] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"484.117672ms","start":"2026-04-18T09:05:07.006492Z","end":"2026-04-18T09:05:07.49061Z","steps":["trace[627511067] 'process raft request' (duration: 483.533153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:07.490702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.743987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:07.491267Z","caller":"traceutil/trace.go:171","msg":"trace[1992164456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"371.330076ms","start":"2026-04-18T09:05:07.119908Z","end":"2026-04-18T09:05:07.491238Z","steps":["trace[1992164456] 'agreement among raft nodes before linearized reading' (duration: 370.743227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:07.49117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:07.006481Z","time spent":"484.536799ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:07.491401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:07.119893Z","time spent":"371.477728ms","remote":"127.0.0.1:34450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:05:07.493487Z","caller":"traceutil/trace.go:171","msg":"trace[372632346] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"113.281673ms","start":"2026-04-18T09:05:07.380188Z","end":"2026-04-18T09:05:07.49347Z","steps":["trace[372632346] 'process raft request' (duration: 113.06291ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:08.340606Z","caller":"traceutil/trace.go:171","msg":"trace[408431965] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"141.484575ms","start":"2026-04-18T09:05:08.199091Z","end":"2026-04-18T09:05:08.340576Z","steps":["trace[408431965] 'process raft request' (duration: 54.099837ms)","trace[408431965] 'compare' (duration: 87.270656ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:14.59618Z","caller":"traceutil/trace.go:171","msg":"trace[1636016572] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"230.720901ms","start":"2026-04-18T09:05:14.365441Z","end":"2026-04-18T09:05:14.596162Z","steps":["trace[1636016572] 'read index received' (duration: 230.632109ms)","trace[1636016572] 'applied index is now lower than readState.Index' (duration: 88.002µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:14.596236Z","caller":"traceutil/trace.go:171","msg":"trace[348225924] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"373.182061ms","start":"2026-04-18T09:05:14.223023Z","end":"2026-04-18T09:05:14.596205Z","steps":["trace[348225924] 'process raft request' (duration: 373.011079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:14.596386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.922514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T09:05:14.596478Z","caller":"traceutil/trace.go:171","msg":"trace[389639456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1333; }","duration":"231.061955ms","start":"2026-04-18T09:05:14.365391Z","end":"2026-04-18T09:05:14.596453Z","steps":["trace[389639456] 'agreement among raft nodes before linearized reading' (duration: 230.838242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:14.596478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:14.223012Z","time spent":"373.297124ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:19.465765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.181916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-18T09:05:19.465846Z","caller":"traceutil/trace.go:171","msg":"trace[1849123769] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1353; }","duration":"107.340059ms","start":"2026-04-18T09:05:19.358489Z","end":"2026-04-18T09:05:19.465829Z","steps":["trace[1849123769] 'range keys from in-memory index tree' (duration: 106.980333ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:20.097699Z","caller":"traceutil/trace.go:171","msg":"trace[1498608489] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"154.003282ms","start":"2026-04-18T09:05:19.943661Z","end":"2026-04-18T09:05:20.097665Z","steps":["trace[1498608489] 'process raft request' (duration: 70.557713ms)","trace[1498608489] 'compare' (duration: 83.183075ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:25.119589Z","caller":"traceutil/trace.go:171","msg":"trace[16719199] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"133.458932ms","start":"2026-04-18T09:05:24.986095Z","end":"2026-04-18T09:05:25.119554Z","steps":["trace[16719199] 'process raft request' (duration: 133.208958ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:25.446763Z","caller":"traceutil/trace.go:171","msg":"trace[1822086006] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"172.061426ms","start":"2026-04-18T09:05:25.274676Z","end":"2026-04-18T09:05:25.446737Z","steps":["trace[1822086006] 'process raft request' (duration: 163.914469ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:25.446792Z","caller":"traceutil/trace.go:171","msg":"trace[370876695] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"121.64177ms","start":"2026-04-18T09:05:25.325091Z","end":"2026-04-18T09:05:25.446733Z","steps":["trace[370876695] 'read index received' (duration: 112.744792ms)","trace[370876695] 'applied index is now lower than readState.Index' (duration: 8.895698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:25.447217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.049874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-04-18T09:05:25.447279Z","caller":"traceutil/trace.go:171","msg":"trace[1496799589] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1374; }","duration":"117.256106ms","start":"2026-04-18T09:05:25.330003Z","end":"2026-04-18T09:05:25.447259Z","steps":["trace[1496799589] 'agreement among raft nodes before linearized reading' (duration: 116.994133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.447615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.497622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1102"} {"level":"info","ts":"2026-04-18T09:05:25.44768Z","caller":"traceutil/trace.go:171","msg":"trace[1762496426] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:1374; }","duration":"122.613394ms","start":"2026-04-18T09:05:25.32505Z","end":"2026-04-18T09:05:25.447663Z","steps":["trace[1762496426] 'agreement among raft nodes before linearized reading' (duration: 121.792832ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:37.559881Z","caller":"traceutil/trace.go:171","msg":"trace[1867025853] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"181.598525ms","start":"2026-04-18T09:05:37.378257Z","end":"2026-04-18T09:05:37.559855Z","steps":["trace[1867025853] 'read index received' (duration: 181.529074ms)","trace[1867025853] 'applied index is now lower than readState.Index' (duration: 68.161µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:37.559971Z","caller":"traceutil/trace.go:171","msg":"trace[1899279618] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"184.829188ms","start":"2026-04-18T09:05:37.375083Z","end":"2026-04-18T09:05:37.559912Z","steps":["trace[1899279618] 'process raft request' (duration: 184.652436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:37.560151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.628339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"warn","ts":"2026-04-18T09:05:37.560226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.724742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-04-18T09:05:37.56024Z","caller":"traceutil/trace.go:171","msg":"trace[196818891] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1414; }","duration":"147.748041ms","start":"2026-04-18T09:05:37.412471Z","end":"2026-04-18T09:05:37.560219Z","steps":["trace[196818891] 'agreement among raft nodes before linearized reading' (duration: 147.60879ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:37.560281Z","caller":"traceutil/trace.go:171","msg":"trace[1346668847] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1414; }","duration":"142.815704ms","start":"2026-04-18T09:05:37.417451Z","end":"2026-04-18T09:05:37.560266Z","steps":["trace[1346668847] 'agreement among raft nodes before linearized reading' (duration: 142.654612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:37.560281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.518895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:11 size:5916"} {"level":"info","ts":"2026-04-18T09:05:37.56036Z","caller":"traceutil/trace.go:171","msg":"trace[1572090683] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:11; response_revision:1414; }","duration":"127.596686ms","start":"2026-04-18T09:05:37.432725Z","end":"2026-04-18T09:05:37.560321Z","steps":["trace[1572090683] 'agreement among raft nodes before linearized reading' (duration: 127.387653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:37.560076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.796927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-04-18T09:05:37.561032Z","caller":"traceutil/trace.go:171","msg":"trace[235404620] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1414; }","duration":"182.77233ms","start":"2026-04-18T09:05:37.378226Z","end":"2026-04-18T09:05:37.560998Z","steps":["trace[235404620] 'agreement among raft nodes before linearized reading' (duration: 181.769896ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:41.336899Z","caller":"traceutil/trace.go:171","msg":"trace[182298394] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"118.198482ms","start":"2026-04-18T09:05:41.21868Z","end":"2026-04-18T09:05:41.336878Z","steps":["trace[182298394] 'read index received' (duration: 117.939679ms)","trace[182298394] 'applied index is now lower than readState.Index' (duration: 257.443µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:41.337076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.371965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:618"} {"level":"info","ts":"2026-04-18T09:05:41.337168Z","caller":"traceutil/trace.go:171","msg":"trace[375390226] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1426; }","duration":"118.501706ms","start":"2026-04-18T09:05:41.21864Z","end":"2026-04-18T09:05:41.337142Z","steps":["trace[375390226] 'agreement among raft nodes before linearized reading' (duration: 118.332404ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:41.336956Z","caller":"traceutil/trace.go:171","msg":"trace[2030207049] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"119.670262ms","start":"2026-04-18T09:05:41.217253Z","end":"2026-04-18T09:05:41.336924Z","steps":["trace[2030207049] 'process raft request' (duration: 119.460969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:41.338035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.262008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:05:41.338134Z","caller":"traceutil/trace.go:171","msg":"trace[1239505884] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1426; }","duration":"109.037449ms","start":"2026-04-18T09:05:41.229056Z","end":"2026-04-18T09:05:41.338093Z","steps":["trace[1239505884] 'agreement among raft nodes before linearized reading' (duration: 108.186527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:42.602479Z","caller":"traceutil/trace.go:171","msg":"trace[2018056286] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"316.161263ms","start":"2026-04-18T09:05:42.286295Z","end":"2026-04-18T09:05:42.602456Z","steps":["trace[2018056286] 'read index received' (duration: 315.94098ms)","trace[2018056286] 'applied index is now lower than readState.Index' (duration: 219.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:42.602615Z","caller":"traceutil/trace.go:171","msg":"trace[214516660] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"325.999656ms","start":"2026-04-18T09:05:42.276576Z","end":"2026-04-18T09:05:42.602576Z","steps":["trace[214516660] 'process raft request' (duration: 325.739572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:42.602789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.460687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-04-18T09:05:42.602929Z","caller":"traceutil/trace.go:171","msg":"trace[1126692182] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:1430; }","duration":"316.64911ms","start":"2026-04-18T09:05:42.286257Z","end":"2026-04-18T09:05:42.602906Z","steps":["trace[1126692182] 'agreement among raft nodes before linearized reading' (duration: 316.395697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:42.602936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.089738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:42.602983Z","caller":"traceutil/trace.go:171","msg":"trace[1323061272] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1430; }","duration":"195.20656ms","start":"2026-04-18T09:05:42.407763Z","end":"2026-04-18T09:05:42.60297Z","steps":["trace[1323061272] 'agreement among raft nodes before linearized reading' (duration: 195.107909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:42.602986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:42.28624Z","time spent":"316.729401ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"warn","ts":"2026-04-18T09:05:42.603309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:42.276565Z","time spent":"326.646904ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:42.603418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.730388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:88 size:65611"} {"level":"info","ts":"2026-04-18T09:05:42.60379Z","caller":"traceutil/trace.go:171","msg":"trace[985824058] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:88; response_revision:1430; }","duration":"313.044792ms","start":"2026-04-18T09:05:42.290628Z","end":"2026-04-18T09:05:42.603673Z","steps":["trace[985824058] 'agreement among raft nodes before linearized reading' (duration: 312.215571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:42.603952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:42.290618Z","time spent":"313.229934ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":88,"response size":65635,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "}