{"level":"warn","ts":"2026-02-04T05:13:49.271078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:13:49.271215Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-02-04T05:13:49.271309Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:13:49.271335Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-04T05:13:49.271393Z","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-02-04T05:13:49.272194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-04T05:13:49.272295Z","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://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-02-04T05:13:49.287043Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.504513ms"} {"level":"info","ts":"2026-02-04T05:13:49.384244Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-04T05:13:49.384376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T05:13:49.384422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-04T05:13:49.384443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T05:13:49.384459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-04T05:13:49.384521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-04T05:13:49.418755Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T05:13:49.456752Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T05:13:49.501537Z","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-02-04T05:13:49.51082Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T05:13:49.5127Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T05:13:49.512916Z","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-02-04T05:13:49.512965Z","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-02-04T05:13:49.513002Z","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-02-04T05:13:49.513462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-04T05:13:49.513588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-04T05:13:49.520484Z","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-02-04T05:13:49.520685Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-04T05:13:49.520733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-04T05:13:49.521157Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T05:13:49.521183Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T05:13:50.185176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T05:13:50.185236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T05:13:50.18525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-04T05:13:50.185261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-04T05:13:50.185349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-04T05:13:50.185365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-04T05:13:50.185372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-04T05:13:50.314971Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:13:50.353812Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T05:13:50.353992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:13:50.360724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:13:50.36281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T05:13:50.368648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-04T05:13:50.368728Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T05:13:50.368752Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T05:13:50.368934Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:13:50.369066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:13:50.36911Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-04T05:13:52.420233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.838319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:13:52.420325Z","caller":"traceutil/trace.go:171","msg":"trace[196086676] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"203.069372ms","start":"2026-02-04T05:13:52.217241Z","end":"2026-02-04T05:13:52.42031Z","steps":["trace[196086676] 'read index received' (duration: 58.172042ms)","trace[196086676] 'applied index is now lower than readState.Index' (duration: 144.89609ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:52.420947Z","caller":"traceutil/trace.go:171","msg":"trace[173466231] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"207.231312ms","start":"2026-02-04T05:13:52.213701Z","end":"2026-02-04T05:13:52.420932Z","steps":["trace[173466231] 'process raft request' (duration: 61.753035ms)","trace[173466231] 'compare' (duration: 143.407596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:52.42297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.689908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1890f32cfeeb32f0\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-04T05:13:52.423305Z","caller":"traceutil/trace.go:171","msg":"trace[1455903859] range","detail":"{range_begin:/registry/events/default/instance.1890f32cfeeb32f0; range_end:; response_count:1; response_revision:84; }","duration":"205.996236ms","start":"2026-02-04T05:13:52.217218Z","end":"2026-02-04T05:13:52.423214Z","steps":["trace[1455903859] 'agreement among raft nodes before linearized reading' (duration: 203.133053ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:52.572987Z","caller":"traceutil/trace.go:171","msg":"trace[1324867394] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"144.183268ms","start":"2026-02-04T05:13:52.42876Z","end":"2026-02-04T05:13:52.572943Z","steps":["trace[1324867394] 'process raft request' (duration: 95.564573ms)","trace[1324867394] 'compare' (duration: 47.411001ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:52.573212Z","caller":"traceutil/trace.go:171","msg":"trace[505317362] linearizableReadLoop","detail":"{readStateIndex:91; appliedIndex:90; }","duration":"141.205443ms","start":"2026-02-04T05:13:52.431992Z","end":"2026-02-04T05:13:52.573197Z","steps":["trace[505317362] 'read index received' (duration: 80.987992ms)","trace[505317362] 'applied index is now lower than readState.Index' (duration: 60.216151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:52.573385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.317036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:13:52.57342Z","caller":"traceutil/trace.go:171","msg":"trace[1805935556] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:87; }","duration":"141.441619ms","start":"2026-02-04T05:13:52.431969Z","end":"2026-02-04T05:13:52.57341Z","steps":["trace[1805935556] 'agreement among raft nodes before linearized reading' (duration: 141.293995ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:52.746986Z","caller":"traceutil/trace.go:171","msg":"trace[641298527] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"103.276385ms","start":"2026-02-04T05:13:52.643689Z","end":"2026-02-04T05:13:52.746965Z","steps":["trace[641298527] 'process raft request' (duration: 46.204505ms)","trace[641298527] 'compare' (duration: 56.836483ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:52.978099Z","caller":"traceutil/trace.go:171","msg":"trace[998511822] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:96; }","duration":"189.145609ms","start":"2026-02-04T05:13:52.788927Z","end":"2026-02-04T05:13:52.978073Z","steps":["trace[998511822] 'read index received' (duration: 101.895436ms)","trace[998511822] 'applied index is now lower than readState.Index' (duration: 87.249103ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:52.978278Z","caller":"traceutil/trace.go:171","msg":"trace[1754187918] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"223.039849ms","start":"2026-02-04T05:13:52.755209Z","end":"2026-02-04T05:13:52.978249Z","steps":["trace[1754187918] 'process raft request' (duration: 135.58188ms)","trace[1754187918] 'compare' (duration: 87.1483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:52.978416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.476858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:13:52.979163Z","caller":"traceutil/trace.go:171","msg":"trace[1182378764] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:92; }","duration":"190.248491ms","start":"2026-02-04T05:13:52.788903Z","end":"2026-02-04T05:13:52.979151Z","steps":["trace[1182378764] 'agreement among raft nodes before linearized reading' (duration: 189.421717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:52.980557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.96204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:13:52.980591Z","caller":"traceutil/trace.go:171","msg":"trace[2143845871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:92; }","duration":"156.013561ms","start":"2026-02-04T05:13:52.824565Z","end":"2026-02-04T05:13:52.980579Z","steps":["trace[2143845871] 'agreement among raft nodes before linearized reading' (duration: 155.971569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:52.980785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.494619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1890f32cfeeb4916\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-04T05:13:52.980812Z","caller":"traceutil/trace.go:171","msg":"trace[1084088287] range","detail":"{range_begin:/registry/events/default/instance.1890f32cfeeb4916; range_end:; response_count:1; response_revision:92; }","duration":"188.534531ms","start":"2026-02-04T05:13:52.79227Z","end":"2026-02-04T05:13:52.980805Z","steps":["trace[1084088287] 'agreement among raft nodes before linearized reading' (duration: 188.1506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:53.275227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.637918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:13:53.275593Z","caller":"traceutil/trace.go:171","msg":"trace[1313602091] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:99; }","duration":"222.988527ms","start":"2026-02-04T05:13:53.052586Z","end":"2026-02-04T05:13:53.275574Z","steps":["trace[1313602091] 'read index received' (duration: 36.877044ms)","trace[1313602091] 'applied index is now lower than readState.Index' (duration: 186.109683ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:53.275639Z","caller":"traceutil/trace.go:171","msg":"trace[1474502247] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"224.321895ms","start":"2026-02-04T05:13:53.051267Z","end":"2026-02-04T05:13:53.275589Z","steps":["trace[1474502247] 'process raft request' (duration: 38.241804ms)","trace[1474502247] 'compare' (duration: 185.150985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:53.275855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.244354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:13:53.276087Z","caller":"traceutil/trace.go:171","msg":"trace[2141419552] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:95; }","duration":"223.516493ms","start":"2026-02-04T05:13:53.052559Z","end":"2026-02-04T05:13:53.276075Z","steps":["trace[2141419552] 'agreement among raft nodes before linearized reading' (duration: 223.128872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:55.0332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.601509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:13:55.033512Z","caller":"traceutil/trace.go:171","msg":"trace[621877604] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"989.875785ms","start":"2026-02-04T05:13:54.043607Z","end":"2026-02-04T05:13:55.033482Z","steps":["trace[621877604] 'process raft request' (duration: 849.916155ms)","trace[621877604] 'compare' (duration: 139.420154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:55.033647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:54.043595Z","time spent":"989.972417ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":650,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:13:55.034142Z","caller":"traceutil/trace.go:171","msg":"trace[820123718] linearizableReadLoop","detail":"{readStateIndex:173; appliedIndex:171; }","duration":"110.130995ms","start":"2026-02-04T05:13:54.923998Z","end":"2026-02-04T05:13:55.034129Z","steps":["trace[820123718] 'read index received' (duration: 109.073805ms)","trace[820123718] 'applied index is now lower than readState.Index' (duration: 1.05639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:55.034489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.485605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:13:55.034549Z","caller":"traceutil/trace.go:171","msg":"trace[409156903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"110.558767ms","start":"2026-02-04T05:13:54.923961Z","end":"2026-02-04T05:13:55.03452Z","steps":["trace[409156903] 'agreement among raft nodes before linearized reading' (duration: 110.216897ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:55.034951Z","caller":"traceutil/trace.go:171","msg":"trace[2144574318] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"659.756414ms","start":"2026-02-04T05:13:54.375182Z","end":"2026-02-04T05:13:55.034938Z","steps":["trace[2144574318] 'process raft request' (duration: 658.16378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:55.035016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:54.375158Z","time spent":"659.829286ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:13:56.414488Z","caller":"traceutil/trace.go:171","msg":"trace[1576417060] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"141.058416ms","start":"2026-02-04T05:13:56.273404Z","end":"2026-02-04T05:13:56.414462Z","steps":["trace[1576417060] 'process raft request' (duration: 76.269072ms)","trace[1576417060] 'compare' (duration: 64.685251ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:58.057303Z","caller":"traceutil/trace.go:171","msg":"trace[2035770377] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"124.438922ms","start":"2026-02-04T05:13:57.932845Z","end":"2026-02-04T05:13:58.057284Z","steps":["trace[2035770377] 'read index received' (duration: 91.53629ms)","trace[2035770377] 'applied index is now lower than readState.Index' (duration: 32.901792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:58.05748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.618837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T05:13:58.057573Z","caller":"traceutil/trace.go:171","msg":"trace[2013245663] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:277; }","duration":"124.732881ms","start":"2026-02-04T05:13:57.932827Z","end":"2026-02-04T05:13:58.05756Z","steps":["trace[2013245663] 'agreement among raft nodes before linearized reading' (duration: 124.573507ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:58.05772Z","caller":"traceutil/trace.go:171","msg":"trace[963368564] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"148.388209ms","start":"2026-02-04T05:13:57.90932Z","end":"2026-02-04T05:13:58.057708Z","steps":["trace[963368564] 'process raft request' (duration: 115.090166ms)","trace[963368564] 'compare' (duration: 32.792159ms)"],"step_count":2} 2026/02/04 05:13:58 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-04T05:13:58.459174Z","caller":"traceutil/trace.go:171","msg":"trace[1261153622] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"126.123019ms","start":"2026-02-04T05:13:58.333021Z","end":"2026-02-04T05:13:58.459144Z","steps":["trace[1261153622] 'process raft request' (duration: 125.80724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.112984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.325898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:13:59.113205Z","caller":"traceutil/trace.go:171","msg":"trace[1022498877] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"367.501298ms","start":"2026-02-04T05:13:58.745656Z","end":"2026-02-04T05:13:59.113158Z","steps":["trace[1022498877] 'process raft request' (duration: 49.925223ms)","trace[1022498877] 'compare' (duration: 317.119423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:59.113282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:58.745646Z","time spent":"367.610271ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:13:59.11399Z","caller":"traceutil/trace.go:171","msg":"trace[190796575] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"365.109333ms","start":"2026-02-04T05:13:58.748867Z","end":"2026-02-04T05:13:59.113976Z","steps":["trace[190796575] 'read index received' (duration: 46.808719ms)","trace[190796575] 'applied index is now lower than readState.Index' (duration: 318.298624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:13:59.114231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.441861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T05:13:59.114269Z","caller":"traceutil/trace.go:171","msg":"trace[247459112] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:306; }","duration":"369.500062ms","start":"2026-02-04T05:13:58.744758Z","end":"2026-02-04T05:13:59.114258Z","steps":["trace[247459112] 'agreement among raft nodes before linearized reading' (duration: 369.293387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.114463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:58.744752Z","time spent":"369.700377ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-04T05:13:59.116388Z","caller":"traceutil/trace.go:171","msg":"trace[1838829422] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"364.091885ms","start":"2026-02-04T05:13:58.752283Z","end":"2026-02-04T05:13:59.116375Z","steps":["trace[1838829422] 'process raft request' (duration: 360.839658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.116594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:58.752255Z","time spent":"364.170508ms","remote":"127.0.0.1:43372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:13:59.117082Z","caller":"traceutil/trace.go:171","msg":"trace[1750451446] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"234.566944ms","start":"2026-02-04T05:13:58.882503Z","end":"2026-02-04T05:13:59.11707Z","steps":["trace[1750451446] 'process raft request' (duration: 233.67964ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:59.152899Z","caller":"traceutil/trace.go:171","msg":"trace[866964441] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"266.481969ms","start":"2026-02-04T05:13:58.886403Z","end":"2026-02-04T05:13:59.152885Z","steps":["trace[866964441] 'process raft request' (duration: 266.465329ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:59.15309Z","caller":"traceutil/trace.go:171","msg":"trace[1261836130] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"268.621548ms","start":"2026-02-04T05:13:58.884464Z","end":"2026-02-04T05:13:59.153086Z","steps":["trace[1261836130] 'process raft request' (duration: 268.282889ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:59.153184Z","caller":"traceutil/trace.go:171","msg":"trace[1001529063] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"267.108546ms","start":"2026-02-04T05:13:58.886072Z","end":"2026-02-04T05:13:59.15318Z","steps":["trace[1001529063] 'process raft request' (duration: 266.738347ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:59.153246Z","caller":"traceutil/trace.go:171","msg":"trace[1025467734] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"267.066145ms","start":"2026-02-04T05:13:58.886176Z","end":"2026-02-04T05:13:59.153243Z","steps":["trace[1025467734] 'process raft request' (duration: 266.677835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.153622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.994327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-04T05:13:59.153647Z","caller":"traceutil/trace.go:171","msg":"trace[944015034] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:309; }","duration":"400.08812ms","start":"2026-02-04T05:13:58.753553Z","end":"2026-02-04T05:13:59.153641Z","steps":["trace[944015034] 'agreement among raft nodes before linearized reading' (duration: 400.014888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.153663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:58.753536Z","time spent":"400.12299ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3728,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-04T05:13:59.351088Z","caller":"traceutil/trace.go:171","msg":"trace[137852987] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"144.880615ms","start":"2026-02-04T05:13:59.206183Z","end":"2026-02-04T05:13:59.351063Z","steps":["trace[137852987] 'process raft request' (duration: 61.206491ms)","trace[137852987] 'compare' (duration: 83.556211ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:59.354034Z","caller":"traceutil/trace.go:171","msg":"trace[607168903] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"143.960919ms","start":"2026-02-04T05:13:59.210064Z","end":"2026-02-04T05:13:59.354025Z","steps":["trace[607168903] 'process raft request' (duration: 143.894618ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:13:59.753326Z","caller":"traceutil/trace.go:171","msg":"trace[1574477138] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"116.152855ms","start":"2026-02-04T05:13:59.637154Z","end":"2026-02-04T05:13:59.753307Z","steps":["trace[1574477138] 'read index received' (duration: 74.882µs)","trace[1574477138] 'applied index is now lower than readState.Index' (duration: 116.076993ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:13:59.753404Z","caller":"traceutil/trace.go:171","msg":"trace[1186212783] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"116.258767ms","start":"2026-02-04T05:13:59.637137Z","end":"2026-02-04T05:13:59.753396Z","steps":["trace[1186212783] 'process raft request' (duration: 116.109063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:13:59.753478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.304778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-04T05:13:59.753591Z","caller":"traceutil/trace.go:171","msg":"trace[961273495] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:316; }","duration":"116.474114ms","start":"2026-02-04T05:13:59.637111Z","end":"2026-02-04T05:13:59.753585Z","steps":["trace[961273495] 'agreement among raft nodes before linearized reading' (duration: 116.312159ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:00.39577Z","caller":"traceutil/trace.go:171","msg":"trace[840172272] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"457.577981ms","start":"2026-02-04T05:13:59.938177Z","end":"2026-02-04T05:14:00.395755Z","steps":["trace[840172272] 'read index received' (duration: 457.467438ms)","trace[840172272] 'applied index is now lower than readState.Index' (duration: 109.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:00.395981Z","caller":"traceutil/trace.go:171","msg":"trace[824662815] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"481.961284ms","start":"2026-02-04T05:13:59.914014Z","end":"2026-02-04T05:14:00.395976Z","steps":["trace[824662815] 'process raft request' (duration: 481.669006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:00.396041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:59.914002Z","time spent":"481.998495ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:00.396142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.963711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T05:14:00.396165Z","caller":"traceutil/trace.go:171","msg":"trace[1343456203] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:323; }","duration":"458.004682ms","start":"2026-02-04T05:13:59.938156Z","end":"2026-02-04T05:14:00.39616Z","steps":["trace[1343456203] 'agreement among raft nodes before linearized reading' (duration: 457.966551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:00.396179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:13:59.938146Z","time spent":"458.029922ms","remote":"127.0.0.1:43142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-04T05:14:00.396256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.997693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:14:00.396273Z","caller":"traceutil/trace.go:171","msg":"trace[1312447001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:323; }","duration":"282.050215ms","start":"2026-02-04T05:14:00.114219Z","end":"2026-02-04T05:14:00.396269Z","steps":["trace[1312447001] 'agreement among raft nodes before linearized reading' (duration: 282.022394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:00.396394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.103186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T05:14:00.396408Z","caller":"traceutil/trace.go:171","msg":"trace[678852020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:323; }","duration":"282.130667ms","start":"2026-02-04T05:14:00.114273Z","end":"2026-02-04T05:14:00.396404Z","steps":["trace[678852020] 'agreement among raft nodes before linearized reading' (duration: 282.107837ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:00.524875Z","caller":"traceutil/trace.go:171","msg":"trace[1189301663] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:334; }","duration":"119.523002ms","start":"2026-02-04T05:14:00.405338Z","end":"2026-02-04T05:14:00.524861Z","steps":["trace[1189301663] 'read index received' (duration: 104.290967ms)","trace[1189301663] 'applied index is now lower than readState.Index' (duration: 15.231425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:00.524984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.628565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5098"} {"level":"info","ts":"2026-02-04T05:14:00.52501Z","caller":"traceutil/trace.go:171","msg":"trace[1212851874] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:326; }","duration":"119.682606ms","start":"2026-02-04T05:14:00.405323Z","end":"2026-02-04T05:14:00.525005Z","steps":["trace[1212851874] 'agreement among raft nodes before linearized reading' (duration: 119.613184ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:00.525266Z","caller":"traceutil/trace.go:171","msg":"trace[1595080992] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"122.758838ms","start":"2026-02-04T05:14:00.402501Z","end":"2026-02-04T05:14:00.52526Z","steps":["trace[1595080992] 'process raft request' (duration: 107.120642ms)","trace[1595080992] 'compare' (duration: 15.120422ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:00.525333Z","caller":"traceutil/trace.go:171","msg":"trace[702132906] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"122.705816ms","start":"2026-02-04T05:14:00.402623Z","end":"2026-02-04T05:14:00.525329Z","steps":["trace[702132906] 'process raft request' (duration: 122.191843ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:00.525458Z","caller":"traceutil/trace.go:171","msg":"trace[1001317605] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"121.234467ms","start":"2026-02-04T05:14:00.404217Z","end":"2026-02-04T05:14:00.525451Z","steps":["trace[1001317605] 'process raft request' (duration: 120.6184ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:04.244254Z","caller":"traceutil/trace.go:171","msg":"trace[1989075537] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"114.620407ms","start":"2026-02-04T05:14:04.129618Z","end":"2026-02-04T05:14:04.244238Z","steps":["trace[1989075537] 'process raft request' (duration: 114.525105ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:06.781908Z","caller":"traceutil/trace.go:171","msg":"trace[524630810] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"125.479458ms","start":"2026-02-04T05:14:06.656406Z","end":"2026-02-04T05:14:06.781886Z","steps":["trace[524630810] 'process raft request' (duration: 124.943185ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:07.817782Z","caller":"traceutil/trace.go:171","msg":"trace[1437177837] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"154.717429ms","start":"2026-02-04T05:14:07.663044Z","end":"2026-02-04T05:14:07.817761Z","steps":["trace[1437177837] 'process raft request' (duration: 154.251268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:11.106652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526559597743019,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T05:14:11.246868Z","caller":"traceutil/trace.go:171","msg":"trace[1852273320] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"640.290541ms","start":"2026-02-04T05:14:10.606556Z","end":"2026-02-04T05:14:11.246846Z","steps":["trace[1852273320] 'read index received' (duration: 640.185918ms)","trace[1852273320] 'applied index is now lower than readState.Index' (duration: 101.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:11.247102Z","caller":"traceutil/trace.go:171","msg":"trace[1639219551] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"673.296072ms","start":"2026-02-04T05:14:10.573761Z","end":"2026-02-04T05:14:11.247058Z","steps":["trace[1639219551] 'process raft request' (duration: 672.960624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:11.247317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:10.573749Z","time spent":"673.421524ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4005,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:11.247657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.233652ms","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-02-04T05:14:11.247745Z","caller":"traceutil/trace.go:171","msg":"trace[1419870725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"638.357375ms","start":"2026-02-04T05:14:10.609362Z","end":"2026-02-04T05:14:11.247719Z","steps":["trace[1419870725] 'agreement among raft nodes before linearized reading' (duration: 638.219022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:11.248187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:10.609343Z","time spent":"638.59351ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:14:11.248598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.042162ms","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-02-04T05:14:11.248647Z","caller":"traceutil/trace.go:171","msg":"trace[1178019698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"642.129694ms","start":"2026-02-04T05:14:10.606504Z","end":"2026-02-04T05:14:11.248634Z","steps":["trace[1178019698] 'agreement among raft nodes before linearized reading' (duration: 642.048683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:11.24885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:10.606484Z","time spent":"642.35107ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T05:14:11.437765Z","caller":"traceutil/trace.go:171","msg":"trace[1352973196] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"179.651871ms","start":"2026-02-04T05:14:11.258087Z","end":"2026-02-04T05:14:11.437739Z","steps":["trace[1352973196] 'process raft request' (duration: 90.033776ms)","trace[1352973196] 'compare' (duration: 89.483632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:12.713259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.997971ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T05:14:12.713395Z","caller":"traceutil/trace.go:171","msg":"trace[1573471217] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:440; }","duration":"307.268947ms","start":"2026-02-04T05:14:12.406116Z","end":"2026-02-04T05:14:12.713385Z","steps":["trace[1573471217] 'read index received' (duration: 303.184941ms)","trace[1573471217] 'applied index is now lower than readState.Index' (duration: 4.083526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:12.713444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.329658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:12.71346Z","caller":"traceutil/trace.go:171","msg":"trace[147892280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"307.37603ms","start":"2026-02-04T05:14:12.406079Z","end":"2026-02-04T05:14:12.713455Z","steps":["trace[147892280] 'agreement among raft nodes before linearized reading' (duration: 307.338539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:12.713479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:12.406065Z","time spent":"307.41007ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:14:12.713642Z","caller":"traceutil/trace.go:171","msg":"trace[1235634112] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"413.893581ms","start":"2026-02-04T05:14:12.299743Z","end":"2026-02-04T05:14:12.713636Z","steps":["trace[1235634112] 'process raft request' (duration: 413.576884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:12.713692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:12.299722Z","time spent":"413.936942ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:16.904453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526559597743093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:14:17.405238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526559597743093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:14:17.73069Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.773832187s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T05:14:17.730894Z","caller":"traceutil/trace.go:171","msg":"trace[829754183] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"1.327396853s","start":"2026-02-04T05:14:16.403484Z","end":"2026-02-04T05:14:17.730881Z","steps":["trace[829754183] 'read index received' (duration: 1.32729696s)","trace[829754183] 'applied index is now lower than readState.Index' (duration: 99.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:17.731009Z","caller":"traceutil/trace.go:171","msg":"trace[1412444271] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"1.774200955s","start":"2026-02-04T05:14:15.956803Z","end":"2026-02-04T05:14:17.731004Z","steps":["trace[1412444271] 'process raft request' (duration: 1.774011611s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:17.731064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:15.956793Z","time spent":"1.774229557s","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:17.731158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327673109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:17.731173Z","caller":"traceutil/trace.go:171","msg":"trace[1022255976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"1.32771411s","start":"2026-02-04T05:14:16.403454Z","end":"2026-02-04T05:14:17.731168Z","steps":["trace[1022255976] 'agreement among raft nodes before linearized reading' (duration: 1.327689949s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:17.731186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:16.40341Z","time spent":"1.327773311s","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:14:17.731267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204888754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:14:17.731279Z","caller":"traceutil/trace.go:171","msg":"trace[1841572083] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:466; }","duration":"1.204933805s","start":"2026-02-04T05:14:16.526341Z","end":"2026-02-04T05:14:17.731275Z","steps":["trace[1841572083] 'agreement among raft nodes before linearized reading' (duration: 1.204909065s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:17.73129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:16.526325Z","time spent":"1.204962866s","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.141\" "} {"level":"warn","ts":"2026-02-04T05:14:17.731436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.117769ms","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-02-04T05:14:17.73145Z","caller":"traceutil/trace.go:171","msg":"trace[1007853697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"276.16477ms","start":"2026-02-04T05:14:17.455282Z","end":"2026-02-04T05:14:17.731447Z","steps":["trace[1007853697] 'agreement among raft nodes before linearized reading' (duration: 276.14193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:17.731506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.933273ms","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-02-04T05:14:17.731517Z","caller":"traceutil/trace.go:171","msg":"trace[1744745844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"405.944914ms","start":"2026-02-04T05:14:17.325569Z","end":"2026-02-04T05:14:17.731514Z","steps":["trace[1744745844] 'agreement among raft nodes before linearized reading' (duration: 405.926023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:17.731536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.325532Z","time spent":"406.000955ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:14:18.621753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.984945ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-04T05:14:18.621857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.733242Z","time spent":"888.595143ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T05:14:19.14881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.633246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:19.149049Z","caller":"traceutil/trace.go:171","msg":"trace[273175038] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"525.57299ms","start":"2026-02-04T05:14:18.623462Z","end":"2026-02-04T05:14:19.149035Z","steps":["trace[273175038] 'process raft request' (duration: 525.543169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:19.149101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:18.623445Z","time spent":"525.629841ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:19.14936Z","caller":"traceutil/trace.go:171","msg":"trace[1695483621] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"1.406611012s","start":"2026-02-04T05:14:17.742734Z","end":"2026-02-04T05:14:19.149345Z","steps":["trace[1695483621] 'process raft request' (duration: 1.406148132s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:19.14941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.742725Z","time spent":"1.406658724s","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:19.149604Z","caller":"traceutil/trace.go:171","msg":"trace[436150777] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.412319341s","start":"2026-02-04T05:14:17.737276Z","end":"2026-02-04T05:14:19.149595Z","steps":["trace[436150777] 'process raft request' (duration: 1.076843666s)","trace[436150777] 'compare' (duration: 334.538764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:19.14964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.737265Z","time spent":"1.412355801s","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:19.149895Z","caller":"traceutil/trace.go:171","msg":"trace[798252729] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:482; }","duration":"1.410457368s","start":"2026-02-04T05:14:17.73943Z","end":"2026-02-04T05:14:19.149887Z","steps":["trace[798252729] 'read index received' (duration: 118.329967ms)","trace[798252729] 'applied index is now lower than readState.Index' (duration: 1.292126341s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:19.149934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410496269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:19.149954Z","caller":"traceutil/trace.go:171","msg":"trace[390691502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"1.41053489s","start":"2026-02-04T05:14:17.739412Z","end":"2026-02-04T05:14:19.149947Z","steps":["trace[390691502] 'agreement among raft nodes before linearized reading' (duration: 1.410499299s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:19.149973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.739406Z","time spent":"1.410562081s","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:14:19.150059Z","caller":"traceutil/trace.go:171","msg":"trace[1199722389] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"1.406285405s","start":"2026-02-04T05:14:17.743765Z","end":"2026-02-04T05:14:19.15005Z","steps":["trace[1199722389] 'process raft request' (duration: 1.405210882s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:19.150094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:17.743759Z","time spent":"1.406315606s","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:27.427058Z","caller":"traceutil/trace.go:171","msg":"trace[255407998] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"237.104958ms","start":"2026-02-04T05:14:27.189931Z","end":"2026-02-04T05:14:27.427036Z","steps":["trace[255407998] 'read index received' (duration: 236.961885ms)","trace[255407998] 'applied index is now lower than readState.Index' (duration: 141.763µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:27.42718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:26.527794Z","time spent":"899.383778ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T05:14:27.427724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.773882ms","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-02-04T05:14:27.427871Z","caller":"traceutil/trace.go:171","msg":"trace[1981666831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"237.963116ms","start":"2026-02-04T05:14:27.189892Z","end":"2026-02-04T05:14:27.427855Z","steps":["trace[1981666831] 'agreement among raft nodes before linearized reading' (duration: 237.633079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:27.428278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.503005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:14:27.428484Z","caller":"traceutil/trace.go:171","msg":"trace[1062177785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"236.735521ms","start":"2026-02-04T05:14:27.191725Z","end":"2026-02-04T05:14:27.428461Z","steps":["trace[1062177785] 'agreement among raft nodes before linearized reading' (duration: 236.367782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:27.429055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.276821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T05:14:27.437983Z","caller":"traceutil/trace.go:171","msg":"trace[1433088109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"246.212523ms","start":"2026-02-04T05:14:27.191755Z","end":"2026-02-04T05:14:27.437968Z","steps":["trace[1433088109] 'agreement among raft nodes before linearized reading' (duration: 237.21706ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:29.674217Z","caller":"traceutil/trace.go:171","msg":"trace[277821379] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"220.246945ms","start":"2026-02-04T05:14:29.453951Z","end":"2026-02-04T05:14:29.674198Z","steps":["trace[277821379] 'process raft request' (duration: 220.087552ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:29.674263Z","caller":"traceutil/trace.go:171","msg":"trace[1283060228] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:546; }","duration":"217.519159ms","start":"2026-02-04T05:14:29.45671Z","end":"2026-02-04T05:14:29.674229Z","steps":["trace[1283060228] 'read index received' (duration: 217.507279ms)","trace[1283060228] 'applied index is now lower than readState.Index' (duration: 9.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:29.674663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.925347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-04T05:14:29.675038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.276405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:14:29.675096Z","caller":"traceutil/trace.go:171","msg":"trace[1970988183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"218.359297ms","start":"2026-02-04T05:14:29.456722Z","end":"2026-02-04T05:14:29.675081Z","steps":["trace[1970988183] 'agreement among raft nodes before linearized reading' (duration: 218.233764ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:29.678946Z","caller":"traceutil/trace.go:171","msg":"trace[782403458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"218.05324ms","start":"2026-02-04T05:14:29.456687Z","end":"2026-02-04T05:14:29.67474Z","steps":["trace[782403458] 'agreement among raft nodes before linearized reading' (duration: 217.760145ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:30.023708Z","caller":"traceutil/trace.go:171","msg":"trace[1667323038] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"110.92829ms","start":"2026-02-04T05:14:29.912763Z","end":"2026-02-04T05:14:30.023692Z","steps":["trace[1667323038] 'process raft request' (duration: 110.90207ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:30.023899Z","caller":"traceutil/trace.go:171","msg":"trace[1534239417] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"112.718605ms","start":"2026-02-04T05:14:29.911174Z","end":"2026-02-04T05:14:30.023893Z","steps":["trace[1534239417] 'process raft request' (duration: 112.46011ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:30.023892Z","caller":"traceutil/trace.go:171","msg":"trace[121619456] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"112.661414ms","start":"2026-02-04T05:14:29.911208Z","end":"2026-02-04T05:14:30.023869Z","steps":["trace[121619456] 'process raft request' (duration: 112.343938ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.374808Z","caller":"traceutil/trace.go:171","msg":"trace[2680475] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"233.51434ms","start":"2026-02-04T05:14:33.141273Z","end":"2026-02-04T05:14:33.374787Z","steps":["trace[2680475] 'process raft request' (duration: 233.378628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:33.95169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.993126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:33.952296Z","caller":"traceutil/trace.go:171","msg":"trace[2091669989] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"197.133136ms","start":"2026-02-04T05:14:33.754702Z","end":"2026-02-04T05:14:33.951835Z","steps":["trace[2091669989] 'process raft request' (duration: 197.051074ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.952374Z","caller":"traceutil/trace.go:171","msg":"trace[1363810337] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"198.777608ms","start":"2026-02-04T05:14:33.7531Z","end":"2026-02-04T05:14:33.951878Z","steps":["trace[1363810337] 'process raft request' (duration: 81.540748ms)","trace[1363810337] 'compare' (duration: 116.859373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:36.628141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.572409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:14:36.62819Z","caller":"traceutil/trace.go:171","msg":"trace[1195675778] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:564; }","duration":"100.67387ms","start":"2026-02-04T05:14:36.527506Z","end":"2026-02-04T05:14:36.62818Z","steps":["trace[1195675778] 'range keys from in-memory index tree' (duration: 100.446347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904450203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2h46b\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-04T05:14:36.628401Z","caller":"traceutil/trace.go:171","msg":"trace[1314906520] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2h46b; range_end:; response_count:1; response_revision:564; }","duration":"1.904489533s","start":"2026-02-04T05:14:34.723908Z","end":"2026-02-04T05:14:36.628397Z","steps":["trace[1314906520] 'range keys from in-memory index tree' (duration: 1.904377821s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:34.723894Z","time spent":"1.904515923s","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-2h46b\" "} {"level":"warn","ts":"2026-02-04T05:14:36.628529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212114651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:36.628546Z","caller":"traceutil/trace.go:171","msg":"trace[1530043900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.212131252s","start":"2026-02-04T05:14:35.41641Z","end":"2026-02-04T05:14:36.628542Z","steps":["trace[1530043900] 'range keys from in-memory index tree' (duration: 1.2120647s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:35.416369Z","time spent":"1.212186143s","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:14:36.628636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.25658ms","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-02-04T05:14:36.62865Z","caller":"traceutil/trace.go:171","msg":"trace[1989267958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"875.295731ms","start":"2026-02-04T05:14:35.753348Z","end":"2026-02-04T05:14:36.628643Z","steps":["trace[1989267958] 'range keys from in-memory index tree' (duration: 875.174229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:35.753333Z","time spent":"875.325291ms","remote":"127.0.0.1:43296","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-02-04T05:14:36.62875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.868169ms","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-02-04T05:14:36.628761Z","caller":"traceutil/trace.go:171","msg":"trace[1158312489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"660.90604ms","start":"2026-02-04T05:14:35.967852Z","end":"2026-02-04T05:14:36.628758Z","steps":["trace[1158312489] 'range keys from in-memory index tree' (duration: 660.791827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:35.967834Z","time spent":"660.93589ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:14:36.628892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.651875ms","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-02-04T05:14:36.628909Z","caller":"traceutil/trace.go:171","msg":"trace[2145800551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"660.678795ms","start":"2026-02-04T05:14:35.968226Z","end":"2026-02-04T05:14:36.628905Z","steps":["trace[2145800551] 'range keys from in-memory index tree' (duration: 660.615694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.628921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:35.968222Z","time spent":"660.696586ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:14:36.629035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.933067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:36.629053Z","caller":"traceutil/trace.go:171","msg":"trace[1803160487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"622.967667ms","start":"2026-02-04T05:14:36.006082Z","end":"2026-02-04T05:14:36.629049Z","steps":["trace[1803160487] 'range keys from in-memory index tree' (duration: 622.837645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.629069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.006072Z","time spent":"622.992128ms","remote":"127.0.0.1:43296","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-02-04T05:14:37.532854Z","caller":"traceutil/trace.go:171","msg":"trace[1511225049] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"891.389522ms","start":"2026-02-04T05:14:36.641448Z","end":"2026-02-04T05:14:37.532837Z","steps":["trace[1511225049] 'read index received' (duration: 48.542006ms)","trace[1511225049] 'applied index is now lower than readState.Index' (duration: 842.846795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:37.532979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.518715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:37.533008Z","caller":"traceutil/trace.go:171","msg":"trace[1262399165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"891.588205ms","start":"2026-02-04T05:14:36.641411Z","end":"2026-02-04T05:14:37.532999Z","steps":["trace[1262399165] 'agreement among raft nodes before linearized reading' (duration: 891.492504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.533039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.641386Z","time spent":"891.647826ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:14:37.533456Z","caller":"traceutil/trace.go:171","msg":"trace[233556317] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"895.106112ms","start":"2026-02-04T05:14:36.638341Z","end":"2026-02-04T05:14:37.533447Z","steps":["trace[233556317] 'process raft request' (duration: 894.190794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.533553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.63833Z","time spent":"895.152842ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:37.533776Z","caller":"traceutil/trace.go:171","msg":"trace[180696452] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"892.485462ms","start":"2026-02-04T05:14:36.641282Z","end":"2026-02-04T05:14:37.533768Z","steps":["trace[180696452] 'process raft request' (duration: 891.424171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.533824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.641275Z","time spent":"892.518803ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:37.534061Z","caller":"traceutil/trace.go:171","msg":"trace[680128569] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"887.5826ms","start":"2026-02-04T05:14:36.646471Z","end":"2026-02-04T05:14:37.534053Z","steps":["trace[680128569] 'process raft request' (duration: 886.274875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.534157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.646465Z","time spent":"887.669141ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:37.534338Z","caller":"traceutil/trace.go:171","msg":"trace[670318603] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"885.432228ms","start":"2026-02-04T05:14:36.648898Z","end":"2026-02-04T05:14:37.53433Z","steps":["trace[670318603] 'process raft request' (duration: 883.89699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.534382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.648892Z","time spent":"885.468699ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:37.534539Z","caller":"traceutil/trace.go:171","msg":"trace[1668024945] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"885.579851ms","start":"2026-02-04T05:14:36.648952Z","end":"2026-02-04T05:14:37.534532Z","steps":["trace[1668024945] 'process raft request' (duration: 883.863559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.534593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.648948Z","time spent":"885.615811ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:39.718787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.077116974s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:39.718845Z","caller":"traceutil/trace.go:171","msg":"trace[182140962] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"1.315870187s","start":"2026-02-04T05:14:38.402965Z","end":"2026-02-04T05:14:39.718835Z","steps":["trace[182140962] 'read index received' (duration: 29.2µs)","trace[182140962] 'applied index is now lower than readState.Index' (duration: 1.315840177s)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:39.718904Z","caller":"traceutil/trace.go:171","msg":"trace[376692239] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"3.028453868s","start":"2026-02-04T05:14:36.690445Z","end":"2026-02-04T05:14:39.718898Z","steps":["trace[376692239] 'process raft request' (duration: 951.171501ms)","trace[376692239] 'compare' (duration: 2.076827469s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:39.718934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.690432Z","time spent":"3.028484898s","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:39.71905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316084941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:39.719065Z","caller":"traceutil/trace.go:171","msg":"trace[1365172972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"1.316123622s","start":"2026-02-04T05:14:38.402935Z","end":"2026-02-04T05:14:39.719059Z","steps":["trace[1365172972] 'agreement among raft nodes before linearized reading' (duration: 1.316083851s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.719079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:38.40292Z","time spent":"1.316156862s","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:14:39.719171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.816298ms","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-02-04T05:14:39.719183Z","caller":"traceutil/trace.go:171","msg":"trace[252866416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"174.861768ms","start":"2026-02-04T05:14:39.544318Z","end":"2026-02-04T05:14:39.71918Z","steps":["trace[252866416] 'agreement among raft nodes before linearized reading' (duration: 174.811447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.719282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.563305ms","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-02-04T05:14:39.719297Z","caller":"traceutil/trace.go:171","msg":"trace[585863082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"173.599706ms","start":"2026-02-04T05:14:39.545694Z","end":"2026-02-04T05:14:39.719293Z","steps":["trace[585863082] 'agreement among raft nodes before linearized reading' (duration: 173.560996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.71936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.654807ms","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-02-04T05:14:39.719371Z","caller":"traceutil/trace.go:171","msg":"trace[1721286189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"173.674728ms","start":"2026-02-04T05:14:39.545693Z","end":"2026-02-04T05:14:39.719368Z","steps":["trace[1721286189] 'agreement among raft nodes before linearized reading' (duration: 173.644687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.719437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.752229ms","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-02-04T05:14:39.719448Z","caller":"traceutil/trace.go:171","msg":"trace[1949656125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"173.776999ms","start":"2026-02-04T05:14:39.545668Z","end":"2026-02-04T05:14:39.719445Z","steps":["trace[1949656125] 'agreement among raft nodes before linearized reading' (duration: 173.745078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.069773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.384337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:40.069834Z","caller":"traceutil/trace.go:171","msg":"trace[953759793] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"344.226728ms","start":"2026-02-04T05:14:39.725597Z","end":"2026-02-04T05:14:40.069824Z","steps":["trace[953759793] 'process raft request' (duration: 198.744449ms)","trace[953759793] 'compare' (duration: 144.191785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:40.069866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.725588Z","time spent":"344.263178ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:40.071844Z","caller":"traceutil/trace.go:171","msg":"trace[1600155492] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"342.399103ms","start":"2026-02-04T05:14:39.729436Z","end":"2026-02-04T05:14:40.071836Z","steps":["trace[1600155492] 'read index received' (duration: 194.914837ms)","trace[1600155492] 'applied index is now lower than readState.Index' (duration: 147.483656ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:40.072228Z","caller":"traceutil/trace.go:171","msg":"trace[1134121280] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"345.788105ms","start":"2026-02-04T05:14:39.726366Z","end":"2026-02-04T05:14:40.072154Z","steps":["trace[1134121280] 'process raft request' (duration: 345.328327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.072313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.726361Z","time spent":"345.925108ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:40.072557Z","caller":"traceutil/trace.go:171","msg":"trace[1907916321] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"343.051905ms","start":"2026-02-04T05:14:39.729474Z","end":"2026-02-04T05:14:40.072525Z","steps":["trace[1907916321] 'process raft request' (duration: 342.262081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.072679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.729468Z","time spent":"343.141407ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:40.072751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.627049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:40.073032Z","caller":"traceutil/trace.go:171","msg":"trace[733143784] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"231.676814ms","start":"2026-02-04T05:14:39.841348Z","end":"2026-02-04T05:14:40.073025Z","steps":["trace[733143784] 'process raft request' (duration: 230.467802ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:40.073095Z","caller":"traceutil/trace.go:171","msg":"trace[2046048215] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"343.575424ms","start":"2026-02-04T05:14:39.729515Z","end":"2026-02-04T05:14:40.073091Z","steps":["trace[2046048215] 'process raft request' (duration: 342.276401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.07312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.729511Z","time spent":"343.594005ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:40.073259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.818488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-04T05:14:40.073276Z","caller":"traceutil/trace.go:171","msg":"trace[1992565927] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:575; }","duration":"343.837199ms","start":"2026-02-04T05:14:39.729434Z","end":"2026-02-04T05:14:40.073271Z","steps":["trace[1992565927] 'agreement among raft nodes before linearized reading' (duration: 343.799828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.07329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.729426Z","time spent":"343.86133ms","remote":"127.0.0.1:43302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-04T05:14:40.0728Z","caller":"traceutil/trace.go:171","msg":"trace[1790424275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"341.69078ms","start":"2026-02-04T05:14:39.731101Z","end":"2026-02-04T05:14:40.072792Z","steps":["trace[1790424275] 'agreement among raft nodes before linearized reading' (duration: 341.626409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:40.076086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:39.731096Z","time spent":"344.9793ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:14:40.853808Z","caller":"traceutil/trace.go:171","msg":"trace[1316620245] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"111.929944ms","start":"2026-02-04T05:14:40.741861Z","end":"2026-02-04T05:14:40.853791Z","steps":["trace[1316620245] 'process raft request' (duration: 111.818262ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:40.862537Z","caller":"traceutil/trace.go:171","msg":"trace[296161951] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"111.459415ms","start":"2026-02-04T05:14:40.751069Z","end":"2026-02-04T05:14:40.862528Z","steps":["trace[296161951] 'process raft request' (duration: 111.415534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.369178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.774228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-04T05:14:42.369232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.387767ms","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-02-04T05:14:42.369324Z","caller":"traceutil/trace.go:171","msg":"trace[207810470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"295.52697ms","start":"2026-02-04T05:14:42.073781Z","end":"2026-02-04T05:14:42.369308Z","steps":["trace[207810470] 'range keys from in-memory index tree' (duration: 295.237224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.369343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.895929ms","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-02-04T05:14:42.369374Z","caller":"traceutil/trace.go:171","msg":"trace[1184263645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"279.93253ms","start":"2026-02-04T05:14:42.089431Z","end":"2026-02-04T05:14:42.369363Z","steps":["trace[1184263645] 'range keys from in-memory index tree' (duration: 279.841078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.369471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.64415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:42.369497Z","caller":"traceutil/trace.go:171","msg":"trace[198897909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"291.67571ms","start":"2026-02-04T05:14:42.077811Z","end":"2026-02-04T05:14:42.369487Z","steps":["trace[198897909] 'range keys from in-memory index tree' (duration: 291.546119ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.369274Z","caller":"traceutil/trace.go:171","msg":"trace[42810027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"284.887349ms","start":"2026-02-04T05:14:42.08437Z","end":"2026-02-04T05:14:42.369257Z","steps":["trace[42810027] 'range keys from in-memory index tree' (duration: 284.667935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.36954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.855776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T05:14:42.369566Z","caller":"traceutil/trace.go:171","msg":"trace[819013658] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:586; }","duration":"231.894927ms","start":"2026-02-04T05:14:42.137664Z","end":"2026-02-04T05:14:42.369559Z","steps":["trace[819013658] 'range keys from in-memory index tree' (duration: 231.805005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.751203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.818469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:42.751817Z","caller":"traceutil/trace.go:171","msg":"trace[1604742090] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"271.993808ms","start":"2026-02-04T05:14:42.479797Z","end":"2026-02-04T05:14:42.751791Z","steps":["trace[1604742090] 'process raft request' (duration: 165.489765ms)","trace[1604742090] 'compare' (duration: 100.664078ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:42.752446Z","caller":"traceutil/trace.go:171","msg":"trace[163340956] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"272.534548ms","start":"2026-02-04T05:14:42.479901Z","end":"2026-02-04T05:14:42.752435Z","steps":["trace[163340956] 'read index received' (duration: 28.681726ms)","trace[163340956] 'applied index is now lower than readState.Index' (duration: 243.851882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:42.762292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.368744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.762545Z","caller":"traceutil/trace.go:171","msg":"trace[223486101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:593; }","duration":"282.648989ms","start":"2026-02-04T05:14:42.479879Z","end":"2026-02-04T05:14:42.762528Z","steps":["trace[223486101] 'agreement among raft nodes before linearized reading' (duration: 272.799762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.76484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.744647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.76499Z","caller":"traceutil/trace.go:171","msg":"trace[1927420446] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:594; }","duration":"285.016082ms","start":"2026-02-04T05:14:42.479962Z","end":"2026-02-04T05:14:42.764978Z","steps":["trace[1927420446] 'agreement among raft nodes before linearized reading' (duration: 284.713586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.765163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.329285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.765244Z","caller":"traceutil/trace.go:171","msg":"trace[918472520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:594; }","duration":"143.432696ms","start":"2026-02-04T05:14:42.621803Z","end":"2026-02-04T05:14:42.765236Z","steps":["trace[918472520] 'agreement among raft nodes before linearized reading' (duration: 143.337995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.765361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.264645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.765617Z","caller":"traceutil/trace.go:171","msg":"trace[582263932] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:594; }","duration":"285.52771ms","start":"2026-02-04T05:14:42.480079Z","end":"2026-02-04T05:14:42.765607Z","steps":["trace[582263932] 'agreement among raft nodes before linearized reading' (duration: 285.265235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.765842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.843816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.765974Z","caller":"traceutil/trace.go:171","msg":"trace[1276627382] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:594; }","duration":"285.96185ms","start":"2026-02-04T05:14:42.47998Z","end":"2026-02-04T05:14:42.765942Z","steps":["trace[1276627382] 'agreement among raft nodes before linearized reading' (duration: 285.805037ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.988389Z","caller":"traceutil/trace.go:171","msg":"trace[1633523384] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"200.89722ms","start":"2026-02-04T05:14:42.787463Z","end":"2026-02-04T05:14:42.988361Z","steps":["trace[1633523384] 'process raft request' (duration: 138.030281ms)","trace[1633523384] 'compare' (duration: 62.661045ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:42.989149Z","caller":"traceutil/trace.go:171","msg":"trace[1857975275] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"163.084121ms","start":"2026-02-04T05:14:42.826057Z","end":"2026-02-04T05:14:42.989141Z","steps":["trace[1857975275] 'process raft request' (duration: 162.271196ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.995103Z","caller":"traceutil/trace.go:171","msg":"trace[255080624] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"147.52874ms","start":"2026-02-04T05:14:42.847566Z","end":"2026-02-04T05:14:42.995094Z","steps":["trace[255080624] 'process raft request' (duration: 147.263145ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.995483Z","caller":"traceutil/trace.go:171","msg":"trace[280469890] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"116.369101ms","start":"2026-02-04T05:14:42.879106Z","end":"2026-02-04T05:14:42.995475Z","steps":["trace[280469890] 'process raft request' (duration: 115.781209ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.17043Z","caller":"traceutil/trace.go:171","msg":"trace[2082807529] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"159.818719ms","start":"2026-02-04T05:14:43.010587Z","end":"2026-02-04T05:14:43.170406Z","steps":["trace[2082807529] 'process raft request' (duration: 91.187595ms)","trace[2082807529] 'compare' (duration: 68.558343ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:43.212252Z","caller":"traceutil/trace.go:171","msg":"trace[257350084] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"201.33855ms","start":"2026-02-04T05:14:43.0109Z","end":"2026-02-04T05:14:43.212238Z","steps":["trace[257350084] 'process raft request' (duration: 200.942593ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.21245Z","caller":"traceutil/trace.go:171","msg":"trace[214467048] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"189.489138ms","start":"2026-02-04T05:14:43.022954Z","end":"2026-02-04T05:14:43.212444Z","steps":["trace[214467048] 'process raft request' (duration: 189.233514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.446913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.185147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:43.447695Z","caller":"traceutil/trace.go:171","msg":"trace[1255521440] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"220.668194ms","start":"2026-02-04T05:14:43.227008Z","end":"2026-02-04T05:14:43.447676Z","steps":["trace[1255521440] 'process raft request' (duration: 92.645932ms)","trace[1255521440] 'compare' (duration: 127.074576ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:43.448251Z","caller":"traceutil/trace.go:171","msg":"trace[1924245277] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"221.105692ms","start":"2026-02-04T05:14:43.227121Z","end":"2026-02-04T05:14:43.448226Z","steps":["trace[1924245277] 'process raft request' (duration: 220.067724ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.44857Z","caller":"traceutil/trace.go:171","msg":"trace[1934210769] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"221.388136ms","start":"2026-02-04T05:14:43.227169Z","end":"2026-02-04T05:14:43.448557Z","steps":["trace[1934210769] 'process raft request' (duration: 220.129495ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.448964Z","caller":"traceutil/trace.go:171","msg":"trace[692724745] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"199.105179ms","start":"2026-02-04T05:14:43.249847Z","end":"2026-02-04T05:14:43.448952Z","steps":["trace[692724745] 'process raft request' (duration: 197.502591ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:45.624781Z","caller":"traceutil/trace.go:171","msg":"trace[1147610566] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"114.412767ms","start":"2026-02-04T05:14:45.510352Z","end":"2026-02-04T05:14:45.624765Z","steps":["trace[1147610566] 'process raft request' (duration: 113.962709ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:46.788029Z","caller":"traceutil/trace.go:171","msg":"trace[1076860262] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"116.814302ms","start":"2026-02-04T05:14:46.671053Z","end":"2026-02-04T05:14:46.787868Z","steps":["trace[1076860262] 'process raft request' (duration: 116.69541ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:46.982433Z","caller":"traceutil/trace.go:171","msg":"trace[558281146] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"136.538637ms","start":"2026-02-04T05:14:46.84588Z","end":"2026-02-04T05:14:46.982419Z","steps":["trace[558281146] 'process raft request' (duration: 136.493537ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:46.982784Z","caller":"traceutil/trace.go:171","msg":"trace[105903595] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"136.932254ms","start":"2026-02-04T05:14:46.845846Z","end":"2026-02-04T05:14:46.982778Z","steps":["trace[105903595] 'process raft request' (duration: 135.614281ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.447188Z","caller":"traceutil/trace.go:171","msg":"trace[322754040] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"152.178859ms","start":"2026-02-04T05:14:48.294962Z","end":"2026-02-04T05:14:48.447141Z","steps":["trace[322754040] 'process raft request' (duration: 148.631228ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.690584Z","caller":"traceutil/trace.go:171","msg":"trace[1486379356] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:700; }","duration":"286.448271ms","start":"2026-02-04T05:14:48.40412Z","end":"2026-02-04T05:14:48.690569Z","steps":["trace[1486379356] 'read index received' (duration: 39.392285ms)","trace[1486379356] 'applied index is now lower than readState.Index' (duration: 247.055356ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:48.690649Z","caller":"traceutil/trace.go:171","msg":"trace[1637621638] transaction","detail":"{read_only:false; number_of_response:0; response_revision:676; }","duration":"395.359769ms","start":"2026-02-04T05:14:48.295282Z","end":"2026-02-04T05:14:48.690642Z","steps":["trace[1637621638] 'process raft request' (duration: 395.108524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.69074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:48.295267Z","time spent":"395.39793ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:48.690907Z","caller":"traceutil/trace.go:171","msg":"trace[24165531] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"376.049318ms","start":"2026-02-04T05:14:48.31485Z","end":"2026-02-04T05:14:48.690899Z","steps":["trace[24165531] 'process raft request' (duration: 375.659371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.69094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:48.314827Z","time spent":"376.098409ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:14:48.691055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.93037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:48.691078Z","caller":"traceutil/trace.go:171","msg":"trace[1701253687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"287.000611ms","start":"2026-02-04T05:14:48.404072Z","end":"2026-02-04T05:14:48.691073Z","steps":["trace[1701253687] 'agreement among raft nodes before linearized reading' (duration: 286.94269ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.694362Z","caller":"traceutil/trace.go:171","msg":"trace[1715706298] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"147.475409ms","start":"2026-02-04T05:14:48.54688Z","end":"2026-02-04T05:14:48.694355Z","steps":["trace[1715706298] 'process raft request' (duration: 147.357087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.694485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.210395ms","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-02-04T05:14:48.694505Z","caller":"traceutil/trace.go:171","msg":"trace[1674588334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"266.243495ms","start":"2026-02-04T05:14:48.428256Z","end":"2026-02-04T05:14:48.694499Z","steps":["trace[1674588334] 'agreement among raft nodes before linearized reading' (duration: 266.176504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.694625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.869907ms","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-02-04T05:14:48.694652Z","caller":"traceutil/trace.go:171","msg":"trace[1932978887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"269.918927ms","start":"2026-02-04T05:14:48.424726Z","end":"2026-02-04T05:14:48.694645Z","steps":["trace[1932978887] 'agreement among raft nodes before linearized reading' (duration: 269.857566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.694782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.2997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:48.694802Z","caller":"traceutil/trace.go:171","msg":"trace[1537782880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"275.34686ms","start":"2026-02-04T05:14:48.419451Z","end":"2026-02-04T05:14:48.694798Z","steps":["trace[1537782880] 'agreement among raft nodes before linearized reading' (duration: 275.297169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.694887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.620391ms","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-02-04T05:14:48.694904Z","caller":"traceutil/trace.go:171","msg":"trace[1411545153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"280.676582ms","start":"2026-02-04T05:14:48.414224Z","end":"2026-02-04T05:14:48.6949Z","steps":["trace[1411545153] 'agreement among raft nodes before linearized reading' (duration: 280.627711ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.107258Z","caller":"traceutil/trace.go:171","msg":"trace[1219295561] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"109.813024ms","start":"2026-02-04T05:14:52.997429Z","end":"2026-02-04T05:14:53.107242Z","steps":["trace[1219295561] 'process raft request' (duration: 109.674992ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.10752Z","caller":"traceutil/trace.go:171","msg":"trace[568482415] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"104.935272ms","start":"2026-02-04T05:14:53.002579Z","end":"2026-02-04T05:14:53.107515Z","steps":["trace[568482415] 'read index received' (duration: 24.83472ms)","trace[568482415] 'applied index is now lower than readState.Index' (duration: 80.099722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:53.107645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.054544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-gtpjg\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-04T05:14:53.107668Z","caller":"traceutil/trace.go:171","msg":"trace[2080998578] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-gtpjg; range_end:; response_count:1; response_revision:728; }","duration":"105.122886ms","start":"2026-02-04T05:14:53.00254Z","end":"2026-02-04T05:14:53.107662Z","steps":["trace[2080998578] 'agreement among raft nodes before linearized reading' (duration: 105.013154ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.107721Z","caller":"traceutil/trace.go:171","msg":"trace[278385361] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"110.109919ms","start":"2026-02-04T05:14:52.997604Z","end":"2026-02-04T05:14:53.107714Z","steps":["trace[278385361] 'process raft request' (duration: 109.59409ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.107957Z","caller":"traceutil/trace.go:171","msg":"trace[1427824607] transaction","detail":"{read_only:false; number_of_response:1; response_revision:728; }","duration":"105.319009ms","start":"2026-02-04T05:14:53.00263Z","end":"2026-02-04T05:14:53.107949Z","steps":["trace[1427824607] 'process raft request' (duration: 104.863561ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.267419Z","caller":"traceutil/trace.go:171","msg":"trace[103454034] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"150.369063ms","start":"2026-02-04T05:14:53.117035Z","end":"2026-02-04T05:14:53.267404Z","steps":["trace[103454034] 'read index received' (duration: 146.083323ms)","trace[103454034] 'applied index is now lower than readState.Index' (duration: 4.28489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:53.267547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.497665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-04T05:14:53.267564Z","caller":"traceutil/trace.go:171","msg":"trace[1908571220] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:730; }","duration":"150.549936ms","start":"2026-02-04T05:14:53.117009Z","end":"2026-02-04T05:14:53.267559Z","steps":["trace[1908571220] 'agreement among raft nodes before linearized reading' (duration: 150.452714ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.267636Z","caller":"traceutil/trace.go:171","msg":"trace[44008854] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"235.407358ms","start":"2026-02-04T05:14:53.032207Z","end":"2026-02-04T05:14:53.267615Z","steps":["trace[44008854] 'process raft request' (duration: 230.900954ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.267734Z","caller":"traceutil/trace.go:171","msg":"trace[333682762] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"234.498652ms","start":"2026-02-04T05:14:53.033229Z","end":"2026-02-04T05:14:53.267728Z","steps":["trace[333682762] 'process raft request' (duration: 234.136997ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.341851Z","caller":"traceutil/trace.go:171","msg":"trace[2075236423] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"221.65476ms","start":"2026-02-04T05:14:53.120161Z","end":"2026-02-04T05:14:53.341816Z","steps":["trace[2075236423] 'process raft request' (duration: 221.322735ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.543629Z","caller":"traceutil/trace.go:171","msg":"trace[1910982266] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"268.147199ms","start":"2026-02-04T05:14:53.275466Z","end":"2026-02-04T05:14:53.543614Z","steps":["trace[1910982266] 'process raft request' (duration: 268.070238ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.543935Z","caller":"traceutil/trace.go:171","msg":"trace[1116202957] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"270.265743ms","start":"2026-02-04T05:14:53.273663Z","end":"2026-02-04T05:14:53.543929Z","steps":["trace[1116202957] 'process raft request' (duration: 269.783576ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.665457Z","caller":"traceutil/trace.go:171","msg":"trace[847356782] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:760; }","duration":"258.743192ms","start":"2026-02-04T05:14:53.406693Z","end":"2026-02-04T05:14:53.665436Z","steps":["trace[847356782] 'read index received' (duration: 136.712498ms)","trace[847356782] 'applied index is now lower than readState.Index' (duration: 122.029844ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:53.665612Z","caller":"traceutil/trace.go:171","msg":"trace[235460573] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"316.786531ms","start":"2026-02-04T05:14:53.348812Z","end":"2026-02-04T05:14:53.665599Z","steps":["trace[235460573] 'process raft request' (duration: 316.535757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:53.665751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:53.348808Z","time spent":"316.876812ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:53.666087Z","caller":"traceutil/trace.go:171","msg":"trace[541822262] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"317.377402ms","start":"2026-02-04T05:14:53.348697Z","end":"2026-02-04T05:14:53.666074Z","steps":["trace[541822262] 'process raft request' (duration: 315.290607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:53.666223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:53.348681Z","time spent":"317.503204ms","remote":"127.0.0.1:43302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:53.667976Z","caller":"traceutil/trace.go:171","msg":"trace[2070514197] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"318.038051ms","start":"2026-02-04T05:14:53.349929Z","end":"2026-02-04T05:14:53.667967Z","steps":["trace[2070514197] 'process raft request' (duration: 315.468749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:53.668025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:53.349921Z","time spent":"318.081202ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:53.668252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.555549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:53.668274Z","caller":"traceutil/trace.go:171","msg":"trace[768983117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"261.61755ms","start":"2026-02-04T05:14:53.40665Z","end":"2026-02-04T05:14:53.668268Z","steps":["trace[768983117] 'agreement among raft nodes before linearized reading' (duration: 261.58226ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.765626Z","caller":"traceutil/trace.go:171","msg":"trace[624643156] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"212.060393ms","start":"2026-02-04T05:14:53.553525Z","end":"2026-02-04T05:14:53.765586Z","steps":["trace[624643156] 'process raft request' (duration: 211.645946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:53.765973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.34533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rbmsb\" ","response":"range_response_count:1 size:4809"} {"level":"info","ts":"2026-02-04T05:14:53.766017Z","caller":"traceutil/trace.go:171","msg":"trace[965576779] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-rbmsb; range_end:; response_count:1; response_revision:737; }","duration":"214.432081ms","start":"2026-02-04T05:14:53.551575Z","end":"2026-02-04T05:14:53.766007Z","steps":["trace[965576779] 'agreement among raft nodes before linearized reading' (duration: 214.289618ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:57.122922Z","caller":"traceutil/trace.go:171","msg":"trace[243984043] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"271.678417ms","start":"2026-02-04T05:14:56.851222Z","end":"2026-02-04T05:14:57.1229Z","steps":["trace[243984043] 'process raft request' (duration: 271.547954ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:59.237614Z","caller":"traceutil/trace.go:171","msg":"trace[1027664826] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"102.706475ms","start":"2026-02-04T05:14:59.13487Z","end":"2026-02-04T05:14:59.237577Z","steps":["trace[1027664826] 'process raft request' (duration: 102.548272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.906757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526559597743701,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T05:15:00.992922Z","caller":"traceutil/trace.go:171","msg":"trace[1416570374] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"656.733139ms","start":"2026-02-04T05:15:00.336172Z","end":"2026-02-04T05:15:00.992905Z","steps":["trace[1416570374] 'process raft request' (duration: 656.620617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.993045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.336158Z","time spent":"656.81142ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:01.464901Z","caller":"traceutil/trace.go:171","msg":"trace[290360131] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"1.05893928s","start":"2026-02-04T05:15:00.405942Z","end":"2026-02-04T05:15:01.464882Z","steps":["trace[290360131] 'read index received' (duration: 586.900122ms)","trace[290360131] 'applied index is now lower than readState.Index' (duration: 472.038468ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:01.465149Z","caller":"traceutil/trace.go:171","msg":"trace[1185770130] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"803.540887ms","start":"2026-02-04T05:15:00.6616Z","end":"2026-02-04T05:15:01.465141Z","steps":["trace[1185770130] 'process raft request' (duration: 803.16034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.661582Z","time spent":"803.587217ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:01.465355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.037922ms","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-02-04T05:15:01.465411Z","caller":"traceutil/trace.go:171","msg":"trace[1365237713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"625.122772ms","start":"2026-02-04T05:15:00.840272Z","end":"2026-02-04T05:15:01.465395Z","steps":["trace[1365237713] 'agreement among raft nodes before linearized reading' (duration: 624.94438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.402943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-04T05:15:01.46545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.840256Z","time spent":"625.183474ms","remote":"127.0.0.1:43296","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":"info","ts":"2026-02-04T05:15:01.465464Z","caller":"traceutil/trace.go:171","msg":"trace[1877906018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"605.434273ms","start":"2026-02-04T05:15:00.860023Z","end":"2026-02-04T05:15:01.465457Z","steps":["trace[1877906018] 'agreement among raft nodes before linearized reading' (duration: 605.374813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.860018Z","time spent":"605.490544ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:15:01.465558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05961745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:01.465569Z","caller":"traceutil/trace.go:171","msg":"trace[821432147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"1.05965336s","start":"2026-02-04T05:15:00.405912Z","end":"2026-02-04T05:15:01.465565Z","steps":["trace[821432147] 'agreement among raft nodes before linearized reading' (duration: 1.05963499s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.405898Z","time spent":"1.059679521s","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:01.465672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.123543ms","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-02-04T05:15:01.465685Z","caller":"traceutil/trace.go:171","msg":"trace[826390875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"223.137233ms","start":"2026-02-04T05:15:01.242544Z","end":"2026-02-04T05:15:01.465681Z","steps":["trace[826390875] 'agreement among raft nodes before linearized reading' (duration: 223.098342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.679537ms","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-02-04T05:15:01.465765Z","caller":"traceutil/trace.go:171","msg":"trace[136681375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"605.750738ms","start":"2026-02-04T05:15:00.860005Z","end":"2026-02-04T05:15:01.465755Z","steps":["trace[136681375] 'agreement among raft nodes before linearized reading' (duration: 605.631636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.465794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.860001Z","time spent":"605.785438ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:15:01.465904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.911241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:01.465918Z","caller":"traceutil/trace.go:171","msg":"trace[1332170321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"605.947591ms","start":"2026-02-04T05:15:00.859967Z","end":"2026-02-04T05:15:01.465914Z","steps":["trace[1332170321] 'agreement among raft nodes before linearized reading' (duration: 605.908081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.46593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.859953Z","time spent":"605.974402ms","remote":"127.0.0.1:43296","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-02-04T05:15:01.746281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.890605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:01.746351Z","caller":"traceutil/trace.go:171","msg":"trace[1747021777] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"268.271557ms","start":"2026-02-04T05:15:01.478067Z","end":"2026-02-04T05:15:01.746338Z","steps":["trace[1747021777] 'process raft request' (duration: 101.264231ms)","trace[1747021777] 'compare' (duration: 166.682201ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:02.05538Z","caller":"traceutil/trace.go:171","msg":"trace[1345404495] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"577.05897ms","start":"2026-02-04T05:15:01.478313Z","end":"2026-02-04T05:15:02.055372Z","steps":["trace[1345404495] 'process raft request' (duration: 495.198748ms)","trace[1345404495] 'compare' (duration: 81.486066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:02.055465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.478304Z","time spent":"577.109561ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.055711Z","caller":"traceutil/trace.go:171","msg":"trace[452372274] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"574.809834ms","start":"2026-02-04T05:15:01.480897Z","end":"2026-02-04T05:15:02.055706Z","steps":["trace[452372274] 'process raft request' (duration: 574.422018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.055747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.480886Z","time spent":"574.844836ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.055348Z","caller":"traceutil/trace.go:171","msg":"trace[2142343794] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"576.979309ms","start":"2026-02-04T05:15:01.478355Z","end":"2026-02-04T05:15:02.055334Z","steps":["trace[2142343794] 'process raft request' (duration: 576.925908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.05609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.47835Z","time spent":"577.721231ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.05629Z","caller":"traceutil/trace.go:171","msg":"trace[1311612095] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"574.225236ms","start":"2026-02-04T05:15:01.48206Z","end":"2026-02-04T05:15:02.056285Z","steps":["trace[1311612095] 'process raft request' (duration: 573.625536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:02.056321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.482051Z","time spent":"574.255976ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:02.056485Z","caller":"traceutil/trace.go:171","msg":"trace[1317431189] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:802; }","duration":"575.494996ms","start":"2026-02-04T05:15:01.480949Z","end":"2026-02-04T05:15:02.056444Z","steps":["trace[1317431189] 'read index received' (duration: 98.390206ms)","trace[1317431189] 'applied index is now lower than readState.Index' (duration: 477.09979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:02.092829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.86491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:02.092878Z","caller":"traceutil/trace.go:171","msg":"trace[1533944210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"611.936601ms","start":"2026-02-04T05:15:01.480929Z","end":"2026-02-04T05:15:02.092866Z","steps":["trace[1533944210] 'agreement among raft nodes before linearized reading' (duration: 575.589748ms)","trace[1533944210] 'range keys from in-memory index tree' (duration: 36.279632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:02.092899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.480924Z","time spent":"611.971191ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:15:02.374236Z","caller":"traceutil/trace.go:171","msg":"trace[804753199] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"178.630359ms","start":"2026-02-04T05:15:02.195588Z","end":"2026-02-04T05:15:02.374218Z","steps":["trace[804753199] 'process raft request' (duration: 154.1625ms)","trace[804753199] 'compare' (duration: 24.253366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:04.164837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.023219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:04.164876Z","caller":"traceutil/trace.go:171","msg":"trace[786917281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"102.07292ms","start":"2026-02-04T05:15:04.062793Z","end":"2026-02-04T05:15:04.164866Z","steps":["trace[786917281] 'agreement among raft nodes before linearized reading' (duration: 101.973538ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:04.330437Z","caller":"traceutil/trace.go:171","msg":"trace[573696150] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"190.081416ms","start":"2026-02-04T05:15:04.140157Z","end":"2026-02-04T05:15:04.330238Z","steps":["trace[573696150] 'process raft request' (duration: 189.161532ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:04.331923Z","caller":"traceutil/trace.go:171","msg":"trace[444008967] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"159.831204ms","start":"2026-02-04T05:15:04.172086Z","end":"2026-02-04T05:15:04.331917Z","steps":["trace[444008967] 'process raft request' (duration: 159.802733ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:04.332006Z","caller":"traceutil/trace.go:171","msg":"trace[69435360] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"164.589008ms","start":"2026-02-04T05:15:04.167383Z","end":"2026-02-04T05:15:04.331972Z","steps":["trace[69435360] 'process raft request' (duration: 163.526512ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:12.529709Z","caller":"traceutil/trace.go:171","msg":"trace[251417314] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"160.40778ms","start":"2026-02-04T05:15:12.369287Z","end":"2026-02-04T05:15:12.529695Z","steps":["trace[251417314] 'process raft request' (duration: 160.342319ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:12.642137Z","caller":"traceutil/trace.go:171","msg":"trace[1319151668] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"272.633671ms","start":"2026-02-04T05:15:12.369482Z","end":"2026-02-04T05:15:12.642115Z","steps":["trace[1319151668] 'process raft request' (duration: 210.956605ms)","trace[1319151668] 'compare' (duration: 61.471613ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:20.40657Z","caller":"traceutil/trace.go:171","msg":"trace[660780884] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"117.701511ms","start":"2026-02-04T05:15:20.288843Z","end":"2026-02-04T05:15:20.406545Z","steps":["trace[660780884] 'process raft request' (duration: 113.787527ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:22.049999Z","caller":"traceutil/trace.go:171","msg":"trace[1739916058] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"187.207286ms","start":"2026-02-04T05:15:21.862768Z","end":"2026-02-04T05:15:22.049975Z","steps":["trace[1739916058] 'process raft request' (duration: 187.056404ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:24.158666Z","caller":"traceutil/trace.go:171","msg":"trace[1958993069] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"101.666358ms","start":"2026-02-04T05:15:24.056983Z","end":"2026-02-04T05:15:24.15865Z","steps":["trace[1958993069] 'process raft request' (duration: 101.559966ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:28.617456Z","caller":"traceutil/trace.go:171","msg":"trace[2086979527] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"213.899526ms","start":"2026-02-04T05:15:28.403542Z","end":"2026-02-04T05:15:28.617441Z","steps":["trace[2086979527] 'read index received' (duration: 213.777244ms)","trace[2086979527] 'applied index is now lower than readState.Index' (duration: 121.712µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:28.617634Z","caller":"traceutil/trace.go:171","msg":"trace[695926669] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"409.308927ms","start":"2026-02-04T05:15:28.20832Z","end":"2026-02-04T05:15:28.617629Z","steps":["trace[695926669] 'process raft request' (duration: 409.043114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:28.617704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:28.208301Z","time spent":"409.349177ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:28.617814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.27117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:28.617836Z","caller":"traceutil/trace.go:171","msg":"trace[1957286534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"214.313941ms","start":"2026-02-04T05:15:28.403517Z","end":"2026-02-04T05:15:28.617831Z","steps":["trace[1957286534] 'agreement among raft nodes before linearized reading' (duration: 214.28203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:28.617973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.777048ms","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-02-04T05:15:28.618017Z","caller":"traceutil/trace.go:171","msg":"trace[829068128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:851; }","duration":"150.844039ms","start":"2026-02-04T05:15:28.467166Z","end":"2026-02-04T05:15:28.61801Z","steps":["trace[829068128] 'agreement among raft nodes before linearized reading' (duration: 150.747868ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:28.777315Z","caller":"traceutil/trace.go:171","msg":"trace[721068853] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"154.163124ms","start":"2026-02-04T05:15:28.623135Z","end":"2026-02-04T05:15:28.777298Z","steps":["trace[721068853] 'process raft request' (duration: 136.974826ms)","trace[721068853] 'compare' (duration: 15.832641ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:28.777444Z","caller":"traceutil/trace.go:171","msg":"trace[1055920507] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"154.123484ms","start":"2026-02-04T05:15:28.623315Z","end":"2026-02-04T05:15:28.777438Z","steps":["trace[1055920507] 'process raft request' (duration: 153.197072ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:34.912561Z","caller":"traceutil/trace.go:171","msg":"trace[1844705002] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"106.807773ms","start":"2026-02-04T05:15:34.805736Z","end":"2026-02-04T05:15:34.912544Z","steps":["trace[1844705002] 'process raft request' (duration: 106.705212ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:38.978639Z","caller":"traceutil/trace.go:171","msg":"trace[1616846375] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"158.302008ms","start":"2026-02-04T05:15:38.820324Z","end":"2026-02-04T05:15:38.978626Z","steps":["trace[1616846375] 'process raft request' (duration: 158.081405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:42.009902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.617992ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-04T05:15:43.319687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.938909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:15:43.319752Z","caller":"traceutil/trace.go:171","msg":"trace[1401877656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"125.074402ms","start":"2026-02-04T05:15:43.194661Z","end":"2026-02-04T05:15:43.319735Z","steps":["trace[1401877656] 'range keys from in-memory index tree' (duration: 124.800068ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:45.309596Z","caller":"traceutil/trace.go:171","msg":"trace[591610602] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"204.398448ms","start":"2026-02-04T05:15:45.105179Z","end":"2026-02-04T05:15:45.309577Z","steps":["trace[591610602] 'process raft request' (duration: 203.253923ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:46.694511Z","caller":"traceutil/trace.go:171","msg":"trace[962947781] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"123.378021ms","start":"2026-02-04T05:15:46.57112Z","end":"2026-02-04T05:15:46.694498Z","steps":["trace[962947781] 'process raft request' (duration: 91.604684ms)","trace[962947781] 'compare' (duration: 31.683126ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:51.207498Z","caller":"traceutil/trace.go:171","msg":"trace[57361488] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"118.984728ms","start":"2026-02-04T05:15:51.088494Z","end":"2026-02-04T05:15:51.207479Z","steps":["trace[57361488] 'read index received' (duration: 47.552447ms)","trace[57361488] 'applied index is now lower than readState.Index' (duration: 71.431541ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:51.207739Z","caller":"traceutil/trace.go:171","msg":"trace[1955008270] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"120.620298ms","start":"2026-02-04T05:15:51.087109Z","end":"2026-02-04T05:15:51.207729Z","steps":["trace[1955008270] 'process raft request' (duration: 48.998345ms)","trace[1955008270] 'compare' (duration: 71.278229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:51.2079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.390872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-04T05:15:51.207924Z","caller":"traceutil/trace.go:171","msg":"trace[1155734797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:914; }","duration":"119.446113ms","start":"2026-02-04T05:15:51.08847Z","end":"2026-02-04T05:15:51.207917Z","steps":["trace[1155734797] 'agreement among raft nodes before linearized reading' (duration: 119.388962ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:51.53446Z","caller":"traceutil/trace.go:171","msg":"trace[97162062] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"142.445317ms","start":"2026-02-04T05:15:51.391994Z","end":"2026-02-04T05:15:51.53444Z","steps":["trace[97162062] 'read index received' (duration: 50.154497ms)","trace[97162062] 'applied index is now lower than readState.Index' (duration: 92.28968ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:51.535249Z","caller":"traceutil/trace.go:171","msg":"trace[1888488121] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"318.456583ms","start":"2026-02-04T05:15:51.216772Z","end":"2026-02-04T05:15:51.535229Z","steps":["trace[1888488121] 'process raft request' (duration: 225.436825ms)","trace[1888488121] 'compare' (duration: 91.887875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:51.535477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:51.216762Z","time spent":"318.534794ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:15:51.535978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.972854ms","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-02-04T05:15:51.536026Z","caller":"traceutil/trace.go:171","msg":"trace[504761749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"144.063496ms","start":"2026-02-04T05:15:51.391952Z","end":"2026-02-04T05:15:51.536015Z","steps":["trace[504761749] 'agreement among raft nodes before linearized reading' (duration: 143.900164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:51.536716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.237802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:51.536761Z","caller":"traceutil/trace.go:171","msg":"trace[1178792153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"130.298693ms","start":"2026-02-04T05:15:51.406452Z","end":"2026-02-04T05:15:51.53675Z","steps":["trace[1178792153] 'agreement among raft nodes before linearized reading' (duration: 130.232723ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:51.882357Z","caller":"traceutil/trace.go:171","msg":"trace[474819573] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"142.111053ms","start":"2026-02-04T05:15:51.740225Z","end":"2026-02-04T05:15:51.882336Z","steps":["trace[474819573] 'process raft request' (duration: 142.004302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:52.139837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.425834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T05:15:52.140062Z","caller":"traceutil/trace.go:171","msg":"trace[1795377502] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"280.651837ms","start":"2026-02-04T05:15:51.859395Z","end":"2026-02-04T05:15:52.140047Z","steps":["trace[1795377502] 'read index received' (duration: 23.426018ms)","trace[1795377502] 'applied index is now lower than readState.Index' (duration: 257.224359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:52.140183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.782778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:52.140212Z","caller":"traceutil/trace.go:171","msg":"trace[1813099721] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:919; }","duration":"280.844788ms","start":"2026-02-04T05:15:51.859358Z","end":"2026-02-04T05:15:52.140203Z","steps":["trace[1813099721] 'agreement among raft nodes before linearized reading' (duration: 280.787778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:52.141083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.270534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-04T05:15:52.141115Z","caller":"traceutil/trace.go:171","msg":"trace[1518340600] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:921; }","duration":"250.321155ms","start":"2026-02-04T05:15:51.890786Z","end":"2026-02-04T05:15:52.141107Z","steps":["trace[1518340600] 'agreement among raft nodes before linearized reading' (duration: 250.221314ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:52.141298Z","caller":"traceutil/trace.go:171","msg":"trace[1910177000] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"107.233953ms","start":"2026-02-04T05:15:52.034057Z","end":"2026-02-04T05:15:52.141291Z","steps":["trace[1910177000] 'process raft request' (duration: 106.902448ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:52.141067Z","caller":"traceutil/trace.go:171","msg":"trace[79818805] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"250.307905ms","start":"2026-02-04T05:15:51.890732Z","end":"2026-02-04T05:15:52.14104Z","steps":["trace[79818805] 'process raft request' (duration: 249.204532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:52.400756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.153316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:52.400832Z","caller":"traceutil/trace.go:171","msg":"trace[1688761171] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:924; }","duration":"169.269468ms","start":"2026-02-04T05:15:52.231543Z","end":"2026-02-04T05:15:52.400813Z","steps":["trace[1688761171] 'range keys from in-memory index tree' (duration: 169.046615ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:52.747078Z","caller":"traceutil/trace.go:171","msg":"trace[1942445397] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"271.068157ms","start":"2026-02-04T05:15:52.475985Z","end":"2026-02-04T05:15:52.747053Z","steps":["trace[1942445397] 'read index received' (duration: 244.851535ms)","trace[1942445397] 'applied index is now lower than readState.Index' (duration: 26.215572ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:52.74719Z","caller":"traceutil/trace.go:171","msg":"trace[1832434545] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"279.218483ms","start":"2026-02-04T05:15:52.467957Z","end":"2026-02-04T05:15:52.747176Z","steps":["trace[1832434545] 'process raft request' (duration: 252.925141ms)","trace[1832434545] 'compare' (duration: 25.894148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:52.747414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.408881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ff0296f-a11a-4825-9d08-c438b5289979\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-04T05:15:52.747462Z","caller":"traceutil/trace.go:171","msg":"trace[976315938] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ff0296f-a11a-4825-9d08-c438b5289979; range_end:; response_count:1; response_revision:926; }","duration":"271.481292ms","start":"2026-02-04T05:15:52.47597Z","end":"2026-02-04T05:15:52.747451Z","steps":["trace[976315938] 'agreement among raft nodes before linearized reading' (duration: 271.395061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:52.747703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.187382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-02-04T05:15:52.747742Z","caller":"traceutil/trace.go:171","msg":"trace[276321499] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:926; }","duration":"134.258263ms","start":"2026-02-04T05:15:52.613475Z","end":"2026-02-04T05:15:52.747733Z","steps":["trace[276321499] 'agreement among raft nodes before linearized reading' (duration: 134.128001ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:53.34812Z","caller":"traceutil/trace.go:171","msg":"trace[1552717391] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"132.033571ms","start":"2026-02-04T05:15:53.216063Z","end":"2026-02-04T05:15:53.348097Z","steps":["trace[1552717391] 'process raft request' (duration: 131.712447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:56.925412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.547876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ff0296f-a11a-4825-9d08-c438b5289979\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-04T05:15:56.925633Z","caller":"traceutil/trace.go:171","msg":"trace[1217101450] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ff0296f-a11a-4825-9d08-c438b5289979; range_end:; response_count:1; response_revision:944; }","duration":"149.793859ms","start":"2026-02-04T05:15:56.775815Z","end":"2026-02-04T05:15:56.925609Z","steps":["trace[1217101450] 'range keys from in-memory index tree' (duration: 149.468126ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:57.416655Z","caller":"traceutil/trace.go:171","msg":"trace[105519694] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"265.378977ms","start":"2026-02-04T05:15:57.151255Z","end":"2026-02-04T05:15:57.416634Z","steps":["trace[105519694] 'read index received' (duration: 265.183455ms)","trace[105519694] 'applied index is now lower than readState.Index' (duration: 192.402µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:57.416999Z","caller":"traceutil/trace.go:171","msg":"trace[776124077] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"335.878458ms","start":"2026-02-04T05:15:57.081107Z","end":"2026-02-04T05:15:57.416985Z","steps":["trace[776124077] 'process raft request' (duration: 335.390152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:57.417101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:57.081096Z","time spent":"335.939038ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3794,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:57.417364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.111026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-04T05:15:57.417408Z","caller":"traceutil/trace.go:171","msg":"trace[1765697412] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:946; }","duration":"266.182346ms","start":"2026-02-04T05:15:57.151214Z","end":"2026-02-04T05:15:57.417396Z","steps":["trace[1765697412] 'agreement among raft nodes before linearized reading' (duration: 266.051335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:02.19824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.677262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T05:16:02.696595Z","caller":"traceutil/trace.go:171","msg":"trace[390886500] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"153.757315ms","start":"2026-02-04T05:16:02.54282Z","end":"2026-02-04T05:16:02.696578Z","steps":["trace[390886500] 'process raft request' (duration: 153.605144ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:08.028867Z","caller":"traceutil/trace.go:171","msg":"trace[1301079425] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"222.216513ms","start":"2026-02-04T05:16:07.806629Z","end":"2026-02-04T05:16:08.028846Z","steps":["trace[1301079425] 'process raft request' (duration: 222.062171ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:08.070303Z","caller":"traceutil/trace.go:171","msg":"trace[841547981] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"263.370754ms","start":"2026-02-04T05:16:07.806918Z","end":"2026-02-04T05:16:08.070289Z","steps":["trace[841547981] 'process raft request' (duration: 263.02796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:08.904414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.060869ms","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-02-04T05:16:08.904481Z","caller":"traceutil/trace.go:171","msg":"trace[355371813] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1000; }","duration":"259.180081ms","start":"2026-02-04T05:16:08.645286Z","end":"2026-02-04T05:16:08.904466Z","steps":["trace[355371813] 'count revisions from in-memory index tree' (duration: 258.941167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:08.90485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.335089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:08.904881Z","caller":"traceutil/trace.go:171","msg":"trace[323129592] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1000; }","duration":"113.38755ms","start":"2026-02-04T05:16:08.791484Z","end":"2026-02-04T05:16:08.904871Z","steps":["trace[323129592] 'range keys from in-memory index tree' (duration: 113.300069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:10.654519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.789932ms","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-02-04T05:16:10.654576Z","caller":"traceutil/trace.go:171","msg":"trace[1091897868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"621.894813ms","start":"2026-02-04T05:16:10.032669Z","end":"2026-02-04T05:16:10.654564Z","steps":["trace[1091897868] 'range keys from in-memory index tree' (duration: 621.660271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:10.654613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:10.03265Z","time spent":"621.949564ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:16:10.654827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.646289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:16:10.654846Z","caller":"traceutil/trace.go:171","msg":"trace[222428336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"995.696819ms","start":"2026-02-04T05:16:09.659144Z","end":"2026-02-04T05:16:10.654841Z","steps":["trace[222428336] 'range keys from in-memory index tree' (duration: 995.570868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:10.654859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:09.659126Z","time spent":"995.729619ms","remote":"127.0.0.1:43296","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-02-04T05:16:10.655045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.980847ms","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-02-04T05:16:10.655144Z","caller":"traceutil/trace.go:171","msg":"trace[574061722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"580.125788ms","start":"2026-02-04T05:16:10.075006Z","end":"2026-02-04T05:16:10.655132Z","steps":["trace[574061722] 'range keys from in-memory index tree' (duration: 579.836865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:10.655255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:10.074988Z","time spent":"580.259381ms","remote":"127.0.0.1:43296","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-02-04T05:16:10.655396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.752837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:10.656138Z","caller":"traceutil/trace.go:171","msg":"trace[1484028852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"250.543185ms","start":"2026-02-04T05:16:10.405587Z","end":"2026-02-04T05:16:10.65613Z","steps":["trace[1484028852] 'range keys from in-memory index tree' (duration: 249.693056ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:10.93344Z","caller":"traceutil/trace.go:171","msg":"trace[1498834851] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"272.604251ms","start":"2026-02-04T05:16:10.660809Z","end":"2026-02-04T05:16:10.933413Z","steps":["trace[1498834851] 'process raft request' (duration: 272.49163ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:10.934139Z","caller":"traceutil/trace.go:171","msg":"trace[1101268689] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1049; }","duration":"273.296467ms","start":"2026-02-04T05:16:10.660831Z","end":"2026-02-04T05:16:10.934128Z","steps":["trace[1101268689] 'read index received' (duration: 273.292587ms)","trace[1101268689] 'applied index is now lower than readState.Index' (duration: 3.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:16:10.935285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.435759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:10.935557Z","caller":"traceutil/trace.go:171","msg":"trace[833786633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"274.737325ms","start":"2026-02-04T05:16:10.660803Z","end":"2026-02-04T05:16:10.93554Z","steps":["trace[833786633] 'agreement among raft nodes before linearized reading' (duration: 274.409901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:11.581132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526559597744363,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T05:16:11.649678Z","caller":"traceutil/trace.go:171","msg":"trace[1054509670] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"987.32568ms","start":"2026-02-04T05:16:10.66233Z","end":"2026-02-04T05:16:11.649656Z","steps":["trace[1054509670] 'process raft request' (duration: 987.27858ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:11.650031Z","caller":"traceutil/trace.go:171","msg":"trace[982502757] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1051; }","duration":"570.651983ms","start":"2026-02-04T05:16:11.079361Z","end":"2026-02-04T05:16:11.650013Z","steps":["trace[982502757] 'read index received' (duration: 570.641023ms)","trace[982502757] 'applied index is now lower than readState.Index' (duration: 5.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:16:11.650559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:10.662325Z","time spent":"988.16977ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:16:11.650278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.814375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T05:16:11.653365Z","caller":"traceutil/trace.go:171","msg":"trace[1768170372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1005; }","duration":"573.9915ms","start":"2026-02-04T05:16:11.079356Z","end":"2026-02-04T05:16:11.653347Z","steps":["trace[1768170372] 'agreement among raft nodes before linearized reading' (duration: 570.711674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:11.653564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:11.07931Z","time spent":"574.236142ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T05:16:11.650339Z","caller":"traceutil/trace.go:171","msg":"trace[905999962] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"988.586565ms","start":"2026-02-04T05:16:10.661723Z","end":"2026-02-04T05:16:11.650309Z","steps":["trace[905999962] 'process raft request' (duration: 987.769886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:11.655175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:10.661717Z","time spent":"993.400168ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:16:11.651199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.111467ms","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-02-04T05:16:11.655282Z","caller":"traceutil/trace.go:171","msg":"trace[129925656] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1005; }","duration":"519.468075ms","start":"2026-02-04T05:16:11.135805Z","end":"2026-02-04T05:16:11.655273Z","steps":["trace[129925656] 'agreement among raft nodes before linearized reading' (duration: 514.997596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:11.655447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:11.135792Z","time spent":"519.646248ms","remote":"127.0.0.1:54874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-04T05:16:11.65155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.190748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:11.655522Z","caller":"traceutil/trace.go:171","msg":"trace[1335365516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"250.191533ms","start":"2026-02-04T05:16:11.405323Z","end":"2026-02-04T05:16:11.655515Z","steps":["trace[1335365516] 'agreement among raft nodes before linearized reading' (duration: 246.198328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:11.651597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.319219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:11.655682Z","caller":"traceutil/trace.go:171","msg":"trace[358853731] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1005; }","duration":"260.409686ms","start":"2026-02-04T05:16:11.395234Z","end":"2026-02-04T05:16:11.655644Z","steps":["trace[358853731] 'agreement among raft nodes before linearized reading' (duration: 256.34172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:24.246895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.148783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:16:24.247182Z","caller":"traceutil/trace.go:171","msg":"trace[1291184349] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"144.53305ms","start":"2026-02-04T05:16:24.102635Z","end":"2026-02-04T05:16:24.247168Z","steps":["trace[1291184349] 'compare' (duration: 138.941081ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:24.247353Z","caller":"traceutil/trace.go:171","msg":"trace[2008923238] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"144.694143ms","start":"2026-02-04T05:16:24.102652Z","end":"2026-02-04T05:16:24.247346Z","steps":["trace[2008923238] 'process raft request' (duration: 144.336528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:42.076689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.859555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-04T05:16:42.07694Z","caller":"traceutil/trace.go:171","msg":"trace[622391132] range","detail":"{range_begin:/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1; range_end:; response_count:1; response_revision:1093; }","duration":"117.047827ms","start":"2026-02-04T05:16:41.959785Z","end":"2026-02-04T05:16:42.076833Z","steps":["trace[622391132] 'range keys from in-memory index tree' (duration: 116.728164ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:44.376748Z","caller":"traceutil/trace.go:171","msg":"trace[1286584121] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"106.320191ms","start":"2026-02-04T05:16:44.270411Z","end":"2026-02-04T05:16:44.376732Z","steps":["trace[1286584121] 'process raft request' (duration: 106.21192ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:46.562392Z","caller":"traceutil/trace.go:171","msg":"trace[1006266085] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"111.524549ms","start":"2026-02-04T05:16:46.450843Z","end":"2026-02-04T05:16:46.562367Z","steps":["trace[1006266085] 'process raft request' (duration: 111.351517ms)"],"step_count":1}