{"level":"warn","ts":"2026-06-01T19:23:05.023914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-01T19:23:05.024016Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-01T19:23:05.024085Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-01T19:23:05.024099Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-06-01T19:23:05.024122Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-01T19:23:05.024894Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-06-01T19:23:05.02498Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-01T19:23:05.2854Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"260.158224ms"} {"level":"info","ts":"2026-06-01T19:23:05.332915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-06-01T19:23:05.333035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-06-01T19:23:05.333065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-06-01T19:23:05.333092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-01T19:23:05.333114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-06-01T19:23:05.333172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-06-01T19:23:05.346661Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-01T19:23:05.350769Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-01T19:23:05.352961Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-01T19:23:05.357154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-01T19:23:05.357367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-01T19:23:05.357495Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-01T19:23:05.35754Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-01T19:23:05.357554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-01T19:23:05.35898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-06-01T19:23:05.359674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-06-01T19:23:05.363192Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-01T19:23:05.363278Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-06-01T19:23:05.36343Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-06-01T19:23:05.363791Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-01T19:23:05.363882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-01T19:23:05.934394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-06-01T19:23:05.934457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-01T19:23:05.93449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-06-01T19:23:05.934509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-06-01T19:23:05.93452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-06-01T19:23:05.934538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-06-01T19:23:05.934551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-06-01T19:23:05.987982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-01T19:23:06.024303Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-06-01T19:23:06.024325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-01T19:23:06.024394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-01T19:23:06.024636Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-01T19:23:06.024687Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-01T19:23:06.027553Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-01T19:23:06.035758Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-06-01T19:23:06.046533Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-01T19:23:06.046621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-01T19:23:06.04665Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-01T19:23:08.698272Z","caller":"traceutil/trace.go:171","msg":"trace[263214453] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"104.033539ms","start":"2026-06-01T19:23:08.594212Z","end":"2026-06-01T19:23:08.698246Z","steps":["trace[263214453] 'process raft request' (duration: 86.793133ms)","trace[263214453] 'compare' (duration: 17.119603ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:09.512843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.217066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-01T19:23:09.51301Z","caller":"traceutil/trace.go:171","msg":"trace[466724815] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"225.646124ms","start":"2026-06-01T19:23:09.28734Z","end":"2026-06-01T19:23:09.512986Z","steps":["trace[466724815] 'process raft request' (duration: 100.607803ms)","trace[466724815] 'compare' (duration: 123.710181ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:09.513492Z","caller":"traceutil/trace.go:171","msg":"trace[314601304] transaction","detail":"{read_only:false; number_of_response:0; response_revision:108; }","duration":"205.051351ms","start":"2026-06-01T19:23:09.308418Z","end":"2026-06-01T19:23:09.513469Z","steps":["trace[314601304] 'process raft request' (duration: 204.909896ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:09.513501Z","caller":"traceutil/trace.go:171","msg":"trace[149547515] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:111; }","duration":"127.762303ms","start":"2026-06-01T19:23:09.385697Z","end":"2026-06-01T19:23:09.513459Z","steps":["trace[149547515] 'read index received' (duration: 2.260698ms)","trace[149547515] 'applied index is now lower than readState.Index' (duration: 125.500485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:09.515477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.711173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-01T19:23:09.515612Z","caller":"traceutil/trace.go:171","msg":"trace[983111235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:108; }","duration":"129.93818ms","start":"2026-06-01T19:23:09.385647Z","end":"2026-06-01T19:23:09.515585Z","steps":["trace[983111235] 'agreement among raft nodes before linearized reading' (duration: 128.069093ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:09.889264Z","caller":"traceutil/trace.go:171","msg":"trace[2121689804] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"161.517784ms","start":"2026-06-01T19:23:09.727673Z","end":"2026-06-01T19:23:09.889191Z","steps":["trace[2121689804] 'process raft request' (duration: 69.460051ms)","trace[2121689804] 'compare' (duration: 91.761514ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:11.811759Z","caller":"traceutil/trace.go:171","msg":"trace[2039851109] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"109.593955ms","start":"2026-06-01T19:23:11.702122Z","end":"2026-06-01T19:23:11.811716Z","steps":["trace[2039851109] 'process raft request' (duration: 68.932752ms)","trace[2039851109] 'compare' (duration: 40.467457ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:12.613396Z","caller":"traceutil/trace.go:171","msg":"trace[284216127] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"116.718219ms","start":"2026-06-01T19:23:12.496655Z","end":"2026-06-01T19:23:12.613373Z","steps":["trace[284216127] 'process raft request' (duration: 103.035909ms)","trace[284216127] 'compare' (duration: 13.584297ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:17.156632Z","caller":"traceutil/trace.go:171","msg":"trace[116499982] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"236.847414ms","start":"2026-06-01T19:23:16.919753Z","end":"2026-06-01T19:23:17.156601Z","steps":["trace[116499982] 'process raft request' (duration: 236.687069ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:17.783798Z","caller":"traceutil/trace.go:171","msg":"trace[723136805] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"213.703556ms","start":"2026-06-01T19:23:17.570069Z","end":"2026-06-01T19:23:17.783772Z","steps":["trace[723136805] 'process raft request' (duration: 213.565073ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:17.799898Z","caller":"traceutil/trace.go:171","msg":"trace[1208975030] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"152.483044ms","start":"2026-06-01T19:23:17.647396Z","end":"2026-06-01T19:23:17.799879Z","steps":["trace[1208975030] 'process raft request' (duration: 152.3365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:18.110813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.43466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:18.110911Z","caller":"traceutil/trace.go:171","msg":"trace[88198796] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"169.654251ms","start":"2026-06-01T19:23:17.94124Z","end":"2026-06-01T19:23:18.110895Z","steps":["trace[88198796] 'read index received' (duration: 38.721µs)","trace[88198796] 'applied index is now lower than readState.Index' (duration: 169.61443ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:18.111013Z","caller":"traceutil/trace.go:171","msg":"trace[6158904] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"240.212104ms","start":"2026-06-01T19:23:17.870786Z","end":"2026-06-01T19:23:18.110998Z","steps":["trace[6158904] 'process raft request' (duration: 54.521898ms)","trace[6158904] 'compare' (duration: 185.286835ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:18.111268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.011181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-01T19:23:18.111341Z","caller":"traceutil/trace.go:171","msg":"trace[1813459190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:302; }","duration":"170.140244ms","start":"2026-06-01T19:23:17.941182Z","end":"2026-06-01T19:23:18.111323Z","steps":["trace[1813459190] 'agreement among raft nodes before linearized reading' (duration: 169.940138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:18.111367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.417633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:23:18.111649Z","caller":"traceutil/trace.go:171","msg":"trace[302579333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:302; }","duration":"142.751792ms","start":"2026-06-01T19:23:17.968874Z","end":"2026-06-01T19:23:18.111626Z","steps":["trace[302579333] 'agreement among raft nodes before linearized reading' (duration: 142.30668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:18.379758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.845912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:18.379861Z","caller":"traceutil/trace.go:171","msg":"trace[1355991071] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"263.042834ms","start":"2026-06-01T19:23:18.116802Z","end":"2026-06-01T19:23:18.379845Z","steps":["trace[1355991071] 'read index received' (duration: 67.598401ms)","trace[1355991071] 'applied index is now lower than readState.Index' (duration: 195.443363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:18.380015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.215519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-01T19:23:18.380092Z","caller":"traceutil/trace.go:171","msg":"trace[1306867328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:304; }","duration":"263.293872ms","start":"2026-06-01T19:23:18.116756Z","end":"2026-06-01T19:23:18.38005Z","steps":["trace[1306867328] 'agreement among raft nodes before linearized reading' (duration: 263.173328ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:18.379933Z","caller":"traceutil/trace.go:171","msg":"trace[1298943347] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"264.093854ms","start":"2026-06-01T19:23:18.115801Z","end":"2026-06-01T19:23:18.379894Z","steps":["trace[1298943347] 'process raft request' (duration: 119.897552ms)","trace[1298943347] 'compare' (duration: 143.705518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:18.380299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.148944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-01T19:23:18.380371Z","caller":"traceutil/trace.go:171","msg":"trace[1812641929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:304; }","duration":"118.278137ms","start":"2026-06-01T19:23:18.262074Z","end":"2026-06-01T19:23:18.380352Z","steps":["trace[1812641929] 'agreement among raft nodes before linearized reading' (duration: 118.090562ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:18.561049Z","caller":"traceutil/trace.go:171","msg":"trace[1197026040] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"123.210203ms","start":"2026-06-01T19:23:18.437809Z","end":"2026-06-01T19:23:18.561019Z","steps":["trace[1197026040] 'read index received' (duration: 81.36152ms)","trace[1197026040] 'applied index is now lower than readState.Index' (duration: 41.847043ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:18.561127Z","caller":"traceutil/trace.go:171","msg":"trace[368648118] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"127.542343ms","start":"2026-06-01T19:23:18.433548Z","end":"2026-06-01T19:23:18.56109Z","steps":["trace[368648118] 'process raft request' (duration: 85.630089ms)","trace[368648118] 'compare' (duration: 41.653267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:18.561269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.408049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:23:18.561374Z","caller":"traceutil/trace.go:171","msg":"trace[1534051184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:306; }","duration":"123.550663ms","start":"2026-06-01T19:23:18.437763Z","end":"2026-06-01T19:23:18.561313Z","steps":["trace[1534051184] 'agreement among raft nodes before linearized reading' (duration: 123.369368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:18.965975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.369495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:18.966116Z","caller":"traceutil/trace.go:171","msg":"trace[408124409] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"167.77873ms","start":"2026-06-01T19:23:18.798281Z","end":"2026-06-01T19:23:18.96606Z","steps":["trace[408124409] 'process raft request' (duration: 39.259041ms)","trace[408124409] 'compare' (duration: 128.229362ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:19.003734Z","caller":"traceutil/trace.go:171","msg":"trace[341017376] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"197.227822ms","start":"2026-06-01T19:23:18.806482Z","end":"2026-06-01T19:23:19.00371Z","steps":["trace[341017376] 'process raft request' (duration: 196.982445ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.003878Z","caller":"traceutil/trace.go:171","msg":"trace[966008405] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:320; }","duration":"183.938036ms","start":"2026-06-01T19:23:18.819671Z","end":"2026-06-01T19:23:19.003609Z","steps":["trace[966008405] 'read index received' (duration: 17.875462ms)","trace[966008405] 'applied index is now lower than readState.Index' (duration: 166.061334ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:19.003977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.259535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-01T19:23:19.004037Z","caller":"traceutil/trace.go:171","msg":"trace[317914082] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:311; }","duration":"184.385399ms","start":"2026-06-01T19:23:18.819638Z","end":"2026-06-01T19:23:19.004023Z","steps":["trace[317914082] 'agreement among raft nodes before linearized reading' (duration: 184.189203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.318095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.165316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:19.318317Z","caller":"traceutil/trace.go:171","msg":"trace[1888990675] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"348.960434ms","start":"2026-06-01T19:23:18.969319Z","end":"2026-06-01T19:23:19.318279Z","steps":["trace[1888990675] 'process raft request' (duration: 111.546201ms)","trace[1888990675] 'compare' (duration: 236.990932ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:19.31851Z","caller":"traceutil/trace.go:171","msg":"trace[1773336085] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"314.465064ms","start":"2026-06-01T19:23:19.00398Z","end":"2026-06-01T19:23:19.318445Z","steps":["trace[1773336085] 'read index received' (duration: 76.897727ms)","trace[1773336085] 'applied index is now lower than readState.Index' (duration: 237.558356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:19.318534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:18.969289Z","time spent":"349.109817ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:23:19.318715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.564172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-01T19:23:19.318802Z","caller":"traceutil/trace.go:171","msg":"trace[2128425535] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:314; }","duration":"348.707176ms","start":"2026-06-01T19:23:18.970077Z","end":"2026-06-01T19:23:19.318784Z","steps":["trace[2128425535] 'agreement among raft nodes before linearized reading' (duration: 348.411888ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.318783Z","caller":"traceutil/trace.go:171","msg":"trace[536513839] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"310.328801ms","start":"2026-06-01T19:23:19.00843Z","end":"2026-06-01T19:23:19.318759Z","steps":["trace[536513839] 'process raft request' (duration: 309.781526ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.318781Z","caller":"traceutil/trace.go:171","msg":"trace[1719345945] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"307.591606ms","start":"2026-06-01T19:23:19.011171Z","end":"2026-06-01T19:23:19.318763Z","steps":["trace[1719345945] 'process raft request' (duration: 307.261337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.318856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:18.970062Z","time spent":"348.785678ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-06-01T19:23:19.318921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.008405Z","time spent":"310.457544ms","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:23:19.318985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.011124Z","time spent":"307.75309ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:19.319098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.615546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-01T19:23:19.319141Z","caller":"traceutil/trace.go:171","msg":"trace[660497855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:315; }","duration":"300.707968ms","start":"2026-06-01T19:23:19.018422Z","end":"2026-06-01T19:23:19.31913Z","steps":["trace[660497855] 'agreement among raft nodes before linearized reading' (duration: 300.590865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.319227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.018411Z","time spent":"300.808041ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-06-01T19:23:19.319451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.884729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-01T19:23:19.319528Z","caller":"traceutil/trace.go:171","msg":"trace[1681320612] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:315; }","duration":"199.995962ms","start":"2026-06-01T19:23:19.119511Z","end":"2026-06-01T19:23:19.319507Z","steps":["trace[1681320612] 'agreement among raft nodes before linearized reading' (duration: 199.832488ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.319594Z","caller":"traceutil/trace.go:171","msg":"trace[1731615474] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"308.10084ms","start":"2026-06-01T19:23:19.011475Z","end":"2026-06-01T19:23:19.319576Z","steps":["trace[1731615474] 'process raft request' (duration: 307.054032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.319689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.011468Z","time spent":"308.182822ms","remote":"127.0.0.1:59120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:23:19.320451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.025644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-01T19:23:19.320506Z","caller":"traceutil/trace.go:171","msg":"trace[1538017835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:315; }","duration":"251.149328ms","start":"2026-06-01T19:23:19.069343Z","end":"2026-06-01T19:23:19.320493Z","steps":["trace[1538017835] 'agreement among raft nodes before linearized reading' (duration: 250.087538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.320712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.202928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-01T19:23:19.320773Z","caller":"traceutil/trace.go:171","msg":"trace[228164663] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:315; }","duration":"451.306481ms","start":"2026-06-01T19:23:18.86945Z","end":"2026-06-01T19:23:19.320757Z","steps":["trace[228164663] 'agreement among raft nodes before linearized reading' (duration: 449.234374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.320869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.91141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-06-01T19:23:19.320819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:18.869432Z","time spent":"451.374423ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-06-01T19:23:19.320935Z","caller":"traceutil/trace.go:171","msg":"trace[1744629342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"102.019092ms","start":"2026-06-01T19:23:19.218897Z","end":"2026-06-01T19:23:19.320916Z","steps":["trace[1744629342] 'agreement among raft nodes before linearized reading' (duration: 100.522351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.321195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.319645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-06-01T19:23:19.321249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.171381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-06-01T19:23:19.321271Z","caller":"traceutil/trace.go:171","msg":"trace[1377894622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:315; }","duration":"151.438219ms","start":"2026-06-01T19:23:19.169818Z","end":"2026-06-01T19:23:19.321256Z","steps":["trace[1377894622] 'agreement among raft nodes before linearized reading' (duration: 149.786694ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.321305Z","caller":"traceutil/trace.go:171","msg":"trace[2058384142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:315; }","duration":"401.288814ms","start":"2026-06-01T19:23:18.92Z","end":"2026-06-01T19:23:19.321288Z","steps":["trace[2058384142] 'agreement among raft nodes before linearized reading' (duration: 398.662033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.321352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:18.919985Z","time spent":"401.353256ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-06-01T19:23:19.861434Z","caller":"traceutil/trace.go:171","msg":"trace[2080234593] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"532.810446ms","start":"2026-06-01T19:23:19.328603Z","end":"2026-06-01T19:23:19.861413Z","steps":["trace[2080234593] 'process raft request' (duration: 532.514048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.861546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.328593Z","time spent":"532.902408ms","remote":"127.0.0.1:59128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:19.861775Z","caller":"traceutil/trace.go:171","msg":"trace[2102388302] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"532.95074ms","start":"2026-06-01T19:23:19.328799Z","end":"2026-06-01T19:23:19.861749Z","steps":["trace[2102388302] 'process raft request' (duration: 532.735674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.861814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.328789Z","time spent":"533.005181ms","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:19.861872Z","caller":"traceutil/trace.go:171","msg":"trace[147278767] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:326; }","duration":"533.097624ms","start":"2026-06-01T19:23:19.328658Z","end":"2026-06-01T19:23:19.861756Z","steps":["trace[147278767] 'read index received' (duration: 69.546336ms)","trace[147278767] 'applied index is now lower than readState.Index' (duration: 463.550308ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:19.861832Z","caller":"traceutil/trace.go:171","msg":"trace[1196348512] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"533.149925ms","start":"2026-06-01T19:23:19.328668Z","end":"2026-06-01T19:23:19.861818Z","steps":["trace[1196348512] 'process raft request' (duration: 532.642661ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.862044Z","caller":"traceutil/trace.go:171","msg":"trace[850336315] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"531.379628ms","start":"2026-06-01T19:23:19.330652Z","end":"2026-06-01T19:23:19.862031Z","steps":["trace[850336315] 'process raft request' (duration: 531.061199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.862095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.611492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-01T19:23:19.862138Z","caller":"traceutil/trace.go:171","msg":"trace[1560414279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:320; }","duration":"491.678544ms","start":"2026-06-01T19:23:19.370452Z","end":"2026-06-01T19:23:19.862131Z","steps":["trace[1560414279] 'agreement among raft nodes before linearized reading' (duration: 491.619373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.862156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.37044Z","time spent":"491.711975ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-06-01T19:23:19.862188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.330644Z","time spent":"531.46301ms","remote":"127.0.0.1:59216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:23:19.862134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.261045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-01T19:23:19.862402Z","caller":"traceutil/trace.go:171","msg":"trace[527068742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:320; }","duration":"341.582353ms","start":"2026-06-01T19:23:19.520809Z","end":"2026-06-01T19:23:19.862391Z","steps":["trace[527068742] 'agreement among raft nodes before linearized reading' (duration: 341.265485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.862427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.520793Z","time spent":"341.626294ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-06-01T19:23:19.862539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.538792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-06-01T19:23:19.862001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.328656Z","time spent":"533.32183ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-01T19:23:19.862563Z","caller":"traceutil/trace.go:171","msg":"trace[1085104710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:320; }","duration":"392.608674ms","start":"2026-06-01T19:23:19.469948Z","end":"2026-06-01T19:23:19.862557Z","steps":["trace[1085104710] 'agreement among raft nodes before linearized reading' (duration: 392.309876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.862634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.469925Z","time spent":"392.702957ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-06-01T19:23:19.862677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.938507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-06-01T19:23:19.862714Z","caller":"traceutil/trace.go:171","msg":"trace[614651431] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:320; }","duration":"534.07169ms","start":"2026-06-01T19:23:19.328635Z","end":"2026-06-01T19:23:19.862707Z","steps":["trace[614651431] 'agreement among raft nodes before linearized reading' (duration: 533.933496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.862806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.328622Z","time spent":"534.109412ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-06-01T19:23:19.862805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.116584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-06-01T19:23:19.86284Z","caller":"traceutil/trace.go:171","msg":"trace[1208285933] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:320; }","duration":"292.151945ms","start":"2026-06-01T19:23:19.570683Z","end":"2026-06-01T19:23:19.862835Z","steps":["trace[1208285933] 'agreement among raft nodes before linearized reading' (duration: 292.103854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.863147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.084472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-01T19:23:19.863309Z","caller":"traceutil/trace.go:171","msg":"trace[71157664] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:320; }","duration":"442.275417ms","start":"2026-06-01T19:23:19.421005Z","end":"2026-06-01T19:23:19.86328Z","steps":["trace[71157664] 'agreement among raft nodes before linearized reading' (duration: 441.462245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:19.863436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:19.420991Z","time spent":"442.424902ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-06-01T19:23:19.995362Z","caller":"traceutil/trace.go:171","msg":"trace[1549205178] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"123.757635ms","start":"2026-06-01T19:23:19.871571Z","end":"2026-06-01T19:23:19.995329Z","steps":["trace[1549205178] 'process raft request' (duration: 79.583141ms)","trace[1549205178] 'compare' (duration: 43.99615ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:19.995785Z","caller":"traceutil/trace.go:171","msg":"trace[796379226] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"124.053383ms","start":"2026-06-01T19:23:19.871686Z","end":"2026-06-01T19:23:19.99574Z","steps":["trace[796379226] 'process raft request' (duration: 123.56582ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.99629Z","caller":"traceutil/trace.go:171","msg":"trace[437557215] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"124.437922ms","start":"2026-06-01T19:23:19.871829Z","end":"2026-06-01T19:23:19.996267Z","steps":["trace[437557215] 'process raft request' (duration: 124.166275ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.996297Z","caller":"traceutil/trace.go:171","msg":"trace[1796478674] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"124.583217ms","start":"2026-06-01T19:23:19.871694Z","end":"2026-06-01T19:23:19.996277Z","steps":["trace[1796478674] 'process raft request' (duration: 124.050422ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.996377Z","caller":"traceutil/trace.go:171","msg":"trace[1389904593] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"124.501034ms","start":"2026-06-01T19:23:19.871835Z","end":"2026-06-01T19:23:19.996336Z","steps":["trace[1389904593] 'process raft request' (duration: 124.32589ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.996661Z","caller":"traceutil/trace.go:171","msg":"trace[282905074] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"124.37243ms","start":"2026-06-01T19:23:19.872256Z","end":"2026-06-01T19:23:19.996628Z","steps":["trace[282905074] 'process raft request' (duration: 124.032391ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.997Z","caller":"traceutil/trace.go:171","msg":"trace[1510985930] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"124.377561ms","start":"2026-06-01T19:23:19.872602Z","end":"2026-06-01T19:23:19.99698Z","steps":["trace[1510985930] 'process raft request' (duration: 123.728013ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:19.99667Z","caller":"traceutil/trace.go:171","msg":"trace[60081734] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"124.491474ms","start":"2026-06-01T19:23:19.872158Z","end":"2026-06-01T19:23:19.996649Z","steps":["trace[60081734] 'process raft request' (duration: 124.067692ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:24.434885Z","caller":"traceutil/trace.go:171","msg":"trace[1342051451] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"105.756463ms","start":"2026-06-01T19:23:24.329108Z","end":"2026-06-01T19:23:24.434864Z","steps":["trace[1342051451] 'process raft request' (duration: 102.200751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:31.544733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.137135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:31.54485Z","caller":"traceutil/trace.go:171","msg":"trace[220035324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"111.242098ms","start":"2026-06-01T19:23:31.433566Z","end":"2026-06-01T19:23:31.544808Z","steps":["trace[220035324] 'range keys from in-memory index tree' (duration: 111.061073ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:38.085051Z","caller":"traceutil/trace.go:171","msg":"trace[2059329727] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"125.390573ms","start":"2026-06-01T19:23:37.959645Z","end":"2026-06-01T19:23:38.085036Z","steps":["trace[2059329727] 'process raft request' (duration: 125.334641ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:38.085205Z","caller":"traceutil/trace.go:171","msg":"trace[1851206933] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"127.336086ms","start":"2026-06-01T19:23:37.957736Z","end":"2026-06-01T19:23:38.085072Z","steps":["trace[1851206933] 'process raft request' (duration: 127.154612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:39.015265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.02632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:23:39.015357Z","caller":"traceutil/trace.go:171","msg":"trace[2046597369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:439; }","duration":"175.156012ms","start":"2026-06-01T19:23:38.840179Z","end":"2026-06-01T19:23:39.015335Z","steps":["trace[2046597369] 'range keys from in-memory index tree' (duration: 174.884856ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:39.132856Z","caller":"traceutil/trace.go:171","msg":"trace[1103145548] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"113.418516ms","start":"2026-06-01T19:23:39.019413Z","end":"2026-06-01T19:23:39.132831Z","steps":["trace[1103145548] 'process raft request' (duration: 113.255633ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:40.652588Z","caller":"traceutil/trace.go:171","msg":"trace[2076384792] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"165.726984ms","start":"2026-06-01T19:23:40.486838Z","end":"2026-06-01T19:23:40.652565Z","steps":["trace[2076384792] 'process raft request' (duration: 165.593252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:41.116803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.493842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-01T19:23:41.11689Z","caller":"traceutil/trace.go:171","msg":"trace[1633577666] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:443; }","duration":"129.634395ms","start":"2026-06-01T19:23:40.987236Z","end":"2026-06-01T19:23:41.116871Z","steps":["trace[1633577666] 'range keys from in-memory index tree' (duration: 129.330549ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:42.842491Z","caller":"traceutil/trace.go:171","msg":"trace[1552334072] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"102.01433ms","start":"2026-06-01T19:23:42.740457Z","end":"2026-06-01T19:23:42.842471Z","steps":["trace[1552334072] 'read index received' (duration: 101.840096ms)","trace[1552334072] 'applied index is now lower than readState.Index' (duration: 173.514µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:42.842533Z","caller":"traceutil/trace.go:171","msg":"trace[1551922790] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"182.390195ms","start":"2026-06-01T19:23:42.66012Z","end":"2026-06-01T19:23:42.84251Z","steps":["trace[1551922790] 'process raft request' (duration: 182.241432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:42.842681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.197104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-01T19:23:42.842741Z","caller":"traceutil/trace.go:171","msg":"trace[1216580151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"102.334377ms","start":"2026-06-01T19:23:42.740392Z","end":"2026-06-01T19:23:42.842727Z","steps":["trace[1216580151] 'agreement among raft nodes before linearized reading' (duration: 102.183524ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:45.127844Z","caller":"traceutil/trace.go:171","msg":"trace[59992951] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"203.312474ms","start":"2026-06-01T19:23:44.924507Z","end":"2026-06-01T19:23:45.127819Z","steps":["trace[59992951] 'read index received' (duration: 106.741498ms)","trace[59992951] 'applied index is now lower than readState.Index' (duration: 96.570146ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:45.127885Z","caller":"traceutil/trace.go:171","msg":"trace[734643666] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"228.940759ms","start":"2026-06-01T19:23:44.89892Z","end":"2026-06-01T19:23:45.127861Z","steps":["trace[734643666] 'process raft request' (duration: 132.364893ms)","trace[734643666] 'compare' (duration: 96.443773ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:45.127975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.448649ms","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-06-01T19:23:45.128083Z","caller":"traceutil/trace.go:171","msg":"trace[1104571086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"203.597622ms","start":"2026-06-01T19:23:44.924474Z","end":"2026-06-01T19:23:45.128071Z","steps":["trace[1104571086] 'agreement among raft nodes before linearized reading' (duration: 203.448428ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:47.310869Z","caller":"traceutil/trace.go:171","msg":"trace[1213960536] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"126.196396ms","start":"2026-06-01T19:23:47.18465Z","end":"2026-06-01T19:23:47.310847Z","steps":["trace[1213960536] 'process raft request' (duration: 81.871054ms)","trace[1213960536] 'compare' (duration: 44.143668ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:47.311068Z","caller":"traceutil/trace.go:171","msg":"trace[218693905] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"108.81117ms","start":"2026-06-01T19:23:47.201967Z","end":"2026-06-01T19:23:47.310778Z","steps":["trace[218693905] 'read index received' (duration: 64.619311ms)","trace[218693905] 'applied index is now lower than readState.Index' (duration: 44.190579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:47.311183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.198678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-01T19:23:47.311274Z","caller":"traceutil/trace.go:171","msg":"trace[62205863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"109.31224ms","start":"2026-06-01T19:23:47.201931Z","end":"2026-06-01T19:23:47.311243Z","steps":["trace[62205863] 'agreement among raft nodes before linearized reading' (duration: 109.081365ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:49.516759Z","caller":"traceutil/trace.go:171","msg":"trace[420321824] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"137.900633ms","start":"2026-06-01T19:23:49.378835Z","end":"2026-06-01T19:23:49.516736Z","steps":["trace[420321824] 'process raft request' (duration: 137.780441ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:49.521642Z","caller":"traceutil/trace.go:171","msg":"trace[1252004002] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"142.477478ms","start":"2026-06-01T19:23:49.379143Z","end":"2026-06-01T19:23:49.521621Z","steps":["trace[1252004002] 'process raft request' (duration: 142.327974ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:49.841961Z","caller":"traceutil/trace.go:171","msg":"trace[2065348192] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"122.297241ms","start":"2026-06-01T19:23:49.71964Z","end":"2026-06-01T19:23:49.841937Z","steps":["trace[2065348192] 'process raft request' (duration: 122.25275ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:49.842183Z","caller":"traceutil/trace.go:171","msg":"trace[1767747246] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"122.555756ms","start":"2026-06-01T19:23:49.719594Z","end":"2026-06-01T19:23:49.84215Z","steps":["trace[1767747246] 'process raft request' (duration: 30.139284ms)","trace[1767747246] 'compare' (duration: 91.987644ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:50.277736Z","caller":"traceutil/trace.go:171","msg":"trace[139202160] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"107.22768ms","start":"2026-06-01T19:23:50.170469Z","end":"2026-06-01T19:23:50.277696Z","steps":["trace[139202160] 'process raft request' (duration: 103.922912ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:50.284596Z","caller":"traceutil/trace.go:171","msg":"trace[2102705824] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"112.491769ms","start":"2026-06-01T19:23:50.172085Z","end":"2026-06-01T19:23:50.284577Z","steps":["trace[2102705824] 'process raft request' (duration: 111.908827ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:50.284802Z","caller":"traceutil/trace.go:171","msg":"trace[2117986666] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"112.342485ms","start":"2026-06-01T19:23:50.172418Z","end":"2026-06-01T19:23:50.284761Z","steps":["trace[2117986666] 'process raft request' (duration: 111.657201ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:50.575449Z","caller":"traceutil/trace.go:171","msg":"trace[1286351090] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"208.211984ms","start":"2026-06-01T19:23:50.367214Z","end":"2026-06-01T19:23:50.575426Z","steps":["trace[1286351090] 'read index received' (duration: 131.229473ms)","trace[1286351090] 'applied index is now lower than readState.Index' (duration: 76.980901ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:50.575505Z","caller":"traceutil/trace.go:171","msg":"trace[413355981] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"208.679922ms","start":"2026-06-01T19:23:50.366784Z","end":"2026-06-01T19:23:50.575464Z","steps":["trace[413355981] 'process raft request' (duration: 131.640731ms)","trace[413355981] 'compare' (duration: 76.807097ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:50.575586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.357996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-06-01T19:23:50.575614Z","caller":"traceutil/trace.go:171","msg":"trace[221537794] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:493; }","duration":"208.396827ms","start":"2026-06-01T19:23:50.367209Z","end":"2026-06-01T19:23:50.575606Z","steps":["trace[221537794] 'agreement among raft nodes before linearized reading' (duration: 208.310225ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:50.577744Z","caller":"traceutil/trace.go:171","msg":"trace[1156426826] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"210.043731ms","start":"2026-06-01T19:23:50.367667Z","end":"2026-06-01T19:23:50.577711Z","steps":["trace[1156426826] 'process raft request' (duration: 209.938598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:50.578013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.063595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-06-01T19:23:50.578047Z","caller":"traceutil/trace.go:171","msg":"trace[1112456973] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:494; }","duration":"120.131755ms","start":"2026-06-01T19:23:50.457905Z","end":"2026-06-01T19:23:50.578036Z","steps":["trace[1112456973] 'agreement among raft nodes before linearized reading' (duration: 119.770488ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:51.328131Z","caller":"traceutil/trace.go:171","msg":"trace[1792791051] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"100.999246ms","start":"2026-06-01T19:23:51.227108Z","end":"2026-06-01T19:23:51.328107Z","steps":["trace[1792791051] 'read index received' (duration: 100.802782ms)","trace[1792791051] 'applied index is now lower than readState.Index' (duration: 195.424µs)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:51.32839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.267121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:23:51.328436Z","caller":"traceutil/trace.go:171","msg":"trace[1409326740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"101.332872ms","start":"2026-06-01T19:23:51.227092Z","end":"2026-06-01T19:23:51.328425Z","steps":["trace[1409326740] 'agreement among raft nodes before linearized reading' (duration: 101.142408ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:51.328364Z","caller":"traceutil/trace.go:171","msg":"trace[537571018] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"125.425552ms","start":"2026-06-01T19:23:51.20274Z","end":"2026-06-01T19:23:51.328165Z","steps":["trace[537571018] 'process raft request' (duration: 125.232078ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:51.692707Z","caller":"traceutil/trace.go:171","msg":"trace[1342127114] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"145.858909ms","start":"2026-06-01T19:23:51.546816Z","end":"2026-06-01T19:23:51.692675Z","steps":["trace[1342127114] 'process raft request' (duration: 144.94705ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:51.994331Z","caller":"traceutil/trace.go:171","msg":"trace[1689491338] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"113.270705ms","start":"2026-06-01T19:23:51.881007Z","end":"2026-06-01T19:23:51.994278Z","steps":["trace[1689491338] 'process raft request' (duration: 113.054321ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069003Z","caller":"traceutil/trace.go:171","msg":"trace[922524853] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"179.81478ms","start":"2026-06-01T19:23:51.889148Z","end":"2026-06-01T19:23:52.068962Z","steps":["trace[922524853] 'process raft request' (duration: 179.712118ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069045Z","caller":"traceutil/trace.go:171","msg":"trace[1041058789] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"173.816367ms","start":"2026-06-01T19:23:51.895179Z","end":"2026-06-01T19:23:52.068995Z","steps":["trace[1041058789] 'process raft request' (duration: 173.719906ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069336Z","caller":"traceutil/trace.go:171","msg":"trace[644251829] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"173.463181ms","start":"2026-06-01T19:23:51.895854Z","end":"2026-06-01T19:23:52.069318Z","steps":["trace[644251829] 'process raft request' (duration: 173.117904ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069366Z","caller":"traceutil/trace.go:171","msg":"trace[1939460510] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"174.089793ms","start":"2026-06-01T19:23:51.895247Z","end":"2026-06-01T19:23:52.069337Z","steps":["trace[1939460510] 'process raft request' (duration: 173.688775ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069525Z","caller":"traceutil/trace.go:171","msg":"trace[326449538] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"169.98785ms","start":"2026-06-01T19:23:51.899525Z","end":"2026-06-01T19:23:52.069513Z","steps":["trace[326449538] 'process raft request' (duration: 169.526471ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069415Z","caller":"traceutil/trace.go:171","msg":"trace[1246380965] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"170.940119ms","start":"2026-06-01T19:23:51.898453Z","end":"2026-06-01T19:23:52.069393Z","steps":["trace[1246380965] 'process raft request' (duration: 170.565692ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069628Z","caller":"traceutil/trace.go:171","msg":"trace[1939703232] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"131.789882ms","start":"2026-06-01T19:23:51.937813Z","end":"2026-06-01T19:23:52.069603Z","steps":["trace[1939703232] 'process raft request' (duration: 131.69865ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069653Z","caller":"traceutil/trace.go:171","msg":"trace[252121044] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"163.793363ms","start":"2026-06-01T19:23:51.90585Z","end":"2026-06-01T19:23:52.069643Z","steps":["trace[252121044] 'process raft request' (duration: 163.333954ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069742Z","caller":"traceutil/trace.go:171","msg":"trace[583496553] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"180.783209ms","start":"2026-06-01T19:23:51.888927Z","end":"2026-06-01T19:23:52.06971Z","steps":["trace[583496553] 'process raft request' (duration: 179.815669ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.069755Z","caller":"traceutil/trace.go:171","msg":"trace[1336188349] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"158.537756ms","start":"2026-06-01T19:23:51.911169Z","end":"2026-06-01T19:23:52.069707Z","steps":["trace[1336188349] 'process raft request' (duration: 158.27681ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.283547Z","caller":"traceutil/trace.go:171","msg":"trace[1450491357] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"157.807786ms","start":"2026-06-01T19:23:52.125711Z","end":"2026-06-01T19:23:52.283519Z","steps":["trace[1450491357] 'process raft request' (duration: 66.699657ms)","trace[1450491357] 'compare' (duration: 90.940356ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:52.296519Z","caller":"traceutil/trace.go:171","msg":"trace[1801775375] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"158.709725ms","start":"2026-06-01T19:23:52.137764Z","end":"2026-06-01T19:23:52.296474Z","steps":["trace[1801775375] 'process raft request' (duration: 158.350548ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.296768Z","caller":"traceutil/trace.go:171","msg":"trace[438955432] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"108.852818ms","start":"2026-06-01T19:23:52.187734Z","end":"2026-06-01T19:23:52.296587Z","steps":["trace[438955432] 'process raft request' (duration: 108.745076ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.296939Z","caller":"traceutil/trace.go:171","msg":"trace[517803298] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"159.084112ms","start":"2026-06-01T19:23:52.137847Z","end":"2026-06-01T19:23:52.296931Z","steps":["trace[517803298] 'process raft request' (duration: 158.46996ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.297003Z","caller":"traceutil/trace.go:171","msg":"trace[226884583] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"147.796734ms","start":"2026-06-01T19:23:52.1492Z","end":"2026-06-01T19:23:52.296996Z","steps":["trace[226884583] 'process raft request' (duration: 147.219823ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.296996Z","caller":"traceutil/trace.go:171","msg":"trace[1393307583] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"151.505179ms","start":"2026-06-01T19:23:52.145459Z","end":"2026-06-01T19:23:52.296964Z","steps":["trace[1393307583] 'process raft request' (duration: 150.924087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:52.647694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.126886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:52.648084Z","caller":"traceutil/trace.go:171","msg":"trace[150736787] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"338.952974ms","start":"2026-06-01T19:23:52.309094Z","end":"2026-06-01T19:23:52.648047Z","steps":["trace[150736787] 'process raft request' (duration: 207.356757ms)","trace[150736787] 'compare' (duration: 130.81525ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:52.648105Z","caller":"traceutil/trace.go:171","msg":"trace[124960317] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"338.898722ms","start":"2026-06-01T19:23:52.309188Z","end":"2026-06-01T19:23:52.648087Z","steps":["trace[124960317] 'process raft request' (duration: 338.652206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:52.648268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.309064Z","time spent":"339.100537ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:52.6483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.309178Z","time spent":"338.997724ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-01T19:23:52.711563Z","caller":"traceutil/trace.go:171","msg":"trace[769996447] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"277.983262ms","start":"2026-06-01T19:23:52.433551Z","end":"2026-06-01T19:23:52.711534Z","steps":["trace[769996447] 'read index received' (duration: 82.767471ms)","trace[769996447] 'applied index is now lower than readState.Index' (duration: 195.214531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:52.711788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.219927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:52.711824Z","caller":"traceutil/trace.go:171","msg":"trace[1698601437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"278.314508ms","start":"2026-06-01T19:23:52.433497Z","end":"2026-06-01T19:23:52.711811Z","steps":["trace[1698601437] 'agreement among raft nodes before linearized reading' (duration: 278.207906ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:52.712036Z","caller":"traceutil/trace.go:171","msg":"trace[174589385] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"338.74741ms","start":"2026-06-01T19:23:52.373236Z","end":"2026-06-01T19:23:52.711983Z","steps":["trace[174589385] 'process raft request' (duration: 338.149958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:52.71227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.37319Z","time spent":"338.966344ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:23:53.037534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.382712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:53.037887Z","caller":"traceutil/trace.go:171","msg":"trace[460549356] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"325.149299ms","start":"2026-06-01T19:23:52.712713Z","end":"2026-06-01T19:23:53.037862Z","steps":["trace[460549356] 'read index received' (duration: 221.292728ms)","trace[460549356] 'applied index is now lower than readState.Index' (duration: 103.855051ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:53.038046Z","caller":"traceutil/trace.go:171","msg":"trace[1163006697] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"380.027558ms","start":"2026-06-01T19:23:52.657998Z","end":"2026-06-01T19:23:53.038026Z","steps":["trace[1163006697] 'process raft request' (duration: 276.052124ms)","trace[1163006697] 'compare' (duration: 103.224089ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:53.038108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.386934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:23:53.038217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.657965Z","time spent":"380.168291ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:53.038072Z","caller":"traceutil/trace.go:171","msg":"trace[88320814] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"377.332433ms","start":"2026-06-01T19:23:52.660675Z","end":"2026-06-01T19:23:53.038007Z","steps":["trace[88320814] 'process raft request' (duration: 377.048208ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.038279Z","caller":"traceutil/trace.go:171","msg":"trace[2000704904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"325.599658ms","start":"2026-06-01T19:23:52.712659Z","end":"2026-06-01T19:23:53.038259Z","steps":["trace[2000704904] 'agreement among raft nodes before linearized reading' (duration: 325.387274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.038448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.712636Z","time spent":"325.796152ms","remote":"127.0.0.1:58964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:23:53.038487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.660666Z","time spent":"377.710351ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:53.311064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.260169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:53.31117Z","caller":"traceutil/trace.go:171","msg":"trace[1533186041] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"273.210431ms","start":"2026-06-01T19:23:53.037942Z","end":"2026-06-01T19:23:53.311153Z","steps":["trace[1533186041] 'read index received' (duration: 104.84347ms)","trace[1533186041] 'applied index is now lower than readState.Index' (duration: 168.365761ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:53.311358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.512116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:23:53.311399Z","caller":"traceutil/trace.go:171","msg":"trace[381525130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"348.612298ms","start":"2026-06-01T19:23:52.962777Z","end":"2026-06-01T19:23:53.311389Z","steps":["trace[381525130] 'agreement among raft nodes before linearized reading' (duration: 348.424104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.311436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.962743Z","time spent":"348.68573ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-01T19:23:53.311532Z","caller":"traceutil/trace.go:171","msg":"trace[2014774478] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"539.287019ms","start":"2026-06-01T19:23:52.772133Z","end":"2026-06-01T19:23:53.31142Z","steps":["trace[2014774478] 'process raft request' (duration: 370.606531ms)","trace[2014774478] 'compare' (duration: 168.048355ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:53.311673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:52.772091Z","time spent":"539.521702ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-01T19:23:53.40757Z","caller":"traceutil/trace.go:171","msg":"trace[1114039627] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"349.540821ms","start":"2026-06-01T19:23:53.058018Z","end":"2026-06-01T19:23:53.407558Z","steps":["trace[1114039627] 'process raft request' (duration: 349.445529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.407719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:53.057996Z","time spent":"349.657473ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:53.40781Z","caller":"traceutil/trace.go:171","msg":"trace[835986063] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"349.861647ms","start":"2026-06-01T19:23:53.05791Z","end":"2026-06-01T19:23:53.407771Z","steps":["trace[835986063] 'process raft request' (duration: 349.476379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.408048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:53.05789Z","time spent":"350.02163ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:53.548474Z","caller":"traceutil/trace.go:171","msg":"trace[970841874] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"141.757529ms","start":"2026-06-01T19:23:53.406692Z","end":"2026-06-01T19:23:53.54845Z","steps":["trace[970841874] 'read index received' (duration: 1.070132ms)","trace[970841874] 'applied index is now lower than readState.Index' (duration: 140.686307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:53.548634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.917963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:23:53.548673Z","caller":"traceutil/trace.go:171","msg":"trace[1602497979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"142.000194ms","start":"2026-06-01T19:23:53.406662Z","end":"2026-06-01T19:23:53.548662Z","steps":["trace[1602497979] 'agreement among raft nodes before linearized reading' (duration: 141.886172ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.548759Z","caller":"traceutil/trace.go:171","msg":"trace[1319607530] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"233.418835ms","start":"2026-06-01T19:23:53.315169Z","end":"2026-06-01T19:23:53.548588Z","steps":["trace[1319607530] 'process raft request' (duration: 201.416654ms)","trace[1319607530] 'compare' (duration: 31.611713ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:53.548885Z","caller":"traceutil/trace.go:171","msg":"trace[1440115681] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"170.27011ms","start":"2026-06-01T19:23:53.378581Z","end":"2026-06-01T19:23:53.548851Z","steps":["trace[1440115681] 'process raft request' (duration: 169.797551ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.758746Z","caller":"traceutil/trace.go:171","msg":"trace[1651374391] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"338.616862ms","start":"2026-06-01T19:23:53.420108Z","end":"2026-06-01T19:23:53.758725Z","steps":["trace[1651374391] 'process raft request' (duration: 311.137632ms)","trace[1651374391] 'compare' (duration: 27.199325ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:53.758906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:53.420088Z","time spent":"338.727694ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:53.759172Z","caller":"traceutil/trace.go:171","msg":"trace[53261161] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"334.743433ms","start":"2026-06-01T19:23:53.424391Z","end":"2026-06-01T19:23:53.759134Z","steps":["trace[53261161] 'process raft request' (duration: 334.212303ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.759287Z","caller":"traceutil/trace.go:171","msg":"trace[462332446] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"206.191489ms","start":"2026-06-01T19:23:53.55307Z","end":"2026-06-01T19:23:53.759262Z","steps":["trace[462332446] 'process raft request' (duration: 205.844332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.759331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:53.424372Z","time spent":"334.873916ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:53.759712Z","caller":"traceutil/trace.go:171","msg":"trace[309385421] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:563; }","duration":"168.031655ms","start":"2026-06-01T19:23:53.591668Z","end":"2026-06-01T19:23:53.7597Z","steps":["trace[309385421] 'read index received' (duration: 139.555745ms)","trace[309385421] 'applied index is now lower than readState.Index' (duration: 28.47501ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:53.75978Z","caller":"traceutil/trace.go:171","msg":"trace[1456708631] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"190.300931ms","start":"2026-06-01T19:23:53.569453Z","end":"2026-06-01T19:23:53.759754Z","steps":["trace[1456708631] 'process raft request' (duration: 189.72469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:53.759862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.181788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:23:53.759903Z","caller":"traceutil/trace.go:171","msg":"trace[367606020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"168.27542ms","start":"2026-06-01T19:23:53.591616Z","end":"2026-06-01T19:23:53.759892Z","steps":["trace[367606020] 'agreement among raft nodes before linearized reading' (duration: 168.138807ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.949393Z","caller":"traceutil/trace.go:171","msg":"trace[639333937] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"184.554936ms","start":"2026-06-01T19:23:53.764816Z","end":"2026-06-01T19:23:53.949371Z","steps":["trace[639333937] 'process raft request' (duration: 184.22624ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.949873Z","caller":"traceutil/trace.go:171","msg":"trace[166544852] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"169.983714ms","start":"2026-06-01T19:23:53.779869Z","end":"2026-06-01T19:23:53.949853Z","steps":["trace[166544852] 'process raft request' (duration: 169.439713ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:53.95012Z","caller":"traceutil/trace.go:171","msg":"trace[662894008] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"170.099806ms","start":"2026-06-01T19:23:53.779969Z","end":"2026-06-01T19:23:53.950068Z","steps":["trace[662894008] 'process raft request' (duration: 169.79621ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.147167Z","caller":"traceutil/trace.go:171","msg":"trace[868863461] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"161.313149ms","start":"2026-06-01T19:23:53.985823Z","end":"2026-06-01T19:23:54.147136Z","steps":["trace[868863461] 'process raft request' (duration: 158.753358ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.147363Z","caller":"traceutil/trace.go:171","msg":"trace[1110073716] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"150.985921ms","start":"2026-06-01T19:23:53.996337Z","end":"2026-06-01T19:23:54.147323Z","steps":["trace[1110073716] 'process raft request' (duration: 150.747517ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.189099Z","caller":"traceutil/trace.go:171","msg":"trace[581178428] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"138.789948ms","start":"2026-06-01T19:23:54.050273Z","end":"2026-06-01T19:23:54.189063Z","steps":["trace[581178428] 'process raft request' (duration: 138.40321ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.189863Z","caller":"traceutil/trace.go:171","msg":"trace[333412769] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"111.516746ms","start":"2026-06-01T19:23:54.078322Z","end":"2026-06-01T19:23:54.189838Z","steps":["trace[333412769] 'process raft request' (duration: 111.101298ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.364493Z","caller":"traceutil/trace.go:171","msg":"trace[707723530] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"150.921559ms","start":"2026-06-01T19:23:54.21355Z","end":"2026-06-01T19:23:54.364471Z","steps":["trace[707723530] 'process raft request' (duration: 146.535482ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.4004Z","caller":"traceutil/trace.go:171","msg":"trace[151483057] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"121.133047ms","start":"2026-06-01T19:23:54.279246Z","end":"2026-06-01T19:23:54.400379Z","steps":["trace[151483057] 'process raft request' (duration: 121.054165ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.400496Z","caller":"traceutil/trace.go:171","msg":"trace[190737883] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"128.148976ms","start":"2026-06-01T19:23:54.272299Z","end":"2026-06-01T19:23:54.400448Z","steps":["trace[190737883] 'process raft request' (duration: 127.876661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:54.740209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.850446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:54.740292Z","caller":"traceutil/trace.go:171","msg":"trace[1533784882] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"308.726663ms","start":"2026-06-01T19:23:54.431553Z","end":"2026-06-01T19:23:54.74028Z","steps":["trace[1533784882] 'read index received' (duration: 81.613611ms)","trace[1533784882] 'applied index is now lower than readState.Index' (duration: 227.112082ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:54.740348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.806425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:54.740371Z","caller":"traceutil/trace.go:171","msg":"trace[883641547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"308.877266ms","start":"2026-06-01T19:23:54.431487Z","end":"2026-06-01T19:23:54.740364Z","steps":["trace[883641547] 'agreement among raft nodes before linearized reading' (duration: 308.824335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:54.740393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:54.431459Z","time spent":"308.929257ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-01T19:23:54.740644Z","caller":"traceutil/trace.go:171","msg":"trace[361910952] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"311.495499ms","start":"2026-06-01T19:23:54.42914Z","end":"2026-06-01T19:23:54.740635Z","steps":["trace[361910952] 'process raft request' (duration: 84.146832ms)","trace[361910952] 'compare' (duration: 226.679443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:54.74069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:54.429116Z","time spent":"311.55145ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:54.742032Z","caller":"traceutil/trace.go:171","msg":"trace[211921534] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"272.525284ms","start":"2026-06-01T19:23:54.469482Z","end":"2026-06-01T19:23:54.742007Z","steps":["trace[211921534] 'process raft request' (duration: 272.195448ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:54.757802Z","caller":"traceutil/trace.go:171","msg":"trace[210049439] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"215.342439ms","start":"2026-06-01T19:23:54.542424Z","end":"2026-06-01T19:23:54.757766Z","steps":["trace[210049439] 'process raft request' (duration: 215.072793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:55.343996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.317002ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:55.344148Z","caller":"traceutil/trace.go:171","msg":"trace[807300397] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:565; }","duration":"454.493197ms","start":"2026-06-01T19:23:54.889635Z","end":"2026-06-01T19:23:55.344128Z","steps":["trace[807300397] 'range keys from in-memory index tree' (duration: 454.295132ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:55.344164Z","caller":"traceutil/trace.go:171","msg":"trace[1040519970] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"544.228879ms","start":"2026-06-01T19:23:54.79989Z","end":"2026-06-01T19:23:55.344118Z","steps":["trace[1040519970] 'read index received' (duration: 33.587678ms)","trace[1040519970] 'applied index is now lower than readState.Index' (duration: 510.640051ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:55.344432Z","caller":"traceutil/trace.go:171","msg":"trace[65568597] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"569.218905ms","start":"2026-06-01T19:23:54.775198Z","end":"2026-06-01T19:23:55.344417Z","steps":["trace[65568597] 'process raft request' (duration: 516.576417ms)","trace[65568597] 'compare' (duration: 52.147879ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:55.344511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.594576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-06-01T19:23:55.344567Z","caller":"traceutil/trace.go:171","msg":"trace[669860226] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:566; }","duration":"544.696468ms","start":"2026-06-01T19:23:54.799853Z","end":"2026-06-01T19:23:55.344549Z","steps":["trace[669860226] 'agreement among raft nodes before linearized reading' (duration: 544.392932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:55.344552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:54.775179Z","time spent":"569.293917ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:55.344607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:54.799838Z","time spent":"544.75955ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29594,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-06-01T19:23:55.428597Z","caller":"traceutil/trace.go:171","msg":"trace[1798986267] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"536.469241ms","start":"2026-06-01T19:23:54.89208Z","end":"2026-06-01T19:23:55.428549Z","steps":["trace[1798986267] 'process raft request' (duration: 536.133755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:55.429404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:54.892041Z","time spent":"537.272757ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:55.782077Z","caller":"traceutil/trace.go:171","msg":"trace[443837973] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"348.03692ms","start":"2026-06-01T19:23:55.434011Z","end":"2026-06-01T19:23:55.782048Z","steps":["trace[443837973] 'read index received' (duration: 280.701623ms)","trace[443837973] 'applied index is now lower than readState.Index' (duration: 67.333717ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:55.782201Z","caller":"traceutil/trace.go:171","msg":"trace[1919338995] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"412.375417ms","start":"2026-06-01T19:23:55.369814Z","end":"2026-06-01T19:23:55.782189Z","steps":["trace[1919338995] 'process raft request' (duration: 344.888137ms)","trace[1919338995] 'compare' (duration: 67.093943ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:55.782297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.369791Z","time spent":"412.438119ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:55.782463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.444407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:55.782565Z","caller":"traceutil/trace.go:171","msg":"trace[1902057843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"348.58993ms","start":"2026-06-01T19:23:55.433964Z","end":"2026-06-01T19:23:55.782554Z","steps":["trace[1902057843] 'agreement among raft nodes before linearized reading' (duration: 348.459328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:55.782597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.433944Z","time spent":"348.645881ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:23:56.282678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:23:56.698456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.760256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:23:56.698616Z","caller":"traceutil/trace.go:171","msg":"trace[489062512] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.246283677s","start":"2026-06-01T19:23:55.452304Z","end":"2026-06-01T19:23:56.698588Z","steps":["trace[489062512] 'process raft request' (duration: 1.089248166s)","trace[489062512] 'compare' (duration: 156.48355ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:56.698708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.45229Z","time spent":"1.24638117s","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:56.782123Z","caller":"traceutil/trace.go:171","msg":"trace[144873636] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"980.883624ms","start":"2026-06-01T19:23:55.801197Z","end":"2026-06-01T19:23:56.782081Z","steps":["trace[144873636] 'process raft request' (duration: 980.630999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:56.782359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.801168Z","time spent":"981.063397ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:56.916105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.102043ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-01T19:23:56.916221Z","caller":"traceutil/trace.go:171","msg":"trace[867908683] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"1.134043722s","start":"2026-06-01T19:23:55.782158Z","end":"2026-06-01T19:23:56.916202Z","steps":["trace[867908683] 'read index received' (duration: 759.624091ms)","trace[867908683] 'applied index is now lower than readState.Index' (duration: 374.418021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:56.916581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151651158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-01T19:23:56.916666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479067292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-06-01T19:23:56.916713Z","caller":"traceutil/trace.go:171","msg":"trace[572771084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.151832462s","start":"2026-06-01T19:23:55.764852Z","end":"2026-06-01T19:23:56.916684Z","steps":["trace[572771084] 'agreement among raft nodes before linearized reading' (duration: 1.151369353s)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:56.916778Z","caller":"traceutil/trace.go:171","msg":"trace[1255790703] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:570; }","duration":"1.479222575s","start":"2026-06-01T19:23:55.437525Z","end":"2026-06-01T19:23:56.916748Z","steps":["trace[1255790703] 'agreement among raft nodes before linearized reading' (duration: 1.478770067s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:56.9168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.764836Z","time spent":"1.151945854s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-01T19:23:56.916842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.437516Z","time spent":"1.479314407s","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":59655,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-01T19:23:56.916912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363274279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:23:56.916955Z","caller":"traceutil/trace.go:171","msg":"trace[1888939509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.363357041s","start":"2026-06-01T19:23:55.553586Z","end":"2026-06-01T19:23:56.916943Z","steps":["trace[1888939509] 'agreement among raft nodes before linearized reading' (duration: 1.362649988s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:56.917006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.553566Z","time spent":"1.363431212s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-01T19:23:56.938608Z","caller":"traceutil/trace.go:171","msg":"trace[710692947] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"189.346232ms","start":"2026-06-01T19:23:56.749235Z","end":"2026-06-01T19:23:56.938581Z","steps":["trace[710692947] 'process raft request' (duration: 188.903344ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.198499Z","caller":"traceutil/trace.go:171","msg":"trace[1655261582] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"386.216753ms","start":"2026-06-01T19:23:56.812134Z","end":"2026-06-01T19:23:57.19835Z","steps":["trace[1655261582] 'process raft request' (duration: 352.333636ms)","trace[1655261582] 'compare' (duration: 33.48595ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:57.198572Z","caller":"traceutil/trace.go:171","msg":"trace[82174823] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"276.193292ms","start":"2026-06-01T19:23:56.92234Z","end":"2026-06-01T19:23:57.198533Z","steps":["trace[82174823] 'process raft request' (duration: 275.978537ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.198995Z","caller":"traceutil/trace.go:171","msg":"trace[1160256483] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"276.658641ms","start":"2026-06-01T19:23:56.922324Z","end":"2026-06-01T19:23:57.198982Z","steps":["trace[1160256483] 'process raft request' (duration: 275.930067ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.198517Z","caller":"traceutil/trace.go:171","msg":"trace[1629339330] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"282.118277ms","start":"2026-06-01T19:23:56.91625Z","end":"2026-06-01T19:23:57.198369Z","steps":["trace[1629339330] 'read index received' (duration: 21.889738ms)","trace[1629339330] 'applied index is now lower than readState.Index' (duration: 260.226769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:57.198636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415083697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:23:57.199218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382699138s","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-06-01T19:23:57.199288Z","caller":"traceutil/trace.go:171","msg":"trace[493267820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"1.382790931s","start":"2026-06-01T19:23:55.816484Z","end":"2026-06-01T19:23:57.199275Z","steps":["trace[493267820] 'agreement among raft nodes before linearized reading' (duration: 1.382643628s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.199324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.816475Z","time spent":"1.382841551s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-01T19:23:57.198768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:56.812104Z","time spent":"386.54165ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:57.19916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244579677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:23:57.199635Z","caller":"traceutil/trace.go:171","msg":"trace[1016889092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"1.245093027s","start":"2026-06-01T19:23:55.954512Z","end":"2026-06-01T19:23:57.199605Z","steps":["trace[1016889092] 'agreement among raft nodes before linearized reading' (duration: 1.244579877s)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.199669Z","caller":"traceutil/trace.go:171","msg":"trace[1339806063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"1.416173089s","start":"2026-06-01T19:23:55.783479Z","end":"2026-06-01T19:23:57.199652Z","steps":["trace[1339806063] 'agreement among raft nodes before linearized reading' (duration: 1.415070308s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.199719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.95448Z","time spent":"1.245200358s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:23:57.199832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:55.783466Z","time spent":"1.416342852s","remote":"127.0.0.1:58964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-01T19:23:57.478218Z","caller":"traceutil/trace.go:171","msg":"trace[1762429930] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"279.129547ms","start":"2026-06-01T19:23:57.199058Z","end":"2026-06-01T19:23:57.478188Z","steps":["trace[1762429930] 'read index received' (duration: 179.104646ms)","trace[1762429930] 'applied index is now lower than readState.Index' (duration: 100.023311ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:57.478306Z","caller":"traceutil/trace.go:171","msg":"trace[146049147] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"507.576444ms","start":"2026-06-01T19:23:56.970698Z","end":"2026-06-01T19:23:57.478274Z","steps":["trace[146049147] 'process raft request' (duration: 407.504452ms)","trace[146049147] 'compare' (duration: 99.813597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:57.47846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:56.970667Z","time spent":"507.695305ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:57.478517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.395377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-06-01T19:23:57.478582Z","caller":"traceutil/trace.go:171","msg":"trace[246372999] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:575; }","duration":"537.511219ms","start":"2026-06-01T19:23:56.941055Z","end":"2026-06-01T19:23:57.478566Z","steps":["trace[246372999] 'agreement among raft nodes before linearized reading' (duration: 537.275935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.478614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:56.941035Z","time spent":"537.57297ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80727,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-06-01T19:23:57.518714Z","caller":"traceutil/trace.go:171","msg":"trace[235532056] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"314.177076ms","start":"2026-06-01T19:23:57.204517Z","end":"2026-06-01T19:23:57.518695Z","steps":["trace[235532056] 'process raft request' (duration: 314.100795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.518831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.2045Z","time spent":"314.268988ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:57.518962Z","caller":"traceutil/trace.go:171","msg":"trace[1889737764] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"304.019189ms","start":"2026-06-01T19:23:57.214905Z","end":"2026-06-01T19:23:57.518925Z","steps":["trace[1889737764] 'process raft request' (duration: 303.738024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.519068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.214885Z","time spent":"304.131871ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:23:57.519102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.124627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:23:57.518735Z","caller":"traceutil/trace.go:171","msg":"trace[1912470666] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"315.005612ms","start":"2026-06-01T19:23:57.203684Z","end":"2026-06-01T19:23:57.51869Z","steps":["trace[1912470666] 'process raft request' (duration: 314.843319ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.519255Z","caller":"traceutil/trace.go:171","msg":"trace[803682713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"311.26122ms","start":"2026-06-01T19:23:57.207931Z","end":"2026-06-01T19:23:57.519192Z","steps":["trace[803682713] 'agreement among raft nodes before linearized reading' (duration: 311.087277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.51966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.207921Z","time spent":"311.72323ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:23:57.519819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.203649Z","time spent":"315.90998ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:57.63478Z","caller":"traceutil/trace.go:171","msg":"trace[1432918438] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"115.864309ms","start":"2026-06-01T19:23:57.518883Z","end":"2026-06-01T19:23:57.634748Z","steps":["trace[1432918438] 'read index received' (duration: 68.877397ms)","trace[1432918438] 'applied index is now lower than readState.Index' (duration: 46.985602ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:57.634948Z","caller":"traceutil/trace.go:171","msg":"trace[1950583253] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"121.63196ms","start":"2026-06-01T19:23:57.513265Z","end":"2026-06-01T19:23:57.634897Z","steps":["trace[1950583253] 'process raft request' (duration: 74.558857ms)","trace[1950583253] 'compare' (duration: 46.710347ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:57.635113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.444171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:23:57.635211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.808454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-06-01T19:23:57.635235Z","caller":"traceutil/trace.go:171","msg":"trace[440870090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"114.636704ms","start":"2026-06-01T19:23:57.520571Z","end":"2026-06-01T19:23:57.635208Z","steps":["trace[440870090] 'agreement among raft nodes before linearized reading' (duration: 114.40911ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.635247Z","caller":"traceutil/trace.go:171","msg":"trace[682528590] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:579; }","duration":"120.951347ms","start":"2026-06-01T19:23:57.514286Z","end":"2026-06-01T19:23:57.635237Z","steps":["trace[682528590] 'agreement among raft nodes before linearized reading' (duration: 120.620721ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:57.990536Z","caller":"traceutil/trace.go:171","msg":"trace[803311577] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"339.993048ms","start":"2026-06-01T19:23:57.650512Z","end":"2026-06-01T19:23:57.990505Z","steps":["trace[803311577] 'process raft request' (duration: 339.795454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:57.990745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.650472Z","time spent":"340.164671ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:58.138456Z","caller":"traceutil/trace.go:171","msg":"trace[1891063070] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"444.123308ms","start":"2026-06-01T19:23:57.694316Z","end":"2026-06-01T19:23:58.138439Z","steps":["trace[1891063070] 'read index received' (duration: 296.021485ms)","trace[1891063070] 'applied index is now lower than readState.Index' (duration: 148.101043ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:58.138602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.280461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-01T19:23:58.138626Z","caller":"traceutil/trace.go:171","msg":"trace[1656178471] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:581; }","duration":"444.357493ms","start":"2026-06-01T19:23:57.694261Z","end":"2026-06-01T19:23:58.138618Z","steps":["trace[1656178471] 'agreement among raft nodes before linearized reading' (duration: 444.25348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:58.138651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.694237Z","time spent":"444.408444ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7732,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-06-01T19:23:58.138811Z","caller":"traceutil/trace.go:171","msg":"trace[1693818368] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"446.090107ms","start":"2026-06-01T19:23:57.692669Z","end":"2026-06-01T19:23:58.138759Z","steps":["trace[1693818368] 'process raft request' (duration: 445.360352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:58.139083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:57.692628Z","time spent":"446.290261ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:58.334286Z","caller":"traceutil/trace.go:171","msg":"trace[1039075495] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"149.513268ms","start":"2026-06-01T19:23:58.184745Z","end":"2026-06-01T19:23:58.334259Z","steps":["trace[1039075495] 'read index received' (duration: 103.120505ms)","trace[1039075495] 'applied index is now lower than readState.Index' (duration: 46.391753ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:58.334531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.756464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-06-01T19:23:58.334574Z","caller":"traceutil/trace.go:171","msg":"trace[683420199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:582; }","duration":"149.842245ms","start":"2026-06-01T19:23:58.184719Z","end":"2026-06-01T19:23:58.334561Z","steps":["trace[683420199] 'agreement among raft nodes before linearized reading' (duration: 149.636961ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:58.334548Z","caller":"traceutil/trace.go:171","msg":"trace[1107597214] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"152.073488ms","start":"2026-06-01T19:23:58.182437Z","end":"2026-06-01T19:23:58.334511Z","steps":["trace[1107597214] 'process raft request' (duration: 105.565303ms)","trace[1107597214] 'compare' (duration: 46.085047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:58.871637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812565,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-01T19:23:59.306585Z","caller":"traceutil/trace.go:171","msg":"trace[1030435597] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"946.463368ms","start":"2026-06-01T19:23:58.360095Z","end":"2026-06-01T19:23:59.306558Z","steps":["trace[1030435597] 'process raft request' (duration: 901.842826ms)","trace[1030435597] 'compare' (duration: 44.272305ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:59.306752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:58.360071Z","time spent":"946.58904ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:59.312451Z","caller":"traceutil/trace.go:171","msg":"trace[1664744652] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:603; }","duration":"942.957871ms","start":"2026-06-01T19:23:58.36947Z","end":"2026-06-01T19:23:59.312428Z","steps":["trace[1664744652] 'read index received' (duration: 892.270942ms)","trace[1664744652] 'applied index is now lower than readState.Index' (duration: 50.685769ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:59.312566Z","caller":"traceutil/trace.go:171","msg":"trace[2097378910] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"509.460864ms","start":"2026-06-01T19:23:58.803097Z","end":"2026-06-01T19:23:59.312558Z","steps":["trace[2097378910] 'process raft request' (duration: 509.211609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:59.312666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:58.803072Z","time spent":"509.525225ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:59.31266Z","caller":"traceutil/trace.go:171","msg":"trace[1176793674] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"934.918295ms","start":"2026-06-01T19:23:58.377708Z","end":"2026-06-01T19:23:59.312627Z","steps":["trace[1176793674] 'process raft request' (duration: 934.414625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:59.312754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.872517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:23:59.312926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.439471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-01T19:23:59.31295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.37828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-01T19:23:59.31299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.511721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-06-01T19:23:59.312995Z","caller":"traceutil/trace.go:171","msg":"trace[1058380142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"109.536063ms","start":"2026-06-01T19:23:59.203442Z","end":"2026-06-01T19:23:59.312978Z","steps":["trace[1058380142] 'agreement among raft nodes before linearized reading' (duration: 109.324859ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:59.313021Z","caller":"traceutil/trace.go:171","msg":"trace[1045008638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"109.462211ms","start":"2026-06-01T19:23:59.203539Z","end":"2026-06-01T19:23:59.313002Z","steps":["trace[1045008638] 'agreement among raft nodes before linearized reading' (duration: 109.220167ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:59.313031Z","caller":"traceutil/trace.go:171","msg":"trace[1139588519] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:585; }","duration":"943.573642ms","start":"2026-06-01T19:23:58.369445Z","end":"2026-06-01T19:23:59.313018Z","steps":["trace[1139588519] 'agreement among raft nodes before linearized reading' (duration: 943.433719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:59.313076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:58.369433Z","time spent":"943.634183ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98737,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-06-01T19:23:59.312962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:58.377685Z","time spent":"935.181ms","remote":"127.0.0.1:59010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:23:59.312846Z","caller":"traceutil/trace.go:171","msg":"trace[495308741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"878.00763ms","start":"2026-06-01T19:23:58.434819Z","end":"2026-06-01T19:23:59.312827Z","steps":["trace[495308741] 'agreement among raft nodes before linearized reading' (duration: 877.866838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:59.313989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:23:58.4348Z","time spent":"879.172842ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-01T19:23:59.555531Z","caller":"traceutil/trace.go:171","msg":"trace[284161075] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"170.062708ms","start":"2026-06-01T19:23:59.385442Z","end":"2026-06-01T19:23:59.555505Z","steps":["trace[284161075] 'read index received' (duration: 125.062099ms)","trace[284161075] 'applied index is now lower than readState.Index' (duration: 44.999599ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:59.555789Z","caller":"traceutil/trace.go:171","msg":"trace[1117223752] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"176.811238ms","start":"2026-06-01T19:23:59.378965Z","end":"2026-06-01T19:23:59.555777Z","steps":["trace[1117223752] 'process raft request' (duration: 131.645496ms)","trace[1117223752] 'compare' (duration: 44.798335ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:23:59.556078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.61845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-06-01T19:23:59.556113Z","caller":"traceutil/trace.go:171","msg":"trace[1260185264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:588; }","duration":"170.694991ms","start":"2026-06-01T19:23:59.385406Z","end":"2026-06-01T19:23:59.556101Z","steps":["trace[1260185264] 'agreement among raft nodes before linearized reading' (duration: 170.561088ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:23:59.767596Z","caller":"traceutil/trace.go:171","msg":"trace[586022142] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"202.927607ms","start":"2026-06-01T19:23:59.56463Z","end":"2026-06-01T19:23:59.767558Z","steps":["trace[586022142] 'process raft request' (duration: 192.406866ms)","trace[586022142] 'compare' (duration: 10.215895ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:59.767887Z","caller":"traceutil/trace.go:171","msg":"trace[522988758] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"185.954063ms","start":"2026-06-01T19:23:59.581906Z","end":"2026-06-01T19:23:59.76786Z","steps":["trace[522988758] 'read index received' (duration: 26.371244ms)","trace[522988758] 'applied index is now lower than readState.Index' (duration: 159.581829ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:23:59.76834Z","caller":"traceutil/trace.go:171","msg":"trace[740866680] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"164.775668ms","start":"2026-06-01T19:23:59.603531Z","end":"2026-06-01T19:23:59.768307Z","steps":["trace[740866680] 'process raft request' (duration: 163.925942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:23:59.768865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.923231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-06-01T19:23:59.768922Z","caller":"traceutil/trace.go:171","msg":"trace[1477604500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:591; }","duration":"187.028872ms","start":"2026-06-01T19:23:59.581876Z","end":"2026-06-01T19:23:59.768905Z","steps":["trace[1477604500] 'agreement among raft nodes before linearized reading' (duration: 186.080134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:01.85235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:02.018401Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029209137s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:02.018821Z","caller":"traceutil/trace.go:171","msg":"trace[585587248] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"668.150332ms","start":"2026-06-01T19:24:01.35065Z","end":"2026-06-01T19:24:02.0188Z","steps":["trace[585587248] 'read index received' (duration: 668.003729ms)","trace[585587248] 'applied index is now lower than readState.Index' (duration: 145.313µs)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:02.018922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:00.989064Z","time spent":"1.02985579s","remote":"127.0.0.1:58994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-01T19:24:02.019049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.16341ms","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-06-01T19:24:02.019113Z","caller":"traceutil/trace.go:171","msg":"trace[1922186476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"247.268352ms","start":"2026-06-01T19:24:01.771822Z","end":"2026-06-01T19:24:02.01909Z","steps":["trace[1922186476] 'agreement among raft nodes before linearized reading' (duration: 247.15417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:02.019116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.385606ms","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-06-01T19:24:02.019186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.555599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:02.019207Z","caller":"traceutil/trace.go:171","msg":"trace[875899068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"584.62667ms","start":"2026-06-01T19:24:01.434572Z","end":"2026-06-01T19:24:02.019199Z","steps":["trace[875899068] 'agreement among raft nodes before linearized reading' (duration: 584.595249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:02.019221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:01.434541Z","time spent":"584.675791ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-01T19:24:02.019224Z","caller":"traceutil/trace.go:171","msg":"trace[70005173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"668.514349ms","start":"2026-06-01T19:24:01.350682Z","end":"2026-06-01T19:24:02.019196Z","steps":["trace[70005173] 'agreement among raft nodes before linearized reading' (duration: 668.300475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:02.019238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.467067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-01T19:24:02.019235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.628749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-01T19:24:02.019283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:01.350678Z","time spent":"668.5914ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-01T19:24:02.019303Z","caller":"traceutil/trace.go:171","msg":"trace[1101459953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"407.734101ms","start":"2026-06-01T19:24:01.611552Z","end":"2026-06-01T19:24:02.019287Z","steps":["trace[1101459953] 'agreement among raft nodes before linearized reading' (duration: 407.495177ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:02.019303Z","caller":"traceutil/trace.go:171","msg":"trace[463648913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"668.665451ms","start":"2026-06-01T19:24:01.350611Z","end":"2026-06-01T19:24:02.019277Z","steps":["trace[463648913] 'agreement among raft nodes before linearized reading' (duration: 668.390016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:02.019351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:01.611534Z","time spent":"407.808273ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:24:02.019352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:01.350593Z","time spent":"668.749973ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-01T19:24:02.777558Z","caller":"traceutil/trace.go:171","msg":"trace[1076882169] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"189.990728ms","start":"2026-06-01T19:24:02.587541Z","end":"2026-06-01T19:24:02.777532Z","steps":["trace[1076882169] 'process raft request' (duration: 188.922698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:03.328635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.59654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:03.328759Z","caller":"traceutil/trace.go:171","msg":"trace[857673645] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:600; }","duration":"488.801904ms","start":"2026-06-01T19:24:02.839934Z","end":"2026-06-01T19:24:03.328735Z","steps":["trace[857673645] 'range keys from in-memory index tree' (duration: 488.485368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:03.328816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:02.839916Z","time spent":"488.884425ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-06-01T19:24:04.020991Z","caller":"traceutil/trace.go:171","msg":"trace[1427047456] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"210.333177ms","start":"2026-06-01T19:24:03.810623Z","end":"2026-06-01T19:24:04.020956Z","steps":["trace[1427047456] 'process raft request' (duration: 209.655735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:04.648271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.438831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:24:04.648644Z","caller":"traceutil/trace.go:171","msg":"trace[1731230131] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"294.992498ms","start":"2026-06-01T19:24:04.353623Z","end":"2026-06-01T19:24:04.648615Z","steps":["trace[1731230131] 'process raft request' (duration: 294.871186ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:04.648671Z","caller":"traceutil/trace.go:171","msg":"trace[634547864] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"565.016226ms","start":"2026-06-01T19:24:04.083605Z","end":"2026-06-01T19:24:04.648621Z","steps":["trace[634547864] 'process raft request' (duration: 272.105147ms)","trace[634547864] 'compare' (duration: 292.133385ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:04.648811Z","caller":"traceutil/trace.go:171","msg":"trace[1300983802] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"559.280621ms","start":"2026-06-01T19:24:04.089455Z","end":"2026-06-01T19:24:04.648736Z","steps":["trace[1300983802] 'read index received' (duration: 266.194539ms)","trace[1300983802] 'applied index is now lower than readState.Index' (duration: 293.079652ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:04.648871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.083587Z","time spent":"565.179079ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-01T19:24:04.64909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.620338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-01T19:24:04.649126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.752212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:24:04.649135Z","caller":"traceutil/trace.go:171","msg":"trace[228264962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"559.73883ms","start":"2026-06-01T19:24:04.089383Z","end":"2026-06-01T19:24:04.649122Z","steps":["trace[228264962] 'agreement among raft nodes before linearized reading' (duration: 559.526326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:04.649171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.089353Z","time spent":"559.810592ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-01T19:24:04.649266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.871105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:24:04.649166Z","caller":"traceutil/trace.go:171","msg":"trace[126298760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"558.811834ms","start":"2026-06-01T19:24:04.090339Z","end":"2026-06-01T19:24:04.649151Z","steps":["trace[126298760] 'agreement among raft nodes before linearized reading' (duration: 558.698382ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:04.649297Z","caller":"traceutil/trace.go:171","msg":"trace[572619358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"559.081008ms","start":"2026-06-01T19:24:04.090208Z","end":"2026-06-01T19:24:04.649289Z","steps":["trace[572619358] 'agreement among raft nodes before linearized reading' (duration: 558.976677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:04.649302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.090332Z","time spent":"558.962857ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:24:04.649325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.0902Z","time spent":"559.118359ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:04.649244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.808244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-01T19:24:04.649369Z","caller":"traceutil/trace.go:171","msg":"trace[1517159187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"558.989537ms","start":"2026-06-01T19:24:04.090371Z","end":"2026-06-01T19:24:04.649361Z","steps":["trace[1517159187] 'agreement among raft nodes before linearized reading' (duration: 558.686061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:04.649388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.090356Z","time spent":"559.026287ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:05.244144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.750873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:24:05.24437Z","caller":"traceutil/trace.go:171","msg":"trace[1143974581] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:625; }","duration":"583.250391ms","start":"2026-06-01T19:24:04.661102Z","end":"2026-06-01T19:24:05.244353Z","steps":["trace[1143974581] 'read index received' (duration: 37.720743ms)","trace[1143974581] 'applied index is now lower than readState.Index' (duration: 545.528357ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:05.244457Z","caller":"traceutil/trace.go:171","msg":"trace[948332531] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"585.563744ms","start":"2026-06-01T19:24:04.658858Z","end":"2026-06-01T19:24:05.244421Z","steps":["trace[948332531] 'process raft request' (duration: 585.400401ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:05.244486Z","caller":"traceutil/trace.go:171","msg":"trace[71776806] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"589.467095ms","start":"2026-06-01T19:24:04.654942Z","end":"2026-06-01T19:24:05.244409Z","steps":["trace[71776806] 'process raft request' (duration: 374.366595ms)","trace[71776806] 'compare' (duration: 214.601211ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:05.244634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.541106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:05.244686Z","caller":"traceutil/trace.go:171","msg":"trace[1628033333] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:607; }","duration":"583.613287ms","start":"2026-06-01T19:24:04.661054Z","end":"2026-06-01T19:24:05.244667Z","steps":["trace[1628033333] 'agreement among raft nodes before linearized reading' (duration: 583.555706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:05.244677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.658841Z","time spent":"585.716476ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:05.244729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.661038Z","time spent":"583.682929ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":29,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-01T19:24:05.244756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.654921Z","time spent":"589.710649ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:24:05.244465Z","caller":"traceutil/trace.go:171","msg":"trace[486981100] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"585.37967ms","start":"2026-06-01T19:24:04.659075Z","end":"2026-06-01T19:24:05.244455Z","steps":["trace[486981100] 'process raft request' (duration: 585.234948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:05.244576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.936979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:05.244932Z","caller":"traceutil/trace.go:171","msg":"trace[1691472654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"156.350037ms","start":"2026-06-01T19:24:05.088567Z","end":"2026-06-01T19:24:05.244917Z","steps":["trace[1691472654] 'agreement among raft nodes before linearized reading' (duration: 155.931219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:05.244941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:04.659033Z","time spent":"585.864579ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:05.24421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.155714ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:05.245351Z","caller":"traceutil/trace.go:171","msg":"trace[1171829833] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:604; }","duration":"356.347845ms","start":"2026-06-01T19:24:04.888977Z","end":"2026-06-01T19:24:05.245324Z","steps":["trace[1171829833] 'range keys from in-memory index tree' (duration: 354.798557ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:06.355932Z","caller":"traceutil/trace.go:171","msg":"trace[357922348] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"125.568387ms","start":"2026-06-01T19:24:06.230306Z","end":"2026-06-01T19:24:06.355875Z","steps":["trace[357922348] 'process raft request' (duration: 122.96591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:06.665984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.726959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:24:06.666191Z","caller":"traceutil/trace.go:171","msg":"trace[852366838] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"245.848369ms","start":"2026-06-01T19:24:06.420311Z","end":"2026-06-01T19:24:06.666159Z","steps":["trace[852366838] 'process raft request' (duration: 124.600189ms)","trace[852366838] 'compare' (duration: 120.542246ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:06.712496Z","caller":"traceutil/trace.go:171","msg":"trace[1292424076] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"278.122983ms","start":"2026-06-01T19:24:06.434346Z","end":"2026-06-01T19:24:06.712469Z","steps":["trace[1292424076] 'read index received' (duration: 110.570924ms)","trace[1292424076] 'applied index is now lower than readState.Index' (duration: 167.550249ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:06.712707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.220326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:06.712818Z","caller":"traceutil/trace.go:171","msg":"trace[854726119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"278.51464ms","start":"2026-06-01T19:24:06.434289Z","end":"2026-06-01T19:24:06.712804Z","steps":["trace[854726119] 'agreement among raft nodes before linearized reading' (duration: 278.231565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:07.750671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:08.286813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:08.69084Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.565872347s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:08.692118Z","caller":"traceutil/trace.go:171","msg":"trace[909651893] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"1.442417723s","start":"2026-06-01T19:24:07.249678Z","end":"2026-06-01T19:24:08.692096Z","steps":["trace[909651893] 'read index received' (duration: 1.441352954s)","trace[909651893] 'applied index is now lower than readState.Index' (duration: 1.063719ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:08.692251Z","caller":"traceutil/trace.go:171","msg":"trace[1815608637] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.568049566s","start":"2026-06-01T19:24:07.124159Z","end":"2026-06-01T19:24:08.692209Z","steps":["trace[1815608637] 'process raft request' (duration: 1.567288663s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:08.692457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:07.124132Z","time spent":"1.568190168s","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:08.692496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24729066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:08.692661Z","caller":"traceutil/trace.go:171","msg":"trace[1772274681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.247548024s","start":"2026-06-01T19:24:07.445072Z","end":"2026-06-01T19:24:08.69262Z","steps":["trace[1772274681] 'agreement among raft nodes before linearized reading' (duration: 1.247243639s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:08.692745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:07.445024Z","time spent":"1.247704287s","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:24:08.692813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442712488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-01T19:24:08.692889Z","caller":"traceutil/trace.go:171","msg":"trace[1183322201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"1.442818321s","start":"2026-06-01T19:24:07.250053Z","end":"2026-06-01T19:24:08.692872Z","steps":["trace[1183322201] 'agreement among raft nodes before linearized reading' (duration: 1.442254501s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:08.692933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:07.250043Z","time spent":"1.442883182s","remote":"127.0.0.1:59204","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-06-01T19:24:08.693106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44334022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:24:08.69329Z","caller":"traceutil/trace.go:171","msg":"trace[496308586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.443557944s","start":"2026-06-01T19:24:07.249698Z","end":"2026-06-01T19:24:08.693255Z","steps":["trace[496308586] 'agreement among raft nodes before linearized reading' (duration: 1.442880872s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:08.693389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443711007s","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-06-01T19:24:08.693428Z","caller":"traceutil/trace.go:171","msg":"trace[697505177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.443819958s","start":"2026-06-01T19:24:07.249599Z","end":"2026-06-01T19:24:08.693419Z","steps":["trace[697505177] 'agreement among raft nodes before linearized reading' (duration: 1.442621867s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:08.693458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:07.249576Z","time spent":"1.443875649s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:08.693505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:07.24967Z","time spent":"1.443738146s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:13.296842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:13.575593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190405068s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:13.576371Z","caller":"traceutil/trace.go:171","msg":"trace[1876273729] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"779.620786ms","start":"2026-06-01T19:24:12.796713Z","end":"2026-06-01T19:24:13.576334Z","steps":["trace[1876273729] 'read index received' (duration: 779.101027ms)","trace[1876273729] 'applied index is now lower than readState.Index' (duration: 518.109µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:13.576796Z","caller":"traceutil/trace.go:171","msg":"trace[1864977940] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.191745691s","start":"2026-06-01T19:24:12.385014Z","end":"2026-06-01T19:24:13.57676Z","steps":["trace[1864977940] 'process raft request' (duration: 1.190976138s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.576896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.149476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-01T19:24:13.576956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:12.384976Z","time spent":"1.191872475s","remote":"127.0.0.1:59132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:24:13.577004Z","caller":"traceutil/trace.go:171","msg":"trace[2029877740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"780.291909ms","start":"2026-06-01T19:24:12.796677Z","end":"2026-06-01T19:24:13.576969Z","steps":["trace[2029877740] 'agreement among raft nodes before linearized reading' (duration: 780.005273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.576979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.413083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-01T19:24:13.577047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.519606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-01T19:24:13.577074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:12.796662Z","time spent":"780.39652ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:24:13.577059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.851316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:13.577088Z","caller":"traceutil/trace.go:171","msg":"trace[222834167] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:639; }","duration":"196.578675ms","start":"2026-06-01T19:24:13.380483Z","end":"2026-06-01T19:24:13.577061Z","steps":["trace[222834167] 'agreement among raft nodes before linearized reading' (duration: 196.36361ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:13.577107Z","caller":"traceutil/trace.go:171","msg":"trace[545363876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"779.603847ms","start":"2026-06-01T19:24:12.797482Z","end":"2026-06-01T19:24:13.577086Z","steps":["trace[545363876] 'agreement among raft nodes before linearized reading' (duration: 779.367103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.577161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:12.797467Z","time spent":"779.682149ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-01T19:24:13.577162Z","caller":"traceutil/trace.go:171","msg":"trace[984359075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"143.003829ms","start":"2026-06-01T19:24:13.43413Z","end":"2026-06-01T19:24:13.577134Z","steps":["trace[984359075] 'agreement among raft nodes before linearized reading' (duration: 142.778516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.577163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.856804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-01T19:24:13.577265Z","caller":"traceutil/trace.go:171","msg":"trace[1633239782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"774.987286ms","start":"2026-06-01T19:24:12.802255Z","end":"2026-06-01T19:24:13.577243Z","steps":["trace[1633239782] 'agreement among raft nodes before linearized reading' (duration: 774.687631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.577308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:12.80225Z","time spent":"775.047447ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:13.577603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.190482ms","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-06-01T19:24:13.577659Z","caller":"traceutil/trace.go:171","msg":"trace[378219993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"774.252243ms","start":"2026-06-01T19:24:12.803397Z","end":"2026-06-01T19:24:13.577649Z","steps":["trace[378219993] 'agreement among raft nodes before linearized reading' (duration: 773.47109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:13.577695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:12.803393Z","time spent":"774.287515ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-01T19:24:15.090794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.498540429s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:15.091663Z","caller":"traceutil/trace.go:171","msg":"trace[636786866] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.509523718s","start":"2026-06-01T19:24:13.582117Z","end":"2026-06-01T19:24:15.091641Z","steps":["trace[636786866] 'process raft request' (duration: 1.508958198s)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:15.091715Z","caller":"traceutil/trace.go:171","msg":"trace[1370314652] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:663; }","duration":"1.505223604s","start":"2026-06-01T19:24:13.586467Z","end":"2026-06-01T19:24:15.09169Z","steps":["trace[1370314652] 'read index received' (duration: 5.838691ms)","trace[1370314652] 'applied index is now lower than readState.Index' (duration: 1.499383843s)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:15.091747Z","caller":"traceutil/trace.go:171","msg":"trace[700619936] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.507456882s","start":"2026-06-01T19:24:13.584277Z","end":"2026-06-01T19:24:15.091734Z","steps":["trace[700619936] 'process raft request' (duration: 1.50732227s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:15.091792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:13.582102Z","time spent":"1.5096183s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:15.091886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505402777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mbfxp\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-06-01T19:24:15.09191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:13.584261Z","time spent":"1.507562454s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:24:15.091927Z","caller":"traceutil/trace.go:171","msg":"trace[6961964] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mbfxp; range_end:; response_count:1; response_revision:643; }","duration":"1.505464937s","start":"2026-06-01T19:24:13.586452Z","end":"2026-06-01T19:24:15.091917Z","steps":["trace[6961964] 'agreement among raft nodes before linearized reading' (duration: 1.505330195s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:15.091965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:13.586445Z","time spent":"1.505512089s","remote":"127.0.0.1:59132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17169,"request content":"key:\"/registry/pods/kube-system/cilium-mbfxp\" "} {"level":"info","ts":"2026-06-01T19:24:15.091684Z","caller":"traceutil/trace.go:171","msg":"trace[1773097987] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.507537554s","start":"2026-06-01T19:24:13.584116Z","end":"2026-06-01T19:24:15.091653Z","steps":["trace[1773097987] 'process raft request' (duration: 1.507357461s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:15.092921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:13.584089Z","time spent":"1.508735755s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:15.592661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:16.093707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743812707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:16.288464Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.197489327s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:16.288964Z","caller":"traceutil/trace.go:171","msg":"trace[1139834172] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.750062085s","start":"2026-06-01T19:24:14.538871Z","end":"2026-06-01T19:24:16.288933Z","steps":["trace[1139834172] 'process raft request' (duration: 1.749833391s)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:16.289051Z","caller":"traceutil/trace.go:171","msg":"trace[1092790100] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"1.197271054s","start":"2026-06-01T19:24:15.091759Z","end":"2026-06-01T19:24:16.28903Z","steps":["trace[1092790100] 'read index received' (duration: 1.196839367s)","trace[1092790100] 'applied index is now lower than readState.Index' (duration: 429.767µs)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:16.289148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:14.538838Z","time spent":"1.750218598s","remote":"127.0.0.1:59128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:16.28938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657492707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-01T19:24:16.289457Z","caller":"traceutil/trace.go:171","msg":"trace[1610389608] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:645; }","duration":"1.65764585s","start":"2026-06-01T19:24:14.631795Z","end":"2026-06-01T19:24:16.289441Z","steps":["trace[1610389608] 'agreement among raft nodes before linearized reading' (duration: 1.657405286s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.289507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:14.631758Z","time spent":"1.657738041s","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:16.289574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.326779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:16.289643Z","caller":"traceutil/trace.go:171","msg":"trace[2138468134] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:645; }","duration":"983.455751ms","start":"2026-06-01T19:24:15.306165Z","end":"2026-06-01T19:24:16.289621Z","steps":["trace[2138468134] 'agreement among raft nodes before linearized reading' (duration: 983.37482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.289691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:15.306135Z","time spent":"983.541943ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:16.289494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.172323617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:24:16.289716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.373963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-01T19:24:16.289755Z","caller":"traceutil/trace.go:171","msg":"trace[553170074] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:645; }","duration":"2.172594412s","start":"2026-06-01T19:24:14.117149Z","end":"2026-06-01T19:24:16.289744Z","steps":["trace[553170074] 'agreement among raft nodes before linearized reading' (duration: 2.172291327s)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:16.289766Z","caller":"traceutil/trace.go:171","msg":"trace[551950322] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:645; }","duration":"422.459114ms","start":"2026-06-01T19:24:15.867292Z","end":"2026-06-01T19:24:16.289751Z","steps":["trace[551950322] 'agreement among raft nodes before linearized reading' (duration: 422.348472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.289794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:14.117076Z","time spent":"2.172705694s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:16.289808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:15.86724Z","time spent":"422.555276ms","remote":"127.0.0.1:59294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:16.289804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202892559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:16.289913Z","caller":"traceutil/trace.go:171","msg":"trace[542211611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.203086022s","start":"2026-06-01T19:24:15.086802Z","end":"2026-06-01T19:24:16.289888Z","steps":["trace[542211611] 'agreement among raft nodes before linearized reading' (duration: 1.202860779s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.289969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:15.086772Z","time spent":"1.203187835s","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:24:16.290004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.139593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:24:16.290055Z","caller":"traceutil/trace.go:171","msg":"trace[924428164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"694.226304ms","start":"2026-06-01T19:24:15.595814Z","end":"2026-06-01T19:24:16.290041Z","steps":["trace[924428164] 'agreement among raft nodes before linearized reading' (duration: 694.060501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.290109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:15.595795Z","time spent":"694.301276ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-01T19:24:16.290121Z","caller":"traceutil/trace.go:171","msg":"trace[1750331348] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.494649733s","start":"2026-06-01T19:24:14.795457Z","end":"2026-06-01T19:24:16.290107Z","steps":["trace[1750331348] 'process raft request' (duration: 1.493415973s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.290195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.02206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-01T19:24:16.290204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:14.795428Z","time spent":"1.494722786s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:24:16.290245Z","caller":"traceutil/trace.go:171","msg":"trace[730840800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"856.108102ms","start":"2026-06-01T19:24:15.434122Z","end":"2026-06-01T19:24:16.290231Z","steps":["trace[730840800] 'agreement among raft nodes before linearized reading' (duration: 856.012881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:16.290284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:15.434102Z","time spent":"856.171224ms","remote":"127.0.0.1:58964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-01T19:24:20.872773Z","caller":"traceutil/trace.go:171","msg":"trace[1735340358] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"146.311393ms","start":"2026-06-01T19:24:20.726435Z","end":"2026-06-01T19:24:20.872747Z","steps":["trace[1735340358] 'read index received' (duration: 73.809427ms)","trace[1735340358] 'applied index is now lower than readState.Index' (duration: 72.500606ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:20.872896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.444955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:20.872934Z","caller":"traceutil/trace.go:171","msg":"trace[1410914787] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:712; }","duration":"146.542146ms","start":"2026-06-01T19:24:20.726377Z","end":"2026-06-01T19:24:20.872919Z","steps":["trace[1410914787] 'agreement among raft nodes before linearized reading' (duration: 146.464775ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:20.872941Z","caller":"traceutil/trace.go:171","msg":"trace[1122376199] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"262.929025ms","start":"2026-06-01T19:24:20.609978Z","end":"2026-06-01T19:24:20.872907Z","steps":["trace[1122376199] 'process raft request' (duration: 190.302268ms)","trace[1122376199] 'compare' (duration: 72.325842ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:23.372746Z","caller":"traceutil/trace.go:171","msg":"trace[1598278888] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"158.90271ms","start":"2026-06-01T19:24:23.213821Z","end":"2026-06-01T19:24:23.372723Z","steps":["trace[1598278888] 'read index received' (duration: 138.902787ms)","trace[1598278888] 'applied index is now lower than readState.Index' (duration: 19.998833ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:23.37284Z","caller":"traceutil/trace.go:171","msg":"trace[601371364] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"214.584072ms","start":"2026-06-01T19:24:23.158228Z","end":"2026-06-01T19:24:23.372812Z","steps":["trace[601371364] 'process raft request' (duration: 194.68813ms)","trace[601371364] 'compare' (duration: 19.466055ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:23.373006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.172015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:24:23.373043Z","caller":"traceutil/trace.go:171","msg":"trace[729399438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"159.255716ms","start":"2026-06-01T19:24:23.213777Z","end":"2026-06-01T19:24:23.373033Z","steps":["trace[729399438] 'agreement among raft nodes before linearized reading' (duration: 159.088723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:23.373405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.622018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-01T19:24:23.373478Z","caller":"traceutil/trace.go:171","msg":"trace[464796108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"155.743741ms","start":"2026-06-01T19:24:23.217717Z","end":"2026-06-01T19:24:23.37346Z","steps":["trace[464796108] 'agreement among raft nodes before linearized reading' (duration: 155.470106ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:23.59039Z","caller":"traceutil/trace.go:171","msg":"trace[1916203005] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:769; }","duration":"159.43141ms","start":"2026-06-01T19:24:23.430934Z","end":"2026-06-01T19:24:23.590365Z","steps":["trace[1916203005] 'read index received' (duration: 86.404788ms)","trace[1916203005] 'applied index is now lower than readState.Index' (duration: 73.025272ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:23.590527Z","caller":"traceutil/trace.go:171","msg":"trace[210056087] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"213.709918ms","start":"2026-06-01T19:24:23.376804Z","end":"2026-06-01T19:24:23.590514Z","steps":["trace[210056087] 'process raft request' (duration: 140.500664ms)","trace[210056087] 'compare' (duration: 72.870759ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:23.590646Z","caller":"traceutil/trace.go:171","msg":"trace[399723516] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"213.051567ms","start":"2026-06-01T19:24:23.377587Z","end":"2026-06-01T19:24:23.590638Z","steps":["trace[399723516] 'process raft request' (duration: 212.729642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:23.590928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.977278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:23.590968Z","caller":"traceutil/trace.go:171","msg":"trace[1350469888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"160.027119ms","start":"2026-06-01T19:24:23.430927Z","end":"2026-06-01T19:24:23.590954Z","steps":["trace[1350469888] 'agreement among raft nodes before linearized reading' (duration: 159.960018ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:26.896634Z","caller":"traceutil/trace.go:171","msg":"trace[274077927] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"186.924689ms","start":"2026-06-01T19:24:26.70968Z","end":"2026-06-01T19:24:26.896605Z","steps":["trace[274077927] 'process raft request' (duration: 169.036995ms)","trace[274077927] 'compare' (duration: 17.743212ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:27.224289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.758826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:24:27.224384Z","caller":"traceutil/trace.go:171","msg":"trace[546623879] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"318.422167ms","start":"2026-06-01T19:24:26.905945Z","end":"2026-06-01T19:24:27.224367Z","steps":["trace[546623879] 'process raft request' (duration: 116.507168ms)","trace[546623879] 'compare' (duration: 201.664915ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:27.224609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:26.905928Z","time spent":"318.61969ms","remote":"127.0.0.1:59128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-01T19:24:27.242339Z","caller":"traceutil/trace.go:171","msg":"trace[1756146979] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:792; }","duration":"327.176944ms","start":"2026-06-01T19:24:26.91513Z","end":"2026-06-01T19:24:27.242307Z","steps":["trace[1756146979] 'read index received' (duration: 107.337682ms)","trace[1756146979] 'applied index is now lower than readState.Index' (duration: 219.838272ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:27.242442Z","caller":"traceutil/trace.go:171","msg":"trace[1364580713] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"332.106523ms","start":"2026-06-01T19:24:26.910299Z","end":"2026-06-01T19:24:27.242406Z","steps":["trace[1364580713] 'process raft request' (duration: 331.861259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:27.242499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.331778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:27.242572Z","caller":"traceutil/trace.go:171","msg":"trace[688609123] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:764; }","duration":"327.448989ms","start":"2026-06-01T19:24:26.915106Z","end":"2026-06-01T19:24:27.242555Z","steps":["trace[688609123] 'agreement among raft nodes before linearized reading' (duration: 327.311877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:27.242608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:26.910283Z","time spent":"332.240596ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:27.24263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:26.915099Z","time spent":"327.51601ms","remote":"127.0.0.1:59240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:27.271146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.751943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-01T19:24:27.272592Z","caller":"traceutil/trace.go:171","msg":"trace[884812872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"314.238126ms","start":"2026-06-01T19:24:26.958335Z","end":"2026-06-01T19:24:27.272574Z","steps":["trace[884812872] 'agreement among raft nodes before linearized reading' (duration: 312.693552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:27.27269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:26.958318Z","time spent":"314.360509ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:27.271451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.949433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-01T19:24:27.272838Z","caller":"traceutil/trace.go:171","msg":"trace[1357641637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"126.361606ms","start":"2026-06-01T19:24:27.146463Z","end":"2026-06-01T19:24:27.272825Z","steps":["trace[1357641637] 'agreement among raft nodes before linearized reading' (duration: 124.933583ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:27.787168Z","caller":"traceutil/trace.go:171","msg":"trace[541965192] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"178.945176ms","start":"2026-06-01T19:24:27.608188Z","end":"2026-06-01T19:24:27.787133Z","steps":["trace[541965192] 'process raft request' (duration: 131.883522ms)","trace[541965192] 'compare' (duration: 46.932232ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:29.61051Z","caller":"traceutil/trace.go:171","msg":"trace[1916330071] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"177.010462ms","start":"2026-06-01T19:24:29.433473Z","end":"2026-06-01T19:24:29.610483Z","steps":["trace[1916330071] 'read index received' (duration: 123.768752ms)","trace[1916330071] 'applied index is now lower than readState.Index' (duration: 53.24066ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:29.610662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.163845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:29.6107Z","caller":"traceutil/trace.go:171","msg":"trace[1154531081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"177.269947ms","start":"2026-06-01T19:24:29.43342Z","end":"2026-06-01T19:24:29.61069Z","steps":["trace[1154531081] 'agreement among raft nodes before linearized reading' (duration: 177.179075ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:29.610542Z","caller":"traceutil/trace.go:171","msg":"trace[862537676] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"281.439492ms","start":"2026-06-01T19:24:29.329062Z","end":"2026-06-01T19:24:29.610501Z","steps":["trace[862537676] 'process raft request' (duration: 228.241012ms)","trace[862537676] 'compare' (duration: 53.069948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:24:33.004062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743813089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:33.50443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743813089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:34.005206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279214743813089,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-01T19:24:34.121088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.692805128s","expected-duration":"1s"} {"level":"info","ts":"2026-06-01T19:24:34.121624Z","caller":"traceutil/trace.go:171","msg":"trace[1321861776] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"1.617557483s","start":"2026-06-01T19:24:32.503942Z","end":"2026-06-01T19:24:34.1215Z","steps":["trace[1321861776] 'read index received' (duration: 1.61733778s)","trace[1321861776] 'applied index is now lower than readState.Index' (duration: 218.283µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:24:34.121633Z","caller":"traceutil/trace.go:171","msg":"trace[436134807] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.693442207s","start":"2026-06-01T19:24:32.428156Z","end":"2026-06-01T19:24:34.121599Z","steps":["trace[436134807] 'process raft request' (duration: 1.693136493s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.121802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:32.428127Z","time spent":"1.693585359s","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:24:34.121828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241462995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-01T19:24:34.121896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617921508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-662rc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-01T19:24:34.121971Z","caller":"traceutil/trace.go:171","msg":"trace[787761631] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-662rc; range_end:; response_count:1; response_revision:802; }","duration":"1.61803187s","start":"2026-06-01T19:24:32.50391Z","end":"2026-06-01T19:24:34.121941Z","steps":["trace[787761631] 'agreement among raft nodes before linearized reading' (duration: 1.617809157s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.121983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.626443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-01T19:24:34.122035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:32.503894Z","time spent":"1.618127743s","remote":"127.0.0.1:59132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-662rc\" "} {"level":"warn","ts":"2026-06-01T19:24:34.122138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.029932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-01T19:24:34.122165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.904906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-01T19:24:34.122216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.342523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:24:34.122223Z","caller":"traceutil/trace.go:171","msg":"trace[990416277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"821.997547ms","start":"2026-06-01T19:24:33.300215Z","end":"2026-06-01T19:24:34.122212Z","steps":["trace[990416277] 'agreement among raft nodes before linearized reading' (duration: 821.870006ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:34.122259Z","caller":"traceutil/trace.go:171","msg":"trace[1655923652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"689.383414ms","start":"2026-06-01T19:24:33.432864Z","end":"2026-06-01T19:24:34.122247Z","steps":["trace[1655923652] 'agreement among raft nodes before linearized reading' (duration: 689.305323ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:24:34.122229Z","caller":"traceutil/trace.go:171","msg":"trace[806517921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"499.155013ms","start":"2026-06-01T19:24:33.623051Z","end":"2026-06-01T19:24:34.122206Z","steps":["trace[806517921] 'agreement among raft nodes before linearized reading' (duration: 498.766947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.122267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.300186Z","time spent":"822.070748ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:34.122291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.432817Z","time spent":"689.468587ms","remote":"127.0.0.1:58956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-01T19:24:34.122373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.622962Z","time spent":"499.341857ms","remote":"127.0.0.1:59204","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-06-01T19:24:34.122038Z","caller":"traceutil/trace.go:171","msg":"trace[1568973903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"311.721365ms","start":"2026-06-01T19:24:33.8103Z","end":"2026-06-01T19:24:34.122022Z","steps":["trace[1568973903] 'agreement among raft nodes before linearized reading' (duration: 311.582302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.122446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.810278Z","time spent":"312.157512ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-01T19:24:34.122558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.622579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:24:34.122616Z","caller":"traceutil/trace.go:171","msg":"trace[759295370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"415.732111ms","start":"2026-06-01T19:24:33.706869Z","end":"2026-06-01T19:24:34.122601Z","steps":["trace[759295370] 'agreement among raft nodes before linearized reading' (duration: 415.596739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.122656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.706839Z","time spent":"415.806432ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:24:34.122671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.968406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-01T19:24:34.122736Z","caller":"traceutil/trace.go:171","msg":"trace[898358681] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:802; }","duration":"915.092387ms","start":"2026-06-01T19:24:33.207629Z","end":"2026-06-01T19:24:34.122721Z","steps":["trace[898358681] 'agreement among raft nodes before linearized reading' (duration: 914.960155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.12279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.207611Z","time spent":"915.163548ms","remote":"127.0.0.1:59406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-06-01T19:24:34.122773Z","caller":"traceutil/trace.go:171","msg":"trace[1788764084] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:802; }","duration":"1.241525696s","start":"2026-06-01T19:24:32.88033Z","end":"2026-06-01T19:24:34.121856Z","steps":["trace[1788764084] 'agreement among raft nodes before linearized reading' (duration: 1.241408774s)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.122865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:32.880275Z","time spent":"1.242569572s","remote":"127.0.0.1:59298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-01T19:24:34.123951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.950674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-01T19:24:34.124032Z","caller":"traceutil/trace.go:171","msg":"trace[1195611994] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:802; }","duration":"871.090885ms","start":"2026-06-01T19:24:33.252916Z","end":"2026-06-01T19:24:34.124007Z","steps":["trace[1195611994] 'agreement among raft nodes before linearized reading' (duration: 869.655224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:24:34.124762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:24:33.252892Z","time spent":"871.848927ms","remote":"127.0.0.1:59370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-01T19:25:26.656157Z","caller":"traceutil/trace.go:171","msg":"trace[557603425] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"100.621215ms","start":"2026-06-01T19:25:26.555508Z","end":"2026-06-01T19:25:26.65613Z","steps":["trace[557603425] 'read index received' (duration: 100.443513ms)","trace[557603425] 'applied index is now lower than readState.Index' (duration: 176.372µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:26.656235Z","caller":"traceutil/trace.go:171","msg":"trace[1857224538] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"127.778986ms","start":"2026-06-01T19:25:26.528391Z","end":"2026-06-01T19:25:26.65617Z","steps":["trace[1857224538] 'process raft request' (duration: 127.561053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:26.65637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.835138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:25:26.656416Z","caller":"traceutil/trace.go:171","msg":"trace[632091557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"100.921418ms","start":"2026-06-01T19:25:26.555481Z","end":"2026-06-01T19:25:26.656402Z","steps":["trace[632091557] 'agreement among raft nodes before linearized reading' (duration: 100.756417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:31.086618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.102317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:25:31.086778Z","caller":"traceutil/trace.go:171","msg":"trace[1441621862] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"340.2727ms","start":"2026-06-01T19:25:30.746484Z","end":"2026-06-01T19:25:31.086756Z","steps":["trace[1441621862] 'read index received' (duration: 147.873679ms)","trace[1441621862] 'applied index is now lower than readState.Index' (duration: 192.397541ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:31.086891Z","caller":"traceutil/trace.go:171","msg":"trace[1047300364] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"357.540867ms","start":"2026-06-01T19:25:30.72932Z","end":"2026-06-01T19:25:31.086861Z","steps":["trace[1047300364] 'process raft request' (duration: 165.098076ms)","trace[1047300364] 'compare' (duration: 191.865974ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:25:31.086955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.478392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-01T19:25:31.086983Z","caller":"traceutil/trace.go:171","msg":"trace[1636759046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1119; }","duration":"340.548862ms","start":"2026-06-01T19:25:30.746425Z","end":"2026-06-01T19:25:31.086974Z","steps":["trace[1636759046] 'agreement among raft nodes before linearized reading' (duration: 340.428001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:31.087003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:25:30.729298Z","time spent":"357.647278ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:25:31.087019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:25:30.746403Z","time spent":"340.605764ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-01T19:25:31.087032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.097969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-01T19:25:31.087125Z","caller":"traceutil/trace.go:171","msg":"trace[1072791190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1119; }","duration":"267.240141ms","start":"2026-06-01T19:25:30.819865Z","end":"2026-06-01T19:25:31.087105Z","steps":["trace[1072791190] 'agreement among raft nodes before linearized reading' (duration: 267.021338ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:31.261803Z","caller":"traceutil/trace.go:171","msg":"trace[1027655721] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"169.199969ms","start":"2026-06-01T19:25:31.092577Z","end":"2026-06-01T19:25:31.261777Z","steps":["trace[1027655721] 'process raft request' (duration: 169.154329ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:31.261923Z","caller":"traceutil/trace.go:171","msg":"trace[2004187323] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"169.370272ms","start":"2026-06-01T19:25:31.092519Z","end":"2026-06-01T19:25:31.261889Z","steps":["trace[2004187323] 'process raft request' (duration: 169.087578ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:33.435597Z","caller":"traceutil/trace.go:171","msg":"trace[1064161064] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"164.255059ms","start":"2026-06-01T19:25:33.271308Z","end":"2026-06-01T19:25:33.435563Z","steps":["trace[1064161064] 'process raft request' (duration: 137.101315ms)","trace[1064161064] 'compare' (duration: 26.918632ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:34.943422Z","caller":"traceutil/trace.go:171","msg":"trace[1007503512] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"183.907607ms","start":"2026-06-01T19:25:34.759496Z","end":"2026-06-01T19:25:34.943403Z","steps":["trace[1007503512] 'read index received' (duration: 183.756175ms)","trace[1007503512] 'applied index is now lower than readState.Index' (duration: 150.492µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:34.9435Z","caller":"traceutil/trace.go:171","msg":"trace[388069577] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"186.350457ms","start":"2026-06-01T19:25:34.757112Z","end":"2026-06-01T19:25:34.943463Z","steps":["trace[388069577] 'process raft request' (duration: 186.183035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:34.943571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.053429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:25:34.943633Z","caller":"traceutil/trace.go:171","msg":"trace[762556368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"184.096879ms","start":"2026-06-01T19:25:34.759493Z","end":"2026-06-01T19:25:34.94359Z","steps":["trace[762556368] 'agreement among raft nodes before linearized reading' (duration: 183.983728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:35.542213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.150782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-01T19:25:35.542286Z","caller":"traceutil/trace.go:171","msg":"trace[25356257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"102.262814ms","start":"2026-06-01T19:25:35.440005Z","end":"2026-06-01T19:25:35.542268Z","steps":["trace[25356257] 'range keys from in-memory index tree' (duration: 101.991071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:35.542385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.687399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:25:35.542454Z","caller":"traceutil/trace.go:171","msg":"trace[1524363760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"108.7575ms","start":"2026-06-01T19:25:35.433683Z","end":"2026-06-01T19:25:35.542441Z","steps":["trace[1524363760] 'range keys from in-memory index tree' (duration: 108.585988ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:35.542791Z","caller":"traceutil/trace.go:171","msg":"trace[1052979781] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"102.678368ms","start":"2026-06-01T19:25:35.440096Z","end":"2026-06-01T19:25:35.542774Z","steps":["trace[1052979781] 'process raft request' (duration: 99.672233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:39.160806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.288622ms","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-06-01T19:25:39.161054Z","caller":"traceutil/trace.go:171","msg":"trace[2117479443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1144; }","duration":"109.576837ms","start":"2026-06-01T19:25:39.051458Z","end":"2026-06-01T19:25:39.161035Z","steps":["trace[2117479443] 'range keys from in-memory index tree' (duration: 109.146711ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:43.406356Z","caller":"traceutil/trace.go:171","msg":"trace[489509041] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"220.200108ms","start":"2026-06-01T19:25:43.186129Z","end":"2026-06-01T19:25:43.406329Z","steps":["trace[489509041] 'read index received' (duration: 219.951535ms)","trace[489509041] 'applied index is now lower than readState.Index' (duration: 247.473µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:43.406408Z","caller":"traceutil/trace.go:171","msg":"trace[1704268532] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"357.598965ms","start":"2026-06-01T19:25:43.048774Z","end":"2026-06-01T19:25:43.406373Z","steps":["trace[1704268532] 'process raft request' (duration: 357.397883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:43.406647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-01T19:25:43.048761Z","time spent":"357.724076ms","remote":"127.0.0.1:59204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-01T19:25:43.406656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.332863ms","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-06-01T19:25:43.406727Z","caller":"traceutil/trace.go:171","msg":"trace[303154574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"209.435904ms","start":"2026-06-01T19:25:43.197274Z","end":"2026-06-01T19:25:43.40671Z","steps":["trace[303154574] 'agreement among raft nodes before linearized reading' (duration: 209.238701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:43.406656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.505452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:25:43.406972Z","caller":"traceutil/trace.go:171","msg":"trace[1787858380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"220.874116ms","start":"2026-06-01T19:25:43.186079Z","end":"2026-06-01T19:25:43.406953Z","steps":["trace[1787858380] 'agreement among raft nodes before linearized reading' (duration: 220.410291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:43.651332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.761879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:25:43.651464Z","caller":"traceutil/trace.go:171","msg":"trace[611500821] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"215.045348ms","start":"2026-06-01T19:25:43.436397Z","end":"2026-06-01T19:25:43.651442Z","steps":["trace[611500821] 'read index received' (duration: 72.101327ms)","trace[611500821] 'applied index is now lower than readState.Index' (duration: 142.942281ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:43.651771Z","caller":"traceutil/trace.go:171","msg":"trace[403314062] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"237.662461ms","start":"2026-06-01T19:25:43.414088Z","end":"2026-06-01T19:25:43.65175Z","steps":["trace[403314062] 'process raft request' (duration: 94.387186ms)","trace[403314062] 'compare' (duration: 142.648067ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:25:43.651836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.436832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:25:43.651897Z","caller":"traceutil/trace.go:171","msg":"trace[1081619075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"215.539554ms","start":"2026-06-01T19:25:43.436341Z","end":"2026-06-01T19:25:43.651881Z","steps":["trace[1081619075] 'agreement among raft nodes before linearized reading' (duration: 215.439773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:43.697902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.593636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-01T19:25:43.697949Z","caller":"traceutil/trace.go:171","msg":"trace[1421382133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1161; }","duration":"210.686958ms","start":"2026-06-01T19:25:43.48725Z","end":"2026-06-01T19:25:43.697937Z","steps":["trace[1421382133] 'agreement among raft nodes before linearized reading' (duration: 210.558246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:25:43.876643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.585665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:25:43.876754Z","caller":"traceutil/trace.go:171","msg":"trace[881351422] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"163.825784ms","start":"2026-06-01T19:25:43.712908Z","end":"2026-06-01T19:25:43.876733Z","steps":["trace[881351422] 'process raft request' (duration: 53.081717ms)","trace[881351422] 'compare' (duration: 110.337913ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:25:44.231435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.301525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:25:44.231498Z","caller":"traceutil/trace.go:171","msg":"trace[277354701] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1163; }","duration":"240.420527ms","start":"2026-06-01T19:25:43.99106Z","end":"2026-06-01T19:25:44.231481Z","steps":["trace[277354701] 'count revisions from in-memory index tree' (duration: 240.181304ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:53.858777Z","caller":"traceutil/trace.go:171","msg":"trace[451236873] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"115.512202ms","start":"2026-06-01T19:25:53.743239Z","end":"2026-06-01T19:25:53.858751Z","steps":["trace[451236873] 'read index received' (duration: 54.171325ms)","trace[451236873] 'applied index is now lower than readState.Index' (duration: 61.339887ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:25:53.858863Z","caller":"traceutil/trace.go:171","msg":"trace[1604475428] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"128.832082ms","start":"2026-06-01T19:25:53.729992Z","end":"2026-06-01T19:25:53.858824Z","steps":["trace[1604475428] 'process raft request' (duration: 67.430315ms)","trace[1604475428] 'compare' (duration: 61.206835ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:25:53.858976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.719554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-01T19:25:53.859013Z","caller":"traceutil/trace.go:171","msg":"trace[600100660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"115.802294ms","start":"2026-06-01T19:25:53.743203Z","end":"2026-06-01T19:25:53.859005Z","steps":["trace[600100660] 'agreement among raft nodes before linearized reading' (duration: 115.642993ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:25:57.598097Z","caller":"traceutil/trace.go:171","msg":"trace[1839986746] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"108.31935ms","start":"2026-06-01T19:25:57.489748Z","end":"2026-06-01T19:25:57.598067Z","steps":["trace[1839986746] 'process raft request' (duration: 108.125588ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:01.877302Z","caller":"traceutil/trace.go:171","msg":"trace[1741781981] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"218.486831ms","start":"2026-06-01T19:26:01.65879Z","end":"2026-06-01T19:26:01.877277Z","steps":["trace[1741781981] 'read index received' (duration: 218.307889ms)","trace[1741781981] 'applied index is now lower than readState.Index' (duration: 177.762µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:01.877424Z","caller":"traceutil/trace.go:171","msg":"trace[428122512] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"260.341108ms","start":"2026-06-01T19:26:01.617035Z","end":"2026-06-01T19:26:01.877376Z","steps":["trace[428122512] 'process raft request' (duration: 260.026155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:01.877685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.25521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-01T19:26:01.877776Z","caller":"traceutil/trace.go:171","msg":"trace[54866568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"126.403861ms","start":"2026-06-01T19:26:01.751353Z","end":"2026-06-01T19:26:01.877757Z","steps":["trace[54866568] 'agreement among raft nodes before linearized reading' (duration: 126.139219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:01.87811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.30529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-01T19:26:01.878162Z","caller":"traceutil/trace.go:171","msg":"trace[961255946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"219.390091ms","start":"2026-06-01T19:26:01.658754Z","end":"2026-06-01T19:26:01.878144Z","steps":["trace[961255946] 'agreement among raft nodes before linearized reading' (duration: 218.661263ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:14.212105Z","caller":"traceutil/trace.go:171","msg":"trace[1482624278] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"113.726262ms","start":"2026-06-01T19:26:14.098344Z","end":"2026-06-01T19:26:14.21207Z","steps":["trace[1482624278] 'process raft request' (duration: 113.52124ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:14.219571Z","caller":"traceutil/trace.go:171","msg":"trace[1559557548] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"121.019361ms","start":"2026-06-01T19:26:14.098535Z","end":"2026-06-01T19:26:14.219555Z","steps":["trace[1559557548] 'process raft request' (duration: 120.656057ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:14.219714Z","caller":"traceutil/trace.go:171","msg":"trace[1805089861] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"118.366973ms","start":"2026-06-01T19:26:14.101307Z","end":"2026-06-01T19:26:14.219674Z","steps":["trace[1805089861] 'process raft request' (duration: 118.024259ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.115174Z","caller":"traceutil/trace.go:171","msg":"trace[1837294980] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"187.28382ms","start":"2026-06-01T19:26:15.927865Z","end":"2026-06-01T19:26:16.115149Z","steps":["trace[1837294980] 'read index received' (duration: 56.576507ms)","trace[1837294980] 'applied index is now lower than readState.Index' (duration: 130.706003ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:16.115197Z","caller":"traceutil/trace.go:171","msg":"trace[1927227252] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"221.819606ms","start":"2026-06-01T19:26:15.893352Z","end":"2026-06-01T19:26:16.115172Z","steps":["trace[1927227252] 'process raft request' (duration: 180.656748ms)","trace[1927227252] 'compare' (duration: 41.000336ms)"],"step_count":2} {"level":"warn","ts":"2026-06-01T19:26:16.115387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.456942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-01T19:26:16.115463Z","caller":"traceutil/trace.go:171","msg":"trace[1741826411] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1285; }","duration":"187.603933ms","start":"2026-06-01T19:26:15.927819Z","end":"2026-06-01T19:26:16.115423Z","steps":["trace[1741826411] 'agreement among raft nodes before linearized reading' (duration: 187.424571ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.117271Z","caller":"traceutil/trace.go:171","msg":"trace[212420581] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"128.431648ms","start":"2026-06-01T19:26:15.988815Z","end":"2026-06-01T19:26:16.117247Z","steps":["trace[212420581] 'process raft request' (duration: 128.310447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.344997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.660835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-01T19:26:16.345609Z","caller":"traceutil/trace.go:171","msg":"trace[794517085] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"128.429207ms","start":"2026-06-01T19:26:16.217142Z","end":"2026-06-01T19:26:16.345571Z","steps":["trace[794517085] 'read index received' (duration: 127.75184ms)","trace[794517085] 'applied index is now lower than readState.Index' (duration: 676.367µs)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:16.345807Z","caller":"traceutil/trace.go:171","msg":"trace[830924552] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"224.490043ms","start":"2026-06-01T19:26:16.121269Z","end":"2026-06-01T19:26:16.34576Z","steps":["trace[830924552] 'process raft request' (duration: 224.120149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.346058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.850962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-01T19:26:16.346166Z","caller":"traceutil/trace.go:171","msg":"trace[1949588373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"129.051145ms","start":"2026-06-01T19:26:16.217093Z","end":"2026-06-01T19:26:16.346144Z","steps":["trace[1949588373] 'agreement among raft nodes before linearized reading' (duration: 128.706ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.380924Z","caller":"traceutil/trace.go:171","msg":"trace[611802999] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"155.923268ms","start":"2026-06-01T19:26:16.22498Z","end":"2026-06-01T19:26:16.380903Z","steps":["trace[611802999] 'process raft request' (duration: 155.509404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.38093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.195871ms","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-06-01T19:26:16.381073Z","caller":"traceutil/trace.go:171","msg":"trace[1558465578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"156.325802ms","start":"2026-06-01T19:26:16.224676Z","end":"2026-06-01T19:26:16.381002Z","steps":["trace[1558465578] 'agreement among raft nodes before linearized reading' (duration: 155.995838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.381155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.515864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-01T19:26:16.381264Z","caller":"traceutil/trace.go:171","msg":"trace[1889588940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"157.663477ms","start":"2026-06-01T19:26:16.223585Z","end":"2026-06-01T19:26:16.381248Z","steps":["trace[1889588940] 'agreement among raft nodes before linearized reading' (duration: 157.312963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.593007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.408364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-01T19:26:16.593138Z","caller":"traceutil/trace.go:171","msg":"trace[38121283] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"204.82513ms","start":"2026-06-01T19:26:16.388291Z","end":"2026-06-01T19:26:16.593116Z","steps":["trace[38121283] 'process raft request' (duration: 104.203264ms)","trace[38121283] 'compare' (duration: 100.239542ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:16.594009Z","caller":"traceutil/trace.go:171","msg":"trace[1850333988] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1344; }","duration":"205.156673ms","start":"2026-06-01T19:26:16.388836Z","end":"2026-06-01T19:26:16.593993Z","steps":["trace[1850333988] 'read index received' (duration: 103.631898ms)","trace[1850333988] 'applied index is now lower than readState.Index' (duration: 101.523725ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:16.594252Z","caller":"traceutil/trace.go:171","msg":"trace[1019118637] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"205.906532ms","start":"2026-06-01T19:26:16.388333Z","end":"2026-06-01T19:26:16.59424Z","steps":["trace[1019118637] 'process raft request' (duration: 205.508978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.594481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.483457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-01T19:26:16.594465Z","caller":"traceutil/trace.go:171","msg":"trace[202984564] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"206.055793ms","start":"2026-06-01T19:26:16.388382Z","end":"2026-06-01T19:26:16.594438Z","steps":["trace[202984564] 'process raft request' (duration: 205.534297ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.59452Z","caller":"traceutil/trace.go:171","msg":"trace[1010532210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"160.574508ms","start":"2026-06-01T19:26:16.433934Z","end":"2026-06-01T19:26:16.594509Z","steps":["trace[1010532210] 'agreement among raft nodes before linearized reading' (duration: 160.510877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-01T19:26:16.594509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.659769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-06-01T19:26:16.594569Z","caller":"traceutil/trace.go:171","msg":"trace[1752215699] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1293; }","duration":"205.73454ms","start":"2026-06-01T19:26:16.388819Z","end":"2026-06-01T19:26:16.594554Z","steps":["trace[1752215699] 'agreement among raft nodes before linearized reading' (duration: 205.580578ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.594601Z","caller":"traceutil/trace.go:171","msg":"trace[1737995600] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"205.856671ms","start":"2026-06-01T19:26:16.388698Z","end":"2026-06-01T19:26:16.594554Z","steps":["trace[1737995600] 'process raft request' (duration: 205.256785ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:16.717533Z","caller":"traceutil/trace.go:171","msg":"trace[5982308] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"100.395292ms","start":"2026-06-01T19:26:16.617111Z","end":"2026-06-01T19:26:16.717506Z","steps":["trace[5982308] 'process raft request' (duration: 87.277289ms)","trace[5982308] 'compare' (duration: 13.001572ms)"],"step_count":2} {"level":"info","ts":"2026-06-01T19:26:19.275231Z","caller":"traceutil/trace.go:171","msg":"trace[579984058] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"115.906275ms","start":"2026-06-01T19:26:19.159297Z","end":"2026-06-01T19:26:19.275203Z","steps":["trace[579984058] 'process raft request' (duration: 115.722563ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:26.518842Z","caller":"traceutil/trace.go:171","msg":"trace[21593964] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"104.632782ms","start":"2026-06-01T19:26:26.414194Z","end":"2026-06-01T19:26:26.518827Z","steps":["trace[21593964] 'process raft request' (duration: 104.092357ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:26.907916Z","caller":"traceutil/trace.go:171","msg":"trace[124682426] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"237.67885ms","start":"2026-06-01T19:26:26.670201Z","end":"2026-06-01T19:26:26.90788Z","steps":["trace[124682426] 'process raft request' (duration: 237.376007ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:26.907919Z","caller":"traceutil/trace.go:171","msg":"trace[1209814551] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"237.096084ms","start":"2026-06-01T19:26:26.670799Z","end":"2026-06-01T19:26:26.907895Z","steps":["trace[1209814551] 'process raft request' (duration: 236.968902ms)"],"step_count":1} {"level":"info","ts":"2026-06-01T19:26:26.908093Z","caller":"traceutil/trace.go:171","msg":"trace[883902621] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"236.477337ms","start":"2026-06-01T19:26:26.67161Z","end":"2026-06-01T19:26:26.908088Z","steps":["trace[883902621] 'process raft request' (duration: 236.221734ms)"],"step_count":1}