{"level":"warn","ts":"2026-06-25T22:14:41.784358Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:14:41.784455Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.66: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.66:2380","--initial-cluster=instance=https://199.19.213.66:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.66:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.66: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-06-25T22:14:41.784535Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:14:41.784547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.66:2380"]} {"level":"info","ts":"2026-06-25T22:14:41.784573Z","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-06-25T22:14:41.785198Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.66:2379"]} {"level":"info","ts":"2026-06-25T22:14:41.785265Z","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.66:2380"],"listen-peer-urls":["https://199.19.213.66:2380"],"advertise-client-urls":["https://199.19.213.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.66: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-06-25T22:14:41.789972Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.505781ms"} {"level":"info","ts":"2026-06-25T22:14:41.798831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dc35f804a62878ec","cluster-id":"ef5544f2dd66d370"} {"level":"info","ts":"2026-06-25T22:14:41.798889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T22:14:41.798908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec became follower at term 0"} {"level":"info","ts":"2026-06-25T22:14:41.798916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dc35f804a62878ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T22:14:41.798925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec became follower at term 1"} {"level":"info","ts":"2026-06-25T22:14:41.798957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec switched to configuration voters=(15867861560961038572)"} {"level":"warn","ts":"2026-06-25T22:14:41.806143Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T22:14:41.807881Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T22:14:41.809423Z","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-06-25T22:14:41.812274Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dc35f804a62878ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T22:14:41.812632Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dc35f804a62878ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T22:14:41.81287Z","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-06-25T22:14:41.813068Z","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-06-25T22:14:41.813089Z","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-06-25T22:14:41.813993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec switched to configuration voters=(15867861560961038572)"} {"level":"info","ts":"2026-06-25T22:14:41.814085Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ef5544f2dd66d370","local-member-id":"dc35f804a62878ec","added-peer-id":"dc35f804a62878ec","added-peer-peer-urls":["https://199.19.213.66:2380"]} {"level":"info","ts":"2026-06-25T22:14:41.818548Z","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-06-25T22:14:41.818654Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.66:2380"} {"level":"info","ts":"2026-06-25T22:14:41.81868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.66:2380"} {"level":"info","ts":"2026-06-25T22:14:41.820819Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dc35f804a62878ec","initial-advertise-peer-urls":["https://199.19.213.66:2380"],"listen-peer-urls":["https://199.19.213.66:2380"],"advertise-client-urls":["https://199.19.213.66:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.66:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T22:14:41.82086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T22:14:42.001402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T22:14:42.001443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T22:14:42.001461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec received MsgPreVoteResp from dc35f804a62878ec at term 1"} {"level":"info","ts":"2026-06-25T22:14:42.001472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec became candidate at term 2"} {"level":"info","ts":"2026-06-25T22:14:42.001478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec received MsgVoteResp from dc35f804a62878ec at term 2"} {"level":"info","ts":"2026-06-25T22:14:42.001486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dc35f804a62878ec became leader at term 2"} {"level":"info","ts":"2026-06-25T22:14:42.001493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dc35f804a62878ec elected leader dc35f804a62878ec at term 2"} {"level":"info","ts":"2026-06-25T22:14:42.008926Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dc35f804a62878ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.66:2379]}","request-path":"/0/members/dc35f804a62878ec/attributes","cluster-id":"ef5544f2dd66d370","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T22:14:42.009203Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:14:42.00949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:14:42.009972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:14:42.010183Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T22:14:42.010208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T22:14:42.011175Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ef5544f2dd66d370","local-member-id":"dc35f804a62878ec","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:14:42.01129Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:14:42.011383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:14:42.012719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.66:2379"} {"level":"info","ts":"2026-06-25T22:14:42.012953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-25T22:14:45.901082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.712781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:14:45.901377Z","caller":"traceutil/trace.go:171","msg":"trace[270385101] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"202.74579ms","start":"2026-06-25T22:14:45.698556Z","end":"2026-06-25T22:14:45.901302Z","steps":["trace[270385101] 'process raft request' (duration: 58.13463ms)","trace[270385101] 'compare' (duration: 143.522057ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:14:57.931299Z","caller":"traceutil/trace.go:171","msg":"trace[1650981615] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"119.989295ms","start":"2026-06-25T22:14:57.811269Z","end":"2026-06-25T22:14:57.931258Z","steps":["trace[1650981615] 'process raft request' (duration: 119.721798ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:00.057933Z","caller":"traceutil/trace.go:171","msg":"trace[434935929] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"122.191135ms","start":"2026-06-25T22:14:59.935713Z","end":"2026-06-25T22:15:00.057904Z","steps":["trace[434935929] 'read index received' (duration: 122.090376ms)","trace[434935929] 'applied index is now lower than readState.Index' (duration: 99.609µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:00.058069Z","caller":"traceutil/trace.go:171","msg":"trace[1985684563] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"147.661367ms","start":"2026-06-25T22:14:59.910368Z","end":"2026-06-25T22:15:00.05803Z","steps":["trace[1985684563] 'process raft request' (duration: 147.370859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:00.058314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.601173ms","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-06-25T22:15:00.058311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.117749ms","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-06-25T22:15:00.058381Z","caller":"traceutil/trace.go:171","msg":"trace[885779748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"105.260428ms","start":"2026-06-25T22:14:59.9531Z","end":"2026-06-25T22:15:00.058361Z","steps":["trace[885779748] 'agreement among raft nodes before linearized reading' (duration: 105.03549ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:00.058501Z","caller":"traceutil/trace.go:171","msg":"trace[1857630653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"122.707232ms","start":"2026-06-25T22:14:59.935645Z","end":"2026-06-25T22:15:00.058352Z","steps":["trace[1857630653] 'agreement among raft nodes before linearized reading' (duration: 122.407704ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:00.175565Z","caller":"traceutil/trace.go:171","msg":"trace[1164003522] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"112.345278ms","start":"2026-06-25T22:15:00.063191Z","end":"2026-06-25T22:15:00.175537Z","steps":["trace[1164003522] 'process raft request' (duration: 112.284479ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:00.175623Z","caller":"traceutil/trace.go:171","msg":"trace[1608467586] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"112.902635ms","start":"2026-06-25T22:15:00.06269Z","end":"2026-06-25T22:15:00.175593Z","steps":["trace[1608467586] 'process raft request' (duration: 54.573235ms)","trace[1608467586] 'compare' (duration: 58.067552ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:05.62769Z","caller":"traceutil/trace.go:171","msg":"trace[103764110] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"158.794338ms","start":"2026-06-25T22:15:05.468867Z","end":"2026-06-25T22:15:05.627662Z","steps":["trace[103764110] 'read index received' (duration: 158.494158ms)","trace[103764110] 'applied index is now lower than readState.Index' (duration: 298.93µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:05.627852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.966768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:15:05.6279Z","caller":"traceutil/trace.go:171","msg":"trace[334135778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"159.097797ms","start":"2026-06-25T22:15:05.468788Z","end":"2026-06-25T22:15:05.627886Z","steps":["trace[334135778] 'agreement among raft nodes before linearized reading' (duration: 158.993498ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:05.62805Z","caller":"traceutil/trace.go:171","msg":"trace[103672532] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"259.454861ms","start":"2026-06-25T22:15:05.368553Z","end":"2026-06-25T22:15:05.628007Z","steps":["trace[103672532] 'process raft request' (duration: 258.882082ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:06.388661Z","caller":"traceutil/trace.go:171","msg":"trace[284894827] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"190.786992ms","start":"2026-06-25T22:15:06.197845Z","end":"2026-06-25T22:15:06.388632Z","steps":["trace[284894827] 'read index received' (duration: 106.777151ms)","trace[284894827] 'applied index is now lower than readState.Index' (duration: 84.008001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:06.388913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.692727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:15:06.388964Z","caller":"traceutil/trace.go:171","msg":"trace[418570260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:435; }","duration":"179.811687ms","start":"2026-06-25T22:15:06.20914Z","end":"2026-06-25T22:15:06.388952Z","steps":["trace[418570260] 'agreement among raft nodes before linearized reading' (duration: 179.689437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:06.388993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.110412ms","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-06-25T22:15:06.389125Z","caller":"traceutil/trace.go:171","msg":"trace[1214918688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:435; }","duration":"191.266702ms","start":"2026-06-25T22:15:06.197829Z","end":"2026-06-25T22:15:06.389095Z","steps":["trace[1214918688] 'agreement among raft nodes before linearized reading' (duration: 190.988822ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:07.906492Z","caller":"traceutil/trace.go:171","msg":"trace[2056639989] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"270.293521ms","start":"2026-06-25T22:15:07.636161Z","end":"2026-06-25T22:15:07.906454Z","steps":["trace[2056639989] 'process raft request' (duration: 270.04119ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:08.514968Z","caller":"traceutil/trace.go:171","msg":"trace[177803767] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"313.504846ms","start":"2026-06-25T22:15:08.201431Z","end":"2026-06-25T22:15:08.514936Z","steps":["trace[177803767] 'read index received' (duration: 217.336173ms)","trace[177803767] 'applied index is now lower than readState.Index' (duration: 96.166973ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:08.515015Z","caller":"traceutil/trace.go:171","msg":"trace[2060432645] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"383.09473ms","start":"2026-06-25T22:15:08.131844Z","end":"2026-06-25T22:15:08.514938Z","steps":["trace[2060432645] 'process raft request' (duration: 287.017737ms)","trace[2060432645] 'compare' (duration: 95.951012ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:08.515153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.696816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-25T22:15:08.515219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.957263ms","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-06-25T22:15:08.51527Z","caller":"traceutil/trace.go:171","msg":"trace[1993490259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:440; }","duration":"110.077514ms","start":"2026-06-25T22:15:08.405175Z","end":"2026-06-25T22:15:08.515253Z","steps":["trace[1993490259] 'agreement among raft nodes before linearized reading' (duration: 109.939724ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:08.515289Z","caller":"traceutil/trace.go:171","msg":"trace[386638524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:440; }","duration":"313.894366ms","start":"2026-06-25T22:15:08.201344Z","end":"2026-06-25T22:15:08.515238Z","steps":["trace[386638524] 'agreement among raft nodes before linearized reading' (duration: 313.698706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:08.515338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:08.201318Z","time spent":"314.010496ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T22:15:08.515429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:08.13181Z","time spent":"383.28784ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:08.515532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.284733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:15:08.515563Z","caller":"traceutil/trace.go:171","msg":"trace[1472778055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:440; }","duration":"110.364705ms","start":"2026-06-25T22:15:08.405188Z","end":"2026-06-25T22:15:08.515553Z","steps":["trace[1472778055] 'agreement among raft nodes before linearized reading' (duration: 110.310024ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:08.664897Z","caller":"traceutil/trace.go:171","msg":"trace[720154400] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"143.155193ms","start":"2026-06-25T22:15:08.521706Z","end":"2026-06-25T22:15:08.664861Z","steps":["trace[720154400] 'process raft request' (duration: 98.985087ms)","trace[720154400] 'compare' (duration: 43.920735ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:08.665116Z","caller":"traceutil/trace.go:171","msg":"trace[572321785] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"142.534722ms","start":"2026-06-25T22:15:08.522551Z","end":"2026-06-25T22:15:08.665086Z","steps":["trace[572321785] 'process raft request' (duration: 142.251482ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:10.109595Z","caller":"traceutil/trace.go:171","msg":"trace[658018903] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"193.85447ms","start":"2026-06-25T22:15:09.915698Z","end":"2026-06-25T22:15:10.109553Z","steps":["trace[658018903] 'process raft request' (duration: 193.196578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:13.195828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.296389ms","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-06-25T22:15:13.195933Z","caller":"traceutil/trace.go:171","msg":"trace[1977852662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"474.470161ms","start":"2026-06-25T22:15:12.721438Z","end":"2026-06-25T22:15:13.195908Z","steps":["trace[1977852662] 'range keys from in-memory index tree' (duration: 474.134739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:13.195908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.39359ms","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-06-25T22:15:13.195984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:12.72141Z","time spent":"474.564701ms","remote":"127.0.0.1:44210","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":"info","ts":"2026-06-25T22:15:13.19602Z","caller":"traceutil/trace.go:171","msg":"trace[28275469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"472.575441ms","start":"2026-06-25T22:15:12.723415Z","end":"2026-06-25T22:15:13.19599Z","steps":["trace[28275469] 'range keys from in-memory index tree' (duration: 472.199209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:13.196146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:12.723388Z","time spent":"472.745102ms","remote":"127.0.0.1:44210","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-06-25T22:15:13.196238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.796372ms","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-06-25T22:15:13.196336Z","caller":"traceutil/trace.go:171","msg":"trace[2143732797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"619.100314ms","start":"2026-06-25T22:15:12.577209Z","end":"2026-06-25T22:15:13.196309Z","steps":["trace[2143732797] 'range keys from in-memory index tree' (duration: 618.591751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:13.196411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:12.577129Z","time spent":"619.244425ms","remote":"127.0.0.1:44210","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-06-25T22:15:13.84534Z","caller":"traceutil/trace.go:171","msg":"trace[1877138142] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"644.906794ms","start":"2026-06-25T22:15:13.200399Z","end":"2026-06-25T22:15:13.845306Z","steps":["trace[1877138142] 'process raft request' (duration: 644.649683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:13.84561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:13.200367Z","time spent":"645.119245ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:14.385986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.233681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:14.386293Z","caller":"traceutil/trace.go:171","msg":"trace[1996803924] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"1.184638991s","start":"2026-06-25T22:15:13.201626Z","end":"2026-06-25T22:15:14.386265Z","steps":["trace[1996803924] 'process raft request' (duration: 1.184514321s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:14.386322Z","caller":"traceutil/trace.go:171","msg":"trace[1242631147] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"1.184751172s","start":"2026-06-25T22:15:13.201495Z","end":"2026-06-25T22:15:14.386246Z","steps":["trace[1242631147] 'process raft request' (duration: 899.162689ms)","trace[1242631147] 'compare' (duration: 284.856118ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:14.38645Z","caller":"traceutil/trace.go:171","msg":"trace[307403336] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:468; }","duration":"922.860275ms","start":"2026-06-25T22:15:13.463576Z","end":"2026-06-25T22:15:14.386436Z","steps":["trace[307403336] 'read index received' (duration: 381.524477ms)","trace[307403336] 'applied index is now lower than readState.Index' (duration: 541.334498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:14.386586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.013326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:15:14.386627Z","caller":"traceutil/trace.go:171","msg":"trace[1143376513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"923.131717ms","start":"2026-06-25T22:15:13.463481Z","end":"2026-06-25T22:15:14.386613Z","steps":["trace[1143376513] 'agreement among raft nodes before linearized reading' (duration: 923.059796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:14.386671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:13.463456Z","time spent":"923.205987ms","remote":"127.0.0.1:43974","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-06-25T22:15:14.386763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.17371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-25T22:15:14.386798Z","caller":"traceutil/trace.go:171","msg":"trace[1816702318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"259.26265ms","start":"2026-06-25T22:15:14.127525Z","end":"2026-06-25T22:15:14.386788Z","steps":["trace[1816702318] 'agreement among raft nodes before linearized reading' (duration: 259.20058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:14.386384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:13.201588Z","time spent":"1.184757393s","remote":"127.0.0.1:44210","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-06-25T22:15:14.386796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.93475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-06-25T22:15:14.386866Z","caller":"traceutil/trace.go:171","msg":"trace[505290609] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:452; }","duration":"371.06346ms","start":"2026-06-25T22:15:14.015784Z","end":"2026-06-25T22:15:14.386847Z","steps":["trace[505290609] 'agreement among raft nodes before linearized reading' (duration: 370.93326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:14.386918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:14.015757Z","time spent":"371.148361ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-06-25T22:15:14.387076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:13.201461Z","time spent":"1.185038144s","remote":"127.0.0.1:44210","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-06-25T22:15:14.797479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.534892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:14.797631Z","caller":"traceutil/trace.go:171","msg":"trace[1051752065] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"394.945086ms","start":"2026-06-25T22:15:14.402654Z","end":"2026-06-25T22:15:14.797599Z","steps":["trace[1051752065] 'process raft request' (duration: 250.939591ms)","trace[1051752065] 'compare' (duration: 143.375501ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:14.797704Z","caller":"traceutil/trace.go:171","msg":"trace[2143392801] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"330.274935ms","start":"2026-06-25T22:15:14.467413Z","end":"2026-06-25T22:15:14.797688Z","steps":["trace[2143392801] 'process raft request' (duration: 330.192155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:14.797741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:14.402644Z","time spent":"395.049657ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:14.797781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:14.467398Z","time spent":"330.346655ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:14.79789Z","caller":"traceutil/trace.go:171","msg":"trace[2104512844] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"331.267842ms","start":"2026-06-25T22:15:14.466578Z","end":"2026-06-25T22:15:14.797846Z","steps":["trace[2104512844] 'read index received' (duration: 187.034305ms)","trace[2104512844] 'applied index is now lower than readState.Index' (duration: 144.229827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:14.797992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.414483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:15:14.798021Z","caller":"traceutil/trace.go:171","msg":"trace[1316923254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"331.486603ms","start":"2026-06-25T22:15:14.466526Z","end":"2026-06-25T22:15:14.798012Z","steps":["trace[1316923254] 'agreement among raft nodes before linearized reading' (duration: 331.437993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:14.798048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:14.466473Z","time spent":"331.570355ms","remote":"127.0.0.1:43974","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-06-25T22:15:15.507879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.058636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:15.508241Z","caller":"traceutil/trace.go:171","msg":"trace[875146576] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"699.721719ms","start":"2026-06-25T22:15:14.808455Z","end":"2026-06-25T22:15:15.508176Z","steps":["trace[875146576] 'process raft request' (duration: 553.302231ms)","trace[875146576] 'compare' (duration: 145.751143ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:15.508471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:14.808433Z","time spent":"699.938411ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:15:15.678176Z","caller":"traceutil/trace.go:171","msg":"trace[305378399] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"136.32951ms","start":"2026-06-25T22:15:15.541803Z","end":"2026-06-25T22:15:15.678133Z","steps":["trace[305378399] 'process raft request' (duration: 115.518867ms)","trace[305378399] 'compare' (duration: 20.653942ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:15.702581Z","caller":"traceutil/trace.go:171","msg":"trace[1147824774] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"105.76206ms","start":"2026-06-25T22:15:15.596778Z","end":"2026-06-25T22:15:15.70254Z","steps":["trace[1147824774] 'process raft request' (duration: 105.413297ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:15.702658Z","caller":"traceutil/trace.go:171","msg":"trace[1510068566] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"102.494494ms","start":"2026-06-25T22:15:15.600142Z","end":"2026-06-25T22:15:15.702637Z","steps":["trace[1510068566] 'process raft request' (duration: 102.313293ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:15.702709Z","caller":"traceutil/trace.go:171","msg":"trace[1351851216] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"102.283483ms","start":"2026-06-25T22:15:15.600386Z","end":"2026-06-25T22:15:15.702669Z","steps":["trace[1351851216] 'process raft request' (duration: 102.150172ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:15.703143Z","caller":"traceutil/trace.go:171","msg":"trace[780310522] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"104.669412ms","start":"2026-06-25T22:15:15.59789Z","end":"2026-06-25T22:15:15.702559Z","steps":["trace[780310522] 'process raft request' (duration: 104.523771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:16.112138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.45121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:16.112467Z","caller":"traceutil/trace.go:171","msg":"trace[157930814] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"245.134024ms","start":"2026-06-25T22:15:15.867299Z","end":"2026-06-25T22:15:16.112433Z","steps":["trace[157930814] 'process raft request' (duration: 131.27692ms)","trace[157930814] 'compare' (duration: 113.291379ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:16.112529Z","caller":"traceutil/trace.go:171","msg":"trace[352770119] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:492; }","duration":"243.649372ms","start":"2026-06-25T22:15:15.868855Z","end":"2026-06-25T22:15:16.112505Z","steps":["trace[352770119] 'read index received' (duration: 91.291447ms)","trace[352770119] 'applied index is now lower than readState.Index' (duration: 152.356535ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:16.112946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.069106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-06-25T22:15:16.11299Z","caller":"traceutil/trace.go:171","msg":"trace[2070887009] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:476; }","duration":"244.157947ms","start":"2026-06-25T22:15:15.86882Z","end":"2026-06-25T22:15:16.112978Z","steps":["trace[2070887009] 'agreement among raft nodes before linearized reading' (duration: 244.039586ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.11312Z","caller":"traceutil/trace.go:171","msg":"trace[1694237284] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"244.674261ms","start":"2026-06-25T22:15:15.868433Z","end":"2026-06-25T22:15:16.113107Z","steps":["trace[1694237284] 'process raft request' (duration: 243.831223ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.113394Z","caller":"traceutil/trace.go:171","msg":"trace[886482558] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"243.599552ms","start":"2026-06-25T22:15:15.869785Z","end":"2026-06-25T22:15:16.113384Z","steps":["trace[886482558] 'process raft request' (duration: 242.623524ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.397081Z","caller":"traceutil/trace.go:171","msg":"trace[1164969341] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"279.519572ms","start":"2026-06-25T22:15:16.11753Z","end":"2026-06-25T22:15:16.397049Z","steps":["trace[1164969341] 'process raft request' (duration: 232.636754ms)","trace[1164969341] 'compare' (duration: 46.642266ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:16.397183Z","caller":"traceutil/trace.go:171","msg":"trace[700632348] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:497; }","duration":"231.105041ms","start":"2026-06-25T22:15:16.166062Z","end":"2026-06-25T22:15:16.397167Z","steps":["trace[700632348] 'read index received' (duration: 184.114282ms)","trace[700632348] 'applied index is now lower than readState.Index' (duration: 46.990069ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:16.397433Z","caller":"traceutil/trace.go:171","msg":"trace[1467640927] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"278.465832ms","start":"2026-06-25T22:15:16.118942Z","end":"2026-06-25T22:15:16.397407Z","steps":["trace[1467640927] 'process raft request' (duration: 278.116749ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.397593Z","caller":"traceutil/trace.go:171","msg":"trace[748996810] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"279.569592ms","start":"2026-06-25T22:15:16.11801Z","end":"2026-06-25T22:15:16.39758Z","steps":["trace[748996810] 'process raft request' (duration: 278.999187ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.397751Z","caller":"traceutil/trace.go:171","msg":"trace[1918549500] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"278.386592ms","start":"2026-06-25T22:15:16.119332Z","end":"2026-06-25T22:15:16.397718Z","steps":["trace[1918549500] 'process raft request' (duration: 277.790536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:16.398028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.105043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-06-25T22:15:16.398126Z","caller":"traceutil/trace.go:171","msg":"trace[1602944664] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:480; }","duration":"275.262834ms","start":"2026-06-25T22:15:16.122849Z","end":"2026-06-25T22:15:16.398112Z","steps":["trace[1602944664] 'agreement among raft nodes before linearized reading' (duration: 275.110633ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.405881Z","caller":"traceutil/trace.go:171","msg":"trace[1336847670] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"233.242109ms","start":"2026-06-25T22:15:16.17262Z","end":"2026-06-25T22:15:16.405862Z","steps":["trace[1336847670] 'process raft request' (duration: 233.175679ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.406179Z","caller":"traceutil/trace.go:171","msg":"trace[354063974] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"239.13789ms","start":"2026-06-25T22:15:16.16702Z","end":"2026-06-25T22:15:16.406158Z","steps":["trace[354063974] 'process raft request' (duration: 238.659886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:16.53368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.666914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-25T22:15:16.53377Z","caller":"traceutil/trace.go:171","msg":"trace[513240375] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:486; }","duration":"101.806436ms","start":"2026-06-25T22:15:16.431943Z","end":"2026-06-25T22:15:16.533749Z","steps":["trace[513240375] 'agreement among raft nodes before linearized reading' (duration: 101.631954ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:16.687401Z","caller":"traceutil/trace.go:171","msg":"trace[1314254165] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"129.784849ms","start":"2026-06-25T22:15:16.557573Z","end":"2026-06-25T22:15:16.687358Z","steps":["trace[1314254165] 'process raft request' (duration: 112.236026ms)","trace[1314254165] 'compare' (duration: 17.351671ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:18.684635Z","caller":"traceutil/trace.go:171","msg":"trace[1913099205] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"131.113679ms","start":"2026-06-25T22:15:18.55349Z","end":"2026-06-25T22:15:18.684604Z","steps":["trace[1913099205] 'process raft request' (duration: 128.409171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.048033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:29.35157Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.13159889s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:15:29.352144Z","caller":"traceutil/trace.go:171","msg":"trace[581474] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.132294093s","start":"2026-06-25T22:15:28.219818Z","end":"2026-06-25T22:15:29.352112Z","steps":["trace[581474] 'process raft request' (duration: 1.132030558s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.352345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.219785Z","time spent":"1.132455666s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:29.508341Z","caller":"traceutil/trace.go:171","msg":"trace[542854602] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.281027131s","start":"2026-06-25T22:15:28.227275Z","end":"2026-06-25T22:15:29.508302Z","steps":["trace[542854602] 'process raft request' (duration: 1.280728265s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.508543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.227248Z","time spent":"1.281232505s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:15:29.508493Z","caller":"traceutil/trace.go:171","msg":"trace[233589467] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.27983781s","start":"2026-06-25T22:15:28.228589Z","end":"2026-06-25T22:15:29.508427Z","steps":["trace[233589467] 'process raft request' (duration: 1.279626546s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:29.508362Z","caller":"traceutil/trace.go:171","msg":"trace[524930369] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.273484616s","start":"2026-06-25T22:15:28.234843Z","end":"2026-06-25T22:15:29.508328Z","steps":["trace[524930369] 'process raft request' (duration: 1.273416015s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.508717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.234804Z","time spent":"1.273894533s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:15:29.508761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.228581Z","time spent":"1.280065204s","remote":"127.0.0.1:44032","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-06-25T22:15:29.508879Z","caller":"traceutil/trace.go:171","msg":"trace[1785582217] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.264411683s","start":"2026-06-25T22:15:28.244441Z","end":"2026-06-25T22:15:29.508852Z","steps":["trace[1785582217] 'process raft request' (duration: 1.263920884s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:29.508889Z","caller":"traceutil/trace.go:171","msg":"trace[1758463188] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.261839447s","start":"2026-06-25T22:15:28.246893Z","end":"2026-06-25T22:15:29.508733Z","steps":["trace[1758463188] 'process raft request' (duration: 1.261606563s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.244429Z","time spent":"1.264520635s","remote":"127.0.0.1:44032","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-06-25T22:15:29.509109Z","caller":"traceutil/trace.go:171","msg":"trace[1104694723] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.262112362s","start":"2026-06-25T22:15:28.246947Z","end":"2026-06-25T22:15:29.50906Z","steps":["trace[1104694723] 'process raft request' (duration: 1.261602872s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:29.509023Z","caller":"traceutil/trace.go:171","msg":"trace[1554541505] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.261545312s","start":"2026-06-25T22:15:28.24747Z","end":"2026-06-25T22:15:29.509016Z","steps":["trace[1554541505] 'process raft request' (duration: 1.261158435s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.246885Z","time spent":"1.262124822s","remote":"127.0.0.1:44032","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-06-25T22:15:29.509194Z","caller":"traceutil/trace.go:171","msg":"trace[1577641669] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:569; }","duration":"962.145068ms","start":"2026-06-25T22:15:28.546992Z","end":"2026-06-25T22:15:29.509137Z","steps":["trace[1577641669] 'read index received' (duration: 804.966088ms)","trace[1577641669] 'applied index is now lower than readState.Index' (duration: 157.17793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:29.50923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.247464Z","time spent":"1.261724545s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:15:29.509254Z","caller":"traceutil/trace.go:171","msg":"trace[435558200] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.261861787s","start":"2026-06-25T22:15:28.247386Z","end":"2026-06-25T22:15:29.509248Z","steps":["trace[435558200] 'process raft request' (duration: 1.261202065s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:29.509294Z","caller":"traceutil/trace.go:171","msg":"trace[446879971] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.253376755s","start":"2026-06-25T22:15:28.255908Z","end":"2026-06-25T22:15:29.509285Z","steps":["trace[446879971] 'process raft request' (duration: 1.252818785s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.50931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.24736Z","time spent":"1.261939149s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:15:29.509392Z","caller":"traceutil/trace.go:171","msg":"trace[1312140068] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"182.151506ms","start":"2026-06-25T22:15:29.327197Z","end":"2026-06-25T22:15:29.509348Z","steps":["trace[1312140068] 'process raft request' (duration: 181.83048ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:29.509403Z","caller":"traceutil/trace.go:171","msg":"trace[1204769469] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.23746872s","start":"2026-06-25T22:15:28.27188Z","end":"2026-06-25T22:15:29.509349Z","steps":["trace[1204769469] 'process raft request' (duration: 1.236949591s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.255891Z","time spent":"1.253429967s","remote":"127.0.0.1:44032","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-06-25T22:15:29.509454Z","caller":"traceutil/trace.go:171","msg":"trace[1346658583] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.253044949s","start":"2026-06-25T22:15:28.256404Z","end":"2026-06-25T22:15:29.509448Z","steps":["trace[1346658583] 'process raft request' (duration: 1.252372747s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.271866Z","time spent":"1.237576852s","remote":"127.0.0.1:44032","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-06-25T22:15:29.509493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.256397Z","time spent":"1.25308136s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:15:29.50959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.593566ms","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-06-25T22:15:29.509607Z","caller":"traceutil/trace.go:171","msg":"trace[34612261] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.091228225s","start":"2026-06-25T22:15:28.418374Z","end":"2026-06-25T22:15:29.509602Z","steps":["trace[34612261] 'process raft request' (duration: 1.090589993s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.246941Z","time spent":"1.262193643s","remote":"127.0.0.1:44032","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-06-25T22:15:29.509674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.418341Z","time spent":"1.091318527s","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:15:29.509629Z","caller":"traceutil/trace.go:171","msg":"trace[1092630452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"962.679898ms","start":"2026-06-25T22:15:28.546939Z","end":"2026-06-25T22:15:29.509619Z","steps":["trace[1092630452] 'agreement among raft nodes before linearized reading' (duration: 962.562626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.546914Z","time spent":"962.823881ms","remote":"127.0.0.1:44210","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-06-25T22:15:29.509802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.631598ms","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-06-25T22:15:29.509834Z","caller":"traceutil/trace.go:171","msg":"trace[530060841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"923.679198ms","start":"2026-06-25T22:15:28.586148Z","end":"2026-06-25T22:15:29.509827Z","steps":["trace[530060841] 'agreement among raft nodes before linearized reading' (duration: 923.567336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.509866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.58614Z","time spent":"923.719189ms","remote":"127.0.0.1:44210","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-06-25T22:15:29.509982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.460186ms","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-06-25T22:15:29.510011Z","caller":"traceutil/trace.go:171","msg":"trace[2121194715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"786.537727ms","start":"2026-06-25T22:15:28.723465Z","end":"2026-06-25T22:15:29.510002Z","steps":["trace[2121194715] 'agreement among raft nodes before linearized reading' (duration: 786.463766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:29.510039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:28.723442Z","time spent":"786.591548ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:15:30.448995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.338562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:30.449606Z","caller":"traceutil/trace.go:171","msg":"trace[448542841] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"317.098117ms","start":"2026-06-25T22:15:30.132444Z","end":"2026-06-25T22:15:30.449542Z","steps":["trace[448542841] 'process raft request' (duration: 162.841817ms)","trace[448542841] 'compare' (duration: 153.025216ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:30.449811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:30.132413Z","time spent":"317.288521ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:30.449783Z","caller":"traceutil/trace.go:171","msg":"trace[700003371] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"308.043163ms","start":"2026-06-25T22:15:30.14169Z","end":"2026-06-25T22:15:30.449733Z","steps":["trace[700003371] 'process raft request' (duration: 307.709026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:30.449981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:30.141666Z","time spent":"308.212696ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:30.452073Z","caller":"traceutil/trace.go:171","msg":"trace[466882567] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"208.898847ms","start":"2026-06-25T22:15:30.243154Z","end":"2026-06-25T22:15:30.452053Z","steps":["trace[466882567] 'process raft request' (duration: 208.119192ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:32.809816Z","caller":"traceutil/trace.go:171","msg":"trace[1437136915] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"120.04971ms","start":"2026-06-25T22:15:32.689734Z","end":"2026-06-25T22:15:32.809784Z","steps":["trace[1437136915] 'process raft request' (duration: 119.592621ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.68882Z","caller":"traceutil/trace.go:171","msg":"trace[1683406844] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"320.286367ms","start":"2026-06-25T22:15:33.36849Z","end":"2026-06-25T22:15:33.688776Z","steps":["trace[1683406844] 'read index received' (duration: 319.423259ms)","trace[1683406844] 'applied index is now lower than readState.Index' (duration: 861.948µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:33.688874Z","caller":"traceutil/trace.go:171","msg":"trace[1468156313] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"435.079693ms","start":"2026-06-25T22:15:33.25377Z","end":"2026-06-25T22:15:33.68885Z","steps":["trace[1468156313] 'process raft request' (duration: 434.353838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:33.689133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:33.253739Z","time spent":"435.215307ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:33.68925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.756727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-25T22:15:33.689272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.625317ms","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-06-25T22:15:33.689304Z","caller":"traceutil/trace.go:171","msg":"trace[163370029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"320.818188ms","start":"2026-06-25T22:15:33.368468Z","end":"2026-06-25T22:15:33.689286Z","steps":["trace[163370029] 'agreement among raft nodes before linearized reading' (duration: 320.47093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:33.68928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.722677ms","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-06-25T22:15:33.689331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.417925ms","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-06-25T22:15:33.689352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:33.368377Z","time spent":"320.965551ms","remote":"127.0.0.1:44210","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":"info","ts":"2026-06-25T22:15:33.689383Z","caller":"traceutil/trace.go:171","msg":"trace[1207795752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"109.75944ms","start":"2026-06-25T22:15:33.579575Z","end":"2026-06-25T22:15:33.689335Z","steps":["trace[1207795752] 'agreement among raft nodes before linearized reading' (duration: 109.489484ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.689415Z","caller":"traceutil/trace.go:171","msg":"trace[1861477772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"138.978242ms","start":"2026-06-25T22:15:33.550409Z","end":"2026-06-25T22:15:33.689387Z","steps":["trace[1861477772] 'agreement among raft nodes before linearized reading' (duration: 138.616474ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.689436Z","caller":"traceutil/trace.go:171","msg":"trace[1378798688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"111.565418ms","start":"2026-06-25T22:15:33.577845Z","end":"2026-06-25T22:15:33.68941Z","steps":["trace[1378798688] 'agreement among raft nodes before linearized reading' (duration: 111.355634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:33.966833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.107185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:15:33.967158Z","caller":"traceutil/trace.go:171","msg":"trace[1581534272] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"273.485418ms","start":"2026-06-25T22:15:33.693636Z","end":"2026-06-25T22:15:33.967121Z","steps":["trace[1581534272] 'process raft request' (duration: 49.959414ms)","trace[1581534272] 'compare' (duration: 222.89576ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:33.967261Z","caller":"traceutil/trace.go:171","msg":"trace[1781281238] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"271.72687ms","start":"2026-06-25T22:15:33.695508Z","end":"2026-06-25T22:15:33.967235Z","steps":["trace[1781281238] 'process raft request' (duration: 271.668219ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.967455Z","caller":"traceutil/trace.go:171","msg":"trace[1346024369] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"273.332015ms","start":"2026-06-25T22:15:33.694113Z","end":"2026-06-25T22:15:33.967445Z","steps":["trace[1346024369] 'process raft request' (duration: 272.914586ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.96761Z","caller":"traceutil/trace.go:171","msg":"trace[2116969301] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"273.377176ms","start":"2026-06-25T22:15:33.694225Z","end":"2026-06-25T22:15:33.967602Z","steps":["trace[2116969301] 'process raft request' (duration: 272.908456ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:33.968008Z","caller":"traceutil/trace.go:171","msg":"trace[798315346] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"212.881046ms","start":"2026-06-25T22:15:33.75511Z","end":"2026-06-25T22:15:33.967991Z","steps":["trace[798315346] 'process raft request' (duration: 212.808555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:35.905283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020776672s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:15:35.908069Z","caller":"traceutil/trace.go:171","msg":"trace[2132808055] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.025216901s","start":"2026-06-25T22:15:34.882819Z","end":"2026-06-25T22:15:35.908036Z","steps":["trace[2132808055] 'process raft request' (duration: 1.023647316s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:35.908259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:34.882785Z","time spent":"1.025371554s","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:35.915632Z","caller":"traceutil/trace.go:171","msg":"trace[49616317] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"448.86174ms","start":"2026-06-25T22:15:35.466705Z","end":"2026-06-25T22:15:35.915567Z","steps":["trace[49616317] 'read index received' (duration: 438.860134ms)","trace[49616317] 'applied index is now lower than readState.Index' (duration: 9.999846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:35.91587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.154326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:15:35.915925Z","caller":"traceutil/trace.go:171","msg":"trace[1000069332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"449.243898ms","start":"2026-06-25T22:15:35.466659Z","end":"2026-06-25T22:15:35.915903Z","steps":["trace[1000069332] 'agreement among raft nodes before linearized reading' (duration: 449.041024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:35.915967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:35.466643Z","time spent":"449.304729ms","remote":"127.0.0.1:43974","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-06-25T22:15:35.921983Z","caller":"traceutil/trace.go:171","msg":"trace[404301365] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"463.750386ms","start":"2026-06-25T22:15:35.458193Z","end":"2026-06-25T22:15:35.921944Z","steps":["trace[404301365] 'process raft request' (duration: 457.221418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:35.9223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:35.45815Z","time spent":"464.036802ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:36.234719Z","caller":"traceutil/trace.go:171","msg":"trace[494890385] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"262.883341ms","start":"2026-06-25T22:15:35.971799Z","end":"2026-06-25T22:15:36.234683Z","steps":["trace[494890385] 'read index received' (duration: 237.834779ms)","trace[494890385] 'applied index is now lower than readState.Index' (duration: 25.047102ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:36.234828Z","caller":"traceutil/trace.go:171","msg":"trace[239963687] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"266.883731ms","start":"2026-06-25T22:15:35.967895Z","end":"2026-06-25T22:15:36.234779Z","steps":["trace[239963687] 'process raft request' (duration: 241.700666ms)","trace[239963687] 'compare' (duration: 24.892368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:36.235047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.216278ms","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-06-25T22:15:36.235049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.128976ms","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-06-25T22:15:36.235098Z","caller":"traceutil/trace.go:171","msg":"trace[1108994045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"263.330501ms","start":"2026-06-25T22:15:35.971755Z","end":"2026-06-25T22:15:36.235085Z","steps":["trace[1108994045] 'agreement among raft nodes before linearized reading' (duration: 263.095535ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:36.235136Z","caller":"traceutil/trace.go:171","msg":"trace[1533964446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"263.25571ms","start":"2026-06-25T22:15:35.971862Z","end":"2026-06-25T22:15:36.235117Z","steps":["trace[1533964446] 'agreement among raft nodes before linearized reading' (duration: 262.995954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:36.235308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.2503ms","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-06-25T22:15:36.235343Z","caller":"traceutil/trace.go:171","msg":"trace[1184768306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"263.328191ms","start":"2026-06-25T22:15:35.972006Z","end":"2026-06-25T22:15:36.235334Z","steps":["trace[1184768306] 'agreement among raft nodes before linearized reading' (duration: 263.237109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:36.235423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.087456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.66\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-25T22:15:36.235409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.194259ms","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-06-25T22:15:36.235482Z","caller":"traceutil/trace.go:171","msg":"trace[1779947192] range","detail":"{range_begin:/registry/masterleases/199.19.213.66; range_end:; response_count:1; response_revision:645; }","duration":"129.205999ms","start":"2026-06-25T22:15:36.106261Z","end":"2026-06-25T22:15:36.235467Z","steps":["trace[1779947192] 'agreement among raft nodes before linearized reading' (duration: 129.053775ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:36.235518Z","caller":"traceutil/trace.go:171","msg":"trace[1742100123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"263.369692ms","start":"2026-06-25T22:15:35.972118Z","end":"2026-06-25T22:15:36.235488Z","steps":["trace[1742100123] 'agreement among raft nodes before linearized reading' (duration: 263.062885ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:37.412858Z","caller":"traceutil/trace.go:171","msg":"trace[765501486] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"393.520484ms","start":"2026-06-25T22:15:37.019309Z","end":"2026-06-25T22:15:37.412829Z","steps":["trace[765501486] 'read index received' (duration: 392.414388ms)","trace[765501486] 'applied index is now lower than readState.Index' (duration: 1.105186ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:37.412932Z","caller":"traceutil/trace.go:171","msg":"trace[1721202574] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"742.838242ms","start":"2026-06-25T22:15:36.670065Z","end":"2026-06-25T22:15:37.412903Z","steps":["trace[1721202574] 'process raft request' (duration: 741.91596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:37.413154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:36.67002Z","time spent":"743.016566ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:37.413228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.73827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfrqg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-25T22:15:37.41349Z","caller":"traceutil/trace.go:171","msg":"trace[527801364] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfrqg; range_end:; response_count:1; response_revision:654; }","duration":"394.199581ms","start":"2026-06-25T22:15:37.019253Z","end":"2026-06-25T22:15:37.413453Z","steps":["trace[527801364] 'agreement among raft nodes before linearized reading' (duration: 393.73532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:37.41361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:37.01923Z","time spent":"394.347604ms","remote":"127.0.0.1:44150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-dfrqg\" "} {"level":"warn","ts":"2026-06-25T22:15:37.968033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:38.468468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:38.969442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:39.467789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000163076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-25T22:15:39.467896Z","caller":"traceutil/trace.go:171","msg":"trace[1735167700] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00033904s","start":"2026-06-25T22:15:37.467533Z","end":"2026-06-25T22:15:39.467872Z","steps":["trace[1735167700] 'agreement among raft nodes before linearized reading' (duration: 2.000203277s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.467987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:37.467513Z","time spent":"2.000459274s","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/25 22:15:39 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-25T22:15:39.470291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:39.498663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.1053ms","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-06-25T22:15:39.498829Z","caller":"traceutil/trace.go:171","msg":"trace[798160351] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:654; }","duration":"356.923501ms","start":"2026-06-25T22:15:39.14188Z","end":"2026-06-25T22:15:39.498803Z","steps":["trace[798160351] 'range keys from in-memory index tree' (duration: 356.08421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.504689Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.05469285s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:15:39.505112Z","caller":"traceutil/trace.go:171","msg":"trace[582975002] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"2.055282164s","start":"2026-06-25T22:15:37.449813Z","end":"2026-06-25T22:15:39.505095Z","steps":["trace[582975002] 'process raft request' (duration: 2.055104629s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.505272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:37.449771Z","time spent":"2.055411137s","remote":"127.0.0.1:44150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:39.506085Z","caller":"traceutil/trace.go:171","msg":"trace[236981809] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"2.038469805s","start":"2026-06-25T22:15:37.467586Z","end":"2026-06-25T22:15:39.506056Z","steps":["trace[236981809] 'read index received' (duration: 2.037406158s)","trace[236981809] 'applied index is now lower than readState.Index' (duration: 1.062197ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:39.506176Z","caller":"traceutil/trace.go:171","msg":"trace[1271216608] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"2.014944875s","start":"2026-06-25T22:15:37.491222Z","end":"2026-06-25T22:15:39.506167Z","steps":["trace[1271216608] 'process raft request' (duration: 2.014703199s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.506287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:37.491195Z","time spent":"2.015014237s","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:15:39.506383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256303115s","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-06-25T22:15:39.506419Z","caller":"traceutil/trace.go:171","msg":"trace[1475788116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.256339196s","start":"2026-06-25T22:15:38.250066Z","end":"2026-06-25T22:15:39.506405Z","steps":["trace[1475788116] 'agreement among raft nodes before linearized reading' (duration: 1.256139421s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.50644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:38.249948Z","time spent":"1.256487799s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:15:39.506509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258299703s","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-06-25T22:15:39.506631Z","caller":"traceutil/trace.go:171","msg":"trace[1511180744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"1.258480228s","start":"2026-06-25T22:15:38.24812Z","end":"2026-06-25T22:15:39.5066Z","steps":["trace[1511180744] 'agreement among raft nodes before linearized reading' (duration: 1.258073597s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.506681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:38.248084Z","time spent":"1.25858345s","remote":"127.0.0.1:44210","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-06-25T22:15:39.506744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256679754s","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-06-25T22:15:39.506767Z","caller":"traceutil/trace.go:171","msg":"trace[703423712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.256755616s","start":"2026-06-25T22:15:38.250005Z","end":"2026-06-25T22:15:39.50676Z","steps":["trace[703423712] 'agreement among raft nodes before linearized reading' (duration: 1.256687414s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.506784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:38.24998Z","time spent":"1.256800437s","remote":"127.0.0.1:44210","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-06-25T22:15:39.506831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256789807s","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-06-25T22:15:39.506866Z","caller":"traceutil/trace.go:171","msg":"trace[920893956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.256889759s","start":"2026-06-25T22:15:38.249966Z","end":"2026-06-25T22:15:39.506856Z","steps":["trace[920893956] 'agreement among raft nodes before linearized reading' (duration: 1.256730515s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:39.506889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:38.249938Z","time spent":"1.256946461s","remote":"127.0.0.1:44210","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-06-25T22:15:55.967921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8713514205052898833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:15:56.115364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.069877877s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:15:56.11591Z","caller":"traceutil/trace.go:171","msg":"trace[1089292969] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.070562961s","start":"2026-06-25T22:15:55.045314Z","end":"2026-06-25T22:15:56.115877Z","steps":["trace[1089292969] 'process raft request' (duration: 1.070322713s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.116158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.045277Z","time spent":"1.070763037s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:15:56.307217Z","caller":"traceutil/trace.go:171","msg":"trace[2396548] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"1.150043848s","start":"2026-06-25T22:15:55.1571Z","end":"2026-06-25T22:15:56.307144Z","steps":["trace[2396548] 'process raft request' (duration: 1.149769439s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.307436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.805193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:15:56.307495Z","caller":"traceutil/trace.go:171","msg":"trace[1598072768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"839.937177ms","start":"2026-06-25T22:15:55.467547Z","end":"2026-06-25T22:15:56.307484Z","steps":["trace[1598072768] 'agreement among raft nodes before linearized reading' (duration: 839.797523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.30754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.467529Z","time spent":"840.00286ms","remote":"127.0.0.1:43974","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-06-25T22:15:56.307277Z","caller":"traceutil/trace.go:171","msg":"trace[426127558] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:860; }","duration":"839.647007ms","start":"2026-06-25T22:15:55.467597Z","end":"2026-06-25T22:15:56.307244Z","steps":["trace[426127558] 'read index received' (duration: 648.091582ms)","trace[426127558] 'applied index is now lower than readState.Index' (duration: 191.553705ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:56.307662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.157072Z","time spent":"1.150329018s","remote":"127.0.0.1:44194","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-06-25T22:15:56.307947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.513161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-25T22:15:56.307956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.490447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.66\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-25T22:15:56.308051Z","caller":"traceutil/trace.go:171","msg":"trace[229837146] range","detail":"{range_begin:/registry/masterleases/199.19.213.66; range_end:; response_count:1; response_revision:828; }","duration":"200.660993ms","start":"2026-06-25T22:15:56.107368Z","end":"2026-06-25T22:15:56.308029Z","steps":["trace[229837146] 'agreement among raft nodes before linearized reading' (duration: 200.401004ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:56.308063Z","caller":"traceutil/trace.go:171","msg":"trace[873058875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"671.675786ms","start":"2026-06-25T22:15:55.63634Z","end":"2026-06-25T22:15:56.308016Z","steps":["trace[873058875] 'agreement among raft nodes before linearized reading' (duration: 671.402467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.308113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.636311Z","time spent":"671.79258ms","remote":"127.0.0.1:44210","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-06-25T22:15:56.308129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.527071ms","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-06-25T22:15:56.308241Z","caller":"traceutil/trace.go:171","msg":"trace[898053075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"665.683926ms","start":"2026-06-25T22:15:55.642533Z","end":"2026-06-25T22:15:56.308217Z","steps":["trace[898053075] 'agreement among raft nodes before linearized reading' (duration: 665.399396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.308307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.642508Z","time spent":"665.78943ms","remote":"127.0.0.1:44210","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-06-25T22:15:56.308192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.665582ms","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-06-25T22:15:56.308429Z","caller":"traceutil/trace.go:171","msg":"trace[1332984219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"664.987754ms","start":"2026-06-25T22:15:55.643428Z","end":"2026-06-25T22:15:56.308415Z","steps":["trace[1332984219] 'agreement among raft nodes before linearized reading' (duration: 664.350192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.308444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.086964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:15:56.308489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.643421Z","time spent":"665.047446ms","remote":"127.0.0.1:44210","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-06-25T22:15:56.308549Z","caller":"traceutil/trace.go:171","msg":"trace[1089150314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"664.192197ms","start":"2026-06-25T22:15:55.644288Z","end":"2026-06-25T22:15:56.30848Z","steps":["trace[1089150314] 'agreement among raft nodes before linearized reading' (duration: 663.99876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:56.308679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:55.644282Z","time spent":"664.343902ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-25T22:15:56.31024Z","caller":"traceutil/trace.go:171","msg":"trace[445794944] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"236.776903ms","start":"2026-06-25T22:15:56.070518Z","end":"2026-06-25T22:15:56.307295Z","steps":["trace[445794944] 'process raft request' (duration: 236.547305ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:56.497532Z","caller":"traceutil/trace.go:171","msg":"trace[1216518941] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"116.960914ms","start":"2026-06-25T22:15:56.380542Z","end":"2026-06-25T22:15:56.497503Z","steps":["trace[1216518941] 'process raft request' (duration: 47.295779ms)","trace[1216518941] 'compare' (duration: 69.463578ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:05.429895Z","caller":"traceutil/trace.go:171","msg":"trace[877090829] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"162.774557ms","start":"2026-06-25T22:16:05.267086Z","end":"2026-06-25T22:16:05.42986Z","steps":["trace[877090829] 'process raft request' (duration: 162.703075ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:05.429931Z","caller":"traceutil/trace.go:171","msg":"trace[1339221994] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"166.556069ms","start":"2026-06-25T22:16:05.263329Z","end":"2026-06-25T22:16:05.429885Z","steps":["trace[1339221994] 'process raft request' (duration: 166.234937ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:05.614753Z","caller":"traceutil/trace.go:171","msg":"trace[1655769597] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"110.045146ms","start":"2026-06-25T22:16:05.504677Z","end":"2026-06-25T22:16:05.614722Z","steps":["trace[1655769597] 'read index received' (duration: 79.714896ms)","trace[1655769597] 'applied index is now lower than readState.Index' (duration: 30.3292ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:05.615101Z","caller":"traceutil/trace.go:171","msg":"trace[532088759] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"145.935915ms","start":"2026-06-25T22:16:05.469149Z","end":"2026-06-25T22:16:05.615085Z","steps":["trace[532088759] 'process raft request' (duration: 115.106066ms)","trace[532088759] 'compare' (duration: 30.354371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:05.61534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.215222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:05.615393Z","caller":"traceutil/trace.go:171","msg":"trace[1086169775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"110.767773ms","start":"2026-06-25T22:16:05.504609Z","end":"2026-06-25T22:16:05.615377Z","steps":["trace[1086169775] 'agreement among raft nodes before linearized reading' (duration: 110.229092ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:05.645673Z","caller":"traceutil/trace.go:171","msg":"trace[1923167621] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"135.454541ms","start":"2026-06-25T22:16:05.510189Z","end":"2026-06-25T22:16:05.645644Z","steps":["trace[1923167621] 'process raft request' (duration: 135.218182ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:05.780534Z","caller":"traceutil/trace.go:171","msg":"trace[32023741] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"128.541331ms","start":"2026-06-25T22:16:05.651962Z","end":"2026-06-25T22:16:05.780504Z","steps":["trace[32023741] 'process raft request' (duration: 127.610256ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:05.78067Z","caller":"traceutil/trace.go:171","msg":"trace[1738264126] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"127.555044ms","start":"2026-06-25T22:16:05.653092Z","end":"2026-06-25T22:16:05.780647Z","steps":["trace[1738264126] 'process raft request' (duration: 127.328376ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:26.798574Z","caller":"traceutil/trace.go:171","msg":"trace[565318694] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"133.400866ms","start":"2026-06-25T22:16:26.665144Z","end":"2026-06-25T22:16:26.798545Z","steps":["trace[565318694] 'process raft request' (duration: 133.315883ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:26.798651Z","caller":"traceutil/trace.go:171","msg":"trace[1532211815] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"133.812575ms","start":"2026-06-25T22:16:26.664795Z","end":"2026-06-25T22:16:26.798608Z","steps":["trace[1532211815] 'process raft request' (duration: 97.599971ms)","trace[1532211815] 'compare' (duration: 35.89389ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:39.764757Z","caller":"traceutil/trace.go:171","msg":"trace[1586650310] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"147.017279ms","start":"2026-06-25T22:16:39.617697Z","end":"2026-06-25T22:16:39.764714Z","steps":["trace[1586650310] 'process raft request' (duration: 146.820549ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:46.049263Z","caller":"traceutil/trace.go:171","msg":"trace[175574619] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"250.971033ms","start":"2026-06-25T22:16:45.798264Z","end":"2026-06-25T22:16:46.049235Z","steps":["trace[175574619] 'process raft request' (duration: 250.790254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:46.598749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.478448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:46.598888Z","caller":"traceutil/trace.go:171","msg":"trace[1644493429] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"349.389027ms","start":"2026-06-25T22:16:46.249477Z","end":"2026-06-25T22:16:46.598866Z","steps":["trace[1644493429] 'read index received' (duration: 91.603284ms)","trace[1644493429] 'applied index is now lower than readState.Index' (duration: 257.783943ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:46.598926Z","caller":"traceutil/trace.go:171","msg":"trace[1166519705] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"377.350069ms","start":"2026-06-25T22:16:46.22153Z","end":"2026-06-25T22:16:46.59888Z","steps":["trace[1166519705] 'process raft request' (duration: 119.625029ms)","trace[1166519705] 'compare' (duration: 257.3194ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:46.599067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.563196ms","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":"warn","ts":"2026-06-25T22:16:46.599164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.580767ms","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-06-25T22:16:46.5992Z","caller":"traceutil/trace.go:171","msg":"trace[1895531316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1123; }","duration":"102.675491ms","start":"2026-06-25T22:16:46.496514Z","end":"2026-06-25T22:16:46.599189Z","steps":["trace[1895531316] 'agreement among raft nodes before linearized reading' (duration: 102.489542ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:46.599194Z","caller":"traceutil/trace.go:171","msg":"trace[665594324] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1123; }","duration":"349.764186ms","start":"2026-06-25T22:16:46.249403Z","end":"2026-06-25T22:16:46.599167Z","steps":["trace[665594324] 'agreement among raft nodes before linearized reading' (duration: 349.505413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:46.599123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.22151Z","time spent":"377.486405ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:46.59936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.249341Z","time spent":"349.976946ms","remote":"127.0.0.1:55614","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-06-25T22:16:47.13081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.585633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:47.131228Z","caller":"traceutil/trace.go:171","msg":"trace[1966422479] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"525.394227ms","start":"2026-06-25T22:16:46.605811Z","end":"2026-06-25T22:16:47.131206Z","steps":["trace[1966422479] 'read index received' (duration: 93.321789ms)","trace[1966422479] 'applied index is now lower than readState.Index' (duration: 432.070187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:47.131548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.737204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-25T22:16:47.131724Z","caller":"traceutil/trace.go:171","msg":"trace[161639733] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1124; }","duration":"525.918602ms","start":"2026-06-25T22:16:46.605745Z","end":"2026-06-25T22:16:47.131664Z","steps":["trace[161639733] 'agreement among raft nodes before linearized reading' (duration: 525.742034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:47.131755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.26866ms","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-06-25T22:16:47.131891Z","caller":"traceutil/trace.go:171","msg":"trace[1621540993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"411.469009ms","start":"2026-06-25T22:16:46.720394Z","end":"2026-06-25T22:16:47.131863Z","steps":["trace[1621540993] 'agreement among raft nodes before linearized reading' (duration: 411.146153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:47.131856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.776486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:16:47.131963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.720365Z","time spent":"411.582485ms","remote":"127.0.0.1:44210","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":"info","ts":"2026-06-25T22:16:47.131995Z","caller":"traceutil/trace.go:171","msg":"trace[1369785707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"228.982396ms","start":"2026-06-25T22:16:46.902977Z","end":"2026-06-25T22:16:47.13196Z","steps":["trace[1369785707] 'agreement among raft nodes before linearized reading' (duration: 228.587736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:47.131717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.854672ms","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-06-25T22:16:47.132252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.60572Z","time spent":"526.123762ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-06-25T22:16:47.131544Z","caller":"traceutil/trace.go:171","msg":"trace[953520608] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"526.942283ms","start":"2026-06-25T22:16:46.604551Z","end":"2026-06-25T22:16:47.131493Z","steps":["trace[953520608] 'process raft request' (duration: 94.55897ms)","trace[953520608] 'compare' (duration: 431.382553ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:47.132784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.604525Z","time spent":"528.145722ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:47.131754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.49358ms","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-06-25T22:16:47.132823Z","caller":"traceutil/trace.go:171","msg":"trace[1661502043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"373.390309ms","start":"2026-06-25T22:16:46.758788Z","end":"2026-06-25T22:16:47.132179Z","steps":["trace[1661502043] 'agreement among raft nodes before linearized reading' (duration: 372.771878ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:47.132869Z","caller":"traceutil/trace.go:171","msg":"trace[620935204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"232.663008ms","start":"2026-06-25T22:16:46.900187Z","end":"2026-06-25T22:16:47.13285Z","steps":["trace[620935204] 'agreement among raft nodes before linearized reading' (duration: 231.371314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:47.132921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:46.758765Z","time spent":"374.129685ms","remote":"127.0.0.1:44210","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-06-25T22:16:47.359542Z","caller":"traceutil/trace.go:171","msg":"trace[77846453] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"223.075274ms","start":"2026-06-25T22:16:47.136434Z","end":"2026-06-25T22:16:47.359509Z","steps":["trace[77846453] 'process raft request' (duration: 157.254439ms)","trace[77846453] 'compare' (duration: 65.653966ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:47.362127Z","caller":"traceutil/trace.go:171","msg":"trace[1283369713] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"225.475132ms","start":"2026-06-25T22:16:47.136629Z","end":"2026-06-25T22:16:47.362104Z","steps":["trace[1283369713] 'process raft request' (duration: 225.21904ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:47.362113Z","caller":"traceutil/trace.go:171","msg":"trace[511508249] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"225.385178ms","start":"2026-06-25T22:16:47.136699Z","end":"2026-06-25T22:16:47.362084Z","steps":["trace[511508249] 'process raft request' (duration: 225.297474ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:47.362349Z","caller":"traceutil/trace.go:171","msg":"trace[358092362] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"187.012155ms","start":"2026-06-25T22:16:47.175315Z","end":"2026-06-25T22:16:47.362327Z","steps":["trace[358092362] 'process raft request' (duration: 186.924921ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:47.362394Z","caller":"traceutil/trace.go:171","msg":"trace[225582394] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"224.752907ms","start":"2026-06-25T22:16:47.137587Z","end":"2026-06-25T22:16:47.362339Z","steps":["trace[225582394] 'process raft request' (duration: 224.456072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:47.569577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.660032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:47.569677Z","caller":"traceutil/trace.go:171","msg":"trace[2002740984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"102.83671ms","start":"2026-06-25T22:16:47.466819Z","end":"2026-06-25T22:16:47.569655Z","steps":["trace[2002740984] 'range keys from in-memory index tree' (duration: 102.479952ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:57.750216Z","caller":"traceutil/trace.go:171","msg":"trace[478411419] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"185.538098ms","start":"2026-06-25T22:16:57.564645Z","end":"2026-06-25T22:16:57.750183Z","steps":["trace[478411419] 'process raft request' (duration: 185.343088ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:57.815104Z","caller":"traceutil/trace.go:171","msg":"trace[1976450948] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"250.075899ms","start":"2026-06-25T22:16:57.564994Z","end":"2026-06-25T22:16:57.81507Z","steps":["trace[1976450948] 'process raft request' (duration: 225.048392ms)","trace[1976450948] 'compare' (duration: 24.772644ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:57.815396Z","caller":"traceutil/trace.go:171","msg":"trace[176742746] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"250.164814ms","start":"2026-06-25T22:16:57.565206Z","end":"2026-06-25T22:16:57.815371Z","steps":["trace[176742746] 'process raft request' (duration: 249.814176ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:57.815597Z","caller":"traceutil/trace.go:171","msg":"trace[787964849] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"218.429111ms","start":"2026-06-25T22:16:57.597158Z","end":"2026-06-25T22:16:57.815587Z","steps":["trace[787964849] 'process raft request' (duration: 218.099374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:13.757125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.492331ms","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-06-25T22:17:13.757257Z","caller":"traceutil/trace.go:171","msg":"trace[1137570140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"119.675561ms","start":"2026-06-25T22:17:13.637561Z","end":"2026-06-25T22:17:13.757236Z","steps":["trace[1137570140] 'range keys from in-memory index tree' (duration: 119.300961ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:14.053487Z","caller":"traceutil/trace.go:171","msg":"trace[699463145] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"186.484518ms","start":"2026-06-25T22:17:13.866972Z","end":"2026-06-25T22:17:14.053456Z","steps":["trace[699463145] 'read index received' (duration: 186.217383ms)","trace[699463145] 'applied index is now lower than readState.Index' (duration: 265.555µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:17:14.05353Z","caller":"traceutil/trace.go:171","msg":"trace[910121242] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"291.91035ms","start":"2026-06-25T22:17:13.761595Z","end":"2026-06-25T22:17:14.053505Z","steps":["trace[910121242] 'process raft request' (duration: 291.643816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:14.053833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.835627ms","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-06-25T22:17:14.053942Z","caller":"traceutil/trace.go:171","msg":"trace[1296587015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"187.012517ms","start":"2026-06-25T22:17:13.866909Z","end":"2026-06-25T22:17:14.053922Z","steps":["trace[1296587015] 'agreement among raft nodes before linearized reading' (duration: 186.659278ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:22.101571Z","caller":"traceutil/trace.go:171","msg":"trace[262290078] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"121.785718ms","start":"2026-06-25T22:17:21.979762Z","end":"2026-06-25T22:17:22.101548Z","steps":["trace[262290078] 'process raft request' (duration: 121.63386ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:24.316212Z","caller":"traceutil/trace.go:171","msg":"trace[1957830102] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"100.358736ms","start":"2026-06-25T22:17:24.215808Z","end":"2026-06-25T22:17:24.316167Z","steps":["trace[1957830102] 'read index received' (duration: 100.158785ms)","trace[1957830102] 'applied index is now lower than readState.Index' (duration: 198.711µs)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:17:24.317075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.244554ms","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-06-25T22:17:24.317178Z","caller":"traceutil/trace.go:171","msg":"trace[250199620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"101.389172ms","start":"2026-06-25T22:17:24.215765Z","end":"2026-06-25T22:17:24.317154Z","steps":["trace[250199620] 'agreement among raft nodes before linearized reading' (duration: 101.1574ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:24.316372Z","caller":"traceutil/trace.go:171","msg":"trace[1761979153] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"134.185614ms","start":"2026-06-25T22:17:24.182174Z","end":"2026-06-25T22:17:24.316359Z","steps":["trace[1761979153] 'process raft request' (duration: 133.817714ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:24.47049Z","caller":"traceutil/trace.go:171","msg":"trace[2002438953] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"147.745594ms","start":"2026-06-25T22:17:24.322714Z","end":"2026-06-25T22:17:24.470459Z","steps":["trace[2002438953] 'process raft request' (duration: 89.856075ms)","trace[2002438953] 'compare' (duration: 57.750891ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:17:24.473598Z","caller":"traceutil/trace.go:171","msg":"trace[802537961] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"150.669335ms","start":"2026-06-25T22:17:24.322911Z","end":"2026-06-25T22:17:24.473581Z","steps":["trace[802537961] 'process raft request' (duration: 150.493426ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:24.473667Z","caller":"traceutil/trace.go:171","msg":"trace[1310588477] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"150.504256ms","start":"2026-06-25T22:17:24.323128Z","end":"2026-06-25T22:17:24.473632Z","steps":["trace[1310588477] 'process raft request' (duration: 150.376099ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:49.077069Z","caller":"traceutil/trace.go:171","msg":"trace[218965750] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"345.485304ms","start":"2026-06-25T22:17:48.731559Z","end":"2026-06-25T22:17:49.077045Z","steps":["trace[218965750] 'process raft request' (duration: 345.377138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:49.077198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:48.731522Z","time spent":"345.613911ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:17:49.133683Z","caller":"traceutil/trace.go:171","msg":"trace[1770345901] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"399.758808ms","start":"2026-06-25T22:17:48.73389Z","end":"2026-06-25T22:17:49.133649Z","steps":["trace[1770345901] 'process raft request' (duration: 398.92284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:49.133893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:48.733857Z","time spent":"399.920818ms","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}