{"level":"warn","ts":"2026-08-12T01:06:43.604938Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T01:06:43.605031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-08-12T01:06:43.605104Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T01:06:43.605116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-08-12T01:06:43.605136Z","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-08-12T01:06:43.605728Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-08-12T01:06:43.605805Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-08-12T01:06:43.610629Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.602354ms"} {"level":"info","ts":"2026-08-12T01:06:43.616666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-08-12T01:06:43.616761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T01:06:43.616787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-08-12T01:06:43.616863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T01:06:43.616878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-08-12T01:06:43.616916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-08-12T01:06:43.643633Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T01:06:43.645498Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T01:06:43.647113Z","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-08-12T01:06:43.649555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T01:06:43.649726Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T01:06:43.649888Z","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-08-12T01:06:43.650016Z","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-08-12T01:06:43.650036Z","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-08-12T01:06:43.651321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-08-12T01:06:43.651997Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-08-12T01:06:43.653538Z","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-08-12T01:06:43.653753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T01:06:43.653794Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T01:06:43.654157Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-08-12T01:06:43.654194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-08-12T01:06:43.91794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T01:06:43.91802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T01:06:43.918043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-08-12T01:06:43.918059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-08-12T01:06:43.918067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-08-12T01:06:43.918079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-08-12T01:06:43.918093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-08-12T01:06:43.919297Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T01:06:43.920236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T01:06:43.920231Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T01:06:43.920402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T01:06:43.920603Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T01:06:43.920669Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T01:06:43.920653Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T01:06:43.920842Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T01:06:43.920881Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T01:06:43.924168Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T01:06:43.934268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"warn","ts":"2026-08-12T01:06:52.552491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.280383ms","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-08-12T01:06:52.552681Z","caller":"traceutil/trace.go:171","msg":"trace[1072466175] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:308; }","duration":"142.490428ms","start":"2026-08-12T01:06:52.41017Z","end":"2026-08-12T01:06:52.552661Z","steps":["trace[1072466175] 'range keys from in-memory index tree' (duration: 142.16783ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:06:58.210684Z","caller":"traceutil/trace.go:171","msg":"trace[1348024130] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"541.002116ms","start":"2026-08-12T01:06:57.669655Z","end":"2026-08-12T01:06:58.210657Z","steps":["trace[1348024130] 'process raft request' (duration: 540.840512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:58.211657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:57.66963Z","time spent":"541.12812ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:06:59.048464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.001359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:06:59.048549Z","caller":"traceutil/trace.go:171","msg":"trace[368871988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"576.132011ms","start":"2026-08-12T01:06:58.472401Z","end":"2026-08-12T01:06:59.048533Z","steps":["trace[368871988] 'range keys from in-memory index tree' (duration: 575.910736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:59.048584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:58.472384Z","time spent":"576.193383ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:06:59.048746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.033257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"info","ts":"2026-08-12T01:06:59.048842Z","caller":"traceutil/trace.go:171","msg":"trace[54580212] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:369; }","duration":"619.182361ms","start":"2026-08-12T01:06:58.429636Z","end":"2026-08-12T01:06:59.048818Z","steps":["trace[54580212] 'range keys from in-memory index tree' (duration: 618.916974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:59.048896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:58.429615Z","time spent":"619.271583ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4818,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-08-12T01:06:59.549717Z","caller":"traceutil/trace.go:171","msg":"trace[325360314] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"792.20701ms","start":"2026-08-12T01:06:58.757483Z","end":"2026-08-12T01:06:59.54969Z","steps":["trace[325360314] 'process raft request' (duration: 791.966594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:06:59.549873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:58.757454Z","time spent":"792.340694ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:00.801321Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.251764492s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T01:07:00.801682Z","caller":"traceutil/trace.go:171","msg":"trace[1560238833] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"1.458441349s","start":"2026-08-12T01:06:59.343212Z","end":"2026-08-12T01:07:00.801653Z","steps":["trace[1560238833] 'read index received' (duration: 206.3703ms)","trace[1560238833] 'applied index is now lower than readState.Index' (duration: 1.252069779s)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:00.802016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458777337s","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-08-12T01:07:00.802051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458813878s","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-08-12T01:07:00.802103Z","caller":"traceutil/trace.go:171","msg":"trace[1274929988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.4588994s","start":"2026-08-12T01:06:59.34318Z","end":"2026-08-12T01:07:00.802079Z","steps":["trace[1274929988] 'agreement among raft nodes before linearized reading' (duration: 1.458702896s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.802213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:59.343166Z","time spent":"1.459033313s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T01:07:00.80223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.639981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T01:07:00.802263Z","caller":"traceutil/trace.go:171","msg":"trace[1442614583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"586.673032ms","start":"2026-08-12T01:07:00.215579Z","end":"2026-08-12T01:07:00.802252Z","steps":["trace[1442614583] 'agreement among raft nodes before linearized reading' (duration: 586.6173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.802303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.215523Z","time spent":"586.772394ms","remote":"127.0.0.1:57596","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-08-12T01:07:00.802114Z","caller":"traceutil/trace.go:171","msg":"trace[493691648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.458913281s","start":"2026-08-12T01:06:59.343188Z","end":"2026-08-12T01:07:00.802102Z","steps":["trace[493691648] 'agreement among raft nodes before linearized reading' (duration: 1.458794188s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.802492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359302928s","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-08-12T01:07:00.802528Z","caller":"traceutil/trace.go:171","msg":"trace[1607849119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"1.35935872s","start":"2026-08-12T01:06:59.443161Z","end":"2026-08-12T01:07:00.802519Z","steps":["trace[1607849119] 'agreement among raft nodes before linearized reading' (duration: 1.359301359s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.802558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:59.44315Z","time spent":"1.359402611s","remote":"127.0.0.1:57596","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-08-12T01:07:00.802551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:59.343176Z","time spent":"1.459343281s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T01:07:00.802497Z","caller":"traceutil/trace.go:171","msg":"trace[265737501] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.745058375s","start":"2026-08-12T01:06:59.057396Z","end":"2026-08-12T01:07:00.802454Z","steps":["trace[265737501] 'process raft request' (duration: 1.744100391s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.802639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377589629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:00.802756Z","caller":"traceutil/trace.go:171","msg":"trace[182886675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"1.377735733s","start":"2026-08-12T01:06:59.425011Z","end":"2026-08-12T01:07:00.802747Z","steps":["trace[182886675] 'agreement among raft nodes before linearized reading' (duration: 1.37760968s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:00.80279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:59.424991Z","time spent":"1.377789924s","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:07:00.802954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:06:59.057368Z","time spent":"1.74530643s","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:01.149196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.632143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:07:01.149396Z","caller":"traceutil/trace.go:171","msg":"trace[225137760] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:385; }","duration":"337.441636ms","start":"2026-08-12T01:07:00.811942Z","end":"2026-08-12T01:07:01.149384Z","steps":["trace[225137760] 'read index received' (duration: 81.547957ms)","trace[225137760] 'applied index is now lower than readState.Index' (duration: 255.893029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:01.14945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.505918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:01.149465Z","caller":"traceutil/trace.go:171","msg":"trace[1562250745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"337.543289ms","start":"2026-08-12T01:07:00.811916Z","end":"2026-08-12T01:07:01.149459Z","steps":["trace[1562250745] 'agreement among raft nodes before linearized reading' (duration: 337.502918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:01.149486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.811907Z","time spent":"337.573659ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T01:07:01.149672Z","caller":"traceutil/trace.go:171","msg":"trace[552840861] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"342.125001ms","start":"2026-08-12T01:07:00.807539Z","end":"2026-08-12T01:07:01.149664Z","steps":["trace[552840861] 'process raft request' (duration: 85.859763ms)","trace[552840861] 'compare' (duration: 255.50694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:01.149714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.807518Z","time spent":"342.172043ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:01.149786Z","caller":"traceutil/trace.go:171","msg":"trace[2014782123] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"341.24516ms","start":"2026-08-12T01:07:00.808535Z","end":"2026-08-12T01:07:01.14978Z","steps":["trace[2014782123] 'process raft request' (duration: 340.733827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:01.149857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.808516Z","time spent":"341.320882ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:01.149933Z","caller":"traceutil/trace.go:171","msg":"trace[1663514536] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"340.381328ms","start":"2026-08-12T01:07:00.809546Z","end":"2026-08-12T01:07:01.149928Z","steps":["trace[1663514536] 'process raft request' (duration: 339.758833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:01.149968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.809538Z","time spent":"340.41332ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:01.15005Z","caller":"traceutil/trace.go:171","msg":"trace[978630579] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"340.410418ms","start":"2026-08-12T01:07:00.809632Z","end":"2026-08-12T01:07:01.150042Z","steps":["trace[978630579] 'process raft request' (duration: 339.692331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:01.15008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.809613Z","time spent":"340.45012ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:01.150207Z","caller":"traceutil/trace.go:171","msg":"trace[1980632281] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"337.63976ms","start":"2026-08-12T01:07:00.812562Z","end":"2026-08-12T01:07:01.150201Z","steps":["trace[1980632281] 'process raft request' (duration: 336.789889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:01.150263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:00.812552Z","time spent":"337.669271ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:22.927288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741626692871500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T01:07:23.183696Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.311850638s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T01:07:23.184136Z","caller":"traceutil/trace.go:171","msg":"trace[262989105] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.31233421s","start":"2026-08-12T01:07:21.871764Z","end":"2026-08-12T01:07:23.184098Z","steps":["trace[262989105] 'process raft request' (duration: 1.312189867s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.184292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.871748Z","time spent":"1.312478264s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.513301Z","caller":"traceutil/trace.go:171","msg":"trace[398098430] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.639604823s","start":"2026-08-12T01:07:21.873632Z","end":"2026-08-12T01:07:23.513237Z","steps":["trace[398098430] 'process raft request' (duration: 1.639319235s)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:23.513456Z","caller":"traceutil/trace.go:171","msg":"trace[2124196067] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.618702378s","start":"2026-08-12T01:07:21.89474Z","end":"2026-08-12T01:07:23.513442Z","steps":["trace[2124196067] 'process raft request' (duration: 1.618594115s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.513522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.873626Z","time spent":"1.639793567s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.513594Z","caller":"traceutil/trace.go:171","msg":"trace[1614291089] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.617832405s","start":"2026-08-12T01:07:21.895749Z","end":"2026-08-12T01:07:23.513582Z","steps":["trace[1614291089] 'process raft request' (duration: 1.61762142s)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:23.513531Z","caller":"traceutil/trace.go:171","msg":"trace[1219059864] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.628447282s","start":"2026-08-12T01:07:21.884991Z","end":"2026-08-12T01:07:23.513438Z","steps":["trace[1219059864] 'process raft request' (duration: 1.628262597s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.513659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.89473Z","time spent":"1.618849251s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.513474Z","caller":"traceutil/trace.go:171","msg":"trace[2061213392] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.622905373s","start":"2026-08-12T01:07:21.890518Z","end":"2026-08-12T01:07:23.513423Z","steps":["trace[2061213392] 'process raft request' (duration: 1.62277187s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.513775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.890497Z","time spent":"1.623233711s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T01:07:23.513786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.895728Z","time spent":"1.617926078s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.513924Z","caller":"traceutil/trace.go:171","msg":"trace[8331019] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.598752025s","start":"2026-08-12T01:07:21.91516Z","end":"2026-08-12T01:07:23.513912Z","steps":["trace[8331019] 'process raft request' (duration: 1.598316274s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.513977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.915149Z","time spent":"1.598803037s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.513992Z","caller":"traceutil/trace.go:171","msg":"trace[1912841900] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.602775107s","start":"2026-08-12T01:07:21.911203Z","end":"2026-08-12T01:07:23.513978Z","steps":["trace[1912841900] 'process raft request' (duration: 1.602211753s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.514051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.911181Z","time spent":"1.602846859s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.514083Z","caller":"traceutil/trace.go:171","msg":"trace[1880852119] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.577702137s","start":"2026-08-12T01:07:21.936369Z","end":"2026-08-12T01:07:23.514071Z","steps":["trace[1880852119] 'process raft request' (duration: 1.577285797s)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:23.514121Z","caller":"traceutil/trace.go:171","msg":"trace[1782527923] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.487639545s","start":"2026-08-12T01:07:22.026449Z","end":"2026-08-12T01:07:23.514088Z","steps":["trace[1782527923] 'process raft request' (duration: 1.487331727s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.514134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.936347Z","time spent":"1.577763348s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.514153Z","caller":"traceutil/trace.go:171","msg":"trace[1986362086] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.630260798s","start":"2026-08-12T01:07:21.883112Z","end":"2026-08-12T01:07:23.513373Z","steps":["trace[1986362086] 'process raft request' (duration: 1.630095494s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.514251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.883097Z","time spent":"1.631101458s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T01:07:23.514252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:22.026421Z","time spent":"1.487754418s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.514406Z","caller":"traceutil/trace.go:171","msg":"trace[2075932548] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.637894s","start":"2026-08-12T01:07:21.875452Z","end":"2026-08-12T01:07:23.513346Z","steps":["trace[2075932548] 'process raft request' (duration: 1.637673645s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.514478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.875432Z","time spent":"1.639014598s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T01:07:23.514637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:21.884984Z","time spent":"1.629599541s","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:23.523749Z","caller":"traceutil/trace.go:171","msg":"trace[1903839112] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"330.575566ms","start":"2026-08-12T01:07:23.193153Z","end":"2026-08-12T01:07:23.523729Z","steps":["trace[1903839112] 'process raft request' (duration: 330.540905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.523897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:23.193093Z","time spent":"330.719879ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:23.52412Z","caller":"traceutil/trace.go:171","msg":"trace[67175168] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:508; }","duration":"1.097184006s","start":"2026-08-12T01:07:22.426924Z","end":"2026-08-12T01:07:23.524108Z","steps":["trace[67175168] 'read index received' (duration: 757.138302ms)","trace[67175168] 'applied index is now lower than readState.Index' (duration: 340.044484ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:07:23.524262Z","caller":"traceutil/trace.go:171","msg":"trace[1836642537] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"333.576811ms","start":"2026-08-12T01:07:23.190675Z","end":"2026-08-12T01:07:23.524252Z","steps":["trace[1836642537] 'process raft request' (duration: 332.864753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.524785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097864582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:23.524843Z","caller":"traceutil/trace.go:171","msg":"trace[1927515306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"1.097961535s","start":"2026-08-12T01:07:22.426866Z","end":"2026-08-12T01:07:23.524828Z","steps":["trace[1927515306] 'agreement among raft nodes before linearized reading' (duration: 1.097863932s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.524865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:22.426843Z","time spent":"1.098017237s","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T01:07:23.524987Z","caller":"traceutil/trace.go:171","msg":"trace[257938571] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"331.922398ms","start":"2026-08-12T01:07:23.193056Z","end":"2026-08-12T01:07:23.524979Z","steps":["trace[257938571] 'process raft request' (duration: 330.584495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.525031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:23.192937Z","time spent":"332.066032ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:23.524296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:23.190652Z","time spent":"333.625302ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T01:07:23.525234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.759325ms","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-08-12T01:07:23.525267Z","caller":"traceutil/trace.go:171","msg":"trace[1054467397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"247.851446ms","start":"2026-08-12T01:07:23.277398Z","end":"2026-08-12T01:07:23.52525Z","steps":["trace[1054467397] 'agreement among raft nodes before linearized reading' (duration: 247.806725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.525418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.333182ms","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-08-12T01:07:23.525436Z","caller":"traceutil/trace.go:171","msg":"trace[2143166935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:503; }","duration":"196.402905ms","start":"2026-08-12T01:07:23.329027Z","end":"2026-08-12T01:07:23.52543Z","steps":["trace[2143166935] 'agreement among raft nodes before linearized reading' (duration: 196.324963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.52554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.150518ms","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-08-12T01:07:23.525557Z","caller":"traceutil/trace.go:171","msg":"trace[458981230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"200.23022ms","start":"2026-08-12T01:07:23.325321Z","end":"2026-08-12T01:07:23.525551Z","steps":["trace[458981230] 'agreement among raft nodes before linearized reading' (duration: 200.183078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:23.525838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.546848ms","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-08-12T01:07:23.525885Z","caller":"traceutil/trace.go:171","msg":"trace[2084226097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"200.6508ms","start":"2026-08-12T01:07:23.325218Z","end":"2026-08-12T01:07:23.525869Z","steps":["trace[2084226097] 'agreement among raft nodes before linearized reading' (duration: 200.353883ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:29.745103Z","caller":"traceutil/trace.go:171","msg":"trace[142061255] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"319.18453ms","start":"2026-08-12T01:07:29.425895Z","end":"2026-08-12T01:07:29.745079Z","steps":["trace[142061255] 'read index received' (duration: 318.379779ms)","trace[142061255] 'applied index is now lower than readState.Index' (duration: 803.691µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:29.745281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.222086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lggfq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-08-12T01:07:29.745273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.349804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:29.745337Z","caller":"traceutil/trace.go:171","msg":"trace[1062775645] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lggfq; range_end:; response_count:1; response_revision:596; }","duration":"197.297408ms","start":"2026-08-12T01:07:29.548028Z","end":"2026-08-12T01:07:29.745326Z","steps":["trace[1062775645] 'agreement among raft nodes before linearized reading' (duration: 197.187005ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:29.745373Z","caller":"traceutil/trace.go:171","msg":"trace[109087912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"319.478426ms","start":"2026-08-12T01:07:29.425866Z","end":"2026-08-12T01:07:29.745345Z","steps":["trace[109087912] 'agreement among raft nodes before linearized reading' (duration: 319.316742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:29.745417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.42585Z","time spent":"319.557509ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:07:29.745568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.157266ms","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-08-12T01:07:29.745679Z","caller":"traceutil/trace.go:171","msg":"trace[1645419764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"176.282869ms","start":"2026-08-12T01:07:29.569353Z","end":"2026-08-12T01:07:29.745636Z","steps":["trace[1645419764] 'agreement among raft nodes before linearized reading' (duration: 175.935691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:29.745719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.854289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T01:07:29.745754Z","caller":"traceutil/trace.go:171","msg":"trace[268561965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"177.931371ms","start":"2026-08-12T01:07:29.567812Z","end":"2026-08-12T01:07:29.745744Z","steps":["trace[268561965] 'agreement among raft nodes before linearized reading' (duration: 177.798188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:29.745807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.149345ms","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-08-12T01:07:29.745848Z","caller":"traceutil/trace.go:171","msg":"trace[700731397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"176.221297ms","start":"2026-08-12T01:07:29.569616Z","end":"2026-08-12T01:07:29.745837Z","steps":["trace[700731397] 'agreement among raft nodes before linearized reading' (duration: 176.100704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:29.745935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.630763ms","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-08-12T01:07:29.746Z","caller":"traceutil/trace.go:171","msg":"trace[1193596207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"183.729536ms","start":"2026-08-12T01:07:29.562257Z","end":"2026-08-12T01:07:29.745987Z","steps":["trace[1193596207] 'agreement among raft nodes before linearized reading' (duration: 183.581692ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:29.745702Z","caller":"traceutil/trace.go:171","msg":"trace[716929451] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"388.533431ms","start":"2026-08-12T01:07:29.356613Z","end":"2026-08-12T01:07:29.745147Z","steps":["trace[716929451] 'process raft request' (duration: 387.941876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:29.746517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.356585Z","time spent":"389.830844ms","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:29.943119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.016727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:07:29.943268Z","caller":"traceutil/trace.go:171","msg":"trace[390767179] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"191.380468ms","start":"2026-08-12T01:07:29.751847Z","end":"2026-08-12T01:07:29.943227Z","steps":["trace[390767179] 'process raft request' (duration: 10.121875ms)","trace[390767179] 'compare' (duration: 180.850383ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:07:30.31076Z","caller":"traceutil/trace.go:171","msg":"trace[1969799627] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"558.258385ms","start":"2026-08-12T01:07:29.75248Z","end":"2026-08-12T01:07:30.310738Z","steps":["trace[1969799627] 'process raft request' (duration: 558.194193ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:30.310972Z","caller":"traceutil/trace.go:171","msg":"trace[1109183601] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"558.365149ms","start":"2026-08-12T01:07:29.752591Z","end":"2026-08-12T01:07:30.310956Z","steps":["trace[1109183601] 'process raft request' (duration: 558.115432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:30.31109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.752579Z","time spent":"558.42689ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:30.311109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.75247Z","time spent":"558.513523ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:30.310988Z","caller":"traceutil/trace.go:171","msg":"trace[311602501] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"558.529533ms","start":"2026-08-12T01:07:29.752415Z","end":"2026-08-12T01:07:30.310945Z","steps":["trace[311602501] 'process raft request' (duration: 509.05984ms)","trace[311602501] 'compare' (duration: 49.098733ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:30.311604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.75239Z","time spent":"559.018655ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:30.32869Z","caller":"traceutil/trace.go:171","msg":"trace[1961039636] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"155.183999ms","start":"2026-08-12T01:07:30.17349Z","end":"2026-08-12T01:07:30.328674Z","steps":["trace[1961039636] 'process raft request' (duration: 155.144148ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:30.328693Z","caller":"traceutil/trace.go:171","msg":"trace[1658015953] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"566.185595ms","start":"2026-08-12T01:07:29.762478Z","end":"2026-08-12T01:07:30.328664Z","steps":["trace[1658015953] 'process raft request' (duration: 566.020501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:30.328989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.762455Z","time spent":"566.450491ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:07:30.328825Z","caller":"traceutil/trace.go:171","msg":"trace[425563467] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"533.118723ms","start":"2026-08-12T01:07:29.795683Z","end":"2026-08-12T01:07:30.328802Z","steps":["trace[425563467] 'process raft request' (duration: 532.911088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:30.329399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:29.795663Z","time spent":"533.567685ms","remote":"127.0.0.1:57830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T01:07:31.62336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197608537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:31.62344Z","caller":"traceutil/trace.go:171","msg":"trace[1400349503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.197728932s","start":"2026-08-12T01:07:30.425694Z","end":"2026-08-12T01:07:31.623423Z","steps":["trace[1400349503] 'range keys from in-memory index tree' (duration: 1.197546776s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:31.623502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:30.425677Z","time spent":"1.197792783s","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T01:07:32.124985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741626692871689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T01:07:32.178903Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447836795s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-12T01:07:32.180951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.340688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:32.181102Z","caller":"traceutil/trace.go:171","msg":"trace[1736788051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"556.551933ms","start":"2026-08-12T01:07:31.624522Z","end":"2026-08-12T01:07:32.181074Z","steps":["trace[1736788051] 'agreement among raft nodes before linearized reading' (duration: 556.106152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:32.181254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:31.624506Z","time spent":"556.698967ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T01:07:32.181229Z","caller":"traceutil/trace.go:171","msg":"trace[349573290] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.451654011s","start":"2026-08-12T01:07:30.729538Z","end":"2026-08-12T01:07:32.181192Z","steps":["trace[349573290] 'process raft request' (duration: 1.45001445s)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:32.181489Z","caller":"traceutil/trace.go:171","msg":"trace[2122981017] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"556.060701ms","start":"2026-08-12T01:07:31.624563Z","end":"2026-08-12T01:07:32.180624Z","steps":["trace[2122981017] 'read index received' (duration: 554.489711ms)","trace[2122981017] 'applied index is now lower than readState.Index' (duration: 1.569289ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:32.181528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:30.729507Z","time spent":"1.451909967s","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:32.182258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.728154ms","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-08-12T01:07:32.182335Z","caller":"traceutil/trace.go:171","msg":"trace[336815471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"235.821595ms","start":"2026-08-12T01:07:31.946477Z","end":"2026-08-12T01:07:32.182299Z","steps":["trace[336815471] 'agreement among raft nodes before linearized reading' (duration: 235.373553ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:34.056285Z","caller":"traceutil/trace.go:171","msg":"trace[243981736] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"365.058901ms","start":"2026-08-12T01:07:33.691203Z","end":"2026-08-12T01:07:34.056262Z","steps":["trace[243981736] 'process raft request' (duration: 364.904387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:34.056501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:33.691181Z","time spent":"365.256877ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:07:44.562408Z","caller":"traceutil/trace.go:171","msg":"trace[645256280] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"190.65428ms","start":"2026-08-12T01:07:44.371709Z","end":"2026-08-12T01:07:44.562363Z","steps":["trace[645256280] 'read index received' (duration: 190.376393ms)","trace[645256280] 'applied index is now lower than readState.Index' (duration: 276.877µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:07:44.562458Z","caller":"traceutil/trace.go:171","msg":"trace[965915498] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"198.507667ms","start":"2026-08-12T01:07:44.363923Z","end":"2026-08-12T01:07:44.562431Z","steps":["trace[965915498] 'process raft request' (duration: 198.269192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:44.56269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.955057ms","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-08-12T01:07:44.56273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.139963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:44.562836Z","caller":"traceutil/trace.go:171","msg":"trace[833704936] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:744; }","duration":"150.297196ms","start":"2026-08-12T01:07:44.41252Z","end":"2026-08-12T01:07:44.562817Z","steps":["trace[833704936] 'agreement among raft nodes before linearized reading' (duration: 150.112791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:44.562754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.227465ms","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-08-12T01:07:44.56275Z","caller":"traceutil/trace.go:171","msg":"trace[333437481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"191.055209ms","start":"2026-08-12T01:07:44.37168Z","end":"2026-08-12T01:07:44.562735Z","steps":["trace[333437481] 'agreement among raft nodes before linearized reading' (duration: 190.863304ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:07:44.563071Z","caller":"traceutil/trace.go:171","msg":"trace[1711587648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"189.558612ms","start":"2026-08-12T01:07:44.373472Z","end":"2026-08-12T01:07:44.563031Z","steps":["trace[1711587648] 'agreement among raft nodes before linearized reading' (duration: 189.090221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:47.895213Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021004422s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-12T01:07:48.052704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.189899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:07:48.0528Z","caller":"traceutil/trace.go:171","msg":"trace[525980993] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"629.794983ms","start":"2026-08-12T01:07:47.422989Z","end":"2026-08-12T01:07:48.052784Z","steps":["trace[525980993] 'read index received' (duration: 472.692606ms)","trace[525980993] 'applied index is now lower than readState.Index' (duration: 157.101247ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:07:48.052866Z","caller":"traceutil/trace.go:171","msg":"trace[1503037690] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.178819857s","start":"2026-08-12T01:07:46.874036Z","end":"2026-08-12T01:07:48.052856Z","steps":["trace[1503037690] 'process raft request' (duration: 1.021395502s)","trace[1503037690] 'compare' (duration: 157.074956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:07:48.052942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:46.87401Z","time spent":"1.178899228s","remote":"127.0.0.1:57328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:48.053264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.259304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:48.053454Z","caller":"traceutil/trace.go:171","msg":"trace[776511157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"630.404748ms","start":"2026-08-12T01:07:47.422949Z","end":"2026-08-12T01:07:48.053354Z","steps":["trace[776511157] 'agreement among raft nodes before linearized reading' (duration: 630.005198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:48.053813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:47.422927Z","time spent":"630.871529ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T01:07:48.217058Z","caller":"traceutil/trace.go:171","msg":"trace[134559515] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"164.208935ms","start":"2026-08-12T01:07:48.052825Z","end":"2026-08-12T01:07:48.217034Z","steps":["trace[134559515] 'read index received' (duration: 164.031841ms)","trace[134559515] 'applied index is now lower than readState.Index' (duration: 176.004µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:07:48.217227Z","caller":"traceutil/trace.go:171","msg":"trace[1011999083] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"550.980282ms","start":"2026-08-12T01:07:47.66623Z","end":"2026-08-12T01:07:48.217211Z","steps":["trace[1011999083] 'process raft request' (duration: 550.643964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:48.217388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:47.666206Z","time spent":"551.078395ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:07:48.217395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.554814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-12T01:07:48.217454Z","caller":"traceutil/trace.go:171","msg":"trace[636548992] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:763; }","duration":"162.662728ms","start":"2026-08-12T01:07:48.054772Z","end":"2026-08-12T01:07:48.217435Z","steps":["trace[636548992] 'agreement among raft nodes before linearized reading' (duration: 162.548005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:48.217599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.690157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:07:48.21765Z","caller":"traceutil/trace.go:171","msg":"trace[434695255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"162.77754ms","start":"2026-08-12T01:07:48.054858Z","end":"2026-08-12T01:07:48.217635Z","steps":["trace[434695255] 'agreement among raft nodes before linearized reading' (duration: 162.682467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:48.217693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.158716ms","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-08-12T01:07:48.217718Z","caller":"traceutil/trace.go:171","msg":"trace[549985514] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:763; }","duration":"783.198638ms","start":"2026-08-12T01:07:47.434513Z","end":"2026-08-12T01:07:48.217711Z","steps":["trace[549985514] 'agreement among raft nodes before linearized reading' (duration: 783.149186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:07:48.217737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:07:47.434503Z","time spent":"783.229078ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:08:13.590659Z","caller":"traceutil/trace.go:171","msg":"trace[2030438295] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"165.002625ms","start":"2026-08-12T01:08:13.425641Z","end":"2026-08-12T01:08:13.590643Z","steps":["trace[2030438295] 'read index received' (duration: 164.872402ms)","trace[2030438295] 'applied index is now lower than readState.Index' (duration: 129.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:08:13.590693Z","caller":"traceutil/trace.go:171","msg":"trace[1758120377] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"216.54404ms","start":"2026-08-12T01:08:13.374126Z","end":"2026-08-12T01:08:13.59067Z","steps":["trace[1758120377] 'process raft request' (duration: 216.422827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:13.590739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.086707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:08:13.590758Z","caller":"traceutil/trace.go:171","msg":"trace[1010161725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"165.16896ms","start":"2026-08-12T01:08:13.425584Z","end":"2026-08-12T01:08:13.590753Z","steps":["trace[1010161725] 'agreement among raft nodes before linearized reading' (duration: 165.125409ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:08:14.520006Z","caller":"traceutil/trace.go:171","msg":"trace[1138757993] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"128.652467ms","start":"2026-08-12T01:08:14.39132Z","end":"2026-08-12T01:08:14.519972Z","steps":["trace[1138757993] 'read index received' (duration: 128.531794ms)","trace[1138757993] 'applied index is now lower than readState.Index' (duration: 119.633µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:08:14.520075Z","caller":"traceutil/trace.go:171","msg":"trace[1351316061] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"140.801123ms","start":"2026-08-12T01:08:14.379262Z","end":"2026-08-12T01:08:14.520063Z","steps":["trace[1351316061] 'process raft request' (duration: 140.588758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:14.520175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.842962ms","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-08-12T01:08:14.520202Z","caller":"traceutil/trace.go:171","msg":"trace[131698039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"128.903724ms","start":"2026-08-12T01:08:14.391291Z","end":"2026-08-12T01:08:14.520195Z","steps":["trace[131698039] 'agreement among raft nodes before linearized reading' (duration: 128.77063ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:08:15.949863Z","caller":"traceutil/trace.go:171","msg":"trace[1581623103] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"354.718437ms","start":"2026-08-12T01:08:15.595129Z","end":"2026-08-12T01:08:15.949847Z","steps":["trace[1581623103] 'process raft request' (duration: 354.594254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:15.950046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:15.59511Z","time spent":"354.86696ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:08:36.246947Z","caller":"traceutil/trace.go:171","msg":"trace[1355045300] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"241.952668ms","start":"2026-08-12T01:08:36.004946Z","end":"2026-08-12T01:08:36.246899Z","steps":["trace[1355045300] 'process raft request' (duration: 241.760623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.044588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.868184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:08:54.044681Z","caller":"traceutil/trace.go:171","msg":"trace[518432472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1131; }","duration":"588.014588ms","start":"2026-08-12T01:08:53.456643Z","end":"2026-08-12T01:08:54.044658Z","steps":["trace[518432472] 'count revisions from in-memory index tree' (duration: 587.798221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.044727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:53.456626Z","time spent":"588.088749ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-12T01:08:54.045279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108927327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T01:08:54.045371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.536267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T01:08:54.045402Z","caller":"traceutil/trace.go:171","msg":"trace[1125542646] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1131; }","duration":"1.10909758s","start":"2026-08-12T01:08:52.936275Z","end":"2026-08-12T01:08:54.045372Z","steps":["trace[1125542646] 'count revisions from in-memory index tree' (duration: 1.108802563s)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:08:54.045414Z","caller":"traceutil/trace.go:171","msg":"trace[2010827174] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1131; }","duration":"839.631859ms","start":"2026-08-12T01:08:53.20577Z","end":"2026-08-12T01:08:54.045401Z","steps":["trace[2010827174] 'count revisions from in-memory index tree' (duration: 839.443545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.045451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:53.20575Z","time spent":"839.692231ms","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-12T01:08:54.045466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:52.936266Z","time spent":"1.109190012s","remote":"127.0.0.1:57650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-08-12T01:08:54.045469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053916401s","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-08-12T01:08:54.045553Z","caller":"traceutil/trace.go:171","msg":"trace[725349015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1131; }","duration":"1.054073535s","start":"2026-08-12T01:08:52.991459Z","end":"2026-08-12T01:08:54.045533Z","steps":["trace[725349015] 'range keys from in-memory index tree' (duration: 1.053751558s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.045597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:52.991446Z","time spent":"1.054142007s","remote":"127.0.0.1:57596","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-08-12T01:08:54.045843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109542502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-12T01:08:54.045886Z","caller":"traceutil/trace.go:171","msg":"trace[672715221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"1.109622894s","start":"2026-08-12T01:08:52.936253Z","end":"2026-08-12T01:08:54.045876Z","steps":["trace[672715221] 'range keys from in-memory index tree' (duration: 1.109379808s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.045915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:52.936232Z","time spent":"1.109676355s","remote":"127.0.0.1:57596","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-08-12T01:08:54.045991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077538775s","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-08-12T01:08:54.046038Z","caller":"traceutil/trace.go:171","msg":"trace[722162162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"1.077612178s","start":"2026-08-12T01:08:52.968414Z","end":"2026-08-12T01:08:54.046026Z","steps":["trace[722162162] 'range keys from in-memory index tree' (duration: 1.077403772s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:08:54.046066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:08:52.9684Z","time spent":"1.077658728s","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T01:09:06.586554Z","caller":"traceutil/trace.go:171","msg":"trace[713372460] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"246.280738ms","start":"2026-08-12T01:09:06.340244Z","end":"2026-08-12T01:09:06.586524Z","steps":["trace[713372460] 'process raft request' (duration: 245.96106ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:10.419149Z","caller":"traceutil/trace.go:171","msg":"trace[1570479313] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"292.409689ms","start":"2026-08-12T01:09:10.126715Z","end":"2026-08-12T01:09:10.419125Z","steps":["trace[1570479313] 'process raft request' (duration: 292.261936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:10.663939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.64232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:09:10.664064Z","caller":"traceutil/trace.go:171","msg":"trace[478915116] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"537.014343ms","start":"2026-08-12T01:09:10.127036Z","end":"2026-08-12T01:09:10.66405Z","steps":["trace[478915116] 'process raft request' (duration: 392.182569ms)","trace[478915116] 'compare' (duration: 144.529487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:10.664118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:10.127026Z","time spent":"537.072555ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:10.664327Z","caller":"traceutil/trace.go:171","msg":"trace[404181685] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1256; }","duration":"446.602678ms","start":"2026-08-12T01:09:10.217704Z","end":"2026-08-12T01:09:10.664307Z","steps":["trace[404181685] 'read index received' (duration: 201.321656ms)","trace[404181685] 'applied index is now lower than readState.Index' (duration: 245.279952ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:10.664377Z","caller":"traceutil/trace.go:171","msg":"trace[880167972] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"536.347707ms","start":"2026-08-12T01:09:10.128018Z","end":"2026-08-12T01:09:10.664366Z","steps":["trace[880167972] 'process raft request' (duration: 536.006139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:10.664421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:10.127995Z","time spent":"536.404038ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:10.664525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.810604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:10.66457Z","caller":"traceutil/trace.go:171","msg":"trace[504431008] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1207; }","duration":"446.913046ms","start":"2026-08-12T01:09:10.217644Z","end":"2026-08-12T01:09:10.664557Z","steps":["trace[504431008] 'agreement among raft nodes before linearized reading' (duration: 446.739502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:10.664643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:10.217615Z","time spent":"447.018539ms","remote":"127.0.0.1:39644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:09:10.951058Z","caller":"traceutil/trace.go:171","msg":"trace[38610400] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"506.386603ms","start":"2026-08-12T01:09:10.444651Z","end":"2026-08-12T01:09:10.951037Z","steps":["trace[38610400] 'process raft request' (duration: 506.24383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:10.95118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:10.444625Z","time spent":"506.496306ms","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T01:09:10.995071Z","caller":"traceutil/trace.go:171","msg":"trace[1794435627] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"394.936919ms","start":"2026-08-12T01:09:10.600109Z","end":"2026-08-12T01:09:10.995046Z","steps":["trace[1794435627] 'process raft request' (duration: 394.719693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:10.995209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:10.600082Z","time spent":"395.051582ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:11.14817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.429702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:09:11.14835Z","caller":"traceutil/trace.go:171","msg":"trace[732183166] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"149.411609ms","start":"2026-08-12T01:09:10.998921Z","end":"2026-08-12T01:09:11.148332Z","steps":["trace[732183166] 'process raft request' (duration: 149.348258ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:11.148704Z","caller":"traceutil/trace.go:171","msg":"trace[600081446] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"149.959823ms","start":"2026-08-12T01:09:10.99873Z","end":"2026-08-12T01:09:11.14869Z","steps":["trace[600081446] 'process raft request' (duration: 47.925046ms)","trace[600081446] 'compare' (duration: 100.735945ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:11.913616Z","caller":"traceutil/trace.go:171","msg":"trace[568513387] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"124.624956ms","start":"2026-08-12T01:09:11.788964Z","end":"2026-08-12T01:09:11.913589Z","steps":["trace[568513387] 'process raft request' (duration: 51.753372ms)","trace[568513387] 'compare' (duration: 72.501665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:12.543524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741626692873061,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-12T01:09:12.837869Z","caller":"traceutil/trace.go:171","msg":"trace[33272672] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"850.168834ms","start":"2026-08-12T01:09:11.987676Z","end":"2026-08-12T01:09:12.837845Z","steps":["trace[33272672] 'process raft request' (duration: 850.03369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:12.838076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:11.987652Z","time spent":"850.357689ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:13.370556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.979192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T01:09:13.370763Z","caller":"traceutil/trace.go:171","msg":"trace[1876395365] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"370.927694ms","start":"2026-08-12T01:09:12.99982Z","end":"2026-08-12T01:09:13.370747Z","steps":["trace[1876395365] 'process raft request' (duration: 370.823961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.370869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:12.999794Z","time spent":"371.020286ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:13.370923Z","caller":"traceutil/trace.go:171","msg":"trace[2137595115] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"1.327932978s","start":"2026-08-12T01:09:12.042958Z","end":"2026-08-12T01:09:13.370891Z","steps":["trace[2137595115] 'read index received' (duration: 794.719559ms)","trace[2137595115] 'applied index is now lower than readState.Index' (duration: 533.210349ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:09:13.371229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328249716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:13.371296Z","caller":"traceutil/trace.go:171","msg":"trace[1561332466] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1219; }","duration":"1.328337138s","start":"2026-08-12T01:09:12.042943Z","end":"2026-08-12T01:09:13.37128Z","steps":["trace[1561332466] 'agreement among raft nodes before linearized reading' (duration: 1.328203885s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.371335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.344631ms","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-08-12T01:09:13.371344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:12.042924Z","time spent":"1.32841049s","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-12T01:09:13.371377Z","caller":"traceutil/trace.go:171","msg":"trace[1147776495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1219; }","duration":"219.409612ms","start":"2026-08-12T01:09:13.151958Z","end":"2026-08-12T01:09:13.371367Z","steps":["trace[1147776495] 'agreement among raft nodes before linearized reading' (duration: 219.295399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.37156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.620127ms","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-08-12T01:09:13.371574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.642497ms","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-08-12T01:09:13.37159Z","caller":"traceutil/trace.go:171","msg":"trace[158187936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"217.661547ms","start":"2026-08-12T01:09:13.153921Z","end":"2026-08-12T01:09:13.371583Z","steps":["trace[158187936] 'agreement among raft nodes before linearized reading' (duration: 217.589406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.371704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.479573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-12T01:09:13.371703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.026551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-12T01:09:13.37173Z","caller":"traceutil/trace.go:171","msg":"trace[11528568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"703.504644ms","start":"2026-08-12T01:09:12.668219Z","end":"2026-08-12T01:09:13.371723Z","steps":["trace[11528568] 'agreement among raft nodes before linearized reading' (duration: 703.437502ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:13.371734Z","caller":"traceutil/trace.go:171","msg":"trace[1795225995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"703.089423ms","start":"2026-08-12T01:09:12.668638Z","end":"2026-08-12T01:09:13.371727Z","steps":["trace[1795225995] 'agreement among raft nodes before linearized reading' (duration: 702.97319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.371751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:12.66818Z","time spent":"703.565295ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T01:09:13.371756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:12.66862Z","time spent":"703.132065ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T01:09:13.371785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.349051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:13.371888Z","caller":"traceutil/trace.go:171","msg":"trace[1605401592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1219; }","duration":"233.510745ms","start":"2026-08-12T01:09:13.138358Z","end":"2026-08-12T01:09:13.371869Z","steps":["trace[1605401592] 'agreement among raft nodes before linearized reading' (duration: 233.327631ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:13.371726Z","caller":"traceutil/trace.go:171","msg":"trace[556607011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"945.826772ms","start":"2026-08-12T01:09:12.425873Z","end":"2026-08-12T01:09:13.3717Z","steps":["trace[556607011] 'agreement among raft nodes before linearized reading' (duration: 945.3448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.372989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:12.425857Z","time spent":"947.118994ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T01:09:13.575349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.31179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:09:13.575549Z","caller":"traceutil/trace.go:171","msg":"trace[1253958076] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"200.375424ms","start":"2026-08-12T01:09:13.375139Z","end":"2026-08-12T01:09:13.575515Z","steps":["trace[1253958076] 'process raft request' (duration: 34.779565ms)","trace[1253958076] 'compare' (duration: 165.151697ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:13.575734Z","caller":"traceutil/trace.go:171","msg":"trace[1720732890] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"199.496972ms","start":"2026-08-12T01:09:13.376215Z","end":"2026-08-12T01:09:13.575712Z","steps":["trace[1720732890] 'process raft request' (duration: 199.41606ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:13.57579Z","caller":"traceutil/trace.go:171","msg":"trace[535252305] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"199.621204ms","start":"2026-08-12T01:09:13.376154Z","end":"2026-08-12T01:09:13.575775Z","steps":["trace[535252305] 'process raft request' (duration: 199.429749ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:13.57582Z","caller":"traceutil/trace.go:171","msg":"trace[190062963] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"200.21723ms","start":"2026-08-12T01:09:13.375537Z","end":"2026-08-12T01:09:13.575754Z","steps":["trace[190062963] 'process raft request' (duration: 199.972314ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:13.575915Z","caller":"traceutil/trace.go:171","msg":"trace[1937559676] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1272; }","duration":"149.717858ms","start":"2026-08-12T01:09:13.426175Z","end":"2026-08-12T01:09:13.575892Z","steps":["trace[1937559676] 'read index received' (duration: 142.198778ms)","trace[1937559676] 'applied index is now lower than readState.Index' (duration: 7.5175ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:13.57595Z","caller":"traceutil/trace.go:171","msg":"trace[56584850] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"199.509182ms","start":"2026-08-12T01:09:13.376409Z","end":"2026-08-12T01:09:13.575918Z","steps":["trace[56584850] 'process raft request' (duration: 199.264806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:13.576008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.81549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:13.57604Z","caller":"traceutil/trace.go:171","msg":"trace[156369207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"149.906313ms","start":"2026-08-12T01:09:13.426124Z","end":"2026-08-12T01:09:13.57603Z","steps":["trace[156369207] 'agreement among raft nodes before linearized reading' (duration: 149.832682ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:15.566029Z","caller":"traceutil/trace.go:171","msg":"trace[930205162] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"142.26768ms","start":"2026-08-12T01:09:15.423725Z","end":"2026-08-12T01:09:15.565993Z","steps":["trace[930205162] 'read index received' (duration: 142.087465ms)","trace[930205162] 'applied index is now lower than readState.Index' (duration: 177.885µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:15.566254Z","caller":"traceutil/trace.go:171","msg":"trace[355901805] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"188.849392ms","start":"2026-08-12T01:09:15.37739Z","end":"2026-08-12T01:09:15.566239Z","steps":["trace[355901805] 'process raft request' (duration: 188.35746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:15.566533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.766283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:15.566701Z","caller":"traceutil/trace.go:171","msg":"trace[1567086297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"142.999918ms","start":"2026-08-12T01:09:15.423669Z","end":"2026-08-12T01:09:15.566669Z","steps":["trace[1567086297] 'agreement among raft nodes before linearized reading' (duration: 142.746062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:15.86844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.238709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T01:09:15.86893Z","caller":"traceutil/trace.go:171","msg":"trace[92644472] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"284.767706ms","start":"2026-08-12T01:09:15.584143Z","end":"2026-08-12T01:09:15.86891Z","steps":["trace[92644472] 'process raft request' (duration: 147.996914ms)","trace[92644472] 'compare' (duration: 136.070154ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:16.027685Z","caller":"traceutil/trace.go:171","msg":"trace[340704064] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"441.273525ms","start":"2026-08-12T01:09:15.586389Z","end":"2026-08-12T01:09:16.027663Z","steps":["trace[340704064] 'process raft request' (duration: 441.240594ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:16.0277Z","caller":"traceutil/trace.go:171","msg":"trace[621493022] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"443.245004ms","start":"2026-08-12T01:09:15.584396Z","end":"2026-08-12T01:09:16.027641Z","steps":["trace[621493022] 'process raft request' (duration: 413.054234ms)","trace[621493022] 'compare' (duration: 29.917394ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T01:09:16.027765Z","caller":"traceutil/trace.go:171","msg":"trace[1353992919] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"442.130456ms","start":"2026-08-12T01:09:15.585574Z","end":"2026-08-12T01:09:16.027704Z","steps":["trace[1353992919] 'process raft request' (duration: 441.938331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:16.027843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:15.586378Z","time spent":"441.386057ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:16.027945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:15.584377Z","time spent":"443.431559ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:09:16.027969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:15.585557Z","time spent":"442.297861ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:16.027762Z","caller":"traceutil/trace.go:171","msg":"trace[1260356222] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"442.078505ms","start":"2026-08-12T01:09:15.585647Z","end":"2026-08-12T01:09:16.027725Z","steps":["trace[1260356222] 'process raft request' (duration: 441.942462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:16.02829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:15.58564Z","time spent":"442.576858ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:09:18.325374Z","caller":"traceutil/trace.go:171","msg":"trace[1623881724] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"122.03786ms","start":"2026-08-12T01:09:18.203306Z","end":"2026-08-12T01:09:18.325344Z","steps":["trace[1623881724] 'process raft request' (duration: 121.870236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:18.83693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.404603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T01:09:18.837019Z","caller":"traceutil/trace.go:171","msg":"trace[650316632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"409.557926ms","start":"2026-08-12T01:09:18.427442Z","end":"2026-08-12T01:09:18.837Z","steps":["trace[650316632] 'range keys from in-memory index tree' (duration: 409.281229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:09:18.837069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:09:18.427423Z","time spent":"409.638098ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T01:09:19.732418Z","caller":"traceutil/trace.go:171","msg":"trace[1345445342] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"102.16165ms","start":"2026-08-12T01:09:19.630237Z","end":"2026-08-12T01:09:19.732398Z","steps":["trace[1345445342] 'process raft request' (duration: 102.035007ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:21.190407Z","caller":"traceutil/trace.go:171","msg":"trace[2115584884] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"237.369784ms","start":"2026-08-12T01:09:20.953011Z","end":"2026-08-12T01:09:21.190381Z","steps":["trace[2115584884] 'process raft request' (duration: 237.150229ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:09:32.170556Z","caller":"traceutil/trace.go:171","msg":"trace[288099068] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"135.075858ms","start":"2026-08-12T01:09:32.035451Z","end":"2026-08-12T01:09:32.170526Z","steps":["trace[288099068] 'process raft request' (duration: 46.062659ms)","trace[288099068] 'compare' (duration: 88.64202ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:11:09.311315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199741626692874383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T01:11:09.427048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.982057ms","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-08-12T01:11:09.427239Z","caller":"traceutil/trace.go:171","msg":"trace[505637154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1771; }","duration":"303.206723ms","start":"2026-08-12T01:11:09.124015Z","end":"2026-08-12T01:11:09.427222Z","steps":["trace[505637154] 'range keys from in-memory index tree' (duration: 302.965237ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:11:09.427611Z","caller":"traceutil/trace.go:171","msg":"trace[1941897099] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"617.351901ms","start":"2026-08-12T01:11:08.810236Z","end":"2026-08-12T01:11:09.427588Z","steps":["trace[1941897099] 'process raft request' (duration: 615.815042ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:11:09.427682Z","caller":"traceutil/trace.go:171","msg":"trace[3341071] linearizableReadLoop","detail":"{readStateIndex:1849; appliedIndex:1847; }","duration":"616.830138ms","start":"2026-08-12T01:11:08.810839Z","end":"2026-08-12T01:11:09.427669Z","steps":["trace[3341071] 'read index received' (duration: 615.217887ms)","trace[3341071] 'applied index is now lower than readState.Index' (duration: 1.611521ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T01:11:09.42774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:11:08.810222Z","time spent":"617.450393ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T01:11:09.427858Z","caller":"traceutil/trace.go:171","msg":"trace[1595390539] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"617.227578ms","start":"2026-08-12T01:11:08.810618Z","end":"2026-08-12T01:11:09.427845Z","steps":["trace[1595390539] 'process raft request' (duration: 616.940271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:11:09.427974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:11:08.810605Z","time spent":"617.30082ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T01:11:09.428088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.060146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-12T01:11:09.428121Z","caller":"traceutil/trace.go:171","msg":"trace[450067498] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1773; }","duration":"543.126548ms","start":"2026-08-12T01:11:08.884987Z","end":"2026-08-12T01:11:09.428114Z","steps":["trace[450067498] 'agreement among raft nodes before linearized reading' (duration: 543.020295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:11:09.428141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:11:08.884966Z","time spent":"543.170449ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-12T01:11:09.428255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.419832ms","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-08-12T01:11:09.428279Z","caller":"traceutil/trace.go:171","msg":"trace[931417098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1773; }","duration":"617.464464ms","start":"2026-08-12T01:11:08.810809Z","end":"2026-08-12T01:11:09.428273Z","steps":["trace[931417098] 'agreement among raft nodes before linearized reading' (duration: 617.385272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T01:11:09.428296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T01:11:08.810798Z","time spent":"617.493204ms","remote":"127.0.0.1:57596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-12T01:11:39.759063Z","caller":"traceutil/trace.go:171","msg":"trace[1611106931] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"166.41411ms","start":"2026-08-12T01:11:39.592628Z","end":"2026-08-12T01:11:39.759042Z","steps":["trace[1611106931] 'process raft request' (duration: 166.340358ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:11:39.759274Z","caller":"traceutil/trace.go:171","msg":"trace[928745289] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"167.813056ms","start":"2026-08-12T01:11:39.59141Z","end":"2026-08-12T01:11:39.759223Z","steps":["trace[928745289] 'process raft request' (duration: 166.496293ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T01:12:08.103159Z","caller":"traceutil/trace.go:171","msg":"trace[500231322] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"103.317002ms","start":"2026-08-12T01:12:07.999813Z","end":"2026-08-12T01:12:08.10313Z","steps":["trace[500231322] 'process raft request' (duration: 103.040636ms)"],"step_count":1}