{"level":"warn","ts":"2026-07-21T20:52:52.105512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:52:52.105617Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222: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.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-07-21T20:52:52.105702Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:52:52.105724Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-21T20:52:52.105755Z","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-07-21T20:52:52.107043Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-07-21T20:52:52.107135Z","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.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-07-21T20:52:52.113806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.402251ms"} {"level":"info","ts":"2026-07-21T20:52:52.123397Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-07-21T20:52:52.123489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:52:52.123524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-07-21T20:52:52.123537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:52:52.12355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-07-21T20:52:52.123589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-07-21T20:52:52.137668Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:52:52.139047Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:52:52.14063Z","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-07-21T20:52:52.143937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:52:52.144159Z","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-07-21T20:52:52.144204Z","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-07-21T20:52:52.144215Z","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-07-21T20:52:52.144175Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:52:52.146108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-07-21T20:52:52.146258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-21T20:52:52.147905Z","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-07-21T20:52:52.148103Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:52:52.148128Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:52:52.148227Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-21T20:52:52.148239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-21T20:52:52.926894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:52:52.926959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:52:52.926991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-07-21T20:52:52.927006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:52:52.927017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-21T20:52:52.927028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-07-21T20:52:52.927042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-21T20:52:52.927997Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:52:52.928067Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:52:52.928132Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:52:52.928191Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:52:52.92897Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:52:52.929052Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:52:52.929075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:52:52.930187Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-07-21T20:52:52.930847Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:52:52.930876Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:52:52.930999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:52:54.38243Z","caller":"traceutil/trace.go:171","msg":"trace[1862014409] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"153.809718ms","start":"2026-07-21T20:52:54.228599Z","end":"2026-07-21T20:52:54.382408Z","steps":["trace[1862014409] 'process raft request' (duration: 151.659789ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.382505Z","caller":"traceutil/trace.go:171","msg":"trace[496469863] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"150.432665ms","start":"2026-07-21T20:52:54.232033Z","end":"2026-07-21T20:52:54.382466Z","steps":["trace[496469863] 'process raft request' (duration: 150.284141ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.38265Z","caller":"traceutil/trace.go:171","msg":"trace[1028284938] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"152.664666ms","start":"2026-07-21T20:52:54.229977Z","end":"2026-07-21T20:52:54.382642Z","steps":["trace[1028284938] 'process raft request' (duration: 152.262116ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.382387Z","caller":"traceutil/trace.go:171","msg":"trace[528784719] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"153.688635ms","start":"2026-07-21T20:52:54.228672Z","end":"2026-07-21T20:52:54.382361Z","steps":["trace[528784719] 'process raft request' (duration: 153.50295ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.38287Z","caller":"traceutil/trace.go:171","msg":"trace[1499259874] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"131.592625ms","start":"2026-07-21T20:52:54.251241Z","end":"2026-07-21T20:52:54.382833Z","steps":["trace[1499259874] 'process raft request' (duration: 131.138072ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.38258Z","caller":"traceutil/trace.go:171","msg":"trace[1324632762] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"153.356555ms","start":"2026-07-21T20:52:54.229185Z","end":"2026-07-21T20:52:54.382542Z","steps":["trace[1324632762] 'process raft request' (duration: 153.028446ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.382504Z","caller":"traceutil/trace.go:171","msg":"trace[596551901] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"152.238375ms","start":"2026-07-21T20:52:54.230205Z","end":"2026-07-21T20:52:54.382443Z","steps":["trace[596551901] 'process raft request' (duration: 152.077211ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.383144Z","caller":"traceutil/trace.go:171","msg":"trace[34879752] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"111.483579ms","start":"2026-07-21T20:52:54.271627Z","end":"2026-07-21T20:52:54.38311Z","steps":["trace[34879752] 'read index received' (duration: 108.63433ms)","trace[34879752] 'applied index is now lower than readState.Index' (duration: 2.847909ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:54.383285Z","caller":"traceutil/trace.go:171","msg":"trace[1952466585] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"110.81689ms","start":"2026-07-21T20:52:54.272455Z","end":"2026-07-21T20:52:54.383271Z","steps":["trace[1952466585] 'process raft request' (duration: 110.688217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:54.383486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.497761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.222\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-21T20:52:54.383569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.209176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-21T20:52:54.383576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.394564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:52:54.383606Z","caller":"traceutil/trace.go:171","msg":"trace[440469996] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"197.245718ms","start":"2026-07-21T20:52:54.186344Z","end":"2026-07-21T20:52:54.38359Z","steps":["trace[440469996] 'agreement among raft nodes before linearized reading' (duration: 197.205627ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.383638Z","caller":"traceutil/trace.go:171","msg":"trace[1044401141] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"150.453746ms","start":"2026-07-21T20:52:54.233164Z","end":"2026-07-21T20:52:54.383618Z","steps":["trace[1044401141] 'agreement among raft nodes before linearized reading' (duration: 150.341383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:54.383675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.201569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-21T20:52:54.383607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.498202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:52:54.383721Z","caller":"traceutil/trace.go:171","msg":"trace[814791160] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"154.26013ms","start":"2026-07-21T20:52:54.229447Z","end":"2026-07-21T20:52:54.383707Z","steps":["trace[814791160] 'agreement among raft nodes before linearized reading' (duration: 154.186868ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.383825Z","caller":"traceutil/trace.go:171","msg":"trace[1489469573] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"120.164149ms","start":"2026-07-21T20:52:54.263648Z","end":"2026-07-21T20:52:54.383812Z","steps":["trace[1489469573] 'process raft request' (duration: 119.348746ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.383816Z","caller":"traceutil/trace.go:171","msg":"trace[483034966] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"156.721528ms","start":"2026-07-21T20:52:54.227034Z","end":"2026-07-21T20:52:54.383755Z","steps":["trace[483034966] 'agreement among raft nodes before linearized reading' (duration: 156.309617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:54.383833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.860604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:52:54.383878Z","caller":"traceutil/trace.go:171","msg":"trace[112648851] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"155.924806ms","start":"2026-07-21T20:52:54.227944Z","end":"2026-07-21T20:52:54.383869Z","steps":["trace[112648851] 'agreement among raft nodes before linearized reading' (duration: 155.865104ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.38358Z","caller":"traceutil/trace.go:171","msg":"trace[1534454111] range","detail":"{range_begin:/registry/masterleases/199.19.213.222; range_end:; response_count:0; response_revision:11; }","duration":"157.619723ms","start":"2026-07-21T20:52:54.225943Z","end":"2026-07-21T20:52:54.383562Z","steps":["trace[1534454111] 'agreement among raft nodes before linearized reading' (duration: 157.427108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:54.384037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.797305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-21T20:52:54.383513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.422964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:52:54.384089Z","caller":"traceutil/trace.go:171","msg":"trace[1777938474] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"150.864737ms","start":"2026-07-21T20:52:54.233213Z","end":"2026-07-21T20:52:54.384078Z","steps":["trace[1777938474] 'agreement among raft nodes before linearized reading' (duration: 150.655172ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.384095Z","caller":"traceutil/trace.go:171","msg":"trace[219079619] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"105.043981ms","start":"2026-07-21T20:52:54.279039Z","end":"2026-07-21T20:52:54.384083Z","steps":["trace[219079619] 'agreement among raft nodes before linearized reading' (duration: 104.352393ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.655919Z","caller":"traceutil/trace.go:171","msg":"trace[515829109] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"109.472463ms","start":"2026-07-21T20:52:54.546409Z","end":"2026-07-21T20:52:54.655881Z","steps":["trace[515829109] 'process raft request' (duration: 98.1464ms)","trace[515829109] 'compare' (duration: 11.143058ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:54.842455Z","caller":"traceutil/trace.go:171","msg":"trace[1348397628] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"120.616122ms","start":"2026-07-21T20:52:54.721821Z","end":"2026-07-21T20:52:54.842437Z","steps":["trace[1348397628] 'process raft request' (duration: 120.574141ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:54.842552Z","caller":"traceutil/trace.go:171","msg":"trace[154833469] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"126.496454ms","start":"2026-07-21T20:52:54.716024Z","end":"2026-07-21T20:52:54.842521Z","steps":["trace[154833469] 'process raft request' (duration: 116.286482ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:55.720832Z","caller":"traceutil/trace.go:171","msg":"trace[1475064939] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"114.321893ms","start":"2026-07-21T20:52:55.606487Z","end":"2026-07-21T20:52:55.720809Z","steps":["trace[1475064939] 'process raft request' (duration: 41.75164ms)","trace[1475064939] 'compare' (duration: 72.44546ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:57.719221Z","caller":"traceutil/trace.go:171","msg":"trace[1704047580] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"166.843993ms","start":"2026-07-21T20:52:57.552347Z","end":"2026-07-21T20:52:57.719191Z","steps":["trace[1704047580] 'process raft request' (duration: 124.09501ms)","trace[1704047580] 'compare' (duration: 42.454536ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:59.778868Z","caller":"traceutil/trace.go:171","msg":"trace[196904650] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"106.063339ms","start":"2026-07-21T20:52:59.672782Z","end":"2026-07-21T20:52:59.778845Z","steps":["trace[196904650] 'process raft request' (duration: 105.968446ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:59.847953Z","caller":"traceutil/trace.go:171","msg":"trace[1215052868] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"174.922142ms","start":"2026-07-21T20:52:59.673003Z","end":"2026-07-21T20:52:59.847925Z","steps":["trace[1215052868] 'process raft request' (duration: 174.768027ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:52:59.847987Z","caller":"traceutil/trace.go:171","msg":"trace[1222887121] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"143.677854ms","start":"2026-07-21T20:52:59.704282Z","end":"2026-07-21T20:52:59.84796Z","steps":["trace[1222887121] 'read index received' (duration: 74.531212ms)","trace[1222887121] 'applied index is now lower than readState.Index' (duration: 69.145742ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:52:59.848075Z","caller":"traceutil/trace.go:171","msg":"trace[801434430] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"162.556698ms","start":"2026-07-21T20:52:59.68551Z","end":"2026-07-21T20:52:59.848067Z","steps":["trace[801434430] 'process raft request' (duration: 162.355433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:52:59.848211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.92622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-21T20:52:59.848237Z","caller":"traceutil/trace.go:171","msg":"trace[1560259044] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"143.993362ms","start":"2026-07-21T20:52:59.704237Z","end":"2026-07-21T20:52:59.84823Z","steps":["trace[1560259044] 'agreement among raft nodes before linearized reading' (duration: 143.939521ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:15.233257Z","caller":"traceutil/trace.go:171","msg":"trace[1198770309] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"123.574789ms","start":"2026-07-21T20:53:15.109614Z","end":"2026-07-21T20:53:15.233189Z","steps":["trace[1198770309] 'read index received' (duration: 123.370854ms)","trace[1198770309] 'applied index is now lower than readState.Index' (duration: 203.175µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:15.233335Z","caller":"traceutil/trace.go:171","msg":"trace[911459208] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"226.573528ms","start":"2026-07-21T20:53:15.006647Z","end":"2026-07-21T20:53:15.233221Z","steps":["trace[911459208] 'process raft request' (duration: 226.396265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:15.233368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.722602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:15.233511Z","caller":"traceutil/trace.go:171","msg":"trace[1737086904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"123.870504ms","start":"2026-07-21T20:53:15.109574Z","end":"2026-07-21T20:53:15.233445Z","steps":["trace[1737086904] 'agreement among raft nodes before linearized reading' (duration: 123.701401ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:20.810155Z","caller":"traceutil/trace.go:171","msg":"trace[1925106589] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"136.122409ms","start":"2026-07-21T20:53:20.673994Z","end":"2026-07-21T20:53:20.810117Z","steps":["trace[1925106589] 'process raft request' (duration: 135.946165ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:24.078244Z","caller":"traceutil/trace.go:171","msg":"trace[371172873] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"107.661288ms","start":"2026-07-21T20:53:23.970564Z","end":"2026-07-21T20:53:24.078225Z","steps":["trace[371172873] 'process raft request' (duration: 107.562656ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:24.108049Z","caller":"traceutil/trace.go:171","msg":"trace[2144405306] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"135.360556ms","start":"2026-07-21T20:53:23.972661Z","end":"2026-07-21T20:53:24.108022Z","steps":["trace[2144405306] 'process raft request' (duration: 135.250074ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:26.541757Z","caller":"traceutil/trace.go:171","msg":"trace[42976446] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"429.630028ms","start":"2026-07-21T20:53:26.1121Z","end":"2026-07-21T20:53:26.54173Z","steps":["trace[42976446] 'read index received' (duration: 429.337792ms)","trace[42976446] 'applied index is now lower than readState.Index' (duration: 290.796µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:26.541952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.837892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:53:26.541993Z","caller":"traceutil/trace.go:171","msg":"trace[2043523860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"429.945194ms","start":"2026-07-21T20:53:26.112037Z","end":"2026-07-21T20:53:26.541982Z","steps":["trace[2043523860] 'agreement among raft nodes before linearized reading' (duration: 429.800941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:26.542035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:26.112003Z","time spent":"430.023716ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T20:53:26.542397Z","caller":"traceutil/trace.go:171","msg":"trace[34063137] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"456.575542ms","start":"2026-07-21T20:53:26.085791Z","end":"2026-07-21T20:53:26.542366Z","steps":["trace[34063137] 'process raft request' (duration: 455.749496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:26.543141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:26.085779Z","time spent":"456.809336ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:31.520009Z","caller":"traceutil/trace.go:171","msg":"trace[1512254961] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"664.239694ms","start":"2026-07-21T20:53:30.855745Z","end":"2026-07-21T20:53:31.519985Z","steps":["trace[1512254961] 'process raft request' (duration: 664.076441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.520179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.855714Z","time spent":"664.388897ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:31.865688Z","caller":"traceutil/trace.go:171","msg":"trace[1128534454] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"927.77008ms","start":"2026-07-21T20:53:30.937882Z","end":"2026-07-21T20:53:31.865652Z","steps":["trace[1128534454] 'process raft request' (duration: 927.676719ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.865821Z","caller":"traceutil/trace.go:171","msg":"trace[185692431] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"920.728243ms","start":"2026-07-21T20:53:30.945074Z","end":"2026-07-21T20:53:31.865802Z","steps":["trace[185692431] 'process raft request' (duration: 920.596981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.865844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.937864Z","time spent":"927.907885ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.86591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.945052Z","time spent":"920.821165ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:31.866011Z","caller":"traceutil/trace.go:171","msg":"trace[251337536] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"913.980542ms","start":"2026-07-21T20:53:30.952015Z","end":"2026-07-21T20:53:31.865996Z","steps":["trace[251337536] 'process raft request' (duration: 913.741097ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.86607Z","caller":"traceutil/trace.go:171","msg":"trace[765114163] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"926.993296ms","start":"2026-07-21T20:53:30.939064Z","end":"2026-07-21T20:53:31.866058Z","steps":["trace[765114163] 'process raft request' (duration: 926.534827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.866089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.952005Z","time spent":"914.049283ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.86612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.939034Z","time spent":"927.061928ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:31.86611Z","caller":"traceutil/trace.go:171","msg":"trace[506236416] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"913.185697ms","start":"2026-07-21T20:53:30.952865Z","end":"2026-07-21T20:53:31.86605Z","steps":["trace[506236416] 'process raft request' (duration: 912.973893ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.866199Z","caller":"traceutil/trace.go:171","msg":"trace[108008650] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"913.568645ms","start":"2026-07-21T20:53:30.952622Z","end":"2026-07-21T20:53:31.866191Z","steps":["trace[108008650] 'process raft request' (duration: 913.178847ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.866166Z","caller":"traceutil/trace.go:171","msg":"trace[1310772427] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"886.612789ms","start":"2026-07-21T20:53:30.979492Z","end":"2026-07-21T20:53:31.866105Z","steps":["trace[1310772427] 'process raft request' (duration: 886.384304ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.866218Z","caller":"traceutil/trace.go:171","msg":"trace[1711385158] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"886.679602ms","start":"2026-07-21T20:53:30.979523Z","end":"2026-07-21T20:53:31.866203Z","steps":["trace[1711385158] 'process raft request' (duration: 886.436376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.866229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.952857Z","time spent":"913.31843ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.866249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.952597Z","time spent":"913.631196ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.866286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.9795Z","time spent":"886.755022ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:31.86621Z","caller":"traceutil/trace.go:171","msg":"trace[1741615690] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"918.151903ms","start":"2026-07-21T20:53:30.947866Z","end":"2026-07-21T20:53:31.866018Z","steps":["trace[1741615690] 'process raft request' (duration: 917.845737ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.866396Z","caller":"traceutil/trace.go:171","msg":"trace[528567450] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:507; }","duration":"553.062623ms","start":"2026-07-21T20:53:31.313314Z","end":"2026-07-21T20:53:31.866377Z","steps":["trace[528567450] 'read index received' (duration: 206.555299ms)","trace[528567450] 'applied index is now lower than readState.Index' (duration: 346.506594ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:31.866423Z","caller":"traceutil/trace.go:171","msg":"trace[1011665932] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"768.161287ms","start":"2026-07-21T20:53:31.098247Z","end":"2026-07-21T20:53:31.866408Z","steps":["trace[1011665932] 'process raft request' (duration: 768.006294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:31.866417Z","caller":"traceutil/trace.go:171","msg":"trace[1219944739] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"873.28887ms","start":"2026-07-21T20:53:30.993112Z","end":"2026-07-21T20:53:31.866401Z","steps":["trace[1219944739] 'process raft request' (duration: 872.967983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.866483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:31.09823Z","time spent":"768.230459ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.866509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.993087Z","time spent":"873.386032ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:31.866533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.215745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T20:53:31.866364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.979467Z","time spent":"886.787983ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:31.866566Z","caller":"traceutil/trace.go:171","msg":"trace[396464284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"553.289867ms","start":"2026-07-21T20:53:31.313266Z","end":"2026-07-21T20:53:31.866556Z","steps":["trace[396464284] 'agreement among raft nodes before linearized reading' (duration: 553.193515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.866616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:31.313248Z","time spent":"553.360208ms","remote":"127.0.0.1:34168","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-07-21T20:53:31.866701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.947858Z","time spent":"918.628893ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:31.865675Z","caller":"traceutil/trace.go:171","msg":"trace[1345969708] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"931.175207ms","start":"2026-07-21T20:53:30.934464Z","end":"2026-07-21T20:53:31.865639Z","steps":["trace[1345969708] 'process raft request' (duration: 930.964163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:31.867645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:30.934434Z","time spent":"933.161146ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:36.397861Z","caller":"traceutil/trace.go:171","msg":"trace[1970944750] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"501.583096ms","start":"2026-07-21T20:53:35.896252Z","end":"2026-07-21T20:53:36.397835Z","steps":["trace[1970944750] 'process raft request' (duration: 501.170349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:36.398336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:35.896236Z","time spent":"501.968473ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:36.48508Z","caller":"traceutil/trace.go:171","msg":"trace[561179632] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"373.292073ms","start":"2026-07-21T20:53:36.111762Z","end":"2026-07-21T20:53:36.485054Z","steps":["trace[561179632] 'read index received' (duration: 285.921856ms)","trace[561179632] 'applied index is now lower than readState.Index' (duration: 87.369137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:36.485199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.422565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:36.485232Z","caller":"traceutil/trace.go:171","msg":"trace[1428138350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"373.475317ms","start":"2026-07-21T20:53:36.111747Z","end":"2026-07-21T20:53:36.485223Z","steps":["trace[1428138350] 'agreement among raft nodes before linearized reading' (duration: 373.399965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:36.485268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:36.111617Z","time spent":"373.644521ms","remote":"127.0.0.1:33898","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-07-21T20:53:36.485283Z","caller":"traceutil/trace.go:171","msg":"trace[235782515] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"527.09823ms","start":"2026-07-21T20:53:35.958153Z","end":"2026-07-21T20:53:36.485251Z","steps":["trace[235782515] 'process raft request' (duration: 482.023134ms)","trace[235782515] 'compare' (duration: 44.749099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:36.486004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:35.958124Z","time spent":"527.304283ms","remote":"127.0.0.1:34168","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-07-21T20:53:36.7812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.100155ms","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-07-21T20:53:36.781288Z","caller":"traceutil/trace.go:171","msg":"trace[1023022171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"194.218738ms","start":"2026-07-21T20:53:36.587037Z","end":"2026-07-21T20:53:36.781255Z","steps":["trace[1023022171] 'range keys from in-memory index tree' (duration: 193.877802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:36.781529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.657071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:53:36.781567Z","caller":"traceutil/trace.go:171","msg":"trace[1200156618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"190.733032ms","start":"2026-07-21T20:53:36.590825Z","end":"2026-07-21T20:53:36.781558Z","steps":["trace[1200156618] 'range keys from in-memory index tree' (duration: 190.519469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:37.062103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.035765ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-21T20:53:37.329306Z","caller":"traceutil/trace.go:171","msg":"trace[1323559072] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"198.18461ms","start":"2026-07-21T20:53:37.131093Z","end":"2026-07-21T20:53:37.329277Z","steps":["trace[1323559072] 'process raft request' (duration: 106.801162ms)","trace[1323559072] 'compare' (duration: 91.263366ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:37.552195Z","caller":"traceutil/trace.go:171","msg":"trace[2076752415] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"218.877011ms","start":"2026-07-21T20:53:37.333301Z","end":"2026-07-21T20:53:37.552178Z","steps":["trace[2076752415] 'process raft request' (duration: 218.84835ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:37.552282Z","caller":"traceutil/trace.go:171","msg":"trace[961180292] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"219.025264ms","start":"2026-07-21T20:53:37.333225Z","end":"2026-07-21T20:53:37.55225Z","steps":["trace[961180292] 'process raft request' (duration: 157.580335ms)","trace[961180292] 'compare' (duration: 61.233344ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:37.859935Z","caller":"traceutil/trace.go:171","msg":"trace[1694003909] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"141.815094ms","start":"2026-07-21T20:53:37.718077Z","end":"2026-07-21T20:53:37.859892Z","steps":["trace[1694003909] 'process raft request' (duration: 140.741655ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:38.465402Z","caller":"traceutil/trace.go:171","msg":"trace[859915478] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"119.471457ms","start":"2026-07-21T20:53:38.345894Z","end":"2026-07-21T20:53:38.465366Z","steps":["trace[859915478] 'process raft request' (duration: 118.367997ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:38.770702Z","caller":"traceutil/trace.go:171","msg":"trace[680829410] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"249.208911ms","start":"2026-07-21T20:53:38.521444Z","end":"2026-07-21T20:53:38.770653Z","steps":["trace[680829410] 'process raft request' (duration: 202.033111ms)","trace[680829410] 'compare' (duration: 46.481997ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:39.357575Z","caller":"traceutil/trace.go:171","msg":"trace[703836280] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"174.174588ms","start":"2026-07-21T20:53:39.183371Z","end":"2026-07-21T20:53:39.357546Z","steps":["trace[703836280] 'process raft request' (duration: 173.554027ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:39.412957Z","caller":"traceutil/trace.go:171","msg":"trace[2051365970] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"148.079426ms","start":"2026-07-21T20:53:39.264856Z","end":"2026-07-21T20:53:39.412936Z","steps":["trace[2051365970] 'process raft request' (duration: 147.967633ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:39.53229Z","caller":"traceutil/trace.go:171","msg":"trace[1825665239] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"106.522555ms","start":"2026-07-21T20:53:39.42574Z","end":"2026-07-21T20:53:39.532262Z","steps":["trace[1825665239] 'process raft request' (duration: 44.038476ms)","trace[1825665239] 'compare' (duration: 62.369827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:40.870878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153845938s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:53:40.871501Z","caller":"traceutil/trace.go:171","msg":"trace[1370206507] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.154522029s","start":"2026-07-21T20:53:39.716952Z","end":"2026-07-21T20:53:40.871474Z","steps":["trace[1370206507] 'process raft request' (duration: 1.154247994s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:40.871808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:39.716936Z","time spent":"1.154642882s","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:41.549482Z","caller":"traceutil/trace.go:171","msg":"trace[1287359302] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"1.03899817s","start":"2026-07-21T20:53:40.510458Z","end":"2026-07-21T20:53:41.549456Z","steps":["trace[1287359302] 'read index received' (duration: 360.83008ms)","trace[1287359302] 'applied index is now lower than readState.Index' (duration: 678.1668ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:41.549517Z","caller":"traceutil/trace.go:171","msg":"trace[1707625945] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.588941315s","start":"2026-07-21T20:53:39.960536Z","end":"2026-07-21T20:53:41.549477Z","steps":["trace[1707625945] 'process raft request' (duration: 1.588005518s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.549883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.293543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:41.549972Z","caller":"traceutil/trace.go:171","msg":"trace[618648190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"439.415486ms","start":"2026-07-21T20:53:41.110541Z","end":"2026-07-21T20:53:41.549957Z","steps":["trace[618648190] 'agreement among raft nodes before linearized reading' (duration: 439.317124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.549799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039333887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T20:53:41.550042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:41.110518Z","time spent":"439.488927ms","remote":"127.0.0.1:33898","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-07-21T20:53:41.550087Z","caller":"traceutil/trace.go:171","msg":"trace[372850809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"1.039659532s","start":"2026-07-21T20:53:40.510393Z","end":"2026-07-21T20:53:41.550052Z","steps":["trace[372850809] 'agreement among raft nodes before linearized reading' (duration: 1.039192304s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.55016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.663035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:53:41.550229Z","caller":"traceutil/trace.go:171","msg":"trace[1232146276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"724.779187ms","start":"2026-07-21T20:53:40.825434Z","end":"2026-07-21T20:53:41.550213Z","steps":["trace[1232146276] 'agreement among raft nodes before linearized reading' (duration: 724.407841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.55025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:40.510367Z","time spent":"1.039849375s","remote":"127.0.0.1:34168","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-07-21T20:53:41.550302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:40.825411Z","time spent":"724.854838ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:53:41.550333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.008548ms","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-07-21T20:53:41.550415Z","caller":"traceutil/trace.go:171","msg":"trace[1255960998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"678.17589ms","start":"2026-07-21T20:53:40.872218Z","end":"2026-07-21T20:53:41.550394Z","steps":["trace[1255960998] 'agreement among raft nodes before linearized reading' (duration: 677.714762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:41.550468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:40.872199Z","time spent":"678.258412ms","remote":"127.0.0.1:34168","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-07-21T20:53:41.550162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:39.960506Z","time spent":"1.589382363s","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:41.712193Z","caller":"traceutil/trace.go:171","msg":"trace[1310445038] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"100.63527ms","start":"2026-07-21T20:53:41.611527Z","end":"2026-07-21T20:53:41.712162Z","steps":["trace[1310445038] 'process raft request' (duration: 100.585189ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.712315Z","caller":"traceutil/trace.go:171","msg":"trace[1925051728] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"155.77393ms","start":"2026-07-21T20:53:41.556502Z","end":"2026-07-21T20:53:41.712276Z","steps":["trace[1925051728] 'process raft request' (duration: 155.527106ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:41.712432Z","caller":"traceutil/trace.go:171","msg":"trace[28123766] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"156.630086ms","start":"2026-07-21T20:53:41.555707Z","end":"2026-07-21T20:53:41.712337Z","steps":["trace[28123766] 'process raft request' (duration: 73.574168ms)","trace[28123766] 'compare' (duration: 82.559788ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:41.712523Z","caller":"traceutil/trace.go:171","msg":"trace[2097156378] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"155.402764ms","start":"2026-07-21T20:53:41.557098Z","end":"2026-07-21T20:53:41.712501Z","steps":["trace[2097156378] 'process raft request' (duration: 154.977656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:43.287123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670725047231325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:53:43.788142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670725047231325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:53:44.28859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670725047231325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:53:44.789601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670725047231325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:53:44.8809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.286857725s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:53:44.882448Z","caller":"traceutil/trace.go:171","msg":"trace[1391052944] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"2.096103708s","start":"2026-07-21T20:53:42.786297Z","end":"2026-07-21T20:53:44.882401Z","steps":["trace[1391052944] 'read index received' (duration: 2.094800415s)","trace[1391052944] 'applied index is now lower than readState.Index' (duration: 1.302083ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:44.882598Z","caller":"traceutil/trace.go:171","msg":"trace[1930406292] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"2.290139053s","start":"2026-07-21T20:53:42.592448Z","end":"2026-07-21T20:53:44.882587Z","steps":["trace[1930406292] 'process raft request' (duration: 2.289063214s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.88275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:42.592419Z","time spent":"2.290218074s","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:44.883149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.096848011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24qzs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T20:53:44.883189Z","caller":"traceutil/trace.go:171","msg":"trace[449365339] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24qzs; range_end:; response_count:1; response_revision:605; }","duration":"2.096923352s","start":"2026-07-21T20:53:42.786255Z","end":"2026-07-21T20:53:44.883178Z","steps":["trace[449365339] 'agreement among raft nodes before linearized reading' (duration: 2.09683503s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.883223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:42.786234Z","time spent":"2.096982585s","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-24qzs\" "} {"level":"warn","ts":"2026-07-21T20:53:44.883575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007998613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T20:53:44.883615Z","caller":"traceutil/trace.go:171","msg":"trace[1385743515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"2.008060024s","start":"2026-07-21T20:53:42.875546Z","end":"2026-07-21T20:53:44.883606Z","steps":["trace[1385743515] 'agreement among raft nodes before linearized reading' (duration: 2.007859031s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.883644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:42.875535Z","time spent":"2.008103115s","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T20:53:44.883717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164801304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T20:53:44.88375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166514573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T20:53:44.883806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773555352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:44.883823Z","caller":"traceutil/trace.go:171","msg":"trace[1555188394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.164967466s","start":"2026-07-21T20:53:43.71883Z","end":"2026-07-21T20:53:44.883798Z","steps":["trace[1555188394] 'agreement among raft nodes before linearized reading' (duration: 1.16459403s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:44.883847Z","caller":"traceutil/trace.go:171","msg":"trace[923129203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"1.773615893s","start":"2026-07-21T20:53:43.110221Z","end":"2026-07-21T20:53:44.883837Z","steps":["trace[923129203] 'agreement among raft nodes before linearized reading' (duration: 1.773557552s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.883884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:43.718812Z","time spent":"1.165061248s","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:53:44.883899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:43.110209Z","time spent":"1.773676384s","remote":"127.0.0.1:33894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:53:44.883927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166636435s","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-07-21T20:53:44.883966Z","caller":"traceutil/trace.go:171","msg":"trace[2077822858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"1.166709037s","start":"2026-07-21T20:53:43.717248Z","end":"2026-07-21T20:53:44.883957Z","steps":["trace[2077822858] 'agreement among raft nodes before linearized reading' (duration: 1.166609105s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.884019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:43.717228Z","time spent":"1.166783168s","remote":"127.0.0.1:34168","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":"info","ts":"2026-07-21T20:53:44.883829Z","caller":"traceutil/trace.go:171","msg":"trace[305737361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.166644165s","start":"2026-07-21T20:53:43.717163Z","end":"2026-07-21T20:53:44.883807Z","steps":["trace[305737361] 'agreement among raft nodes before linearized reading' (duration: 1.166392061s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:44.884257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:43.717138Z","time spent":"1.167043233s","remote":"127.0.0.1:34168","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":"info","ts":"2026-07-21T20:53:46.272228Z","caller":"traceutil/trace.go:171","msg":"trace[208432045] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"161.220489ms","start":"2026-07-21T20:53:46.110986Z","end":"2026-07-21T20:53:46.272207Z","steps":["trace[208432045] 'read index received' (duration: 161.134277ms)","trace[208432045] 'applied index is now lower than readState.Index' (duration: 85.402µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:46.272339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.350321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:46.272364Z","caller":"traceutil/trace.go:171","msg":"trace[902432370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"161.418312ms","start":"2026-07-21T20:53:46.110937Z","end":"2026-07-21T20:53:46.272355Z","steps":["trace[902432370] 'agreement among raft nodes before linearized reading' (duration: 161.352401ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:46.272355Z","caller":"traceutil/trace.go:171","msg":"trace[1236464997] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"308.32716ms","start":"2026-07-21T20:53:45.964Z","end":"2026-07-21T20:53:46.272327Z","steps":["trace[1236464997] 'process raft request' (duration: 308.109435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:46.272483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:45.963968Z","time spent":"308.453032ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:47.849114Z","caller":"traceutil/trace.go:171","msg":"trace[1802720377] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"448.112458ms","start":"2026-07-21T20:53:47.400978Z","end":"2026-07-21T20:53:47.849091Z","steps":["trace[1802720377] 'process raft request' (duration: 447.966136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:47.84925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.400954Z","time spent":"448.23283ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:48.301813Z","caller":"traceutil/trace.go:171","msg":"trace[1409803924] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"697.566004ms","start":"2026-07-21T20:53:47.604201Z","end":"2026-07-21T20:53:48.301767Z","steps":["trace[1409803924] 'process raft request' (duration: 697.199539ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.301959Z","caller":"traceutil/trace.go:171","msg":"trace[1333204147] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"500.326115ms","start":"2026-07-21T20:53:47.801611Z","end":"2026-07-21T20:53:48.301937Z","steps":["trace[1333204147] 'process raft request' (duration: 500.036571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.302061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.801582Z","time spent":"500.432848ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:48.301936Z","caller":"traceutil/trace.go:171","msg":"trace[505947798] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:679; }","duration":"490.001901ms","start":"2026-07-21T20:53:47.811856Z","end":"2026-07-21T20:53:48.301858Z","steps":["trace[505947798] 'read index received' (duration: 37.07117ms)","trace[505947798] 'applied index is now lower than readState.Index' (duration: 452.929571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:48.302101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.604172Z","time spent":"697.748667ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:53:48.302176Z","caller":"traceutil/trace.go:171","msg":"trace[1603227742] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"488.527086ms","start":"2026-07-21T20:53:47.813577Z","end":"2026-07-21T20:53:48.302104Z","steps":["trace[1603227742] 'process raft request' (duration: 488.191491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.302315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.813552Z","time spent":"488.698998ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:48.302427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.541251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24qzs\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-21T20:53:48.302517Z","caller":"traceutil/trace.go:171","msg":"trace[51545763] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24qzs; range_end:; response_count:1; response_revision:660; }","duration":"490.676242ms","start":"2026-07-21T20:53:47.811819Z","end":"2026-07-21T20:53:48.302495Z","steps":["trace[51545763] 'agreement among raft nodes before linearized reading' (duration: 490.372766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.302562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.811801Z","time spent":"490.752903ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17548,"request content":"key:\"/registry/pods/kube-system/cilium-24qzs\" "} {"level":"warn","ts":"2026-07-21T20:53:48.583799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.595647ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:48.583971Z","caller":"traceutil/trace.go:171","msg":"trace[458759123] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:660; }","duration":"122.78847ms","start":"2026-07-21T20:53:48.461156Z","end":"2026-07-21T20:53:48.583944Z","steps":["trace[458759123] 'range keys from in-memory index tree' (duration: 122.562955ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.626539Z","caller":"traceutil/trace.go:171","msg":"trace[1193331283] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"324.401787ms","start":"2026-07-21T20:53:48.302113Z","end":"2026-07-21T20:53:48.626515Z","steps":["trace[1193331283] 'read index received' (duration: 324.365846ms)","trace[1193331283] 'applied index is now lower than readState.Index' (duration: 35.121µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:48.626689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.997855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:48.626692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.30402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:48.626735Z","caller":"traceutil/trace.go:171","msg":"trace[120357553] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"627.091987ms","start":"2026-07-21T20:53:47.99963Z","end":"2026-07-21T20:53:48.626721Z","steps":["trace[120357553] 'agreement among raft nodes before linearized reading' (duration: 626.997906ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.626747Z","caller":"traceutil/trace.go:171","msg":"trace[556235576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"427.411801ms","start":"2026-07-21T20:53:48.199319Z","end":"2026-07-21T20:53:48.62673Z","steps":["trace[556235576] 'agreement among raft nodes before linearized reading' (duration: 427.282639ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.626695Z","caller":"traceutil/trace.go:171","msg":"trace[798052368] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"772.584207ms","start":"2026-07-21T20:53:47.853987Z","end":"2026-07-21T20:53:48.626571Z","steps":["trace[798052368] 'process raft request' (duration: 772.415354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.626775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.999608Z","time spent":"627.159588ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-21T20:53:48.626785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.199288Z","time spent":"427.488463ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-21T20:53:48.626731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.348757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:48.626959Z","caller":"traceutil/trace.go:171","msg":"trace[1982678337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"517.625002ms","start":"2026-07-21T20:53:48.109313Z","end":"2026-07-21T20:53:48.626938Z","steps":["trace[1982678337] 'agreement among raft nodes before linearized reading' (duration: 517.325257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.627031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:47.85397Z","time spent":"772.842272ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:48.627091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.109292Z","time spent":"517.787574ms","remote":"127.0.0.1:33898","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-07-21T20:53:48.868059Z","caller":"traceutil/trace.go:171","msg":"trace[98090472] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"241.425999ms","start":"2026-07-21T20:53:48.626604Z","end":"2026-07-21T20:53:48.86803Z","steps":["trace[98090472] 'read index received' (duration: 237.494732ms)","trace[98090472] 'applied index is now lower than readState.Index' (duration: 3.930147ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:48.868137Z","caller":"traceutil/trace.go:171","msg":"trace[1488367656] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"465.122228ms","start":"2026-07-21T20:53:48.403008Z","end":"2026-07-21T20:53:48.86813Z","steps":["trace[1488367656] 'process raft request' (duration: 464.959035ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.868176Z","caller":"traceutil/trace.go:171","msg":"trace[635530737] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"550.214872ms","start":"2026-07-21T20:53:48.317933Z","end":"2026-07-21T20:53:48.868147Z","steps":["trace[635530737] 'process raft request' (duration: 546.153153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.868321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.317907Z","time spent":"550.335993ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:48.86835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.755783ms","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-07-21T20:53:48.868404Z","caller":"traceutil/trace.go:171","msg":"trace[807337140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"258.237922ms","start":"2026-07-21T20:53:48.610128Z","end":"2026-07-21T20:53:48.868366Z","steps":["trace[807337140] 'agreement among raft nodes before linearized reading' (duration: 258.09976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.868211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.402987Z","time spent":"465.165429ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:48.868447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.473574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-21T20:53:48.868514Z","caller":"traceutil/trace.go:171","msg":"trace[1693534198] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"268.587676ms","start":"2026-07-21T20:53:48.599907Z","end":"2026-07-21T20:53:48.868495Z","steps":["trace[1693534198] 'agreement among raft nodes before linearized reading' (duration: 268.334252ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.910167Z","caller":"traceutil/trace.go:171","msg":"trace[927531264] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"276.349407ms","start":"2026-07-21T20:53:48.633795Z","end":"2026-07-21T20:53:48.910144Z","steps":["trace[927531264] 'process raft request' (duration: 276.100872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.910183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.133974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:48.910266Z","caller":"traceutil/trace.go:171","msg":"trace[1139976109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"282.251637ms","start":"2026-07-21T20:53:48.627981Z","end":"2026-07-21T20:53:48.910232Z","steps":["trace[1139976109] 'agreement among raft nodes before linearized reading' (duration: 282.124034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:48.910395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.972904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-21T20:53:48.910476Z","caller":"traceutil/trace.go:171","msg":"trace[863478954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:665; }","duration":"110.101296ms","start":"2026-07-21T20:53:48.800346Z","end":"2026-07-21T20:53:48.910447Z","steps":["trace[863478954] 'agreement among raft nodes before linearized reading' (duration: 109.902753ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:48.910383Z","caller":"traceutil/trace.go:171","msg":"trace[1558960048] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"272.284968ms","start":"2026-07-21T20:53:48.63803Z","end":"2026-07-21T20:53:48.910315Z","steps":["trace[1558960048] 'process raft request' (duration: 271.992253ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:49.243245Z","caller":"traceutil/trace.go:171","msg":"trace[241995698] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"323.748535ms","start":"2026-07-21T20:53:48.919474Z","end":"2026-07-21T20:53:49.243223Z","steps":["trace[241995698] 'process raft request' (duration: 323.564591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.243384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.919455Z","time spent":"323.850037ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:49.243501Z","caller":"traceutil/trace.go:171","msg":"trace[1649031200] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"318.898802ms","start":"2026-07-21T20:53:48.924594Z","end":"2026-07-21T20:53:49.243492Z","steps":["trace[1649031200] 'process raft request' (duration: 318.584106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.243544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.924584Z","time spent":"318.937444ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:49.243464Z","caller":"traceutil/trace.go:171","msg":"trace[422740894] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"323.049213ms","start":"2026-07-21T20:53:48.920351Z","end":"2026-07-21T20:53:49.2434Z","steps":["trace[422740894] 'read index received' (duration: 22.58091ms)","trace[422740894] 'applied index is now lower than readState.Index' (duration: 300.465653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:49.243972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.589842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T20:53:49.244053Z","caller":"traceutil/trace.go:171","msg":"trace[1249544684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"323.707354ms","start":"2026-07-21T20:53:48.920326Z","end":"2026-07-21T20:53:49.244033Z","steps":["trace[1249544684] 'agreement among raft nodes before linearized reading' (duration: 323.309748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.244099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.920318Z","time spent":"323.773345ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T20:53:49.499983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.374509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:49.500064Z","caller":"traceutil/trace.go:171","msg":"trace[1820380768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"390.46367ms","start":"2026-07-21T20:53:49.109582Z","end":"2026-07-21T20:53:49.500046Z","steps":["trace[1820380768] 'agreement among raft nodes before linearized reading' (duration: 390.248237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.500104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.109538Z","time spent":"390.557982ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:53:49.50016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.246956ms","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-07-21T20:53:49.500252Z","caller":"traceutil/trace.go:171","msg":"trace[981281908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"573.46109ms","start":"2026-07-21T20:53:48.926771Z","end":"2026-07-21T20:53:49.500232Z","steps":["trace[981281908] 'agreement among raft nodes before linearized reading' (duration: 573.102264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.50029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.646044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T20:53:49.500326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.926765Z","time spent":"573.548891ms","remote":"127.0.0.1:34168","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":"info","ts":"2026-07-21T20:53:49.500391Z","caller":"traceutil/trace.go:171","msg":"trace[1311436997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"573.777675ms","start":"2026-07-21T20:53:48.926599Z","end":"2026-07-21T20:53:49.500377Z","steps":["trace[1311436997] 'agreement among raft nodes before linearized reading' (duration: 573.383929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.500465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.92658Z","time spent":"573.873557ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:53:49.500597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.952499ms","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-07-21T20:53:49.500673Z","caller":"traceutil/trace.go:171","msg":"trace[744003245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"574.05353ms","start":"2026-07-21T20:53:48.926604Z","end":"2026-07-21T20:53:49.500658Z","steps":["trace[744003245] 'agreement among raft nodes before linearized reading' (duration: 573.867847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.500824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.680083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-21T20:53:49.500822Z","caller":"traceutil/trace.go:171","msg":"trace[725097437] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"296.366926ms","start":"2026-07-21T20:53:49.204432Z","end":"2026-07-21T20:53:49.500799Z","steps":["trace[725097437] 'process raft request' (duration: 214.242843ms)","trace[725097437] 'compare' (duration: 80.978165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:49.500787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:48.926595Z","time spent":"574.180742ms","remote":"127.0.0.1:34168","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":"info","ts":"2026-07-21T20:53:49.501123Z","caller":"traceutil/trace.go:171","msg":"trace[984253737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:669; }","duration":"500.782805ms","start":"2026-07-21T20:53:49.000097Z","end":"2026-07-21T20:53:49.50088Z","steps":["trace[984253737] 'agreement among raft nodes before linearized reading' (duration: 500.602232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.501182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.000074Z","time spent":"501.097371ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-07-21T20:53:49.50304Z","caller":"traceutil/trace.go:171","msg":"trace[1443957606] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"256.193045ms","start":"2026-07-21T20:53:49.243624Z","end":"2026-07-21T20:53:49.499817Z","steps":["trace[1443957606] 'read index received' (duration: 174.943405ms)","trace[1443957606] 'applied index is now lower than readState.Index' (duration: 81.247759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:49.800936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.110646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:49.801874Z","caller":"traceutil/trace.go:171","msg":"trace[1224622639] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"552.571181ms","start":"2026-07-21T20:53:49.249271Z","end":"2026-07-21T20:53:49.801842Z","steps":["trace[1224622639] 'process raft request' (duration: 418.490399ms)","trace[1224622639] 'compare' (duration: 132.977604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:49.80212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.249249Z","time spent":"552.697284ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:49.802144Z","caller":"traceutil/trace.go:171","msg":"trace[41593316] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"552.816805ms","start":"2026-07-21T20:53:49.24931Z","end":"2026-07-21T20:53:49.802126Z","steps":["trace[41593316] 'process raft request' (duration: 551.732717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.802228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.249274Z","time spent":"552.907797ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:49.802482Z","caller":"traceutil/trace.go:171","msg":"trace[651502577] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"552.203835ms","start":"2026-07-21T20:53:49.250266Z","end":"2026-07-21T20:53:49.802469Z","steps":["trace[651502577] 'process raft request' (duration: 550.857102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.802604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.250253Z","time spent":"552.265416ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:49.802746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.716221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-21T20:53:49.802786Z","caller":"traceutil/trace.go:171","msg":"trace[874972198] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:672; }","duration":"403.788943ms","start":"2026-07-21T20:53:49.398987Z","end":"2026-07-21T20:53:49.802776Z","steps":["trace[874972198] 'agreement among raft nodes before linearized reading' (duration: 403.667401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:49.802823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.398972Z","time spent":"403.834073ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-07-21T20:53:49.802633Z","caller":"traceutil/trace.go:171","msg":"trace[1458793943] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:692; }","duration":"299.336756ms","start":"2026-07-21T20:53:49.503282Z","end":"2026-07-21T20:53:49.802619Z","steps":["trace[1458793943] 'read index received' (duration: 164.40587ms)","trace[1458793943] 'applied index is now lower than readState.Index' (duration: 134.929166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:50.086202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.314941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:50.086428Z","caller":"traceutil/trace.go:171","msg":"trace[1006019591] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"580.297545ms","start":"2026-07-21T20:53:49.506096Z","end":"2026-07-21T20:53:50.086394Z","steps":["trace[1006019591] 'process raft request' (duration: 462.685559ms)","trace[1006019591] 'compare' (duration: 116.980575ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:50.0867Z","caller":"traceutil/trace.go:171","msg":"trace[1987166181] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"580.425548ms","start":"2026-07-21T20:53:49.50626Z","end":"2026-07-21T20:53:50.086685Z","steps":["trace[1987166181] 'process raft request' (duration: 580.105423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.086783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.506236Z","time spent":"580.503048ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:50.086793Z","caller":"traceutil/trace.go:171","msg":"trace[151876652] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"580.470698ms","start":"2026-07-21T20:53:49.506261Z","end":"2026-07-21T20:53:50.086732Z","steps":["trace[151876652] 'process raft request' (duration: 580.256405ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.086904Z","caller":"traceutil/trace.go:171","msg":"trace[1403357012] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"577.537648ms","start":"2026-07-21T20:53:49.509338Z","end":"2026-07-21T20:53:50.086875Z","steps":["trace[1403357012] 'process raft request' (duration: 577.266253ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.086966Z","caller":"traceutil/trace.go:171","msg":"trace[262610071] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"451.990219ms","start":"2026-07-21T20:53:49.634947Z","end":"2026-07-21T20:53:50.086937Z","steps":["trace[262610071] 'process raft request' (duration: 451.760665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.087045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.509329Z","time spent":"577.634209ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:50.087075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.506237Z","time spent":"580.723842ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:50.087143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.63492Z","time spent":"452.138292ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:50.087163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.506071Z","time spent":"580.462148ms","remote":"127.0.0.1:34168","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-07-21T20:53:50.087174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.37345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-21T20:53:50.086852Z","caller":"traceutil/trace.go:171","msg":"trace[13227474] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:695; }","duration":"283.968039ms","start":"2026-07-21T20:53:49.802855Z","end":"2026-07-21T20:53:50.086823Z","steps":["trace[13227474] 'read index received' (duration: 166.012667ms)","trace[13227474] 'applied index is now lower than readState.Index' (duration: 117.953192ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:50.087311Z","caller":"traceutil/trace.go:171","msg":"trace[1782929951] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:677; }","duration":"487.533612ms","start":"2026-07-21T20:53:49.599757Z","end":"2026-07-21T20:53:50.087291Z","steps":["trace[1782929951] 'agreement among raft nodes before linearized reading' (duration: 487.164596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.087447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.599738Z","time spent":"487.655765ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-07-21T20:53:50.128884Z","caller":"traceutil/trace.go:171","msg":"trace[726537725] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"317.548406ms","start":"2026-07-21T20:53:49.811302Z","end":"2026-07-21T20:53:50.128851Z","steps":["trace[726537725] 'process raft request' (duration: 317.281071ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.128937Z","caller":"traceutil/trace.go:171","msg":"trace[4311263] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"291.490619ms","start":"2026-07-21T20:53:49.837426Z","end":"2026-07-21T20:53:50.128917Z","steps":["trace[4311263] 'process raft request' (duration: 291.412207ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.12897Z","caller":"traceutil/trace.go:171","msg":"trace[2020752235] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"313.699791ms","start":"2026-07-21T20:53:49.815214Z","end":"2026-07-21T20:53:50.128914Z","steps":["trace[2020752235] 'process raft request' (duration: 313.536628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.129172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.811285Z","time spent":"317.674657ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:50.12931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.815184Z","time spent":"313.951845ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:53:50.185086Z","caller":"traceutil/trace.go:171","msg":"trace[286946741] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"158.739525ms","start":"2026-07-21T20:53:50.02633Z","end":"2026-07-21T20:53:50.18507Z","steps":["trace[286946741] 'process raft request' (duration: 158.376139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.185329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.438352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24qzs\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-07-21T20:53:50.185351Z","caller":"traceutil/trace.go:171","msg":"trace[652283351] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24qzs; range_end:; response_count:1; response_revision:682; }","duration":"361.492303ms","start":"2026-07-21T20:53:49.823853Z","end":"2026-07-21T20:53:50.185346Z","steps":["trace[652283351] 'agreement among raft nodes before linearized reading' (duration: 361.393112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.185367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:49.82384Z","time spent":"361.523123ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17814,"request content":"key:\"/registry/pods/kube-system/cilium-24qzs\" "} {"level":"warn","ts":"2026-07-21T20:53:50.238318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.002028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:50.238395Z","caller":"traceutil/trace.go:171","msg":"trace[1673404632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"127.09454ms","start":"2026-07-21T20:53:50.111283Z","end":"2026-07-21T20:53:50.238377Z","steps":["trace[1673404632] 'agreement among raft nodes before linearized reading' (duration: 126.946167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:50.808017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.205926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-21T20:53:50.808111Z","caller":"traceutil/trace.go:171","msg":"trace[162325454] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:688; }","duration":"208.38062ms","start":"2026-07-21T20:53:50.599709Z","end":"2026-07-21T20:53:50.808089Z","steps":["trace[162325454] 'range keys from in-memory index tree' (duration: 207.948893ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:50.954888Z","caller":"traceutil/trace.go:171","msg":"trace[1499403566] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"113.546855ms","start":"2026-07-21T20:53:50.841305Z","end":"2026-07-21T20:53:50.954851Z","steps":["trace[1499403566] 'process raft request' (duration: 113.337942ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.275761Z","caller":"traceutil/trace.go:171","msg":"trace[1777990490] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"433.645703ms","start":"2026-07-21T20:53:51.842082Z","end":"2026-07-21T20:53:52.275728Z","steps":["trace[1777990490] 'process raft request' (duration: 433.46417ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.275734Z","caller":"traceutil/trace.go:171","msg":"trace[429140083] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"450.667564ms","start":"2026-07-21T20:53:51.82504Z","end":"2026-07-21T20:53:52.275708Z","steps":["trace[429140083] 'process raft request' (duration: 449.734689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.2759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.842057Z","time spent":"433.781715ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:53:52.276157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.466693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24qzs\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-07-21T20:53:52.276251Z","caller":"traceutil/trace.go:171","msg":"trace[1408257136] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24qzs; range_end:; response_count:1; response_revision:696; }","duration":"435.583446ms","start":"2026-07-21T20:53:51.840637Z","end":"2026-07-21T20:53:52.276221Z","steps":["trace[1408257136] 'agreement among raft nodes before linearized reading' (duration: 435.187938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.276297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.840626Z","time spent":"435.661887ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18091,"request content":"key:\"/registry/pods/kube-system/cilium-24qzs\" "} {"level":"info","ts":"2026-07-21T20:53:52.276312Z","caller":"traceutil/trace.go:171","msg":"trace[782443077] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"435.563525ms","start":"2026-07-21T20:53:51.840672Z","end":"2026-07-21T20:53:52.276236Z","steps":["trace[782443077] 'read index received' (duration: 5.728644ms)","trace[782443077] 'applied index is now lower than readState.Index' (duration: 429.259101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:52.276201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.825019Z","time spent":"450.94649ms","remote":"127.0.0.1:34078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:52.620657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.803397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T20:53:52.620764Z","caller":"traceutil/trace.go:171","msg":"trace[1047397207] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"344.359964ms","start":"2026-07-21T20:53:52.276387Z","end":"2026-07-21T20:53:52.620747Z","steps":["trace[1047397207] 'read index received' (duration: 82.957037ms)","trace[1047397207] 'applied index is now lower than readState.Index' (duration: 261.401497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:53:52.620838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.977768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:52.620875Z","caller":"traceutil/trace.go:171","msg":"trace[158695217] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:696; }","duration":"775.035289ms","start":"2026-07-21T20:53:51.845827Z","end":"2026-07-21T20:53:52.620862Z","steps":["trace[158695217] 'agreement among raft nodes before linearized reading' (duration: 774.964147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.620918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.845819Z","time spent":"775.089449ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:53:52.620941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.594677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:52.620976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.75061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:52.621085Z","caller":"traceutil/trace.go:171","msg":"trace[604691712] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:696; }","duration":"724.926233ms","start":"2026-07-21T20:53:51.896142Z","end":"2026-07-21T20:53:52.621068Z","steps":["trace[604691712] 'agreement among raft nodes before linearized reading' (duration: 724.686139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.448546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:52.62116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.810927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:52.621173Z","caller":"traceutil/trace.go:171","msg":"trace[2039128939] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:696; }","duration":"772.590738ms","start":"2026-07-21T20:53:51.84856Z","end":"2026-07-21T20:53:52.621151Z","steps":["trace[2039128939] 'agreement among raft nodes before linearized reading' (duration: 772.346104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.147191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:52.621154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.853538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:52.621218Z","caller":"traceutil/trace.go:171","msg":"trace[1476027576] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:696; }","duration":"739.871818ms","start":"2026-07-21T20:53:51.881324Z","end":"2026-07-21T20:53:52.621195Z","steps":["trace[1476027576] 'agreement among raft nodes before linearized reading' (duration: 739.810667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.848546Z","time spent":"772.666499ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-07-21T20:53:52.621224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.748857ms","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-07-21T20:53:52.621224Z","caller":"traceutil/trace.go:171","msg":"trace[2072147650] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:696; }","duration":"775.192051ms","start":"2026-07-21T20:53:51.846023Z","end":"2026-07-21T20:53:52.621215Z","steps":["trace[2072147650] 'agreement among raft nodes before linearized reading' (duration: 775.14137ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.621323Z","caller":"traceutil/trace.go:171","msg":"trace[87610421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"529.896128ms","start":"2026-07-21T20:53:52.091404Z","end":"2026-07-21T20:53:52.6213Z","steps":["trace[87610421] 'agreement among raft nodes before linearized reading' (duration: 529.596254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.664327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:52.621185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.896133Z","time spent":"725.036814ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-07-21T20:53:52.621432Z","caller":"traceutil/trace.go:171","msg":"trace[91109329] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:696; }","duration":"724.715688ms","start":"2026-07-21T20:53:51.896688Z","end":"2026-07-21T20:53:52.621404Z","steps":["trace[91109329] 'agreement among raft nodes before linearized reading' (duration: 724.661257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.513468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:53:52.621397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.846017Z","time spent":"775.366634ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:53:52.621474Z","caller":"traceutil/trace.go:171","msg":"trace[1394742340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"511.63101ms","start":"2026-07-21T20:53:52.109833Z","end":"2026-07-21T20:53:52.621464Z","steps":["trace[1394742340] 'agreement among raft nodes before linearized reading' (duration: 511.523218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.621522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.081562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T20:53:52.621571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:52.109813Z","time spent":"511.746341ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:53:52.621381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:52.091352Z","time spent":"530.020641ms","remote":"127.0.0.1:34168","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-07-21T20:53:52.621471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.896675Z","time spent":"724.787669ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-07-21T20:53:52.622456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.992326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:53:52.62127Z","caller":"traceutil/trace.go:171","msg":"trace[741082150] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:696; }","duration":"740.034591ms","start":"2026-07-21T20:53:51.881213Z","end":"2026-07-21T20:53:52.621248Z","steps":["trace[741082150] 'agreement among raft nodes before linearized reading' (duration: 739.755106ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.622502Z","caller":"traceutil/trace.go:171","msg":"trace[1717671049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"531.071388ms","start":"2026-07-21T20:53:52.09142Z","end":"2026-07-21T20:53:52.622491Z","steps":["trace[1717671049] 'agreement among raft nodes before linearized reading' (duration: 530.045891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.622539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:52.0914Z","time spent":"531.132389ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:53:52.622536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.881194Z","time spent":"741.321511ms","remote":"127.0.0.1:36062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:53:52.621087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.800858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:53:52.62272Z","caller":"traceutil/trace.go:171","msg":"trace[1895631871] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:696; }","duration":"741.466094ms","start":"2026-07-21T20:53:51.881215Z","end":"2026-07-21T20:53:52.622682Z","steps":["trace[1895631871] 'agreement among raft nodes before linearized reading' (duration: 739.566723ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.621594Z","caller":"traceutil/trace.go:171","msg":"trace[1689170442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"530.185923ms","start":"2026-07-21T20:53:52.091397Z","end":"2026-07-21T20:53:52.621583Z","steps":["trace[1689170442] 'agreement among raft nodes before linearized reading' (duration: 529.944469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.622796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.881191Z","time spent":"741.588725ms","remote":"127.0.0.1:36062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:53:52.622818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:52.091376Z","time spent":"531.412484ms","remote":"127.0.0.1:34168","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":"info","ts":"2026-07-21T20:53:52.622647Z","caller":"traceutil/trace.go:171","msg":"trace[1193319849] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:696; }","duration":"724.78382ms","start":"2026-07-21T20:53:51.89623Z","end":"2026-07-21T20:53:52.621013Z","steps":["trace[1193319849] 'agreement among raft nodes before linearized reading' (duration: 724.535966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.62291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.896217Z","time spent":"726.675111ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:53:52.621263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:51.881314Z","time spent":"739.9402ms","remote":"127.0.0.1:36062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-07-21T20:53:52.663359Z","caller":"traceutil/trace.go:171","msg":"trace[1268592233] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"370.888187ms","start":"2026-07-21T20:53:52.292454Z","end":"2026-07-21T20:53:52.663342Z","steps":["trace[1268592233] 'process raft request' (duration: 370.636113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:52.663499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:53:52.292426Z","time spent":"370.991099ms","remote":"127.0.0.1:34080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18322,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:53:52.87743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.135425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:53:52.877874Z","caller":"traceutil/trace.go:171","msg":"trace[286270170] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"251.225819ms","start":"2026-07-21T20:53:52.626626Z","end":"2026-07-21T20:53:52.877851Z","steps":["trace[286270170] 'process raft request' (duration: 97.586326ms)","trace[286270170] 'compare' (duration: 153.020023ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:52.878436Z","caller":"traceutil/trace.go:171","msg":"trace[31309097] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"250.791353ms","start":"2026-07-21T20:53:52.627625Z","end":"2026-07-21T20:53:52.878416Z","steps":["trace[31309097] 'process raft request' (duration: 250.685551ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:52.878425Z","caller":"traceutil/trace.go:171","msg":"trace[1496633040] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"250.903255ms","start":"2026-07-21T20:53:52.627493Z","end":"2026-07-21T20:53:52.878396Z","steps":["trace[1496633040] 'process raft request' (duration: 250.100601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:01.252452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.583125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:01.252551Z","caller":"traceutil/trace.go:171","msg":"trace[141175681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"141.744857ms","start":"2026-07-21T20:54:01.110779Z","end":"2026-07-21T20:54:01.252524Z","steps":["trace[141175681] 'range keys from in-memory index tree' (duration: 141.479734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:01.252676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.548934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-21T20:54:01.252772Z","caller":"traceutil/trace.go:171","msg":"trace[1438852349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"206.685405ms","start":"2026-07-21T20:54:01.046061Z","end":"2026-07-21T20:54:01.252747Z","steps":["trace[1438852349] 'range keys from in-memory index tree' (duration: 206.337891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.092456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.272195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.092523Z","caller":"traceutil/trace.go:171","msg":"trace[866333827] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:783; }","duration":"966.395197ms","start":"2026-07-21T20:54:04.126112Z","end":"2026-07-21T20:54:05.092507Z","steps":["trace[866333827] 'count revisions from in-memory index tree' (duration: 966.153053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.092558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.126103Z","time spent":"966.447937ms","remote":"127.0.0.1:44464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:54:05.092776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095871888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.092805Z","caller":"traceutil/trace.go:171","msg":"trace[2019896620] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:783; }","duration":"1.09592044s","start":"2026-07-21T20:54:03.996875Z","end":"2026-07-21T20:54:05.092796Z","steps":["trace[2019896620] 'range keys from in-memory index tree' (duration: 1.095825788s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.092856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:03.99686Z","time spent":"1.09596256s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:54:05.092853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.039761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.092933Z","caller":"traceutil/trace.go:171","msg":"trace[187132124] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:783; }","duration":"950.176744ms","start":"2026-07-21T20:54:04.142735Z","end":"2026-07-21T20:54:05.092912Z","steps":["trace[187132124] 'range keys from in-memory index tree' (duration: 949.986601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.092965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096023671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.092986Z","caller":"traceutil/trace.go:171","msg":"trace[1994282167] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:783; }","duration":"1.096071231s","start":"2026-07-21T20:54:03.996908Z","end":"2026-07-21T20:54:05.092979Z","steps":["trace[1994282167] 'count revisions from in-memory index tree' (duration: 1.09596851s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.092982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.142729Z","time spent":"950.240214ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:54:05.092963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.082103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:54:05.09301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:03.99689Z","time spent":"1.096114963s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:54:05.093039Z","caller":"traceutil/trace.go:171","msg":"trace[656573257] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:783; }","duration":"966.180934ms","start":"2026-07-21T20:54:04.126838Z","end":"2026-07-21T20:54:05.093019Z","steps":["trace[656573257] 'range keys from in-memory index tree' (duration: 966.030992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.093089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.126832Z","time spent":"966.243385ms","remote":"127.0.0.1:44464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-07-21T20:54:05.093109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096151173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:05.09313Z","caller":"traceutil/trace.go:171","msg":"trace[1763953328] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:783; }","duration":"1.096212753s","start":"2026-07-21T20:54:03.996909Z","end":"2026-07-21T20:54:05.093121Z","steps":["trace[1763953328] 'count revisions from in-memory index tree' (duration: 1.096090152s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.093155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:03.99689Z","time spent":"1.096257975s","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:54:05.093107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.043122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.09323Z","caller":"traceutil/trace.go:171","msg":"trace[410058992] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:783; }","duration":"950.192713ms","start":"2026-07-21T20:54:04.142992Z","end":"2026-07-21T20:54:05.093185Z","steps":["trace[410058992] 'range keys from in-memory index tree' (duration: 949.989391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.093272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.142983Z","time spent":"950.279464ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-21T20:54:05.093275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095740997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.093305Z","caller":"traceutil/trace.go:171","msg":"trace[348187642] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:783; }","duration":"1.095780027s","start":"2026-07-21T20:54:03.997517Z","end":"2026-07-21T20:54:05.093297Z","steps":["trace[348187642] 'range keys from in-memory index tree' (duration: 1.095705856s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.09329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.816101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:54:05.093439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.104225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.093443Z","caller":"traceutil/trace.go:171","msg":"trace[783147414] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"933.029094ms","start":"2026-07-21T20:54:04.1604Z","end":"2026-07-21T20:54:05.093429Z","steps":["trace[783147414] 'range keys from in-memory index tree' (duration: 932.74193ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:05.093461Z","caller":"traceutil/trace.go:171","msg":"trace[1276649245] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"933.139706ms","start":"2026-07-21T20:54:04.160315Z","end":"2026-07-21T20:54:05.093454Z","steps":["trace[1276649245] 'count revisions from in-memory index tree' (duration: 933.068125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.093502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.160308Z","time spent":"933.188377ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:54:05.093511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.160378Z","time spent":"933.090765ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:54:05.093679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.770381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.093715Z","caller":"traceutil/trace.go:171","msg":"trace[287638347] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:783; }","duration":"932.832622ms","start":"2026-07-21T20:54:04.160872Z","end":"2026-07-21T20:54:05.093705Z","steps":["trace[287638347] 'range keys from in-memory index tree' (duration: 932.523427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.093847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.160835Z","time spent":"932.997714ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-07-21T20:54:05.093936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.770828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:54:05.09377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.677208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.093982Z","caller":"traceutil/trace.go:171","msg":"trace[401608911] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:783; }","duration":"967.818589ms","start":"2026-07-21T20:54:04.126151Z","end":"2026-07-21T20:54:05.09397Z","steps":["trace[401608911] 'range keys from in-memory index tree' (duration: 967.684926ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:05.093997Z","caller":"traceutil/trace.go:171","msg":"trace[113604932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"985.933721ms","start":"2026-07-21T20:54:04.108051Z","end":"2026-07-21T20:54:05.093985Z","steps":["trace[113604932] 'range keys from in-memory index tree' (duration: 985.587176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.094016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.126104Z","time spent":"967.901749ms","remote":"127.0.0.1:44464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:54:05.094061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.108033Z","time spent":"985.988782ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T20:54:05.093123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.292445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.094457Z","caller":"traceutil/trace.go:171","msg":"trace[1677405864] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:783; }","duration":"951.815497ms","start":"2026-07-21T20:54:04.142626Z","end":"2026-07-21T20:54:05.094442Z","steps":["trace[1677405864] 'count revisions from in-memory index tree' (duration: 950.118193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.094498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.142614Z","time spent":"951.868319ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:54:05.09334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:03.997513Z","time spent":"1.095819517s","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"info","ts":"2026-07-21T20:54:05.245133Z","caller":"traceutil/trace.go:171","msg":"trace[1206829413] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"299.437175ms","start":"2026-07-21T20:54:04.945669Z","end":"2026-07-21T20:54:05.245106Z","steps":["trace[1206829413] 'read index received' (duration: 299.301153ms)","trace[1206829413] 'applied index is now lower than readState.Index' (duration: 135.222µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:05.245342Z","caller":"traceutil/trace.go:171","msg":"trace[1237861706] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"325.431517ms","start":"2026-07-21T20:54:04.919872Z","end":"2026-07-21T20:54:05.245304Z","steps":["trace[1237861706] 'process raft request' (duration: 324.517893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.245521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.919846Z","time spent":"325.578619ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:05.245722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.036764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:05.245782Z","caller":"traceutil/trace.go:171","msg":"trace[42670636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"300.142547ms","start":"2026-07-21T20:54:04.945617Z","end":"2026-07-21T20:54:05.24576Z","steps":["trace[42670636] 'agreement among raft nodes before linearized reading' (duration: 299.970294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.245818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:04.944945Z","time spent":"300.865237ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:54:05.246136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.051621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-21T20:54:05.246198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.520191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:05.246229Z","caller":"traceutil/trace.go:171","msg":"trace[1372659085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"150.573643ms","start":"2026-07-21T20:54:05.095645Z","end":"2026-07-21T20:54:05.246219Z","steps":["trace[1372659085] 'agreement among raft nodes before linearized reading' (duration: 150.502232ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:05.246257Z","caller":"traceutil/trace.go:171","msg":"trace[1104063543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"230.147653ms","start":"2026-07-21T20:54:05.016027Z","end":"2026-07-21T20:54:05.246174Z","steps":["trace[1104063543] 'agreement among raft nodes before linearized reading' (duration: 229.984541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.246284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.641263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:05.246346Z","caller":"traceutil/trace.go:171","msg":"trace[2059535053] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:784; }","duration":"150.718844ms","start":"2026-07-21T20:54:05.095611Z","end":"2026-07-21T20:54:05.24633Z","steps":["trace[2059535053] 'agreement among raft nodes before linearized reading' (duration: 150.587402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:05.246749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.363742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T20:54:05.246827Z","caller":"traceutil/trace.go:171","msg":"trace[1702722846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"229.462594ms","start":"2026-07-21T20:54:05.017329Z","end":"2026-07-21T20:54:05.246792Z","steps":["trace[1702722846] 'agreement among raft nodes before linearized reading' (duration: 228.857144ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:13.07654Z","caller":"traceutil/trace.go:171","msg":"trace[1647505827] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"306.425622ms","start":"2026-07-21T20:54:12.770086Z","end":"2026-07-21T20:54:13.076512Z","steps":["trace[1647505827] 'process raft request' (duration: 221.874307ms)","trace[1647505827] 'compare' (duration: 83.184016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:13.077119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:12.770062Z","time spent":"306.698056ms","remote":"127.0.0.1:36042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:54:18.177452Z","caller":"traceutil/trace.go:171","msg":"trace[224588368] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"111.074445ms","start":"2026-07-21T20:54:18.066332Z","end":"2026-07-21T20:54:18.177407Z","steps":["trace[224588368] 'process raft request' (duration: 49.9709ms)","trace[224588368] 'compare' (duration: 60.953153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:19.282992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.600534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:54:19.283067Z","caller":"traceutil/trace.go:171","msg":"trace[1509390223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"172.714325ms","start":"2026-07-21T20:54:19.110334Z","end":"2026-07-21T20:54:19.283048Z","steps":["trace[1509390223] 'range keys from in-memory index tree' (duration: 172.495422ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:32.455764Z","caller":"traceutil/trace.go:171","msg":"trace[1462724273] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"143.645324ms","start":"2026-07-21T20:54:32.3121Z","end":"2026-07-21T20:54:32.455746Z","steps":["trace[1462724273] 'process raft request' (duration: 143.540073ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.611324Z","caller":"traceutil/trace.go:171","msg":"trace[1365956895] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"170.375566ms","start":"2026-07-21T20:54:33.440929Z","end":"2026-07-21T20:54:33.611305Z","steps":["trace[1365956895] 'read index received' (duration: 170.251895ms)","trace[1365956895] 'applied index is now lower than readState.Index' (duration: 123.211µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:33.611403Z","caller":"traceutil/trace.go:171","msg":"trace[1883682242] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"180.084649ms","start":"2026-07-21T20:54:33.431312Z","end":"2026-07-21T20:54:33.611397Z","steps":["trace[1883682242] 'process raft request' (duration: 179.875677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:33.611594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.65232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:54:33.611615Z","caller":"traceutil/trace.go:171","msg":"trace[106233502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"170.693551ms","start":"2026-07-21T20:54:33.440917Z","end":"2026-07-21T20:54:33.611611Z","steps":["trace[106233502] 'agreement among raft nodes before linearized reading' (duration: 170.59207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:33.611693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.272975ms","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-07-21T20:54:33.611721Z","caller":"traceutil/trace.go:171","msg":"trace[1833618558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1005; }","duration":"163.336505ms","start":"2026-07-21T20:54:33.448379Z","end":"2026-07-21T20:54:33.611716Z","steps":["trace[1833618558] 'agreement among raft nodes before linearized reading' (duration: 163.286074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:33.61184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.917788ms","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-07-21T20:54:33.611892Z","caller":"traceutil/trace.go:171","msg":"trace[1961840626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"147.991451ms","start":"2026-07-21T20:54:33.463888Z","end":"2026-07-21T20:54:33.611879Z","steps":["trace[1961840626] 'agreement among raft nodes before linearized reading' (duration: 147.881809ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:55.951363Z","caller":"traceutil/trace.go:171","msg":"trace[810697427] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"108.291768ms","start":"2026-07-21T20:54:55.843034Z","end":"2026-07-21T20:54:55.951326Z","steps":["trace[810697427] 'process raft request' (duration: 108.087375ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.262211Z","caller":"traceutil/trace.go:171","msg":"trace[110236142] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"229.642587ms","start":"2026-07-21T20:54:58.032537Z","end":"2026-07-21T20:54:58.262179Z","steps":["trace[110236142] 'process raft request' (duration: 222.546405ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.310993Z","caller":"traceutil/trace.go:171","msg":"trace[1431180430] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1129; }","duration":"225.704562ms","start":"2026-07-21T20:54:58.085268Z","end":"2026-07-21T20:54:58.310972Z","steps":["trace[1431180430] 'read index received' (duration: 169.912371ms)","trace[1431180430] 'applied index is now lower than readState.Index' (duration: 55.791211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:54:58.311142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.842674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:54:58.311185Z","caller":"traceutil/trace.go:171","msg":"trace[348065756] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1091; }","duration":"225.944155ms","start":"2026-07-21T20:54:58.08523Z","end":"2026-07-21T20:54:58.311174Z","steps":["trace[348065756] 'agreement among raft nodes before linearized reading' (duration: 225.817813ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.311466Z","caller":"traceutil/trace.go:171","msg":"trace[101081912] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"276.679308ms","start":"2026-07-21T20:54:58.034774Z","end":"2026-07-21T20:54:58.311453Z","steps":["trace[101081912] 'process raft request' (duration: 276.07135ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.311808Z","caller":"traceutil/trace.go:171","msg":"trace[458192568] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"275.697006ms","start":"2026-07-21T20:54:58.036071Z","end":"2026-07-21T20:54:58.311768Z","steps":["trace[458192568] 'process raft request' (duration: 274.866776ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:58.823259Z","caller":"traceutil/trace.go:171","msg":"trace[250182471] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"216.76984ms","start":"2026-07-21T20:54:58.606453Z","end":"2026-07-21T20:54:58.823223Z","steps":["trace[250182471] 'process raft request' (duration: 216.576798ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:00.968931Z","caller":"traceutil/trace.go:171","msg":"trace[1896046052] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"139.919975ms","start":"2026-07-21T20:55:00.828986Z","end":"2026-07-21T20:55:00.968906Z","steps":["trace[1896046052] 'process raft request' (duration: 139.793803ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:02.568017Z","caller":"traceutil/trace.go:171","msg":"trace[370151578] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"128.249293ms","start":"2026-07-21T20:55:02.439732Z","end":"2026-07-21T20:55:02.567981Z","steps":["trace[370151578] 'process raft request' (duration: 128.024949ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:06.503731Z","caller":"traceutil/trace.go:171","msg":"trace[1907818217] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"112.669549ms","start":"2026-07-21T20:55:06.391028Z","end":"2026-07-21T20:55:06.503697Z","steps":["trace[1907818217] 'process raft request' (duration: 111.430695ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:06.503731Z","caller":"traceutil/trace.go:171","msg":"trace[1511609699] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"112.70264ms","start":"2026-07-21T20:55:06.390996Z","end":"2026-07-21T20:55:06.503698Z","steps":["trace[1511609699] 'process raft request' (duration: 112.636669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:24.736303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.196822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:55:24.736397Z","caller":"traceutil/trace.go:171","msg":"trace[1852009602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1175; }","duration":"101.337564ms","start":"2026-07-21T20:55:24.635041Z","end":"2026-07-21T20:55:24.736379Z","steps":["trace[1852009602] 'range keys from in-memory index tree' (duration: 101.02872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:24.976874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.53767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:55:24.977076Z","caller":"traceutil/trace.go:171","msg":"trace[755733837] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"235.676637ms","start":"2026-07-21T20:55:24.741383Z","end":"2026-07-21T20:55:24.977059Z","steps":["trace[755733837] 'process raft request' (duration: 235.606016ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:24.977124Z","caller":"traceutil/trace.go:171","msg":"trace[1089918645] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"237.062231ms","start":"2026-07-21T20:55:24.740023Z","end":"2026-07-21T20:55:24.977085Z","steps":["trace[1089918645] 'process raft request' (duration: 111.237898ms)","trace[1089918645] 'compare' (duration: 125.296707ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:55:50.71306Z","caller":"traceutil/trace.go:171","msg":"trace[934900113] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"326.15121ms","start":"2026-07-21T20:55:50.386884Z","end":"2026-07-21T20:55:50.713036Z","steps":["trace[934900113] 'process raft request' (duration: 325.887527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:50.713202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:55:50.386867Z","time spent":"326.264241ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:55:50.833825Z","caller":"traceutil/trace.go:171","msg":"trace[42390534] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"116.929508ms","start":"2026-07-21T20:55:50.716869Z","end":"2026-07-21T20:55:50.833798Z","steps":["trace[42390534] 'process raft request' (duration: 116.804137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:59.322624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.336942ms","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":"warn","ts":"2026-07-21T20:55:59.322677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.187538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T20:55:59.322694Z","caller":"traceutil/trace.go:171","msg":"trace[1531867519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"459.446892ms","start":"2026-07-21T20:55:58.863231Z","end":"2026-07-21T20:55:59.322678Z","steps":["trace[1531867519] 'range keys from in-memory index tree' (duration: 459.188671ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:59.322714Z","caller":"traceutil/trace.go:171","msg":"trace[238030976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"274.250988ms","start":"2026-07-21T20:55:59.048449Z","end":"2026-07-21T20:55:59.3227Z","steps":["trace[238030976] 'range keys from in-memory index tree' (duration: 274.086917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:59.322731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:55:58.863208Z","time spent":"459.512954ms","remote":"127.0.0.1:34168","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":"warn","ts":"2026-07-21T20:55:59.322699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.43613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:55:59.322798Z","caller":"traceutil/trace.go:171","msg":"trace[1464901518] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1324; }","duration":"345.607491ms","start":"2026-07-21T20:55:58.977172Z","end":"2026-07-21T20:55:59.322779Z","steps":["trace[1464901518] 'count revisions from in-memory index tree' (duration: 345.344028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:59.322854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:55:58.977149Z","time spent":"345.693782ms","remote":"127.0.0.1:34100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:55:59.322859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.888836ms","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-07-21T20:55:59.32295Z","caller":"traceutil/trace.go:171","msg":"trace[1121924775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"147.009187ms","start":"2026-07-21T20:55:59.175932Z","end":"2026-07-21T20:55:59.322941Z","steps":["trace[1121924775] 'range keys from in-memory index tree' (duration: 146.789474ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:03.115772Z","caller":"traceutil/trace.go:171","msg":"trace[1349381260] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"326.823228ms","start":"2026-07-21T20:56:02.788927Z","end":"2026-07-21T20:56:03.11575Z","steps":["trace[1349381260] 'process raft request' (duration: 326.628086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:03.115915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:56:02.788907Z","time spent":"326.934708ms","remote":"127.0.0.1:34168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:56:03.32214Z","caller":"traceutil/trace.go:171","msg":"trace[892860367] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1397; }","duration":"211.538688ms","start":"2026-07-21T20:56:03.110572Z","end":"2026-07-21T20:56:03.32211Z","steps":["trace[892860367] 'read index received' (duration: 5.038888ms)","trace[892860367] 'applied index is now lower than readState.Index' (duration: 206.49839ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:56:03.322381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.709741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:56:03.322475Z","caller":"traceutil/trace.go:171","msg":"trace[1430884462] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1343; }","duration":"205.872453ms","start":"2026-07-21T20:56:03.116583Z","end":"2026-07-21T20:56:03.322456Z","steps":["trace[1430884462] 'agreement among raft nodes before linearized reading' (duration: 205.721251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:03.322463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.871961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:56:03.32215Z","caller":"traceutil/trace.go:171","msg":"trace[146206344] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"240.068748ms","start":"2026-07-21T20:56:03.082049Z","end":"2026-07-21T20:56:03.322118Z","steps":["trace[146206344] 'process raft request' (duration: 168.476778ms)","trace[146206344] 'compare' (duration: 71.229725ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:03.322681Z","caller":"traceutil/trace.go:171","msg":"trace[12219603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"212.132964ms","start":"2026-07-21T20:56:03.11053Z","end":"2026-07-21T20:56:03.322663Z","steps":["trace[12219603] 'agreement among raft nodes before linearized reading' (duration: 211.7295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:03.7529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.999745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:56:03.753139Z","caller":"traceutil/trace.go:171","msg":"trace[1061775785] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"228.12429ms","start":"2026-07-21T20:56:03.524967Z","end":"2026-07-21T20:56:03.753091Z","steps":["trace[1061775785] 'process raft request' (duration: 115.567169ms)","trace[1061775785] 'compare' (duration: 111.869894ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:03.753413Z","caller":"traceutil/trace.go:171","msg":"trace[150808226] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"227.755187ms","start":"2026-07-21T20:56:03.525637Z","end":"2026-07-21T20:56:03.753392Z","steps":["trace[150808226] 'process raft request' (duration: 227.385263ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:23.662361Z","caller":"traceutil/trace.go:171","msg":"trace[1460305531] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"183.411996ms","start":"2026-07-21T20:56:23.478926Z","end":"2026-07-21T20:56:23.662338Z","steps":["trace[1460305531] 'read index received' (duration: 183.253674ms)","trace[1460305531] 'applied index is now lower than readState.Index' (duration: 157.302µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:23.662476Z","caller":"traceutil/trace.go:171","msg":"trace[1510512554] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"185.91938ms","start":"2026-07-21T20:56:23.476544Z","end":"2026-07-21T20:56:23.662464Z","steps":["trace[1510512554] 'process raft request' (duration: 185.579297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:23.662942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.993362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:37 size:8042"} {"level":"info","ts":"2026-07-21T20:56:23.66303Z","caller":"traceutil/trace.go:171","msg":"trace[220351008] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:37; response_revision:1409; }","duration":"184.133273ms","start":"2026-07-21T20:56:23.478877Z","end":"2026-07-21T20:56:23.663011Z","steps":["trace[220351008] 'agreement among raft nodes before linearized reading' (duration: 183.713439ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:23.692358Z","caller":"traceutil/trace.go:171","msg":"trace[1730864807] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"143.641338ms","start":"2026-07-21T20:56:23.548691Z","end":"2026-07-21T20:56:23.692332Z","steps":["trace[1730864807] 'process raft request' (duration: 143.486245ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:23.692439Z","caller":"traceutil/trace.go:171","msg":"trace[1100926056] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"118.374817ms","start":"2026-07-21T20:56:23.57403Z","end":"2026-07-21T20:56:23.692405Z","steps":["trace[1100926056] 'process raft request' (duration: 118.247716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:23.6925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.351965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-07-21T20:56:23.692568Z","caller":"traceutil/trace.go:171","msg":"trace[142974110] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1411; }","duration":"143.448096ms","start":"2026-07-21T20:56:23.549101Z","end":"2026-07-21T20:56:23.692549Z","steps":["trace[142974110] 'agreement among raft nodes before linearized reading' (duration: 143.318984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:23.692982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.449124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"info","ts":"2026-07-21T20:56:23.693022Z","caller":"traceutil/trace.go:171","msg":"trace[92004610] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:1411; }","duration":"146.525596ms","start":"2026-07-21T20:56:23.546484Z","end":"2026-07-21T20:56:23.693009Z","steps":["trace[92004610] 'agreement among raft nodes before linearized reading' (duration: 145.83463ms)"],"step_count":1}