{"level":"warn","ts":"2026-01-31T01:44:56.689592Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T01:44:56.689676Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-01-31T01:44:56.689751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T01:44:56.68976Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-01-31T01:44:56.689776Z","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-01-31T01:44:56.690641Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-01-31T01:44:56.690734Z","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":2,"max-cpu-available":2,"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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-01-31T01:44:56.696065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.071759ms"} {"level":"info","ts":"2026-01-31T01:44:56.703921Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-01-31T01:44:56.705008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T01:44:56.705101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-01-31T01:44:56.705167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T01:44:56.705205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-01-31T01:44:56.705289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-01-31T01:44:56.717163Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T01:44:56.719735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T01:44:56.721648Z","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-01-31T01:44:56.72393Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T01:44:56.72774Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T01:44:56.728056Z","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-01-31T01:44:56.728134Z","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-01-31T01:44:56.728153Z","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-01-31T01:44:56.735534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-01-31T01:44:56.735859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-01-31T01:44:56.735729Z","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-01-31T01:44:56.735745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-01-31T01:44:56.735982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-01-31T01:44:56.7369Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T01:44:56.736941Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T01:44:56.905927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T01:44:56.906003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T01:44:56.906033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-01-31T01:44:56.906058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-01-31T01:44:56.906094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-01-31T01:44:56.906115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-01-31T01:44:56.906135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-01-31T01:44:56.908555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T01:44:56.908605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T01:44:56.909003Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T01:44:56.909458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T01:44:56.909658Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T01:44:56.910321Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T01:44:56.912344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-01-31T01:44:56.916774Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T01:44:56.918056Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T01:44:56.918895Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T01:44:56.918945Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-31T01:45:15.541669Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137818866s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T01:45:15.542056Z","caller":"traceutil/trace.go:171","msg":"trace[1889709378] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"178.636439ms","start":"2026-01-31T01:45:15.363392Z","end":"2026-01-31T01:45:15.542028Z","steps":["trace[1889709378] 'read index received' (duration: 178.423234ms)","trace[1889709378] 'applied index is now lower than readState.Index' (duration: 212.075µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:15.542223Z","caller":"traceutil/trace.go:171","msg":"trace[1166373536] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.138438191s","start":"2026-01-31T01:45:14.40377Z","end":"2026-01-31T01:45:15.542208Z","steps":["trace[1166373536] 'process raft request' (duration: 1.138074202s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:15.542358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.892555ms","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-01-31T01:45:15.542408Z","caller":"traceutil/trace.go:171","msg":"trace[1997790369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"178.967496ms","start":"2026-01-31T01:45:15.363427Z","end":"2026-01-31T01:45:15.542394Z","steps":["trace[1997790369] 'agreement among raft nodes before linearized reading' (duration: 178.873214ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:15.542582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.188901ms","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-01-31T01:45:15.5426Z","caller":"traceutil/trace.go:171","msg":"trace[683269313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"179.241703ms","start":"2026-01-31T01:45:15.363353Z","end":"2026-01-31T01:45:15.542595Z","steps":["trace[683269313] 'agreement among raft nodes before linearized reading' (duration: 179.207282ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:15.542806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:14.403754Z","time spent":"1.138520072s","remote":"127.0.0.1:46592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:45:16.157991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485696630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:16.658764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485696630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:16.696932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14887089s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T01:45:16.697405Z","caller":"traceutil/trace.go:171","msg":"trace[1009367571] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"1.149382012s","start":"2026-01-31T01:45:15.548001Z","end":"2026-01-31T01:45:16.697383Z","steps":["trace[1009367571] 'process raft request' (duration: 1.149222458s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.69756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:15.547987Z","time spent":"1.149502915s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:16.70674Z","caller":"traceutil/trace.go:171","msg":"trace[1392048125] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"1.157912268s","start":"2026-01-31T01:45:15.548816Z","end":"2026-01-31T01:45:16.706728Z","steps":["trace[1392048125] 'process raft request' (duration: 1.157603071s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.70683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:15.548808Z","time spent":"1.157966679s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:16.707057Z","caller":"traceutil/trace.go:171","msg":"trace[526646384] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"1.152889872s","start":"2026-01-31T01:45:15.554159Z","end":"2026-01-31T01:45:16.707049Z","steps":["trace[526646384] 'process raft request' (duration: 1.15233397s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.707095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:15.554151Z","time spent":"1.152928403s","remote":"127.0.0.1:46634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T01:45:16.713804Z","caller":"traceutil/trace.go:171","msg":"trace[1766335888] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:396; }","duration":"1.05646674s","start":"2026-01-31T01:45:15.657325Z","end":"2026-01-31T01:45:16.713791Z","steps":["trace[1766335888] 'read index received' (duration: 1.040419302s)","trace[1766335888] 'applied index is now lower than readState.Index' (duration: 16.046558ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:16.713994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056652624s","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-01-31T01:45:16.714021Z","caller":"traceutil/trace.go:171","msg":"trace[1194692645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:386; }","duration":"1.056715056s","start":"2026-01-31T01:45:15.657299Z","end":"2026-01-31T01:45:16.714014Z","steps":["trace[1194692645] 'agreement among raft nodes before linearized reading' (duration: 1.056655435s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.714042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:15.657257Z","time spent":"1.056780118s","remote":"127.0.0.1:46408","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-01-31T01:45:16.714164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.103675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-31T01:45:16.714187Z","caller":"traceutil/trace.go:171","msg":"trace[1912740028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"602.126506ms","start":"2026-01-31T01:45:16.112055Z","end":"2026-01-31T01:45:16.714181Z","steps":["trace[1912740028] 'agreement among raft nodes before linearized reading' (duration: 602.091025ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.714206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:16.111975Z","time spent":"602.225477ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T01:45:16.714308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.888856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:45:16.714329Z","caller":"traceutil/trace.go:171","msg":"trace[145299089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"321.946877ms","start":"2026-01-31T01:45:16.392376Z","end":"2026-01-31T01:45:16.714323Z","steps":["trace[145299089] 'agreement among raft nodes before linearized reading' (duration: 321.916436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:16.714348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:16.392357Z","time spent":"321.987938ms","remote":"127.0.0.1:46138","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-01-31T01:45:23.292031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.560104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:45:23.292209Z","caller":"traceutil/trace.go:171","msg":"trace[889529078] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"501.841184ms","start":"2026-01-31T01:45:22.790353Z","end":"2026-01-31T01:45:23.292194Z","steps":["trace[889529078] 'process raft request' (duration: 501.765952ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:23.292276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:22.790341Z","time spent":"501.906316ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:23.292537Z","caller":"traceutil/trace.go:171","msg":"trace[674860067] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"543.419258ms","start":"2026-01-31T01:45:22.749105Z","end":"2026-01-31T01:45:23.292524Z","steps":["trace[674860067] 'process raft request' (duration: 83.150487ms)","trace[674860067] 'compare' (duration: 459.295748ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:23.292594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:22.749088Z","time spent":"543.48074ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:23.292707Z","caller":"traceutil/trace.go:171","msg":"trace[921208307] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"507.986086ms","start":"2026-01-31T01:45:22.784712Z","end":"2026-01-31T01:45:23.292698Z","steps":["trace[921208307] 'read index received' (duration: 47.549111ms)","trace[921208307] 'applied index is now lower than readState.Index' (duration: 460.435965ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:23.292764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.818963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:45:23.292793Z","caller":"traceutil/trace.go:171","msg":"trace[198882034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"538.865664ms","start":"2026-01-31T01:45:22.75392Z","end":"2026-01-31T01:45:23.292785Z","steps":["trace[198882034] 'agreement among raft nodes before linearized reading' (duration: 538.814622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:23.292825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:22.753914Z","time spent":"538.905954ms","remote":"127.0.0.1:46408","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-01-31T01:45:23.293488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.761035ms","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-01-31T01:45:23.293533Z","caller":"traceutil/trace.go:171","msg":"trace[1035871734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"536.821816ms","start":"2026-01-31T01:45:22.756701Z","end":"2026-01-31T01:45:23.293523Z","steps":["trace[1035871734] 'agreement among raft nodes before linearized reading' (duration: 536.324345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:23.293568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:22.756694Z","time spent":"536.866607ms","remote":"127.0.0.1:46408","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-01-31T01:45:29.609923Z","caller":"traceutil/trace.go:171","msg":"trace[810816355] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"443.481776ms","start":"2026-01-31T01:45:29.166428Z","end":"2026-01-31T01:45:29.60991Z","steps":["trace[810816355] 'read index received' (duration: 443.383614ms)","trace[810816355] 'applied index is now lower than readState.Index' (duration: 97.662µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:29.610067Z","caller":"traceutil/trace.go:171","msg":"trace[240792068] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"734.650566ms","start":"2026-01-31T01:45:28.875411Z","end":"2026-01-31T01:45:29.610062Z","steps":["trace[240792068] 'process raft request' (duration: 734.436871ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:29.610125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:28.8754Z","time spent":"734.681457ms","remote":"127.0.0.1:46408","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-01-31T01:45:29.610224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.795603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-01-31T01:45:29.610241Z","caller":"traceutil/trace.go:171","msg":"trace[1038442669] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:474; }","duration":"443.832673ms","start":"2026-01-31T01:45:29.166403Z","end":"2026-01-31T01:45:29.610235Z","steps":["trace[1038442669] 'agreement among raft nodes before linearized reading' (duration: 443.799202ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:29.610253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.166391Z","time spent":"443.860195ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-31T01:45:29.610324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.514334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:45:29.610337Z","caller":"traceutil/trace.go:171","msg":"trace[428207227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"276.560415ms","start":"2026-01-31T01:45:29.333772Z","end":"2026-01-31T01:45:29.610332Z","steps":["trace[428207227] 'agreement among raft nodes before linearized reading' (duration: 276.539795ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:29.610429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.728403ms","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-01-31T01:45:29.61044Z","caller":"traceutil/trace.go:171","msg":"trace[1359687368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"267.758804ms","start":"2026-01-31T01:45:29.342678Z","end":"2026-01-31T01:45:29.610437Z","steps":["trace[1359687368] 'agreement among raft nodes before linearized reading' (duration: 267.737943ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:29.610497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.735626ms","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-01-31T01:45:29.610508Z","caller":"traceutil/trace.go:171","msg":"trace[1108915572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"268.783327ms","start":"2026-01-31T01:45:29.341721Z","end":"2026-01-31T01:45:29.610504Z","steps":["trace[1108915572] 'agreement among raft nodes before linearized reading' (duration: 268.764387ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:45:30.101701Z","caller":"traceutil/trace.go:171","msg":"trace[493370603] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"484.392704ms","start":"2026-01-31T01:45:29.617291Z","end":"2026-01-31T01:45:30.101684Z","steps":["trace[493370603] 'process raft request' (duration: 484.282852ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:30.101814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.617279Z","time spent":"484.476676ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:30.107981Z","caller":"traceutil/trace.go:171","msg":"trace[1903230901] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"324.775442ms","start":"2026-01-31T01:45:29.78319Z","end":"2026-01-31T01:45:30.107966Z","steps":["trace[1903230901] 'process raft request' (duration: 324.752232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:30.108083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.783169Z","time spent":"324.858894ms","remote":"127.0.0.1:46408","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-01-31T01:45:30.108147Z","caller":"traceutil/trace.go:171","msg":"trace[937821407] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"490.707039ms","start":"2026-01-31T01:45:29.617429Z","end":"2026-01-31T01:45:30.108136Z","steps":["trace[937821407] 'process raft request' (duration: 490.374602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:30.108207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.617424Z","time spent":"490.74402ms","remote":"127.0.0.1:46408","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-01-31T01:45:30.108267Z","caller":"traceutil/trace.go:171","msg":"trace[1022708758] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"490.091665ms","start":"2026-01-31T01:45:29.61817Z","end":"2026-01-31T01:45:30.108262Z","steps":["trace[1022708758] 'process raft request' (duration: 489.751987ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:30.108297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.618165Z","time spent":"490.116596ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:30.10799Z","caller":"traceutil/trace.go:171","msg":"trace[955757700] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"490.519515ms","start":"2026-01-31T01:45:29.617457Z","end":"2026-01-31T01:45:30.107976Z","steps":["trace[955757700] 'process raft request' (duration: 490.445743ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:30.108369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:29.617454Z","time spent":"490.900653ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:45:32.066289Z","caller":"traceutil/trace.go:171","msg":"trace[1998299423] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"447.396105ms","start":"2026-01-31T01:45:31.618877Z","end":"2026-01-31T01:45:32.066273Z","steps":["trace[1998299423] 'process raft request' (duration: 447.295913ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:32.066382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:31.618852Z","time spent":"447.480268ms","remote":"127.0.0.1:46408","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-01-31T01:45:32.612409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485696819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:33.193922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.675743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:45:33.194003Z","caller":"traceutil/trace.go:171","msg":"trace[373656717] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"1.08179857s","start":"2026-01-31T01:45:32.112191Z","end":"2026-01-31T01:45:33.19399Z","steps":["trace[373656717] 'read index received' (duration: 732.008914ms)","trace[373656717] 'applied index is now lower than readState.Index' (duration: 349.788586ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:33.194173Z","caller":"traceutil/trace.go:171","msg":"trace[774895186] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.088077283s","start":"2026-01-31T01:45:32.106081Z","end":"2026-01-31T01:45:33.194158Z","steps":["trace[774895186] 'process raft request' (duration: 738.086713ms)","trace[774895186] 'compare' (duration: 349.481919ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:33.194246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:32.106073Z","time spent":"1.088142896s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:45:33.194416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082219719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:45:33.194452Z","caller":"traceutil/trace.go:171","msg":"trace[1777216923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"1.082269001s","start":"2026-01-31T01:45:32.112174Z","end":"2026-01-31T01:45:33.194443Z","steps":["trace[1777216923] 'agreement among raft nodes before linearized reading' (duration: 1.0822088s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:33.194485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:32.11216Z","time spent":"1.082319892s","remote":"127.0.0.1:46408","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-01-31T01:45:33.194613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074498233s","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-01-31T01:45:33.194643Z","caller":"traceutil/trace.go:171","msg":"trace[1007422824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"1.074542963s","start":"2026-01-31T01:45:32.120093Z","end":"2026-01-31T01:45:33.194636Z","steps":["trace[1007422824] 'agreement among raft nodes before linearized reading' (duration: 1.074494592s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:33.194674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:32.120085Z","time spent":"1.074584454s","remote":"127.0.0.1:46408","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-01-31T01:45:33.797737Z","caller":"traceutil/trace.go:171","msg":"trace[1311747085] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"304.476345ms","start":"2026-01-31T01:45:33.493232Z","end":"2026-01-31T01:45:33.797709Z","steps":["trace[1311747085] 'process raft request' (duration: 304.311272ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:33.797892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:33.49321Z","time spent":"304.595239ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:45:34.361384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.881977ms","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-01-31T01:45:34.36146Z","caller":"traceutil/trace.go:171","msg":"trace[96035971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"291.9991ms","start":"2026-01-31T01:45:34.069443Z","end":"2026-01-31T01:45:34.361442Z","steps":["trace[96035971] 'range keys from in-memory index tree' (duration: 291.792926ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:45:37.525881Z","caller":"traceutil/trace.go:171","msg":"trace[639179296] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"226.019116ms","start":"2026-01-31T01:45:37.299841Z","end":"2026-01-31T01:45:37.52586Z","steps":["trace[639179296] 'read index received' (duration: 225.815602ms)","trace[639179296] 'applied index is now lower than readState.Index' (duration: 202.624µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:37.526158Z","caller":"traceutil/trace.go:171","msg":"trace[40646690] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"288.986161ms","start":"2026-01-31T01:45:37.237158Z","end":"2026-01-31T01:45:37.526144Z","steps":["trace[40646690] 'process raft request' (duration: 288.575071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:37.526423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.572899ms","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-01-31T01:45:37.526454Z","caller":"traceutil/trace.go:171","msg":"trace[581278608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"226.639411ms","start":"2026-01-31T01:45:37.299806Z","end":"2026-01-31T01:45:37.526445Z","steps":["trace[581278608] 'agreement among raft nodes before linearized reading' (duration: 226.517138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:37.526658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.890467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:45:37.526687Z","caller":"traceutil/trace.go:171","msg":"trace[2131518008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"223.947949ms","start":"2026-01-31T01:45:37.302731Z","end":"2026-01-31T01:45:37.526679Z","steps":["trace[2131518008] 'agreement among raft nodes before linearized reading' (duration: 223.868567ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:38.893664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485696846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:39.272556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.698765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:45:39.272649Z","caller":"traceutil/trace.go:171","msg":"trace[1362004360] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.741845226s","start":"2026-01-31T01:45:37.530787Z","end":"2026-01-31T01:45:39.272632Z","steps":["trace[1362004360] 'process raft request' (duration: 803.997447ms)","trace[1362004360] 'compare' (duration: 937.155123ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:39.272705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:37.530776Z","time spent":"1.741902757s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:45:39.394716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485696846,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T01:45:39.54857Z","caller":"traceutil/trace.go:171","msg":"trace[142718970] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"1.155833579s","start":"2026-01-31T01:45:38.392715Z","end":"2026-01-31T01:45:39.548548Z","steps":["trace[142718970] 'read index received' (duration: 1.095937625s)","trace[142718970] 'applied index is now lower than readState.Index' (duration: 59.894994ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:39.548881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149147336s","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-01-31T01:45:39.548909Z","caller":"traceutil/trace.go:171","msg":"trace[813894128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"1.149202167s","start":"2026-01-31T01:45:38.3997Z","end":"2026-01-31T01:45:39.548902Z","steps":["trace[813894128] 'agreement among raft nodes before linearized reading' (duration: 1.149100334s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:39.54893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:38.399687Z","time spent":"1.149238128s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:45:39.549013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15629271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:45:39.549085Z","caller":"traceutil/trace.go:171","msg":"trace[1363570925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"1.156391921s","start":"2026-01-31T01:45:38.392681Z","end":"2026-01-31T01:45:39.549073Z","steps":["trace[1363570925] 'agreement among raft nodes before linearized reading' (duration: 1.156301959s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:39.549116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:38.392641Z","time spent":"1.156467763s","remote":"127.0.0.1:46138","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-01-31T01:45:39.549247Z","caller":"traceutil/trace.go:171","msg":"trace[517458058] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"2.01756644s","start":"2026-01-31T01:45:37.531673Z","end":"2026-01-31T01:45:39.549239Z","steps":["trace[517458058] 'process raft request' (duration: 1.957043272s)","trace[517458058] 'compare' (duration: 59.74267ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:39.549301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:37.531664Z","time spent":"2.017601892s","remote":"127.0.0.1:46408","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-01-31T01:45:40.948326Z","caller":"traceutil/trace.go:171","msg":"trace[531907452] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"169.689743ms","start":"2026-01-31T01:45:40.778623Z","end":"2026-01-31T01:45:40.948313Z","steps":["trace[531907452] 'process raft request' (duration: 169.654902ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:45:40.948473Z","caller":"traceutil/trace.go:171","msg":"trace[1857536016] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"177.138134ms","start":"2026-01-31T01:45:40.771327Z","end":"2026-01-31T01:45:40.948465Z","steps":["trace[1857536016] 'process raft request' (duration: 158.526747ms)","trace[1857536016] 'compare' (duration: 18.347271ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:40.948662Z","caller":"traceutil/trace.go:171","msg":"trace[1657131851] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"171.72098ms","start":"2026-01-31T01:45:40.776931Z","end":"2026-01-31T01:45:40.948652Z","steps":["trace[1657131851] 'read index received' (duration: 152.875827ms)","trace[1657131851] 'applied index is now lower than readState.Index' (duration: 18.844283ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:40.948788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.840902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zqtzp\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-31T01:45:40.948809Z","caller":"traceutil/trace.go:171","msg":"trace[1719730224] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zqtzp; range_end:; response_count:1; response_revision:501; }","duration":"171.915154ms","start":"2026-01-31T01:45:40.776887Z","end":"2026-01-31T01:45:40.948802Z","steps":["trace[1719730224] 'agreement among raft nodes before linearized reading' (duration: 171.793861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:42.549487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.577424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:45:42.549575Z","caller":"traceutil/trace.go:171","msg":"trace[252332152] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"155.745643ms","start":"2026-01-31T01:45:42.393811Z","end":"2026-01-31T01:45:42.549557Z","steps":["trace[252332152] 'read index received' (duration: 12.076867ms)","trace[252332152] 'applied index is now lower than readState.Index' (duration: 143.667586ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:45:42.549649Z","caller":"traceutil/trace.go:171","msg":"trace[2145925662] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"584.157423ms","start":"2026-01-31T01:45:41.96547Z","end":"2026-01-31T01:45:42.549628Z","steps":["trace[2145925662] 'process raft request' (duration: 440.391154ms)","trace[2145925662] 'compare' (duration: 143.271417ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:42.549687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.862876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:45:42.549713Z","caller":"traceutil/trace.go:171","msg":"trace[1713221597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"155.932037ms","start":"2026-01-31T01:45:42.393773Z","end":"2026-01-31T01:45:42.549705Z","steps":["trace[1713221597] 'agreement among raft nodes before linearized reading' (duration: 155.825655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:42.549733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:41.965462Z","time spent":"584.228574ms","remote":"127.0.0.1:46408","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-01-31T01:45:45.263755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.684934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-31T01:45:45.263836Z","caller":"traceutil/trace.go:171","msg":"trace[444550513] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"108.809337ms","start":"2026-01-31T01:45:45.155006Z","end":"2026-01-31T01:45:45.263815Z","steps":["trace[444550513] 'range keys from in-memory index tree' (duration: 108.49919ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.159985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:51.660237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:45:51.914192Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297112091s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T01:45:51.914485Z","caller":"traceutil/trace.go:171","msg":"trace[73196398] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.297505991s","start":"2026-01-31T01:45:50.616959Z","end":"2026-01-31T01:45:51.914465Z","steps":["trace[73196398] 'process raft request' (duration: 1.297355937s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.914877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:50.616942Z","time spent":"1.297865239s","remote":"127.0.0.1:46408","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-01-31T01:45:51.965489Z","caller":"traceutil/trace.go:171","msg":"trace[1922773946] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"1.306304242s","start":"2026-01-31T01:45:50.659167Z","end":"2026-01-31T01:45:51.965471Z","steps":["trace[1922773946] 'read index received' (duration: 1.255262951s)","trace[1922773946] 'applied index is now lower than readState.Index' (duration: 51.040611ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:45:51.965781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224415343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T01:45:51.965805Z","caller":"traceutil/trace.go:171","msg":"trace[943364412] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:613; }","duration":"1.224457844s","start":"2026-01-31T01:45:50.74134Z","end":"2026-01-31T01:45:51.965798Z","steps":["trace[943364412] 'agreement among raft nodes before linearized reading' (duration: 1.224342272s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.965787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306600189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-01-31T01:45:51.965828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:50.741285Z","time spent":"1.224537166s","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"info","ts":"2026-01-31T01:45:51.965846Z","caller":"traceutil/trace.go:171","msg":"trace[1352668199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.306697121s","start":"2026-01-31T01:45:50.659136Z","end":"2026-01-31T01:45:51.965833Z","steps":["trace[1352668199] 'agreement among raft nodes before linearized reading' (duration: 1.306433065s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.965876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:50.659112Z","time spent":"1.306758942s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:45:51.965986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.237678ms","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-01-31T01:45:51.966004Z","caller":"traceutil/trace.go:171","msg":"trace[246710792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"225.276639ms","start":"2026-01-31T01:45:51.740722Z","end":"2026-01-31T01:45:51.965999Z","steps":["trace[246710792] 'agreement among raft nodes before linearized reading' (duration: 225.222418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.293756ms","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-01-31T01:45:51.96609Z","caller":"traceutil/trace.go:171","msg":"trace[627464290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"234.320886ms","start":"2026-01-31T01:45:51.731765Z","end":"2026-01-31T01:45:51.966086Z","steps":["trace[627464290] 'agreement among raft nodes before linearized reading' (duration: 234.280275ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.456344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:45:51.966187Z","caller":"traceutil/trace.go:171","msg":"trace[552569488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"341.487065ms","start":"2026-01-31T01:45:51.624696Z","end":"2026-01-31T01:45:51.966183Z","steps":["trace[552569488] 'agreement among raft nodes before linearized reading' (duration: 341.445654ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:51.624671Z","time spent":"341.528716ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-31T01:45:51.966215Z","caller":"traceutil/trace.go:171","msg":"trace[2103017613] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.220089083s","start":"2026-01-31T01:45:50.7461Z","end":"2026-01-31T01:45:51.966189Z","steps":["trace[2103017613] 'process raft request' (duration: 1.219294595s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:50.746085Z","time spent":"1.220172986s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T01:45:51.966322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.062059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zqtzp\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-01-31T01:45:51.96634Z","caller":"traceutil/trace.go:171","msg":"trace[1095123836] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zqtzp; range_end:; response_count:1; response_revision:613; }","duration":"424.109041ms","start":"2026-01-31T01:45:51.542226Z","end":"2026-01-31T01:45:51.966335Z","steps":["trace[1095123836] 'agreement among raft nodes before linearized reading' (duration: 424.05692ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:51.542192Z","time spent":"424.175252ms","remote":"127.0.0.1:46322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-zqtzp\" "} {"level":"warn","ts":"2026-01-31T01:45:51.96647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.457185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:45:51.966485Z","caller":"traceutil/trace.go:171","msg":"trace[67347549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"572.500996ms","start":"2026-01-31T01:45:51.393979Z","end":"2026-01-31T01:45:51.96648Z","steps":["trace[67347549] 'agreement among raft nodes before linearized reading' (duration: 572.477005ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:51.393965Z","time spent":"572.533206ms","remote":"127.0.0.1:46138","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-01-31T01:45:51.966615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.088345ms","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-01-31T01:45:51.96663Z","caller":"traceutil/trace.go:171","msg":"trace[1360688195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"643.139396ms","start":"2026-01-31T01:45:51.323486Z","end":"2026-01-31T01:45:51.966626Z","steps":["trace[1360688195] 'agreement among raft nodes before linearized reading' (duration: 643.087795ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:45:51.966647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:45:51.323469Z","time spent":"643.172407ms","remote":"127.0.0.1:46408","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-01-31T01:46:02.621007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T01:46:03.066634Z","caller":"traceutil/trace.go:171","msg":"trace[553095627] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"951.607224ms","start":"2026-01-31T01:46:02.115009Z","end":"2026-01-31T01:46:03.066616Z","steps":["trace[553095627] 'process raft request' (duration: 951.509372ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.066742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.115Z","time spent":"951.681346ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:03.484698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.158367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:03.48481Z","caller":"traceutil/trace.go:171","msg":"trace[185259974] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"1.36461456s","start":"2026-01-31T01:46:02.120186Z","end":"2026-01-31T01:46:03.4848Z","steps":["trace[185259974] 'read index received' (duration: 946.819204ms)","trace[185259974] 'applied index is now lower than readState.Index' (duration: 417.794686ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:03.484868Z","caller":"traceutil/trace.go:171","msg":"trace[327601417] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.369214915s","start":"2026-01-31T01:46:02.115647Z","end":"2026-01-31T01:46:03.484862Z","steps":["trace[327601417] 'process raft request' (duration: 1.187592968s)","trace[327601417] 'compare' (duration: 181.080855ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:03.4849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.115642Z","time spent":"1.369239056s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:03.485005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364815675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:03.485018Z","caller":"traceutil/trace.go:171","msg":"trace[814874573] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:689; }","duration":"1.364840175s","start":"2026-01-31T01:46:02.120173Z","end":"2026-01-31T01:46:03.485013Z","steps":["trace[814874573] 'agreement among raft nodes before linearized reading' (duration: 1.364813494s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.485032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.120168Z","time spent":"1.364860256s","remote":"127.0.0.1:46620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:46:03.48517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363459503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-31T01:46:03.485187Z","caller":"traceutil/trace.go:171","msg":"trace[620624219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"1.363485264s","start":"2026-01-31T01:46:02.121698Z","end":"2026-01-31T01:46:03.485183Z","steps":["trace[620624219] 'agreement among raft nodes before linearized reading' (duration: 1.363429002s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.485199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.12169Z","time spent":"1.363506745s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T01:46:03.48544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359024982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T01:46:03.485459Z","caller":"traceutil/trace.go:171","msg":"trace[1472483320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.359045412s","start":"2026-01-31T01:46:02.126409Z","end":"2026-01-31T01:46:03.485454Z","steps":["trace[1472483320] 'agreement among raft nodes before linearized reading' (duration: 1.358994111s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.485472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.126402Z","time spent":"1.359067202s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:03.485543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.985755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:03.485558Z","caller":"traceutil/trace.go:171","msg":"trace[1621073333] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:689; }","duration":"124.036375ms","start":"2026-01-31T01:46:03.361518Z","end":"2026-01-31T01:46:03.485555Z","steps":["trace[1621073333] 'agreement among raft nodes before linearized reading' (duration: 124.005595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.48563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358301395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:46:03.485645Z","caller":"traceutil/trace.go:171","msg":"trace[1574646057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.358326915s","start":"2026-01-31T01:46:02.127315Z","end":"2026-01-31T01:46:03.485642Z","steps":["trace[1574646057] 'agreement among raft nodes before linearized reading' (duration: 1.358290394s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.48566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.12731Z","time spent":"1.358347487s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:03.485747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358753186s","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-01-31T01:46:03.485764Z","caller":"traceutil/trace.go:171","msg":"trace[1310473577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.358781616s","start":"2026-01-31T01:46:02.126977Z","end":"2026-01-31T01:46:03.485759Z","steps":["trace[1310473577] 'agreement among raft nodes before linearized reading' (duration: 1.358744545s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:03.485775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:02.126972Z","time spent":"1.358800286s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:04.511726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.564744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:04.512584Z","caller":"traceutil/trace.go:171","msg":"trace[937543405] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.02036817s","start":"2026-01-31T01:46:03.492198Z","end":"2026-01-31T01:46:04.512566Z","steps":["trace[937543405] 'process raft request' (duration: 1.020323259s)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:04.512857Z","caller":"traceutil/trace.go:171","msg":"trace[651267007] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.023198266s","start":"2026-01-31T01:46:03.489634Z","end":"2026-01-31T01:46:04.512832Z","steps":["trace[651267007] 'process raft request' (duration: 205.449294ms)","trace[651267007] 'compare' (duration: 816.089504ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:04.512996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:03.489628Z","time spent":"1.023298268s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:04.513142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:03.492192Z","time spent":"1.020649157s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:04.513568Z","caller":"traceutil/trace.go:171","msg":"trace[137665678] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.022169822s","start":"2026-01-31T01:46:03.491328Z","end":"2026-01-31T01:46:04.513498Z","steps":["trace[137665678] 'process raft request' (duration: 1.021112238s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:04.513635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:03.491322Z","time spent":"1.022281814s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:04.801779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:05.435668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.849885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:05.435722Z","caller":"traceutil/trace.go:171","msg":"trace[289642869] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.220984094s","start":"2026-01-31T01:46:04.214728Z","end":"2026-01-31T01:46:05.435712Z","steps":["trace[289642869] 'process raft request' (duration: 763.042986ms)","trace[289642869] 'compare' (duration: 457.509998ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:05.435752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:04.214711Z","time spent":"1.221029005s","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:05.445195Z","caller":"traceutil/trace.go:171","msg":"trace[1127157479] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"1.161629102s","start":"2026-01-31T01:46:04.283547Z","end":"2026-01-31T01:46:05.445176Z","steps":["trace[1127157479] 'read index received' (duration: 694.232018ms)","trace[1127157479] 'applied index is now lower than readState.Index' (duration: 467.396114ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:05.445281Z","caller":"traceutil/trace.go:171","msg":"trace[1075083772] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"1.019199715s","start":"2026-01-31T01:46:04.426073Z","end":"2026-01-31T01:46:05.445272Z","steps":["trace[1075083772] 'process raft request' (duration: 1.018996601s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:05.445359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:04.426053Z","time spent":"1.019247296s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:05.445428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161878118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:05.445461Z","caller":"traceutil/trace.go:171","msg":"trace[2117111408] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:695; }","duration":"1.16195984s","start":"2026-01-31T01:46:04.283493Z","end":"2026-01-31T01:46:05.445453Z","steps":["trace[2117111408] 'agreement among raft nodes before linearized reading' (duration: 1.161904359s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:05.445481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:04.283472Z","time spent":"1.162004401s","remote":"127.0.0.1:46218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-01-31T01:46:06.623581Z","caller":"traceutil/trace.go:171","msg":"trace[742995029] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"103.677199ms","start":"2026-01-31T01:46:06.519883Z","end":"2026-01-31T01:46:06.62356Z","steps":["trace[742995029] 'read index received' (duration: 103.564106ms)","trace[742995029] 'applied index is now lower than readState.Index' (duration: 112.573µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:06.623662Z","caller":"traceutil/trace.go:171","msg":"trace[1049633571] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"104.399275ms","start":"2026-01-31T01:46:06.519255Z","end":"2026-01-31T01:46:06.623654Z","steps":["trace[1049633571] 'process raft request' (duration: 104.202331ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:06.623781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.396663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:46:06.623811Z","caller":"traceutil/trace.go:171","msg":"trace[1202808511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"103.433484ms","start":"2026-01-31T01:46:06.520367Z","end":"2026-01-31T01:46:06.6238Z","steps":["trace[1202808511] 'agreement among raft nodes before linearized reading' (duration: 103.331931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:06.624016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.129259ms","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-01-31T01:46:06.624035Z","caller":"traceutil/trace.go:171","msg":"trace[2054421448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"104.1623ms","start":"2026-01-31T01:46:06.519867Z","end":"2026-01-31T01:46:06.624029Z","steps":["trace[2054421448] 'agreement among raft nodes before linearized reading' (duration: 104.109569ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:06.915675Z","caller":"traceutil/trace.go:171","msg":"trace[899088981] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"287.435135ms","start":"2026-01-31T01:46:06.62823Z","end":"2026-01-31T01:46:06.915666Z","steps":["trace[899088981] 'process raft request' (duration: 287.159179ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:19.556525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.989193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:19.556602Z","caller":"traceutil/trace.go:171","msg":"trace[765159594] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:737; }","duration":"382.103006ms","start":"2026-01-31T01:46:19.174481Z","end":"2026-01-31T01:46:19.556584Z","steps":["trace[765159594] 'count revisions from in-memory index tree' (duration: 381.938462ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:19.556643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:19.174469Z","time spent":"382.162557ms","remote":"127.0.0.1:46236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:46:19.556929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.795303ms","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-01-31T01:46:19.556961Z","caller":"traceutil/trace.go:171","msg":"trace[1985865808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"601.852985ms","start":"2026-01-31T01:46:18.955096Z","end":"2026-01-31T01:46:19.556949Z","steps":["trace[1985865808] 'range keys from in-memory index tree' (duration: 601.687481ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:19.556986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:18.955082Z","time spent":"601.898766ms","remote":"127.0.0.1:46408","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-01-31T01:46:19.556994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.148089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:19.557115Z","caller":"traceutil/trace.go:171","msg":"trace[1096019775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"167.316263ms","start":"2026-01-31T01:46:19.389777Z","end":"2026-01-31T01:46:19.557093Z","steps":["trace[1096019775] 'range keys from in-memory index tree' (duration: 167.100778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:19.557323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.956482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:19.557354Z","caller":"traceutil/trace.go:171","msg":"trace[241747457] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:737; }","duration":"119.012743ms","start":"2026-01-31T01:46:19.438331Z","end":"2026-01-31T01:46:19.557344Z","steps":["trace[241747457] 'count revisions from in-memory index tree' (duration: 118.91057ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:20.887127Z","caller":"traceutil/trace.go:171","msg":"trace[619437426] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"166.472883ms","start":"2026-01-31T01:46:20.720638Z","end":"2026-01-31T01:46:20.887111Z","steps":["trace[619437426] 'read index received' (duration: 166.3389ms)","trace[619437426] 'applied index is now lower than readState.Index' (duration: 133.423µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:20.887455Z","caller":"traceutil/trace.go:171","msg":"trace[1513907167] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"211.815593ms","start":"2026-01-31T01:46:20.675631Z","end":"2026-01-31T01:46:20.887447Z","steps":["trace[1513907167] 'process raft request' (duration: 211.393103ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:20.887662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.016126ms","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-01-31T01:46:20.887704Z","caller":"traceutil/trace.go:171","msg":"trace[84484407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"167.098088ms","start":"2026-01-31T01:46:20.720598Z","end":"2026-01-31T01:46:20.887696Z","steps":["trace[84484407] 'agreement among raft nodes before linearized reading' (duration: 166.990415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:20.887829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.617682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T01:46:20.887865Z","caller":"traceutil/trace.go:171","msg":"trace[830683973] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:741; }","duration":"144.684844ms","start":"2026-01-31T01:46:20.743175Z","end":"2026-01-31T01:46:20.88786Z","steps":["trace[830683973] 'agreement among raft nodes before linearized reading' (duration: 144.621722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:21.338956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.89628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:21.339017Z","caller":"traceutil/trace.go:171","msg":"trace[1930665520] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"180.87915ms","start":"2026-01-31T01:46:21.158128Z","end":"2026-01-31T01:46:21.339007Z","steps":["trace[1930665520] 'read index received' (duration: 42.751µs)","trace[1930665520] 'applied index is now lower than readState.Index' (duration: 180.835739ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:21.339093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.971062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:21.339106Z","caller":"traceutil/trace.go:171","msg":"trace[1599016232] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:743; }","duration":"181.016834ms","start":"2026-01-31T01:46:21.158084Z","end":"2026-01-31T01:46:21.339101Z","steps":["trace[1599016232] 'agreement among raft nodes before linearized reading' (duration: 180.947772ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:21.339215Z","caller":"traceutil/trace.go:171","msg":"trace[1664790829] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"432.73741ms","start":"2026-01-31T01:46:20.906471Z","end":"2026-01-31T01:46:21.339209Z","steps":["trace[1664790829] 'process raft request' (duration: 71.533793ms)","trace[1664790829] 'compare' (duration: 360.672325ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:21.339245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:20.906452Z","time spent":"432.774832ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:22.101201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.548833ms","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-01-31T01:46:22.101284Z","caller":"traceutil/trace.go:171","msg":"trace[1798941194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"440.679706ms","start":"2026-01-31T01:46:21.660588Z","end":"2026-01-31T01:46:22.101268Z","steps":["trace[1798941194] 'range keys from in-memory index tree' (duration: 440.40632ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:22.101322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:21.660566Z","time spent":"440.747598ms","remote":"127.0.0.1:46408","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-01-31T01:46:22.101534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.30196ms","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-01-31T01:46:22.10157Z","caller":"traceutil/trace.go:171","msg":"trace[106558930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"438.371862ms","start":"2026-01-31T01:46:21.663186Z","end":"2026-01-31T01:46:22.101557Z","steps":["trace[106558930] 'range keys from in-memory index tree' (duration: 438.165797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:22.101638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:21.663162Z","time spent":"438.469624ms","remote":"127.0.0.1:46408","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-01-31T01:46:22.101807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.133359ms","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-01-31T01:46:22.101856Z","caller":"traceutil/trace.go:171","msg":"trace[2108953747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"436.181021ms","start":"2026-01-31T01:46:21.665652Z","end":"2026-01-31T01:46:22.101833Z","steps":["trace[2108953747] 'range keys from in-memory index tree' (duration: 436.033497ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:22.101887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:21.665645Z","time spent":"436.235302ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-31T01:46:22.363946Z","caller":"traceutil/trace.go:171","msg":"trace[1244489239] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"257.394078ms","start":"2026-01-31T01:46:22.106534Z","end":"2026-01-31T01:46:22.363928Z","steps":["trace[1244489239] 'process raft request' (duration: 257.283335ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:22.831615Z","caller":"traceutil/trace.go:171","msg":"trace[641613547] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"437.813549ms","start":"2026-01-31T01:46:22.393782Z","end":"2026-01-31T01:46:22.831595Z","steps":["trace[641613547] 'read index received' (duration: 342.269865ms)","trace[641613547] 'applied index is now lower than readState.Index' (duration: 95.542814ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:22.831765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.969543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:22.831794Z","caller":"traceutil/trace.go:171","msg":"trace[169188779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"438.052075ms","start":"2026-01-31T01:46:22.393733Z","end":"2026-01-31T01:46:22.831786Z","steps":["trace[169188779] 'agreement among raft nodes before linearized reading' (duration: 437.971413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:22.831826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:22.393712Z","time spent":"438.107565ms","remote":"127.0.0.1:46138","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-01-31T01:46:22.832086Z","caller":"traceutil/trace.go:171","msg":"trace[24955373] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"723.595161ms","start":"2026-01-31T01:46:22.108479Z","end":"2026-01-31T01:46:22.832075Z","steps":["trace[24955373] 'process raft request' (duration: 627.616367ms)","trace[24955373] 'compare' (duration: 95.266748ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:22.832177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:22.108467Z","time spent":"723.644062ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:22.832331Z","caller":"traceutil/trace.go:171","msg":"trace[159859805] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"722.087426ms","start":"2026-01-31T01:46:22.110235Z","end":"2026-01-31T01:46:22.832322Z","steps":["trace[159859805] 'process raft request' (duration: 721.314208ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:22.83238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:22.110223Z","time spent":"722.130287ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:23.090515Z","caller":"traceutil/trace.go:171","msg":"trace[404721984] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"172.711849ms","start":"2026-01-31T01:46:22.917784Z","end":"2026-01-31T01:46:23.090496Z","steps":["trace[404721984] 'read index received' (duration: 172.535234ms)","trace[404721984] 'applied index is now lower than readState.Index' (duration: 175.745µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:23.090746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.947154ms","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-01-31T01:46:23.090777Z","caller":"traceutil/trace.go:171","msg":"trace[182955854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"173.022476ms","start":"2026-01-31T01:46:22.917746Z","end":"2026-01-31T01:46:23.090768Z","steps":["trace[182955854] 'agreement among raft nodes before linearized reading' (duration: 172.854292ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:23.091038Z","caller":"traceutil/trace.go:171","msg":"trace[153223552] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"192.247206ms","start":"2026-01-31T01:46:22.898781Z","end":"2026-01-31T01:46:23.091028Z","steps":["trace[153223552] 'process raft request' (duration: 191.608751ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:23.264286Z","caller":"traceutil/trace.go:171","msg":"trace[1394760659] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"165.131141ms","start":"2026-01-31T01:46:23.099139Z","end":"2026-01-31T01:46:23.26427Z","steps":["trace[1394760659] 'process raft request' (duration: 163.236697ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:34.516638Z","caller":"traceutil/trace.go:171","msg":"trace[1454031935] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"108.302742ms","start":"2026-01-31T01:46:34.408318Z","end":"2026-01-31T01:46:34.516621Z","steps":["trace[1454031935] 'process raft request' (duration: 108.19035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.382242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697573,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T01:46:35.522781Z","caller":"traceutil/trace.go:171","msg":"trace[1140524781] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"640.825076ms","start":"2026-01-31T01:46:34.88194Z","end":"2026-01-31T01:46:35.522765Z","steps":["trace[1140524781] 'read index received' (duration: 640.691723ms)","trace[1140524781] 'applied index is now lower than readState.Index' (duration: 132.773µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:35.522976Z","caller":"traceutil/trace.go:171","msg":"trace[1051487204] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"648.197599ms","start":"2026-01-31T01:46:34.874772Z","end":"2026-01-31T01:46:35.522969Z","steps":["trace[1051487204] 'process raft request' (duration: 647.903232ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.523057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:34.874763Z","time spent":"648.236409ms","remote":"127.0.0.1:46408","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-01-31T01:46:35.523306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.359458ms","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-01-31T01:46:35.523338Z","caller":"traceutil/trace.go:171","msg":"trace[2026017337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"641.40477ms","start":"2026-01-31T01:46:34.881923Z","end":"2026-01-31T01:46:35.523328Z","steps":["trace[2026017337] 'agreement among raft nodes before linearized reading' (duration: 641.293548ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.523559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.904444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-31T01:46:35.523599Z","caller":"traceutil/trace.go:171","msg":"trace[342675570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"367.977926ms","start":"2026-01-31T01:46:35.15561Z","end":"2026-01-31T01:46:35.523588Z","steps":["trace[342675570] 'agreement among raft nodes before linearized reading' (duration: 367.848693ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.523631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:35.155598Z","time spent":"368.027117ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T01:46:35.523776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.133407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:35.523871Z","caller":"traceutil/trace.go:171","msg":"trace[2144487004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"134.24502ms","start":"2026-01-31T01:46:35.389615Z","end":"2026-01-31T01:46:35.52386Z","steps":["trace[2144487004] 'agreement among raft nodes before linearized reading' (duration: 134.140517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.524101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.587522ms","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-01-31T01:46:35.524135Z","caller":"traceutil/trace.go:171","msg":"trace[1307159742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"203.650863ms","start":"2026-01-31T01:46:35.320476Z","end":"2026-01-31T01:46:35.524127Z","steps":["trace[1307159742] 'agreement among raft nodes before linearized reading' (duration: 203.54897ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:35.523382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:34.881917Z","time spent":"641.458311ms","remote":"127.0.0.1:46408","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-01-31T01:46:36.209473Z","caller":"traceutil/trace.go:171","msg":"trace[451033628] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"272.330029ms","start":"2026-01-31T01:46:35.937128Z","end":"2026-01-31T01:46:36.209458Z","steps":["trace[451033628] 'process raft request' (duration: 272.219216ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:37.021329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:37.522088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:38.022937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:38.523548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:38.674396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.324176243s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-31T01:46:39.303622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.777835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:46:39.303734Z","caller":"traceutil/trace.go:171","msg":"trace[1042907452] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"2.782450061s","start":"2026-01-31T01:46:36.521267Z","end":"2026-01-31T01:46:39.303717Z","steps":["trace[1042907452] 'read index received' (duration: 2.153728357s)","trace[1042907452] 'applied index is now lower than readState.Index' (duration: 628.719074ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:39.304287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.783012794s","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-01-31T01:46:39.304321Z","caller":"traceutil/trace.go:171","msg":"trace[126484714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:836; }","duration":"2.783075236s","start":"2026-01-31T01:46:36.521236Z","end":"2026-01-31T01:46:39.304311Z","steps":["trace[126484714] 'agreement among raft nodes before linearized reading' (duration: 2.782919622s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.304351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:36.521221Z","time spent":"2.783122986s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:39.304523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914170575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:39.304552Z","caller":"traceutil/trace.go:171","msg":"trace[1119968399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"1.914221026s","start":"2026-01-31T01:46:37.390323Z","end":"2026-01-31T01:46:39.304544Z","steps":["trace[1119968399] 'agreement among raft nodes before linearized reading' (duration: 1.914180755s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.304575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:37.390311Z","time spent":"1.914259767s","remote":"127.0.0.1:46138","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-01-31T01:46:39.304818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.871894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:39.30486Z","caller":"traceutil/trace.go:171","msg":"trace[1158919745] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:836; }","duration":"143.942366ms","start":"2026-01-31T01:46:39.160898Z","end":"2026-01-31T01:46:39.30484Z","steps":["trace[1158919745] 'agreement among raft nodes before linearized reading' (duration: 143.865794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.307381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770342562s","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-01-31T01:46:39.307462Z","caller":"traceutil/trace.go:171","msg":"trace[1682676878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:836; }","duration":"1.770443574s","start":"2026-01-31T01:46:37.537008Z","end":"2026-01-31T01:46:39.307452Z","steps":["trace[1682676878] 'agreement among raft nodes before linearized reading' (duration: 1.767978796s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.30751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:37.537004Z","time spent":"1.770497576s","remote":"127.0.0.1:46408","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-01-31T01:46:39.307627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770911765s","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-01-31T01:46:39.307658Z","caller":"traceutil/trace.go:171","msg":"trace[692466014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"1.770949506s","start":"2026-01-31T01:46:37.5367Z","end":"2026-01-31T01:46:39.30765Z","steps":["trace[692466014] 'agreement among raft nodes before linearized reading' (duration: 1.770855584s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.307687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:37.536696Z","time spent":"1.770986017s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:39.307885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771758405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-31T01:46:39.307917Z","caller":"traceutil/trace.go:171","msg":"trace[449027455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"1.771798836s","start":"2026-01-31T01:46:37.53611Z","end":"2026-01-31T01:46:39.307909Z","steps":["trace[449027455] 'agreement among raft nodes before linearized reading' (duration: 1.771705314s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.307946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:37.536105Z","time spent":"1.771835857s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T01:46:39.308104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77749578s","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-01-31T01:46:39.308135Z","caller":"traceutil/trace.go:171","msg":"trace[1029978222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"1.77754544s","start":"2026-01-31T01:46:37.530582Z","end":"2026-01-31T01:46:39.308127Z","steps":["trace[1029978222] 'agreement among raft nodes before linearized reading' (duration: 1.777469978s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.308165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:37.530569Z","time spent":"1.777590831s","remote":"127.0.0.1:46408","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-01-31T01:46:39.783191Z","caller":"traceutil/trace.go:171","msg":"trace[420200863] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:871; }","duration":"402.958195ms","start":"2026-01-31T01:46:39.380213Z","end":"2026-01-31T01:46:39.783171Z","steps":["trace[420200863] 'read index received' (duration: 317.988847ms)","trace[420200863] 'applied index is now lower than readState.Index' (duration: 84.968368ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:39.783307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.074808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:39.783357Z","caller":"traceutil/trace.go:171","msg":"trace[1163139191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"403.16919ms","start":"2026-01-31T01:46:39.380178Z","end":"2026-01-31T01:46:39.783347Z","steps":["trace[1163139191] 'agreement among raft nodes before linearized reading' (duration: 403.072168ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.783393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:39.380163Z","time spent":"403.223261ms","remote":"127.0.0.1:46138","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-01-31T01:46:39.783558Z","caller":"traceutil/trace.go:171","msg":"trace[214707344] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"469.436ms","start":"2026-01-31T01:46:39.314111Z","end":"2026-01-31T01:46:39.783547Z","steps":["trace[214707344] 'process raft request' (duration: 468.867887ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:39.783551Z","caller":"traceutil/trace.go:171","msg":"trace[1858651586] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"469.44648ms","start":"2026-01-31T01:46:39.314063Z","end":"2026-01-31T01:46:39.78351Z","steps":["trace[1858651586] 'process raft request' (duration: 384.205416ms)","trace[1858651586] 'compare' (duration: 84.22225ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:39.783628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:39.314105Z","time spent":"469.475611ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:39.78373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:39.314056Z","time spent":"469.575993ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:39.783791Z","caller":"traceutil/trace.go:171","msg":"trace[668463137] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"467.31173ms","start":"2026-01-31T01:46:39.31647Z","end":"2026-01-31T01:46:39.783781Z","steps":["trace[668463137] 'process raft request' (duration: 466.639485ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.783842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:39.316463Z","time spent":"467.351661ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:39.783904Z","caller":"traceutil/trace.go:171","msg":"trace[717964381] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"466.150663ms","start":"2026-01-31T01:46:39.317746Z","end":"2026-01-31T01:46:39.783897Z","steps":["trace[717964381] 'process raft request' (duration: 465.394736ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:39.784166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:39.31774Z","time spent":"466.184164ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:40.997946Z","caller":"traceutil/trace.go:171","msg":"trace[1620479120] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"135.635422ms","start":"2026-01-31T01:46:40.862297Z","end":"2026-01-31T01:46:40.997932Z","steps":["trace[1620479120] 'read index received' (duration: 135.235973ms)","trace[1620479120] 'applied index is now lower than readState.Index' (duration: 398.899µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:40.998038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.728035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:40.998058Z","caller":"traceutil/trace.go:171","msg":"trace[1218846969] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:842; }","duration":"135.794115ms","start":"2026-01-31T01:46:40.86226Z","end":"2026-01-31T01:46:40.998054Z","steps":["trace[1218846969] 'agreement among raft nodes before linearized reading' (duration: 135.724764ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:40.998252Z","caller":"traceutil/trace.go:171","msg":"trace[987079207] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"156.897469ms","start":"2026-01-31T01:46:40.841348Z","end":"2026-01-31T01:46:40.998246Z","steps":["trace[987079207] 'process raft request' (duration: 156.155011ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.596463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.387684ms","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-01-31T01:46:41.59651Z","caller":"traceutil/trace.go:171","msg":"trace[420071220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"220.478576ms","start":"2026-01-31T01:46:41.376019Z","end":"2026-01-31T01:46:41.596498Z","steps":["trace[420071220] 'range keys from in-memory index tree' (duration: 220.260701ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.596626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.886574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:41.596702Z","caller":"traceutil/trace.go:171","msg":"trace[110299443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"199.990307ms","start":"2026-01-31T01:46:41.396685Z","end":"2026-01-31T01:46:41.596675Z","steps":["trace[110299443] 'range keys from in-memory index tree' (duration: 199.819303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.938269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.432448ms","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-01-31T01:46:41.938313Z","caller":"traceutil/trace.go:171","msg":"trace[285040324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"147.52077ms","start":"2026-01-31T01:46:41.790782Z","end":"2026-01-31T01:46:41.938303Z","steps":["trace[285040324] 'range keys from in-memory index tree' (duration: 147.315595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.938433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.22552ms","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-01-31T01:46:41.938445Z","caller":"traceutil/trace.go:171","msg":"trace[157845842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"146.23737ms","start":"2026-01-31T01:46:41.792204Z","end":"2026-01-31T01:46:41.938441Z","steps":["trace[157845842] 'range keys from in-memory index tree' (duration: 146.175558ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.938529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.381503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:46:41.938541Z","caller":"traceutil/trace.go:171","msg":"trace[830422595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:843; }","duration":"146.416134ms","start":"2026-01-31T01:46:41.792121Z","end":"2026-01-31T01:46:41.938537Z","steps":["trace[830422595] 'range keys from in-memory index tree' (duration: 146.25091ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:41.938609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.588877ms","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-01-31T01:46:41.93862Z","caller":"traceutil/trace.go:171","msg":"trace[1418542117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"131.619728ms","start":"2026-01-31T01:46:41.806997Z","end":"2026-01-31T01:46:41.938617Z","steps":["trace[1418542117] 'range keys from in-memory index tree' (duration: 131.487865ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:42.175601Z","caller":"traceutil/trace.go:171","msg":"trace[268072346] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"230.412859ms","start":"2026-01-31T01:46:41.945171Z","end":"2026-01-31T01:46:42.175584Z","steps":["trace[268072346] 'process raft request' (duration: 230.390339ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:42.175848Z","caller":"traceutil/trace.go:171","msg":"trace[983679200] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"232.61818ms","start":"2026-01-31T01:46:41.943225Z","end":"2026-01-31T01:46:42.175844Z","steps":["trace[983679200] 'process raft request' (duration: 232.229221ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:46:42.175903Z","caller":"traceutil/trace.go:171","msg":"trace[655572096] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"230.777038ms","start":"2026-01-31T01:46:41.945122Z","end":"2026-01-31T01:46:42.175899Z","steps":["trace[655572096] 'process raft request' (duration: 230.417669ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:42.616177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.111531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:42.616227Z","caller":"traceutil/trace.go:171","msg":"trace[703215194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"206.361087ms","start":"2026-01-31T01:46:42.409854Z","end":"2026-01-31T01:46:42.616215Z","steps":["trace[703215194] 'range keys from in-memory index tree' (duration: 206.039659ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:44.269411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:44.773205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:45.27421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:45.770525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001254782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-31T01:46:45.770572Z","caller":"traceutil/trace.go:171","msg":"trace[1069377916] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001336454s","start":"2026-01-31T01:46:43.769225Z","end":"2026-01-31T01:46:45.770562Z","steps":["trace[1069377916] 'agreement among raft nodes before linearized reading' (duration: 2.001274722s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:45.770599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:43.769213Z","time spent":"2.001381805s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/31 01:46:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:45.774922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:46.275925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:46.39457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000185497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-31T01:46:46.394647Z","caller":"traceutil/trace.go:171","msg":"trace[910853453] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000297009s","start":"2026-01-31T01:46:44.394334Z","end":"2026-01-31T01:46:46.394631Z","steps":["trace[910853453] 'agreement among raft nodes before linearized reading' (duration: 2.000206167s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:46.394686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.394322Z","time spent":"2.000356891s","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/31 01:46:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:46.681231Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.051081922s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T01:46:46.68256Z","caller":"traceutil/trace.go:171","msg":"trace[1558001030] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"3.051589073s","start":"2026-01-31T01:46:43.630077Z","end":"2026-01-31T01:46:46.681666Z","steps":["trace[1558001030] 'process raft request' (duration: 3.051373478s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:46.682683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:43.630062Z","time spent":"3.052549495s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:48.361385Z","caller":"traceutil/trace.go:171","msg":"trace[1568029875] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"4.332261247s","start":"2026-01-31T01:46:44.029098Z","end":"2026-01-31T01:46:48.361359Z","steps":["trace[1568029875] 'process raft request' (duration: 4.332022611s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:48.361491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.029085Z","time spent":"4.332348838s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:48.36265Z","caller":"traceutil/trace.go:171","msg":"trace[1748892839] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"2.590873892s","start":"2026-01-31T01:46:45.771766Z","end":"2026-01-31T01:46:48.36264Z","steps":["trace[1748892839] 'process raft request' (duration: 2.589440558s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:48.362712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:45.771753Z","time spent":"2.590926792s","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T01:46:48.402639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0007077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:48.402718Z","caller":"traceutil/trace.go:171","msg":"trace[1628872378] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000833564s","start":"2026-01-31T01:46:46.401867Z","end":"2026-01-31T01:46:48.402701Z","steps":["trace[1628872378] 'agreement among raft nodes before linearized reading' (duration: 2.000737221s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:48.402765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:46.401851Z","time spent":"2.000905585s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T01:46:49.001673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000817132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:49.001781Z","caller":"traceutil/trace.go:171","msg":"trace[1805036225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000978215s","start":"2026-01-31T01:46:44.000778Z","end":"2026-01-31T01:46:49.001756Z","steps":["trace[1805036225] 'agreement among raft nodes before linearized reading' (duration: 5.000837112s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.001832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.000767Z","time spent":"5.001053767s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/01/31 01:46:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:49.180171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999360077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:49.180225Z","caller":"traceutil/trace.go:171","msg":"trace[499457445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999439129s","start":"2026-01-31T01:46:44.180769Z","end":"2026-01-31T01:46:49.180208Z","steps":["trace[499457445] 'agreement among raft nodes before linearized reading' (duration: 4.999372338s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.180254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.180761Z","time spent":"4.99948773s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T01:46:49.335725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.459839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:46:49.336929Z","caller":"traceutil/trace.go:171","msg":"trace[1731468052] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:882; }","duration":"5.567659298s","start":"2026-01-31T01:46:43.769251Z","end":"2026-01-31T01:46:49.336911Z","steps":["trace[1731468052] 'read index received' (duration: 2.912309378s)","trace[1731468052] 'applied index is now lower than readState.Index' (duration: 2.65534877s)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:49.337015Z","caller":"traceutil/trace.go:171","msg":"trace[142861107] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"3.092298968s","start":"2026-01-31T01:46:46.244707Z","end":"2026-01-31T01:46:49.337006Z","steps":["trace[142861107] 'process raft request' (duration: 3.09110513s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.337126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:46.244689Z","time spent":"3.09238242s","remote":"127.0.0.1:46408","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-01-31T01:46:49.564497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.551782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:49.564557Z","caller":"traceutil/trace.go:171","msg":"trace[347801653] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"227.578043ms","start":"2026-01-31T01:46:49.336969Z","end":"2026-01-31T01:46:49.564547Z","steps":["trace[347801653] 'read index received' (duration: 329.428µs)","trace[347801653] 'applied index is now lower than readState.Index' (duration: 227.247855ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:49.564639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.384490824s","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-01-31T01:46:49.564653Z","caller":"traceutil/trace.go:171","msg":"trace[1358194927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"5.384536055s","start":"2026-01-31T01:46:44.180113Z","end":"2026-01-31T01:46:49.564649Z","steps":["trace[1358194927] 'agreement among raft nodes before linearized reading' (duration: 5.384459813s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.564671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.180097Z","time spent":"5.384570155s","remote":"127.0.0.1:46408","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-01-31T01:46:49.564768Z","caller":"traceutil/trace.go:171","msg":"trace[1714152451] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"1.197850724s","start":"2026-01-31T01:46:48.366912Z","end":"2026-01-31T01:46:49.564763Z","steps":["trace[1714152451] 'process raft request' (duration: 969.994605ms)","trace[1714152451] 'compare' (duration: 227.428159ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:49.564805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:48.366862Z","time spent":"1.197917515s","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-31T01:46:49.564887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.82574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:49.564902Z","caller":"traceutil/trace.go:171","msg":"trace[1351879581] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:852; }","duration":"313.875841ms","start":"2026-01-31T01:46:49.25102Z","end":"2026-01-31T01:46:49.564896Z","steps":["trace[1351879581] 'agreement among raft nodes before linearized reading' (duration: 313.84622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.564918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.250977Z","time spent":"313.936192ms","remote":"127.0.0.1:46674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:46:49.565015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.629218ms","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-01-31T01:46:49.565031Z","caller":"traceutil/trace.go:171","msg":"trace[2117980372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"878.671649ms","start":"2026-01-31T01:46:48.686355Z","end":"2026-01-31T01:46:49.565027Z","steps":["trace[2117980372] 'agreement among raft nodes before linearized reading' (duration: 878.631158ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.565056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:48.68634Z","time spent":"878.71223ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:49.565117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16222473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:49.565132Z","caller":"traceutil/trace.go:171","msg":"trace[828527160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"1.162279551s","start":"2026-01-31T01:46:48.402849Z","end":"2026-01-31T01:46:49.565128Z","steps":["trace[828527160] 'agreement among raft nodes before linearized reading' (duration: 1.162256291s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.565143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:48.402829Z","time spent":"1.162311963s","remote":"127.0.0.1:46148","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-01-31T01:46:49.565215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549170878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:49.565231Z","caller":"traceutil/trace.go:171","msg":"trace[492244911] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:852; }","duration":"1.54922939s","start":"2026-01-31T01:46:48.015997Z","end":"2026-01-31T01:46:49.565227Z","steps":["trace[492244911] 'agreement among raft nodes before linearized reading' (duration: 1.549204619s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.565242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:48.015976Z","time spent":"1.549263561s","remote":"127.0.0.1:46546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:46:49.565321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143934508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:46:49.565336Z","caller":"traceutil/trace.go:171","msg":"trace[2145709770] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:852; }","duration":"2.143974099s","start":"2026-01-31T01:46:47.421359Z","end":"2026-01-31T01:46:49.565333Z","steps":["trace[2145709770] 'agreement among raft nodes before linearized reading' (duration: 2.143949148s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.56535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:47.421345Z","time spent":"2.144000999s","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:46:49.565439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.385217991s","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-01-31T01:46:49.565454Z","caller":"traceutil/trace.go:171","msg":"trace[1187853954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"5.385245042s","start":"2026-01-31T01:46:44.180205Z","end":"2026-01-31T01:46:49.56545Z","steps":["trace[1187853954] 'agreement among raft nodes before linearized reading' (duration: 5.385204371s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:49.56547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:44.1802Z","time spent":"5.385266402s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:50.834648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026876795s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:50.834857Z","caller":"traceutil/trace.go:171","msg":"trace[2090721523] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:888; }","duration":"1.255972604s","start":"2026-01-31T01:46:49.578868Z","end":"2026-01-31T01:46:50.834841Z","steps":["trace[2090721523] 'read index received' (duration: 228.843483ms)","trace[2090721523] 'applied index is now lower than readState.Index' (duration: 1.027128071s)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:46:50.835091Z","caller":"traceutil/trace.go:171","msg":"trace[1609738772] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.26564697s","start":"2026-01-31T01:46:49.569431Z","end":"2026-01-31T01:46:50.835078Z","steps":["trace[1609738772] 'process raft request' (duration: 238.266933ms)","trace[1609738772] 'compare' (duration: 1.026383214s)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:50.835163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.569383Z","time spent":"1.265747112s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:50.835348Z","caller":"traceutil/trace.go:171","msg":"trace[1738192518] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"1.26523576s","start":"2026-01-31T01:46:49.570103Z","end":"2026-01-31T01:46:50.835339Z","steps":["trace[1738192518] 'process raft request' (duration: 1.264638946s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:50.83541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.57009Z","time spent":"1.265288782s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:46:50.835532Z","caller":"traceutil/trace.go:171","msg":"trace[1719850451] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"1.257006608s","start":"2026-01-31T01:46:49.578517Z","end":"2026-01-31T01:46:50.835524Z","steps":["trace[1719850451] 'process raft request' (duration: 1.256290902s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:50.835585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.578505Z","time spent":"1.257054279s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:50.835772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256899176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-01-31T01:46:50.835811Z","caller":"traceutil/trace.go:171","msg":"trace[1724156839] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff; range_end:; response_count:1; response_revision:855; }","duration":"1.256951047s","start":"2026-01-31T01:46:49.57885Z","end":"2026-01-31T01:46:50.835801Z","steps":["trace[1724156839] 'agreement among raft nodes before linearized reading' (duration: 1.256833284s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:50.835865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.578844Z","time spent":"1.257013818s","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff\" "} {"level":"warn","ts":"2026-01-31T01:46:50.836005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25668626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:46:50.836038Z","caller":"traceutil/trace.go:171","msg":"trace[684989104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"1.256734552s","start":"2026-01-31T01:46:49.579295Z","end":"2026-01-31T01:46:50.83603Z","steps":["trace[684989104] 'agreement among raft nodes before linearized reading' (duration: 1.256687921s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:50.836069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:49.579285Z","time spent":"1.256778783s","remote":"127.0.0.1:46138","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-01-31T01:46:51.348777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485697636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T01:46:52.393096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.501221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:46:52.393203Z","caller":"traceutil/trace.go:171","msg":"trace[920493836] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"1.550554061s","start":"2026-01-31T01:46:50.842635Z","end":"2026-01-31T01:46:52.393189Z","steps":["trace[920493836] 'process raft request' (duration: 823.863456ms)","trace[920493836] 'compare' (duration: 726.218164ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:52.393247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:50.842624Z","time spent":"1.550601622s","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:52.848133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000173646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:52.848186Z","caller":"traceutil/trace.go:171","msg":"trace[97591470] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000243048s","start":"2026-01-31T01:46:50.847935Z","end":"2026-01-31T01:46:52.848178Z","steps":["trace[97591470] 'agreement among raft nodes before linearized reading' (duration: 2.000181056s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:52.848215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:50.84793Z","time spent":"2.000280219s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T01:46:54.001578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99914014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-31T01:46:54.001643Z","caller":"traceutil/trace.go:171","msg":"trace[1568238559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.999237652s","start":"2026-01-31T01:46:51.002393Z","end":"2026-01-31T01:46:54.00163Z","steps":["trace[1568238559] 'agreement among raft nodes before linearized reading' (duration: 2.999158481s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:54.001675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:51.002379Z","time spent":"2.999285773s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/01/31 01:46:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:54.181739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998891244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-31T01:46:54.181787Z","caller":"traceutil/trace.go:171","msg":"trace[715171215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998971586s","start":"2026-01-31T01:46:51.182804Z","end":"2026-01-31T01:46:54.181776Z","steps":["trace[715171215] 'agreement among raft nodes before linearized reading' (duration: 2.998910705s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:54.181823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:51.182791Z","time spent":"2.999026827s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/01/31 01:46:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:54.848643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000210996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:54.848709Z","caller":"traceutil/trace.go:171","msg":"trace[437361736] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000298628s","start":"2026-01-31T01:46:52.848397Z","end":"2026-01-31T01:46:54.848696Z","steps":["trace[437361736] 'agreement among raft nodes before linearized reading' (duration: 2.000216616s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:54.848744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.848391Z","time spent":"2.000341179s","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T01:46:55.105867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.439425874s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-31T01:46:55.769439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000303099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:55.769498Z","caller":"traceutil/trace.go:171","msg":"trace[1896841522] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000398652s","start":"2026-01-31T01:46:53.769089Z","end":"2026-01-31T01:46:55.769487Z","steps":["trace[1896841522] 'agreement among raft nodes before linearized reading' (duration: 2.00032363s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:55.76953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:53.769075Z","time spent":"2.000449043s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T01:46:56.468957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097117488s","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-01-31T01:46:56.469049Z","caller":"traceutil/trace.go:171","msg":"trace[334205107] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:856; }","duration":"1.097203971s","start":"2026-01-31T01:46:55.37182Z","end":"2026-01-31T01:46:56.469024Z","steps":["trace[334205107] 'range keys from in-memory index tree' (duration: 1.097106478s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:56.469294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363307864s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-01-31T01:46:56.469522Z","caller":"traceutil/trace.go:171","msg":"trace[1621213245] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"2.287545166s","start":"2026-01-31T01:46:54.18196Z","end":"2026-01-31T01:46:56.469505Z","steps":["trace[1621213245] 'process raft request' (duration: 2.287434684s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:56.469631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:54.181948Z","time spent":"2.287627649s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:46:56.469784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:50.841668Z","time spent":"5.62811522s","remote":"127.0.0.1:46170","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T01:46:56.469881Z","caller":"traceutil/trace.go:171","msg":"trace[672552355] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:891; }","duration":"5.621926315s","start":"2026-01-31T01:46:50.847945Z","end":"2026-01-31T01:46:56.469871Z","steps":["trace[672552355] 'read index received' (duration: 818.563062ms)","trace[672552355] 'applied index is now lower than readState.Index' (duration: 4.803362323s)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:46:56.855704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001037897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-31T01:46:56.85578Z","caller":"traceutil/trace.go:171","msg":"trace[583981708] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001137389s","start":"2026-01-31T01:46:54.854627Z","end":"2026-01-31T01:46:56.855764Z","steps":["trace[583981708] 'agreement among raft nodes before linearized reading' (duration: 2.001047107s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:56.855987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:54.854621Z","time spent":"2.001188531s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/31 01:46:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-31T01:46:57.843423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999215382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:57.843474Z","caller":"traceutil/trace.go:171","msg":"trace[284108451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999285133s","start":"2026-01-31T01:46:52.844174Z","end":"2026-01-31T01:46:57.843459Z","steps":["trace[284108451] 'agreement among raft nodes before linearized reading' (duration: 4.999220152s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:57.843502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.844171Z","time spent":"4.999325844s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:46:57.879329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:57.542942Z","time spent":"336.381417ms","remote":"127.0.0.1:40824","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-31T01:46:58.855936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000278728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T01:46:58.856006Z","caller":"traceutil/trace.go:171","msg":"trace[703972600] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000424272s","start":"2026-01-31T01:46:56.855564Z","end":"2026-01-31T01:46:58.855988Z","steps":["trace[703972600] 'agreement among raft nodes before linearized reading' (duration: 2.000318259s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:46:58.856052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:56.855541Z","time spent":"2.000504573s","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T01:47:00.339747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.562714518s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:47:00.339935Z","caller":"traceutil/trace.go:171","msg":"trace[2046117251] transaction","detail":"{read_only:false; number_of_response:1; response_revision:858; }","duration":"3.865395536s","start":"2026-01-31T01:46:56.474527Z","end":"2026-01-31T01:47:00.339923Z","steps":["trace[2046117251] 'process raft request' (duration: 3.865294174s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.34001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:56.474514Z","time spent":"3.865449998s","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":41,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:47:00.340778Z","caller":"traceutil/trace.go:171","msg":"trace[826120198] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"3.870869565s","start":"2026-01-31T01:46:56.4699Z","end":"2026-01-31T01:47:00.34077Z","steps":["trace[826120198] 'read index received' (duration: 307.155434ms)","trace[826120198] 'applied index is now lower than readState.Index' (duration: 3.563713391s)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.340929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.487092183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-01-31T01:47:00.340947Z","caller":"traceutil/trace.go:171","msg":"trace[15552895] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff; range_end:; response_count:1; response_revision:858; }","duration":"5.487145444s","start":"2026-01-31T01:46:54.853796Z","end":"2026-01-31T01:47:00.340941Z","steps":["trace[15552895] 'agreement among raft nodes before linearized reading' (duration: 5.487064822s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.340966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:54.853781Z","time spent":"5.487180015s","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.188fad8e9025b0ff\" "} {"level":"warn","ts":"2026-01-31T01:47:00.341164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.619513659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.341185Z","caller":"traceutil/trace.go:171","msg":"trace[498051678] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:858; }","duration":"5.619558271s","start":"2026-01-31T01:46:54.721621Z","end":"2026-01-31T01:47:00.341179Z","steps":["trace[498051678] 'agreement among raft nodes before linearized reading' (duration: 5.6195049s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.341202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:54.721607Z","time spent":"5.619589901s","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-01-31T01:47:00.341533Z","caller":"traceutil/trace.go:171","msg":"trace[1187911492] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"1.704432852s","start":"2026-01-31T01:46:58.637093Z","end":"2026-01-31T01:47:00.341526Z","steps":["trace[1187911492] 'process raft request' (duration: 1.702773313s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.343565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:58.63707Z","time spent":"1.704485023s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:47:00.696963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.536798653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.697013Z","caller":"traceutil/trace.go:171","msg":"trace[1458588074] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:859; }","duration":"8.536892315s","start":"2026-01-31T01:46:52.160107Z","end":"2026-01-31T01:47:00.697Z","steps":["trace[1458588074] 'agreement among raft nodes before linearized reading' (duration: 8.181194307s)","trace[1458588074] 'count revisions from in-memory index tree' (duration: 355.630676ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.697089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.160089Z","time spent":"8.536987857s","remote":"127.0.0.1:46338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.697344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.088243622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.697404Z","caller":"traceutil/trace.go:171","msg":"trace[890640747] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:859; }","duration":"6.088329083s","start":"2026-01-31T01:46:54.609057Z","end":"2026-01-31T01:47:00.697386Z","steps":["trace[890640747] 'agreement among raft nodes before linearized reading' (duration: 5.732220855s)","trace[890640747] 'count revisions from in-memory index tree' (duration: 356.041336ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.697443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:54.609021Z","time spent":"6.088407925s","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.69768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.853914193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:47:00.697715Z","caller":"traceutil/trace.go:171","msg":"trace[902808891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"7.853964703s","start":"2026-01-31T01:46:52.843739Z","end":"2026-01-31T01:47:00.697704Z","steps":["trace[902808891] 'agreement among raft nodes before linearized reading' (duration: 7.497545408s)","trace[902808891] 'range keys from in-memory index tree' (duration: 356.312303ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.697776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.843735Z","time spent":"7.854032915s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:00.698048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.855304945s","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-01-31T01:47:00.698095Z","caller":"traceutil/trace.go:171","msg":"trace[1279335890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:859; }","duration":"7.855375846s","start":"2026-01-31T01:46:52.842706Z","end":"2026-01-31T01:47:00.698082Z","steps":["trace[1279335890] 'agreement among raft nodes before linearized reading' (duration: 7.498583542s)","trace[1279335890] 'range keys from in-memory index tree' (duration: 356.653811ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.698128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.842693Z","time spent":"7.855427878s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:00.698293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.13815811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.69833Z","caller":"traceutil/trace.go:171","msg":"trace[1142322843] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:859; }","duration":"8.138193931s","start":"2026-01-31T01:46:52.560124Z","end":"2026-01-31T01:47:00.698318Z","steps":["trace[1142322843] 'agreement among raft nodes before linearized reading' (duration: 7.781172752s)","trace[1142322843] 'count revisions from in-memory index tree' (duration: 356.976548ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.698364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.560069Z","time spent":"8.138286663s","remote":"127.0.0.1:46508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.698538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.621785821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.698576Z","caller":"traceutil/trace.go:171","msg":"trace[1994902658] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:859; }","duration":"8.621828472s","start":"2026-01-31T01:46:52.076736Z","end":"2026-01-31T01:47:00.698565Z","steps":["trace[1994902658] 'agreement among raft nodes before linearized reading' (duration: 8.264575057s)","trace[1994902658] 'count revisions from in-memory index tree' (duration: 357.202254ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.698611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.076658Z","time spent":"8.621942993s","remote":"127.0.0.1:46270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.698757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.556155575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.698786Z","caller":"traceutil/trace.go:171","msg":"trace[1990341580] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:859; }","duration":"8.556224737s","start":"2026-01-31T01:46:52.142553Z","end":"2026-01-31T01:47:00.698777Z","steps":["trace[1990341580] 'agreement among raft nodes before linearized reading' (duration: 8.198754137s)","trace[1990341580] 'count revisions from in-memory index tree' (duration: 357.435479ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.698813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:52.142533Z","time spent":"8.556272688s","remote":"127.0.0.1:46562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.698942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.660301549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.698982Z","caller":"traceutil/trace.go:171","msg":"trace[1530515340] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:859; }","duration":"9.66034168s","start":"2026-01-31T01:46:51.038631Z","end":"2026-01-31T01:47:00.698973Z","steps":["trace[1530515340] 'agreement among raft nodes before linearized reading' (duration: 9.302685016s)","trace[1530515340] 'count revisions from in-memory index tree' (duration: 357.610223ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.699014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:51.038597Z","time spent":"9.660408111s","remote":"127.0.0.1:46568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.699698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.121545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:47:00.699749Z","caller":"traceutil/trace.go:171","msg":"trace[290377705] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"358.945084ms","start":"2026-01-31T01:47:00.340795Z","end":"2026-01-31T01:47:00.69974Z","steps":["trace[290377705] 'read index received' (duration: 526.922µs)","trace[290377705] 'applied index is now lower than readState.Index' (duration: 358.417182ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.699809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.603124544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.699837Z","caller":"traceutil/trace.go:171","msg":"trace[1618741299] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:860; }","duration":"3.603174705s","start":"2026-01-31T01:46:57.096653Z","end":"2026-01-31T01:47:00.699827Z","steps":["trace[1618741299] 'agreement among raft nodes before linearized reading' (duration: 3.603116974s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.699863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:57.09664Z","time spent":"3.603216276s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-01-31T01:47:00.700124Z","caller":"traceutil/trace.go:171","msg":"trace[35262304] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.035894156s","start":"2026-01-31T01:46:59.664218Z","end":"2026-01-31T01:47:00.700112Z","steps":["trace[35262304] 'process raft request' (duration: 677.30124ms)","trace[35262304] 'compare' (duration: 355.381211ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:00.70018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:59.664201Z","time spent":"1.035948268s","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:47:00.700338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.685591ms","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-01-31T01:47:00.700393Z","caller":"traceutil/trace.go:171","msg":"trace[933659099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"855.776393ms","start":"2026-01-31T01:46:59.844609Z","end":"2026-01-31T01:47:00.700385Z","steps":["trace[933659099] 'agreement among raft nodes before linearized reading' (duration: 855.666201ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.70042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:59.844586Z","time spent":"855.826075ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:00.700564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13826058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.700593Z","caller":"traceutil/trace.go:171","msg":"trace[79936323] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:860; }","duration":"1.138325722s","start":"2026-01-31T01:46:59.562259Z","end":"2026-01-31T01:47:00.700585Z","steps":["trace[79936323] 'agreement among raft nodes before linearized reading' (duration: 1.138281761s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.700621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:59.56224Z","time spent":"1.138374643s","remote":"127.0.0.1:46454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.700764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837613386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.700803Z","caller":"traceutil/trace.go:171","msg":"trace[1692050394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"1.837681798s","start":"2026-01-31T01:46:58.863112Z","end":"2026-01-31T01:47:00.700794Z","steps":["trace[1692050394] 'agreement among raft nodes before linearized reading' (duration: 1.837618777s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.700829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:58.863097Z","time spent":"1.837725629s","remote":"127.0.0.1:46138","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-01-31T01:47:00.700977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.552061784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.701005Z","caller":"traceutil/trace.go:171","msg":"trace[1941260834] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:860; }","duration":"2.552127756s","start":"2026-01-31T01:46:58.148869Z","end":"2026-01-31T01:47:00.700997Z","steps":["trace[1941260834] 'agreement among raft nodes before linearized reading' (duration: 2.552080595s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.702978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.371673581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:00.703067Z","caller":"traceutil/trace.go:171","msg":"trace[401404971] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:860; }","duration":"3.371802244s","start":"2026-01-31T01:46:57.331252Z","end":"2026-01-31T01:47:00.703054Z","steps":["trace[401404971] 'agreement among raft nodes before linearized reading' (duration: 3.371683721s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.7031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:57.33123Z","time spent":"3.371860346s","remote":"127.0.0.1:46634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:00.701034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:46:58.148848Z","time spent":"2.552178366s","remote":"127.0.0.1:46516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-01-31T01:47:00.79311Z","caller":"traceutil/trace.go:171","msg":"trace[1929405246] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"447.377542ms","start":"2026-01-31T01:47:00.3457Z","end":"2026-01-31T01:47:00.793077Z","steps":["trace[1929405246] 'process raft request' (duration: 447.178857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.793243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:00.345686Z","time spent":"447.480985ms","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:47:00.907787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:00.345752Z","time spent":"562.029024ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T01:47:00.907762Z","caller":"traceutil/trace.go:171","msg":"trace[2056525766] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"202.059405ms","start":"2026-01-31T01:47:00.705658Z","end":"2026-01-31T01:47:00.907718Z","steps":["trace[2056525766] 'read index received' (duration: 87.202899ms)","trace[2056525766] 'applied index is now lower than readState.Index' (duration: 114.855556ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:00.907962Z","caller":"traceutil/trace.go:171","msg":"trace[1708835556] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"200.303274ms","start":"2026-01-31T01:47:00.707648Z","end":"2026-01-31T01:47:00.907951Z","steps":["trace[1708835556] 'process raft request' (duration: 199.807113ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.908102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.428844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:00.908128Z","caller":"traceutil/trace.go:171","msg":"trace[1235843038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"202.470195ms","start":"2026-01-31T01:47:00.705647Z","end":"2026-01-31T01:47:00.908118Z","steps":["trace[1235843038] 'agreement among raft nodes before linearized reading' (duration: 202.420854ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:00.908208Z","caller":"traceutil/trace.go:171","msg":"trace[895391590] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"199.209088ms","start":"2026-01-31T01:47:00.708993Z","end":"2026-01-31T01:47:00.908202Z","steps":["trace[895391590] 'process raft request' (duration: 198.628985ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:00.908253Z","caller":"traceutil/trace.go:171","msg":"trace[1361620728] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"198.191825ms","start":"2026-01-31T01:47:00.710057Z","end":"2026-01-31T01:47:00.908249Z","steps":["trace[1361620728] 'process raft request' (duration: 197.623162ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:00.908442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.731053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188fad8e6ae89c3f\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-01-31T01:47:00.908463Z","caller":"traceutil/trace.go:171","msg":"trace[1369922451] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188fad8e6ae89c3f; range_end:; response_count:1; response_revision:864; }","duration":"111.777534ms","start":"2026-01-31T01:47:00.796677Z","end":"2026-01-31T01:47:00.908455Z","steps":["trace[1369922451] 'agreement among raft nodes before linearized reading' (duration: 111.697472ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:01.040575Z","caller":"traceutil/trace.go:171","msg":"trace[2141824106] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"123.329295ms","start":"2026-01-31T01:47:00.917229Z","end":"2026-01-31T01:47:01.040558Z","steps":["trace[2141824106] 'read index received' (duration: 58.463398ms)","trace[2141824106] 'applied index is now lower than readState.Index' (duration: 64.865287ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:01.040662Z","caller":"traceutil/trace.go:171","msg":"trace[638687019] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"129.354625ms","start":"2026-01-31T01:47:00.911299Z","end":"2026-01-31T01:47:01.040653Z","steps":["trace[638687019] 'process raft request' (duration: 109.659094ms)","trace[638687019] 'compare' (duration: 19.529427ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:01.040892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.651842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:01.040908Z","caller":"traceutil/trace.go:171","msg":"trace[282346651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"123.700763ms","start":"2026-01-31T01:47:00.917203Z","end":"2026-01-31T01:47:01.040904Z","steps":["trace[282346651] 'agreement among raft nodes before linearized reading' (duration: 123.643641ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:01.21925Z","caller":"traceutil/trace.go:171","msg":"trace[697652824] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"171.565852ms","start":"2026-01-31T01:47:01.04766Z","end":"2026-01-31T01:47:01.219226Z","steps":["trace[697652824] 'process raft request' (duration: 130.320577ms)","trace[697652824] 'compare' (duration: 41.073631ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:01.411688Z","caller":"traceutil/trace.go:171","msg":"trace[1933161499] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"104.424623ms","start":"2026-01-31T01:47:01.307245Z","end":"2026-01-31T01:47:01.41167Z","steps":["trace[1933161499] 'read index received' (duration: 59.887251ms)","trace[1933161499] 'applied index is now lower than readState.Index' (duration: 44.536562ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:01.411709Z","caller":"traceutil/trace.go:171","msg":"trace[1639583612] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"104.650628ms","start":"2026-01-31T01:47:01.307031Z","end":"2026-01-31T01:47:01.411682Z","steps":["trace[1639583612] 'process raft request' (duration: 60.128677ms)","trace[1639583612] 'compare' (duration: 44.426729ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:01.41181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.542675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-31T01:47:01.411833Z","caller":"traceutil/trace.go:171","msg":"trace[671238753] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:870; }","duration":"104.592286ms","start":"2026-01-31T01:47:01.307234Z","end":"2026-01-31T01:47:01.411827Z","steps":["trace[671238753] 'agreement among raft nodes before linearized reading' (duration: 104.505244ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:03.276037Z","caller":"traceutil/trace.go:171","msg":"trace[1781096633] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"361.202827ms","start":"2026-01-31T01:47:02.914818Z","end":"2026-01-31T01:47:03.276021Z","steps":["trace[1781096633] 'process raft request' (duration: 361.027783ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:03.27629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:02.914808Z","time spent":"361.420552ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:47:03.870949Z","caller":"traceutil/trace.go:171","msg":"trace[969376217] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:910; }","duration":"954.535873ms","start":"2026-01-31T01:47:02.916401Z","end":"2026-01-31T01:47:03.870937Z","steps":["trace[969376217] 'read index received' (duration: 359.549798ms)","trace[969376217] 'applied index is now lower than readState.Index' (duration: 594.985595ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:03.871086Z","caller":"traceutil/trace.go:171","msg":"trace[1432753795] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"955.222119ms","start":"2026-01-31T01:47:02.915857Z","end":"2026-01-31T01:47:03.87108Z","steps":["trace[1432753795] 'process raft request' (duration: 955.014344ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:03.871152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:02.915849Z","time spent":"955.25666ms","remote":"127.0.0.1:46408","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-01-31T01:47:03.871304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.900411ms","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-01-31T01:47:03.871323Z","caller":"traceutil/trace.go:171","msg":"trace[2016818889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:872; }","duration":"954.931781ms","start":"2026-01-31T01:47:02.916386Z","end":"2026-01-31T01:47:03.871318Z","steps":["trace[2016818889] 'agreement among raft nodes before linearized reading' (duration: 954.85609ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:03.871337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:02.91638Z","time spent":"954.953843ms","remote":"127.0.0.1:46408","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-01-31T01:47:03.871406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.786489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:03.871421Z","caller":"traceutil/trace.go:171","msg":"trace[613792770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"476.836381ms","start":"2026-01-31T01:47:03.394581Z","end":"2026-01-31T01:47:03.871418Z","steps":["trace[613792770] 'agreement among raft nodes before linearized reading' (duration: 476.81478ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:03.871434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:03.394563Z","time spent":"476.866942ms","remote":"127.0.0.1:46138","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-01-31T01:47:03.871549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.060016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:03.871566Z","caller":"traceutil/trace.go:171","msg":"trace[139639789] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:872; }","duration":"205.113326ms","start":"2026-01-31T01:47:03.666449Z","end":"2026-01-31T01:47:03.871562Z","steps":["trace[139639789] 'agreement among raft nodes before linearized reading' (duration: 205.086596ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:04.127959Z","caller":"traceutil/trace.go:171","msg":"trace[1788253395] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"252.533886ms","start":"2026-01-31T01:47:03.875401Z","end":"2026-01-31T01:47:04.127935Z","steps":["trace[1788253395] 'read index received' (duration: 223.483547ms)","trace[1788253395] 'applied index is now lower than readState.Index' (duration: 29.049219ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:04.128105Z","caller":"traceutil/trace.go:171","msg":"trace[1542564123] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"252.815053ms","start":"2026-01-31T01:47:03.875284Z","end":"2026-01-31T01:47:04.128099Z","steps":["trace[1542564123] 'process raft request' (duration: 223.641581ms)","trace[1542564123] 'compare' (duration: 28.906326ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:04.128227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.809052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:04.128255Z","caller":"traceutil/trace.go:171","msg":"trace[1831777910] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:873; }","duration":"252.858663ms","start":"2026-01-31T01:47:03.875391Z","end":"2026-01-31T01:47:04.12825Z","steps":["trace[1831777910] 'agreement among raft nodes before linearized reading' (duration: 252.801852ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:04.128335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.167134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:04.128347Z","caller":"traceutil/trace.go:171","msg":"trace[770622779] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:873; }","duration":"189.201424ms","start":"2026-01-31T01:47:03.939142Z","end":"2026-01-31T01:47:04.128344Z","steps":["trace[770622779] 'agreement among raft nodes before linearized reading' (duration: 189.180704ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:05.386797Z","caller":"traceutil/trace.go:171","msg":"trace[362451425] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"101.758471ms","start":"2026-01-31T01:47:05.285019Z","end":"2026-01-31T01:47:05.386777Z","steps":["trace[362451425] 'process raft request' (duration: 101.646368ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:06.034618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.944899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:06.034685Z","caller":"traceutil/trace.go:171","msg":"trace[950107489] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:874; }","duration":"422.04553ms","start":"2026-01-31T01:47:05.61262Z","end":"2026-01-31T01:47:06.034666Z","steps":["trace[950107489] 'count revisions from in-memory index tree' (duration: 421.889217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:06.034737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:05.612607Z","time spent":"422.120153ms","remote":"127.0.0.1:42532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:06.035218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.49801ms","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-01-31T01:47:06.03525Z","caller":"traceutil/trace.go:171","msg":"trace[1905581153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"159.565092ms","start":"2026-01-31T01:47:05.875676Z","end":"2026-01-31T01:47:06.035241Z","steps":["trace[1905581153] 'range keys from in-memory index tree' (duration: 159.384437ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:06.379227Z","caller":"traceutil/trace.go:171","msg":"trace[1158046800] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"248.098372ms","start":"2026-01-31T01:47:06.13111Z","end":"2026-01-31T01:47:06.379208Z","steps":["trace[1158046800] 'read index received' (duration: 247.905927ms)","trace[1158046800] 'applied index is now lower than readState.Index' (duration: 191.804µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:06.379373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.254005ms","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-01-31T01:47:06.379393Z","caller":"traceutil/trace.go:171","msg":"trace[1608978381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"248.290696ms","start":"2026-01-31T01:47:06.131096Z","end":"2026-01-31T01:47:06.379387Z","steps":["trace[1608978381] 'agreement among raft nodes before linearized reading' (duration: 248.176793ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:06.379557Z","caller":"traceutil/trace.go:171","msg":"trace[300881782] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"340.710618ms","start":"2026-01-31T01:47:06.038802Z","end":"2026-01-31T01:47:06.379513Z","steps":["trace[300881782] 'process raft request' (duration: 340.272677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:06.379735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:06.038785Z","time spent":"340.83984ms","remote":"127.0.0.1:46408","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-01-31T01:47:06.636087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.512838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:47:06.636167Z","caller":"traceutil/trace.go:171","msg":"trace[2126685044] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"252.783362ms","start":"2026-01-31T01:47:06.383371Z","end":"2026-01-31T01:47:06.636154Z","steps":["trace[2126685044] 'process raft request' (duration: 147.149651ms)","trace[2126685044] 'compare' (duration: 105.419586ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:07.129361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.066579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-scheduler-instance.188fad90f8cec33f\" ","response":"range_response_count:1 size:837"} {"level":"info","ts":"2026-01-31T01:47:07.129432Z","caller":"traceutil/trace.go:171","msg":"trace[1547199254] range","detail":"{range_begin:/registry/events/kube-system/kube-scheduler-instance.188fad90f8cec33f; range_end:; response_count:1; response_revision:876; }","duration":"386.176351ms","start":"2026-01-31T01:47:06.743236Z","end":"2026-01-31T01:47:07.129413Z","steps":["trace[1547199254] 'range keys from in-memory index tree' (duration: 385.927545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:07.12947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:06.743217Z","time spent":"386.244512ms","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":861,"request content":"key:\"/registry/events/kube-system/kube-scheduler-instance.188fad90f8cec33f\" "} {"level":"warn","ts":"2026-01-31T01:47:07.6854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.874152ms","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-01-31T01:47:07.685454Z","caller":"traceutil/trace.go:171","msg":"trace[2124596453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"290.961094ms","start":"2026-01-31T01:47:07.394481Z","end":"2026-01-31T01:47:07.685442Z","steps":["trace[2124596453] 'range keys from in-memory index tree' (duration: 290.75384ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:07.685559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.907113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:07.685612Z","caller":"traceutil/trace.go:171","msg":"trace[141115561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"290.976885ms","start":"2026-01-31T01:47:07.39462Z","end":"2026-01-31T01:47:07.685597Z","steps":["trace[141115561] 'range keys from in-memory index tree' (duration: 290.877733ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:12.980187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.454608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:12.980274Z","caller":"traceutil/trace.go:171","msg":"trace[1320235753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"575.589021ms","start":"2026-01-31T01:47:12.404668Z","end":"2026-01-31T01:47:12.980257Z","steps":["trace[1320235753] 'range keys from in-memory index tree' (duration: 575.399976ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:12.980322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:12.404655Z","time spent":"575.656142ms","remote":"127.0.0.1:46138","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-01-31T01:47:12.980657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.849763ms","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-01-31T01:47:12.980699Z","caller":"traceutil/trace.go:171","msg":"trace[1752315835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"565.930155ms","start":"2026-01-31T01:47:12.414756Z","end":"2026-01-31T01:47:12.980686Z","steps":["trace[1752315835] 'range keys from in-memory index tree' (duration: 565.71696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:12.980737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:12.414745Z","time spent":"565.982886ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:12.980984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.990228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:12.981025Z","caller":"traceutil/trace.go:171","msg":"trace[655739931] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:909; }","duration":"345.105891ms","start":"2026-01-31T01:47:12.635908Z","end":"2026-01-31T01:47:12.981014Z","steps":["trace[655739931] 'count revisions from in-memory index tree' (duration: 344.936026ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:12.981122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:12.635892Z","time spent":"345.218853ms","remote":"127.0.0.1:42490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:12.9814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.428112ms","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-01-31T01:47:12.981437Z","caller":"traceutil/trace.go:171","msg":"trace[1549471024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"296.489243ms","start":"2026-01-31T01:47:12.684939Z","end":"2026-01-31T01:47:12.981428Z","steps":["trace[1549471024] 'range keys from in-memory index tree' (duration: 296.316519ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:15.239036Z","caller":"traceutil/trace.go:171","msg":"trace[1221519891] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"103.484071ms","start":"2026-01-31T01:47:15.135533Z","end":"2026-01-31T01:47:15.239017Z","steps":["trace[1221519891] 'process raft request' (duration: 92.891424ms)","trace[1221519891] 'compare' (duration: 10.511596ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:16.79093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.924381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:47:16.791069Z","caller":"traceutil/trace.go:171","msg":"trace[333962915] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"555.458926ms","start":"2026-01-31T01:47:16.235581Z","end":"2026-01-31T01:47:16.79104Z","steps":["trace[333962915] 'read index received' (duration: 357.418172ms)","trace[333962915] 'applied index is now lower than readState.Index' (duration: 198.036924ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:16.960278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.268551ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:47:16.960344Z","caller":"traceutil/trace.go:171","msg":"trace[710342355] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"169.207199ms","start":"2026-01-31T01:47:16.791126Z","end":"2026-01-31T01:47:16.960333Z","steps":["trace[710342355] 'read index received' (duration: 60.992µs)","trace[710342355] 'applied index is now lower than readState.Index' (duration: 169.145387ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:16.960435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.035103ms","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-01-31T01:47:16.960459Z","caller":"traceutil/trace.go:171","msg":"trace[1724933709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"657.059194ms","start":"2026-01-31T01:47:16.303389Z","end":"2026-01-31T01:47:16.960448Z","steps":["trace[1724933709] 'agreement among raft nodes before linearized reading' (duration: 656.970492ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:16.960478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:16.303374Z","time spent":"657.099645ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T01:47:16.960624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.040945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:16.960644Z","caller":"traceutil/trace.go:171","msg":"trace[1316160243] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:926; }","duration":"725.084105ms","start":"2026-01-31T01:47:16.235553Z","end":"2026-01-31T01:47:16.960637Z","steps":["trace[1316160243] 'agreement among raft nodes before linearized reading' (duration: 555.61528ms)","trace[1316160243] 'count revisions from in-memory index tree' (duration: 169.434435ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:16.960658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:16.23554Z","time spent":"725.114286ms","remote":"127.0.0.1:48652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:16.960794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.966306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:16.960811Z","caller":"traceutil/trace.go:171","msg":"trace[721867906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"567.005526ms","start":"2026-01-31T01:47:16.393802Z","end":"2026-01-31T01:47:16.960807Z","steps":["trace[721867906] 'agreement among raft nodes before linearized reading' (duration: 566.981706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:16.960823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:16.393787Z","time spent":"567.033568ms","remote":"127.0.0.1:46138","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-01-31T01:47:17.431Z","caller":"traceutil/trace.go:171","msg":"trace[1331170150] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"189.143476ms","start":"2026-01-31T01:47:17.241797Z","end":"2026-01-31T01:47:17.430941Z","steps":["trace[1331170150] 'read index received' (duration: 188.956101ms)","trace[1331170150] 'applied index is now lower than readState.Index' (duration: 186.315µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:17.431177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.35767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:47:17.431166Z","caller":"traceutil/trace.go:171","msg":"trace[672715741] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"258.790105ms","start":"2026-01-31T01:47:17.172354Z","end":"2026-01-31T01:47:17.431144Z","steps":["trace[672715741] 'process raft request' (duration: 258.450737ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:17.431206Z","caller":"traceutil/trace.go:171","msg":"trace[1658822936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"189.426032ms","start":"2026-01-31T01:47:17.241772Z","end":"2026-01-31T01:47:17.431198Z","steps":["trace[1658822936] 'agreement among raft nodes before linearized reading' (duration: 189.292368ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:17.803755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.855215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:47:17.804112Z","caller":"traceutil/trace.go:171","msg":"trace[834140126] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"194.673145ms","start":"2026-01-31T01:47:17.609426Z","end":"2026-01-31T01:47:17.804099Z","steps":["trace[834140126] 'process raft request' (duration: 68.423361ms)","trace[834140126] 'compare' (duration: 125.779643ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:19.556658Z","caller":"traceutil/trace.go:171","msg":"trace[1250853513] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"106.312107ms","start":"2026-01-31T01:47:19.450258Z","end":"2026-01-31T01:47:19.55657Z","steps":["trace[1250853513] 'read index received' (duration: 106.179644ms)","trace[1250853513] 'applied index is now lower than readState.Index' (duration: 131.623µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:19.556822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.544702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:47:19.556858Z","caller":"traceutil/trace.go:171","msg":"trace[1055217012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"106.605633ms","start":"2026-01-31T01:47:19.450243Z","end":"2026-01-31T01:47:19.556849Z","steps":["trace[1055217012] 'agreement among raft nodes before linearized reading' (duration: 106.493311ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:19.556907Z","caller":"traceutil/trace.go:171","msg":"trace[11674294] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"118.476071ms","start":"2026-01-31T01:47:19.438251Z","end":"2026-01-31T01:47:19.556727Z","steps":["trace[11674294] 'process raft request' (duration: 118.211095ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:19.738661Z","caller":"traceutil/trace.go:171","msg":"trace[508426365] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"179.134112ms","start":"2026-01-31T01:47:19.559507Z","end":"2026-01-31T01:47:19.738641Z","steps":["trace[508426365] 'process raft request' (duration: 79.449639ms)","trace[508426365] 'compare' (duration: 99.435097ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:20.21125Z","caller":"traceutil/trace.go:171","msg":"trace[672116099] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"228.377024ms","start":"2026-01-31T01:47:19.982852Z","end":"2026-01-31T01:47:20.211229Z","steps":["trace[672116099] 'process raft request' (duration: 228.23017ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:20.945416Z","caller":"traceutil/trace.go:171","msg":"trace[921010671] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"300.815439ms","start":"2026-01-31T01:47:20.644587Z","end":"2026-01-31T01:47:20.945403Z","steps":["trace[921010671] 'process raft request' (duration: 300.735827ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:20.945522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:20.644562Z","time spent":"300.895811ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:47:21.349926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.581446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T01:47:21.350003Z","caller":"traceutil/trace.go:171","msg":"trace[1230121186] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:941; }","duration":"301.74485ms","start":"2026-01-31T01:47:21.04824Z","end":"2026-01-31T01:47:21.349985Z","steps":["trace[1230121186] 'range keys from in-memory index tree' (duration: 301.405243ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:21.350048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:21.048222Z","time spent":"301.815341ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-01-31T01:47:21.350107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.488325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-31T01:47:21.350191Z","caller":"traceutil/trace.go:171","msg":"trace[522088444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"269.621399ms","start":"2026-01-31T01:47:21.080547Z","end":"2026-01-31T01:47:21.350168Z","steps":["trace[522088444] 'range keys from in-memory index tree' (duration: 269.323222ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:21.797932Z","caller":"traceutil/trace.go:171","msg":"trace[23771684] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"325.840404ms","start":"2026-01-31T01:47:21.472073Z","end":"2026-01-31T01:47:21.797914Z","steps":["trace[23771684] 'read index received' (duration: 325.67303ms)","trace[23771684] 'applied index is now lower than readState.Index' (duration: 166.664µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:21.797973Z","caller":"traceutil/trace.go:171","msg":"trace[782840257] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"364.494169ms","start":"2026-01-31T01:47:21.433454Z","end":"2026-01-31T01:47:21.797948Z","steps":["trace[782840257] 'process raft request' (duration: 364.341535ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:21.798106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:21.433431Z","time spent":"364.602401ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T01:47:21.798129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.050519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7012"} {"level":"info","ts":"2026-01-31T01:47:21.798154Z","caller":"traceutil/trace.go:171","msg":"trace[814602515] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:944; }","duration":"326.124261ms","start":"2026-01-31T01:47:21.472022Z","end":"2026-01-31T01:47:21.798147Z","steps":["trace[814602515] 'agreement among raft nodes before linearized reading' (duration: 325.974237ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:21.798178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:21.472006Z","time spent":"326.166361ms","remote":"127.0.0.1:46322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7036,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-01-31T01:47:21.798369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.624587ms","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-01-31T01:47:21.798387Z","caller":"traceutil/trace.go:171","msg":"trace[643740560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"236.669418ms","start":"2026-01-31T01:47:21.561713Z","end":"2026-01-31T01:47:21.798382Z","steps":["trace[643740560] 'agreement among raft nodes before linearized reading' (duration: 236.617057ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:22.061118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.558621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:47:22.061352Z","caller":"traceutil/trace.go:171","msg":"trace[1112683201] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"252.791395ms","start":"2026-01-31T01:47:21.808544Z","end":"2026-01-31T01:47:22.061335Z","steps":["trace[1112683201] 'process raft request' (duration: 252.742544ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:22.062082Z","caller":"traceutil/trace.go:171","msg":"trace[7140821] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"259.209565ms","start":"2026-01-31T01:47:21.802858Z","end":"2026-01-31T01:47:22.062068Z","steps":["trace[7140821] 'process raft request' (duration: 40.582329ms)","trace[7140821] 'compare' (duration: 217.463009ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:47:22.062241Z","caller":"traceutil/trace.go:171","msg":"trace[938734878] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"256.82613ms","start":"2026-01-31T01:47:21.805404Z","end":"2026-01-31T01:47:22.06223Z","steps":["trace[938734878] 'process raft request' (duration: 255.809446ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:47:25.662391Z","caller":"traceutil/trace.go:171","msg":"trace[1964683892] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"214.084379ms","start":"2026-01-31T01:47:25.448279Z","end":"2026-01-31T01:47:25.662364Z","steps":["trace[1964683892] 'process raft request' (duration: 213.898285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:26.96728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066259568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T01:47:26.967345Z","caller":"traceutil/trace.go:171","msg":"trace[1691118362] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:963; }","duration":"1.066389211s","start":"2026-01-31T01:47:25.900938Z","end":"2026-01-31T01:47:26.967327Z","steps":["trace[1691118362] 'count revisions from in-memory index tree' (duration: 1.066057964s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:26.967374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:25.900878Z","time spent":"1.066488934s","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:26.967699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.066865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-31T01:47:26.967722Z","caller":"traceutil/trace.go:171","msg":"trace[1533344382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"881.128317ms","start":"2026-01-31T01:47:26.086588Z","end":"2026-01-31T01:47:26.967716Z","steps":["trace[1533344382] 'range keys from in-memory index tree' (duration: 880.944572ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:26.967738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:26.086566Z","time spent":"881.168487ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:26.968392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.145921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T01:47:26.968414Z","caller":"traceutil/trace.go:171","msg":"trace[1231131144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"729.167901ms","start":"2026-01-31T01:47:26.239239Z","end":"2026-01-31T01:47:26.968407Z","steps":["trace[1231131144] 'range keys from in-memory index tree' (duration: 729.059128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:26.968432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:26.239178Z","time spent":"729.248483ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T01:47:26.968612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.348408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T01:47:26.968632Z","caller":"traceutil/trace.go:171","msg":"trace[1618318586] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:963; }","duration":"671.384839ms","start":"2026-01-31T01:47:26.297241Z","end":"2026-01-31T01:47:26.968626Z","steps":["trace[1618318586] 'count revisions from in-memory index tree' (duration: 671.307817ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:26.968653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:26.29723Z","time spent":"671.41764ms","remote":"127.0.0.1:46530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-01-31T01:47:27.04935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.81438ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-31T01:47:27.050231Z","caller":"traceutil/trace.go:171","msg":"trace[179827739] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"331.713242ms","start":"2026-01-31T01:47:26.718489Z","end":"2026-01-31T01:47:27.050202Z","steps":["trace[179827739] 'read index received' (duration: 161.9784ms)","trace[179827739] 'applied index is now lower than readState.Index' (duration: 169.733732ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:47:27.050899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.396997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T01:47:27.050939Z","caller":"traceutil/trace.go:171","msg":"trace[860683827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"332.48338ms","start":"2026-01-31T01:47:26.718445Z","end":"2026-01-31T01:47:27.050928Z","steps":["trace[860683827] 'agreement among raft nodes before linearized reading' (duration: 332.06642ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:47:27.050969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:47:26.718423Z","time spent":"332.53833ms","remote":"127.0.0.1:46408","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-01-31T01:47:27.051951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.816261ms","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-01-31T01:47:27.05199Z","caller":"traceutil/trace.go:171","msg":"trace[1008352536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"286.856912ms","start":"2026-01-31T01:47:26.765122Z","end":"2026-01-31T01:47:27.051979Z","steps":["trace[1008352536] 'agreement among raft nodes before linearized reading' (duration: 286.74722ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:50:34.231816Z","caller":"traceutil/trace.go:171","msg":"trace[1772250877] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"351.227951ms","start":"2026-01-31T01:50:33.880562Z","end":"2026-01-31T01:50:34.23179Z","steps":["trace[1772250877] 'process raft request' (duration: 351.064677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:34.23199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:33.880546Z","time spent":"351.336444ms","remote":"127.0.0.1:46408","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-01-31T01:50:34.449767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.356646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T01:50:34.450095Z","caller":"traceutil/trace.go:171","msg":"trace[1032099662] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"211.558265ms","start":"2026-01-31T01:50:34.238489Z","end":"2026-01-31T01:50:34.450047Z","steps":["trace[1032099662] 'process raft request' (duration: 104.854291ms)","trace[1032099662] 'compare' (duration: 106.266074ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T01:50:34.450237Z","caller":"traceutil/trace.go:171","msg":"trace[643663959] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"210.560092ms","start":"2026-01-31T01:50:34.239663Z","end":"2026-01-31T01:50:34.450223Z","steps":["trace[643663959] 'process raft request' (duration: 210.177183ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T01:50:34.450313Z","caller":"traceutil/trace.go:171","msg":"trace[333819709] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"210.601463ms","start":"2026-01-31T01:50:34.239706Z","end":"2026-01-31T01:50:34.450308Z","steps":["trace[333819709] 'process raft request' (duration: 210.214254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:35.03507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.499504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-01-31T01:50:35.035141Z","caller":"traceutil/trace.go:171","msg":"trace[1189241503] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1783; }","duration":"428.608086ms","start":"2026-01-31T01:50:34.606513Z","end":"2026-01-31T01:50:35.035121Z","steps":["trace[1189241503] 'range keys from in-memory index tree' (duration: 428.35436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:35.035179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:34.606494Z","time spent":"428.676787ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-01-31T01:50:35.342228Z","caller":"traceutil/trace.go:171","msg":"trace[1473900332] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"303.852221ms","start":"2026-01-31T01:50:35.038355Z","end":"2026-01-31T01:50:35.342207Z","steps":["trace[1473900332] 'process raft request' (duration: 303.726068ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:35.342525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:35.038341Z","time spent":"304.120656ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:50:43.596245Z","caller":"traceutil/trace.go:171","msg":"trace[1846569577] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"137.163877ms","start":"2026-01-31T01:50:43.459057Z","end":"2026-01-31T01:50:43.596221Z","steps":["trace[1846569577] 'process raft request' (duration: 137.001824ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.061132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540336485700156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T01:50:45.091933Z","caller":"traceutil/trace.go:171","msg":"trace[10760365] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"574.515264ms","start":"2026-01-31T01:50:44.517394Z","end":"2026-01-31T01:50:45.09191Z","steps":["trace[10760365] 'process raft request' (duration: 574.35418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.092086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:44.517384Z","time spent":"574.611416ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:50:45.095885Z","caller":"traceutil/trace.go:171","msg":"trace[754263198] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"577.337288ms","start":"2026-01-31T01:50:44.518533Z","end":"2026-01-31T01:50:45.09587Z","steps":["trace[754263198] 'process raft request' (duration: 577.279767ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.095973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:44.518525Z","time spent":"577.402639ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:50:45.096142Z","caller":"traceutil/trace.go:171","msg":"trace[542866348] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"578.32214ms","start":"2026-01-31T01:50:44.51781Z","end":"2026-01-31T01:50:45.096132Z","steps":["trace[542866348] 'process raft request' (duration: 577.909151ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.096199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:44.517802Z","time spent":"578.368682ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:50:45.097546Z","caller":"traceutil/trace.go:171","msg":"trace[1013818039] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1914; }","duration":"538.757189ms","start":"2026-01-31T01:50:44.55875Z","end":"2026-01-31T01:50:45.097507Z","steps":["trace[1013818039] 'read index received' (duration: 533.808166ms)","trace[1013818039] 'applied index is now lower than readState.Index' (duration: 4.947172ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T01:50:45.098297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.536316ms","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-01-31T01:50:45.098394Z","caller":"traceutil/trace.go:171","msg":"trace[387046798] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"579.529128ms","start":"2026-01-31T01:50:44.518848Z","end":"2026-01-31T01:50:45.098377Z","steps":["trace[387046798] 'process raft request' (duration: 576.995601ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.098473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:44.518842Z","time spent":"579.59415ms","remote":"127.0.0.1:46408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T01:50:45.09841Z","caller":"traceutil/trace.go:171","msg":"trace[2082635422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1829; }","duration":"539.69042ms","start":"2026-01-31T01:50:44.558704Z","end":"2026-01-31T01:50:45.098394Z","steps":["trace[2082635422] 'agreement among raft nodes before linearized reading' (duration: 539.420994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T01:50:45.098631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T01:50:44.558665Z","time spent":"539.927584ms","remote":"127.0.0.1:46408","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\" "}