{"level":"warn","ts":"2026-09-09T20:15:09.589054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T20:15:09.589165Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.183:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.183:2380","--initial-cluster=instance=https://162.253.55.183:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.183:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.183: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-09-09T20:15:09.589246Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T20:15:09.589274Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.183:2380"]} {"level":"info","ts":"2026-09-09T20:15:09.589294Z","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-09-09T20:15:09.589982Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.183:2379"]} {"level":"info","ts":"2026-09-09T20:15:09.590068Z","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://162.253.55.183:2380"],"listen-peer-urls":["https://162.253.55.183:2380"],"advertise-client-urls":["https://162.253.55.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.183: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-09-09T20:15:09.596224Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.890361ms"} {"level":"info","ts":"2026-09-09T20:15:09.614166Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c5fb173a2362815b","cluster-id":"61a2d7c239cb27e"} {"level":"info","ts":"2026-09-09T20:15:09.614267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T20:15:09.614288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b became follower at term 0"} {"level":"info","ts":"2026-09-09T20:15:09.614301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c5fb173a2362815b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T20:15:09.614314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b became follower at term 1"} {"level":"info","ts":"2026-09-09T20:15:09.614365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b switched to configuration voters=(14266021783095378267)"} {"level":"warn","ts":"2026-09-09T20:15:09.624782Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T20:15:09.626171Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T20:15:09.628555Z","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-09-09T20:15:09.630989Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c5fb173a2362815b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T20:15:09.631246Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c5fb173a2362815b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T20:15:09.631304Z","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-09-09T20:15:09.631405Z","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-09-09T20:15:09.631433Z","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-09-09T20:15:09.633681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b switched to configuration voters=(14266021783095378267)"} {"level":"info","ts":"2026-09-09T20:15:09.633816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61a2d7c239cb27e","local-member-id":"c5fb173a2362815b","added-peer-id":"c5fb173a2362815b","added-peer-peer-urls":["https://162.253.55.183:2380"]} {"level":"info","ts":"2026-09-09T20:15:09.636709Z","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-09-09T20:15:09.63698Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c5fb173a2362815b","initial-advertise-peer-urls":["https://162.253.55.183:2380"],"listen-peer-urls":["https://162.253.55.183:2380"],"advertise-client-urls":["https://162.253.55.183:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.183:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T20:15:09.637021Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T20:15:09.637563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.183:2380"} {"level":"info","ts":"2026-09-09T20:15:09.637587Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.183:2380"} {"level":"info","ts":"2026-09-09T20:15:10.615666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T20:15:10.615756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T20:15:10.6158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b received MsgPreVoteResp from c5fb173a2362815b at term 1"} {"level":"info","ts":"2026-09-09T20:15:10.615831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b became candidate at term 2"} {"level":"info","ts":"2026-09-09T20:15:10.615848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b received MsgVoteResp from c5fb173a2362815b at term 2"} {"level":"info","ts":"2026-09-09T20:15:10.615865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c5fb173a2362815b became leader at term 2"} {"level":"info","ts":"2026-09-09T20:15:10.615878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c5fb173a2362815b elected leader c5fb173a2362815b at term 2"} {"level":"info","ts":"2026-09-09T20:15:10.617328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T20:15:10.617531Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c5fb173a2362815b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.183:2379]}","request-path":"/0/members/c5fb173a2362815b/attributes","cluster-id":"61a2d7c239cb27e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T20:15:10.6176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T20:15:10.617605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T20:15:10.617817Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T20:15:10.617855Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T20:15:10.618523Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61a2d7c239cb27e","local-member-id":"c5fb173a2362815b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T20:15:10.618647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T20:15:10.618689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T20:15:10.619521Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.183:2379"} {"level":"info","ts":"2026-09-09T20:15:10.619639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T20:15:12.323747Z","caller":"traceutil/trace.go:171","msg":"trace[1369026744] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"147.479958ms","start":"2026-09-09T20:15:12.176237Z","end":"2026-09-09T20:15:12.323717Z","steps":["trace[1369026744] 'process raft request' (duration: 147.149478ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.521684Z","caller":"traceutil/trace.go:171","msg":"trace[776120582] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"345.267247ms","start":"2026-09-09T20:15:12.176375Z","end":"2026-09-09T20:15:12.521642Z","steps":["trace[776120582] 'process raft request' (duration: 344.938398ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.521844Z","caller":"traceutil/trace.go:171","msg":"trace[1444113414] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"274.202271ms","start":"2026-09-09T20:15:12.247605Z","end":"2026-09-09T20:15:12.521807Z","steps":["trace[1444113414] 'process raft request' (duration: 274.072387ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.521878Z","caller":"traceutil/trace.go:171","msg":"trace[321496942] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"332.65661ms","start":"2026-09-09T20:15:12.189178Z","end":"2026-09-09T20:15:12.521835Z","steps":["trace[321496942] 'read index received' (duration: 134.369435ms)","trace[321496942] 'applied index is now lower than readState.Index' (duration: 198.286045ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:12.521971Z","caller":"traceutil/trace.go:171","msg":"trace[689545706] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"274.360135ms","start":"2026-09-09T20:15:12.247528Z","end":"2026-09-09T20:15:12.521888Z","steps":["trace[689545706] 'process raft request' (duration: 274.023956ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522063Z","caller":"traceutil/trace.go:171","msg":"trace[1146568552] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"274.329085ms","start":"2026-09-09T20:15:12.247709Z","end":"2026-09-09T20:15:12.522038Z","steps":["trace[1146568552] 'process raft request' (duration: 273.994865ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.52216Z","caller":"traceutil/trace.go:171","msg":"trace[1789667747] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"274.50355ms","start":"2026-09-09T20:15:12.247641Z","end":"2026-09-09T20:15:12.522145Z","steps":["trace[1789667747] 'process raft request' (duration: 273.997855ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522203Z","caller":"traceutil/trace.go:171","msg":"trace[1475077520] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"274.400697ms","start":"2026-09-09T20:15:12.247789Z","end":"2026-09-09T20:15:12.522189Z","steps":["trace[1475077520] 'process raft request' (duration: 273.941444ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522241Z","caller":"traceutil/trace.go:171","msg":"trace[742083786] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"256.068634ms","start":"2026-09-09T20:15:12.266161Z","end":"2026-09-09T20:15:12.522229Z","steps":["trace[742083786] 'process raft request' (duration: 255.629702ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522669Z","caller":"traceutil/trace.go:171","msg":"trace[1868688094] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"260.151693ms","start":"2026-09-09T20:15:12.262497Z","end":"2026-09-09T20:15:12.522648Z","steps":["trace[1868688094] 'process raft request' (duration: 259.257307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.522677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.421443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-09T20:15:12.52272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.569531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.522768Z","caller":"traceutil/trace.go:171","msg":"trace[36223632] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"333.625239ms","start":"2026-09-09T20:15:12.189126Z","end":"2026-09-09T20:15:12.522751Z","steps":["trace[36223632] 'agreement among raft nodes before linearized reading' (duration: 333.191106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.52282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.92071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.522859Z","caller":"traceutil/trace.go:171","msg":"trace[156017292] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"223.94969ms","start":"2026-09-09T20:15:12.298891Z","end":"2026-09-09T20:15:12.522841Z","steps":["trace[156017292] 'agreement among raft nodes before linearized reading' (duration: 223.905939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.522817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:12.189107Z","time spent":"333.698391ms","remote":"127.0.0.1:33938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-09T20:15:12.522957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.991795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.522998Z","caller":"traceutil/trace.go:171","msg":"trace[1114593063] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"274.101527ms","start":"2026-09-09T20:15:12.248886Z","end":"2026-09-09T20:15:12.522987Z","steps":["trace[1114593063] 'agreement among raft nodes before linearized reading' (duration: 274.009915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.523034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.488296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.523074Z","caller":"traceutil/trace.go:171","msg":"trace[1987180999] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"278.579498ms","start":"2026-09-09T20:15:12.244483Z","end":"2026-09-09T20:15:12.523062Z","steps":["trace[1987180999] 'agreement among raft nodes before linearized reading' (duration: 278.531876ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522028Z","caller":"traceutil/trace.go:171","msg":"trace[98489668] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"274.335795ms","start":"2026-09-09T20:15:12.24765Z","end":"2026-09-09T20:15:12.521985Z","steps":["trace[98489668] 'process raft request' (duration: 273.949394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.522985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.525374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.183\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.523144Z","caller":"traceutil/trace.go:171","msg":"trace[980648652] range","detail":"{range_begin:/registry/masterleases/162.253.55.183; range_end:; response_count:0; response_revision:11; }","duration":"280.714951ms","start":"2026-09-09T20:15:12.242418Z","end":"2026-09-09T20:15:12.523133Z","steps":["trace[980648652] 'agreement among raft nodes before linearized reading' (duration: 280.475314ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:12.522778Z","caller":"traceutil/trace.go:171","msg":"trace[513509074] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"296.760887ms","start":"2026-09-09T20:15:12.225996Z","end":"2026-09-09T20:15:12.522757Z","steps":["trace[513509074] 'agreement among raft nodes before linearized reading' (duration: 296.378355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.523227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.959245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.523269Z","caller":"traceutil/trace.go:171","msg":"trace[115141106] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.024546ms","start":"2026-09-09T20:15:12.228233Z","end":"2026-09-09T20:15:12.523258Z","steps":["trace[115141106] 'agreement among raft nodes before linearized reading' (duration: 294.940684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.523392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.214812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-09T20:15:12.52343Z","caller":"traceutil/trace.go:171","msg":"trace[715393913] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.253253ms","start":"2026-09-09T20:15:12.228167Z","end":"2026-09-09T20:15:12.52342Z","steps":["trace[715393913] 'agreement among raft nodes before linearized reading' (duration: 295.197301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:12.523649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:12.176357Z","time spent":"345.503764ms","remote":"127.0.0.1:33614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T20:15:20.482899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.255233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-09-09T20:15:20.482992Z","caller":"traceutil/trace.go:171","msg":"trace[1903691697] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"120.396357ms","start":"2026-09-09T20:15:20.362577Z","end":"2026-09-09T20:15:20.482973Z","steps":["trace[1903691697] 'range keys from in-memory index tree' (duration: 120.174511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.482954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.203247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-09T20:15:20.483065Z","caller":"traceutil/trace.go:171","msg":"trace[750479755] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:313; }","duration":"152.369543ms","start":"2026-09-09T20:15:20.330667Z","end":"2026-09-09T20:15:20.483036Z","steps":["trace[750479755] 'range keys from in-memory index tree' (duration: 152.092904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.483183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.464749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-09-09T20:15:20.483246Z","caller":"traceutil/trace.go:171","msg":"trace[1386009477] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"120.552342ms","start":"2026-09-09T20:15:20.362677Z","end":"2026-09-09T20:15:20.483229Z","steps":["trace[1386009477] 'range keys from in-memory index tree' (duration: 120.311075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.483303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.781188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"warn","ts":"2026-09-09T20:15:20.483245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.85001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-n5yk68\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-09T20:15:20.483401Z","caller":"traceutil/trace.go:171","msg":"trace[24342667] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"120.855541ms","start":"2026-09-09T20:15:20.362489Z","end":"2026-09-09T20:15:20.483345Z","steps":["trace[24342667] 'range keys from in-memory index tree' (duration: 120.658985ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:20.483385Z","caller":"traceutil/trace.go:171","msg":"trace[124243238] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-n5yk68; range_end:; response_count:1; response_revision:313; }","duration":"121.030386ms","start":"2026-09-09T20:15:20.362331Z","end":"2026-09-09T20:15:20.483361Z","steps":["trace[124243238] 'range keys from in-memory index tree' (duration: 120.780088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.4835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.307412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-09T20:15:20.483556Z","caller":"traceutil/trace.go:171","msg":"trace[323384000] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:313; }","duration":"103.397514ms","start":"2026-09-09T20:15:20.380144Z","end":"2026-09-09T20:15:20.483541Z","steps":["trace[323384000] 'range keys from in-memory index tree' (duration: 103.219349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.483146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.429778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-09-09T20:15:20.483774Z","caller":"traceutil/trace.go:171","msg":"trace[26267944] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"121.070207ms","start":"2026-09-09T20:15:20.362683Z","end":"2026-09-09T20:15:20.483753Z","steps":["trace[26267944] 'range keys from in-memory index tree' (duration: 120.14466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.484016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.11486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-09-09T20:15:20.484049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.924585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-09T20:15:20.484076Z","caller":"traceutil/trace.go:171","msg":"trace[1104429258] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:313; }","duration":"250.264295ms","start":"2026-09-09T20:15:20.233794Z","end":"2026-09-09T20:15:20.484058Z","steps":["trace[1104429258] 'range keys from in-memory index tree' (duration: 249.986677ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:20.484158Z","caller":"traceutil/trace.go:171","msg":"trace[1965801064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"204.073969ms","start":"2026-09-09T20:15:20.280064Z","end":"2026-09-09T20:15:20.484138Z","steps":["trace[1965801064] 'range keys from in-memory index tree' (duration: 203.762761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:20.484052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.388686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-09-09T20:15:20.483834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.293834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-09T20:15:20.484327Z","caller":"traceutil/trace.go:171","msg":"trace[2127352399] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:313; }","duration":"121.652035ms","start":"2026-09-09T20:15:20.362628Z","end":"2026-09-09T20:15:20.48428Z","steps":["trace[2127352399] 'range keys from in-memory index tree' (duration: 121.226432ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:20.48436Z","caller":"traceutil/trace.go:171","msg":"trace[1853565762] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:313; }","duration":"121.85051ms","start":"2026-09-09T20:15:20.362482Z","end":"2026-09-09T20:15:20.484333Z","steps":["trace[1853565762] 'range keys from in-memory index tree' (duration: 121.13479ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.827839Z","caller":"traceutil/trace.go:171","msg":"trace[421179465] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"106.889082ms","start":"2026-09-09T20:15:50.720919Z","end":"2026-09-09T20:15:50.827808Z","steps":["trace[421179465] 'process raft request' (duration: 106.686015ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.838311Z","caller":"traceutil/trace.go:171","msg":"trace[891445829] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"103.515528ms","start":"2026-09-09T20:15:50.734753Z","end":"2026-09-09T20:15:50.838268Z","steps":["trace[891445829] 'process raft request' (duration: 102.770585ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.838547Z","caller":"traceutil/trace.go:171","msg":"trace[1849399684] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"117.50546ms","start":"2026-09-09T20:15:50.721032Z","end":"2026-09-09T20:15:50.838538Z","steps":["trace[1849399684] 'process raft request' (duration: 115.942202ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.838603Z","caller":"traceutil/trace.go:171","msg":"trace[558708472] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:516; }","duration":"100.880586ms","start":"2026-09-09T20:15:50.737234Z","end":"2026-09-09T20:15:50.838115Z","steps":["trace[558708472] 'read index received' (duration: 90.433282ms)","trace[558708472] 'applied index is now lower than readState.Index' (duration: 10.446104ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:50.838752Z","caller":"traceutil/trace.go:171","msg":"trace[791370853] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"109.352218ms","start":"2026-09-09T20:15:50.729389Z","end":"2026-09-09T20:15:50.838741Z","steps":["trace[791370853] 'process raft request' (duration: 107.671156ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.838871Z","caller":"traceutil/trace.go:171","msg":"trace[208233856] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"109.382088ms","start":"2026-09-09T20:15:50.729479Z","end":"2026-09-09T20:15:50.838861Z","steps":["trace[208233856] 'process raft request' (duration: 107.970535ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.838957Z","caller":"traceutil/trace.go:171","msg":"trace[1073067964] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"106.144208ms","start":"2026-09-09T20:15:50.732805Z","end":"2026-09-09T20:15:50.83895Z","steps":["trace[1073067964] 'process raft request' (duration: 104.682992ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:50.839574Z","caller":"traceutil/trace.go:171","msg":"trace[1107445916] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"100.696181ms","start":"2026-09-09T20:15:50.738867Z","end":"2026-09-09T20:15:50.839563Z","steps":["trace[1107445916] 'process raft request' (duration: 98.875995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:50.840068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.812235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:15:50.840109Z","caller":"traceutil/trace.go:171","msg":"trace[1190476687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"102.911008ms","start":"2026-09-09T20:15:50.737186Z","end":"2026-09-09T20:15:50.840097Z","steps":["trace[1190476687] 'agreement among raft nodes before linearized reading' (duration: 102.805975ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.081277Z","caller":"traceutil/trace.go:171","msg":"trace[1429249598] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"224.473196ms","start":"2026-09-09T20:15:50.856774Z","end":"2026-09-09T20:15:51.081247Z","steps":["trace[1429249598] 'process raft request' (duration: 224.376893ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.082603Z","caller":"traceutil/trace.go:171","msg":"trace[1983731033] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"221.074231ms","start":"2026-09-09T20:15:50.860778Z","end":"2026-09-09T20:15:51.081852Z","steps":["trace[1983731033] 'process raft request' (duration: 220.827134ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.081801Z","caller":"traceutil/trace.go:171","msg":"trace[1518735526] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"225.403035ms","start":"2026-09-09T20:15:50.856297Z","end":"2026-09-09T20:15:51.0817Z","steps":["trace[1518735526] 'process raft request' (duration: 134.392131ms)","trace[1518735526] 'compare' (duration: 90.307701ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:51.08322Z","caller":"traceutil/trace.go:171","msg":"trace[187630251] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"222.33222ms","start":"2026-09-09T20:15:50.860862Z","end":"2026-09-09T20:15:51.083195Z","steps":["trace[187630251] 'process raft request' (duration: 220.898296ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.083324Z","caller":"traceutil/trace.go:171","msg":"trace[1033187709] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"217.763959ms","start":"2026-09-09T20:15:50.865102Z","end":"2026-09-09T20:15:51.082866Z","steps":["trace[1033187709] 'process raft request' (duration: 217.387227ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.086984Z","caller":"traceutil/trace.go:171","msg":"trace[1278562767] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"221.690791ms","start":"2026-09-09T20:15:50.86527Z","end":"2026-09-09T20:15:51.08696Z","steps":["trace[1278562767] 'process raft request' (duration: 217.507621ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.087034Z","caller":"traceutil/trace.go:171","msg":"trace[1365533909] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"221.153493ms","start":"2026-09-09T20:15:50.865826Z","end":"2026-09-09T20:15:51.086979Z","steps":["trace[1365533909] 'process raft request' (duration: 218.693797ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.089255Z","caller":"traceutil/trace.go:171","msg":"trace[1434839588] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"220.124221ms","start":"2026-09-09T20:15:50.869083Z","end":"2026-09-09T20:15:51.089207Z","steps":["trace[1434839588] 'process raft request' (duration: 216.969944ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.091207Z","caller":"traceutil/trace.go:171","msg":"trace[1861892139] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"162.777661ms","start":"2026-09-09T20:15:50.92841Z","end":"2026-09-09T20:15:51.091187Z","steps":["trace[1861892139] 'process raft request' (duration: 162.627617ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.091418Z","caller":"traceutil/trace.go:171","msg":"trace[1422405528] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"220.580136ms","start":"2026-09-09T20:15:50.870826Z","end":"2026-09-09T20:15:51.091406Z","steps":["trace[1422405528] 'process raft request' (duration: 218.161851ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.091592Z","caller":"traceutil/trace.go:171","msg":"trace[553595354] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"207.188382ms","start":"2026-09-09T20:15:50.884316Z","end":"2026-09-09T20:15:51.091504Z","steps":["trace[553595354] 'process raft request' (duration: 206.620294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:51.306681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.726254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T20:15:51.30683Z","caller":"traceutil/trace.go:171","msg":"trace[395204638] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"185.314951ms","start":"2026-09-09T20:15:51.12149Z","end":"2026-09-09T20:15:51.306805Z","steps":["trace[395204638] 'read index received' (duration: 13.069415ms)","trace[395204638] 'applied index is now lower than readState.Index' (duration: 172.244126ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:51.306937Z","caller":"traceutil/trace.go:171","msg":"trace[423154718] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"185.715653ms","start":"2026-09-09T20:15:51.121209Z","end":"2026-09-09T20:15:51.306925Z","steps":["trace[423154718] 'process raft request' (duration: 73.446171ms)","trace[423154718] 'compare' (duration: 111.506518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:15:51.307288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.789015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-09T20:15:51.307334Z","caller":"traceutil/trace.go:171","msg":"trace[1660468023] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:524; }","duration":"185.831376ms","start":"2026-09-09T20:15:51.121486Z","end":"2026-09-09T20:15:51.307318Z","steps":["trace[1660468023] 'agreement among raft nodes before linearized reading' (duration: 185.60542ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.309601Z","caller":"traceutil/trace.go:171","msg":"trace[852826114] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"160.229805ms","start":"2026-09-09T20:15:51.149352Z","end":"2026-09-09T20:15:51.309581Z","steps":["trace[852826114] 'process raft request' (duration: 159.726809ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.309624Z","caller":"traceutil/trace.go:171","msg":"trace[1793546056] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"136.361376ms","start":"2026-09-09T20:15:51.173235Z","end":"2026-09-09T20:15:51.309596Z","steps":["trace[1793546056] 'process raft request' (duration: 136.311705ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.310256Z","caller":"traceutil/trace.go:171","msg":"trace[1558051370] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"145.347565ms","start":"2026-09-09T20:15:51.164888Z","end":"2026-09-09T20:15:51.310236Z","steps":["trace[1558051370] 'process raft request' (duration: 144.5265ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.311248Z","caller":"traceutil/trace.go:171","msg":"trace[944306082] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"161.786723ms","start":"2026-09-09T20:15:51.149446Z","end":"2026-09-09T20:15:51.311233Z","steps":["trace[944306082] 'process raft request' (duration: 159.860483ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.311496Z","caller":"traceutil/trace.go:171","msg":"trace[1672890297] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"142.903448ms","start":"2026-09-09T20:15:51.16858Z","end":"2026-09-09T20:15:51.311483Z","steps":["trace[1672890297] 'process raft request' (duration: 140.920767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:51.319367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.844868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-09T20:15:51.319429Z","caller":"traceutil/trace.go:171","msg":"trace[1884845238] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:531; }","duration":"197.951461ms","start":"2026-09-09T20:15:51.121462Z","end":"2026-09-09T20:15:51.319413Z","steps":["trace[1884845238] 'agreement among raft nodes before linearized reading' (duration: 197.768546ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.319616Z","caller":"traceutil/trace.go:171","msg":"trace[1395140534] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"117.527304ms","start":"2026-09-09T20:15:51.202078Z","end":"2026-09-09T20:15:51.319605Z","steps":["trace[1395140534] 'process raft request' (duration: 116.811291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:51.319866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.851894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T20:15:51.319904Z","caller":"traceutil/trace.go:171","msg":"trace[40079725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"150.923767ms","start":"2026-09-09T20:15:51.168968Z","end":"2026-09-09T20:15:51.319892Z","steps":["trace[40079725] 'agreement among raft nodes before linearized reading' (duration: 150.812183ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.688368Z","caller":"traceutil/trace.go:171","msg":"trace[882481664] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"186.691082ms","start":"2026-09-09T20:15:51.50165Z","end":"2026-09-09T20:15:51.688341Z","steps":["trace[882481664] 'process raft request' (duration: 186.61581ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:51.688412Z","caller":"traceutil/trace.go:171","msg":"trace[60467771] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"200.493529ms","start":"2026-09-09T20:15:51.487862Z","end":"2026-09-09T20:15:51.688355Z","steps":["trace[60467771] 'process raft request' (duration: 143.707254ms)","trace[60467771] 'compare' (duration: 56.491746ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:51.692065Z","caller":"traceutil/trace.go:171","msg":"trace[12833219] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"172.690011ms","start":"2026-09-09T20:15:51.519359Z","end":"2026-09-09T20:15:51.692049Z","steps":["trace[12833219] 'process raft request' (duration: 172.551827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:54.439789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9321220358933525208,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-09T20:15:54.836365Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002923526s","expected-duration":"1s"} {"level":"info","ts":"2026-09-09T20:15:54.836999Z","caller":"traceutil/trace.go:171","msg":"trace[1630898287] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"897.728326ms","start":"2026-09-09T20:15:53.939243Z","end":"2026-09-09T20:15:54.836971Z","steps":["trace[1630898287] 'read index received' (duration: 897.358515ms)","trace[1630898287] 'applied index is now lower than readState.Index' (duration: 368.651µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:15:54.837135Z","caller":"traceutil/trace.go:171","msg":"trace[1152101319] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.003740492s","start":"2026-09-09T20:15:53.833324Z","end":"2026-09-09T20:15:54.837065Z","steps":["trace[1152101319] 'process raft request' (duration: 1.003435502s)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:54.837297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.464497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:15:54.837359Z","caller":"traceutil/trace.go:171","msg":"trace[954542646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"101.595141ms","start":"2026-09-09T20:15:54.735744Z","end":"2026-09-09T20:15:54.837339Z","steps":["trace[954542646] 'agreement among raft nodes before linearized reading' (duration: 101.477097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:54.837361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:53.833299Z","time spent":"1.003932467s","remote":"127.0.0.1:33806","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-09-09T20:15:54.837412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.648337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-09T20:15:54.837499Z","caller":"traceutil/trace.go:171","msg":"trace[543656071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"517.817193ms","start":"2026-09-09T20:15:54.319661Z","end":"2026-09-09T20:15:54.837478Z","steps":["trace[543656071] 'agreement among raft nodes before linearized reading' (duration: 517.537665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:54.837546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:54.319635Z","time spent":"517.902476ms","remote":"127.0.0.1:33806","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-09-09T20:15:54.837962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.466078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.183\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T20:15:54.838011Z","caller":"traceutil/trace.go:171","msg":"trace[1871507269] range","detail":"{range_begin:/registry/masterleases/162.253.55.183; range_end:; response_count:1; response_revision:581; }","duration":"898.794199ms","start":"2026-09-09T20:15:53.939204Z","end":"2026-09-09T20:15:54.837998Z","steps":["trace[1871507269] 'agreement among raft nodes before linearized reading' (duration: 897.934992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:54.838045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:53.939174Z","time spent":"898.860391ms","remote":"127.0.0.1:33576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.183\" "} {"level":"warn","ts":"2026-09-09T20:15:55.186637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.158942ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-09T20:15:55.18701Z","caller":"traceutil/trace.go:171","msg":"trace[2101179599] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"289.769775ms","start":"2026-09-09T20:15:54.897214Z","end":"2026-09-09T20:15:55.186983Z","steps":["trace[2101179599] 'read index received' (duration: 150.287174ms)","trace[2101179599] 'applied index is now lower than readState.Index' (duration: 139.481061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:15:55.187182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.413617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:15:55.18724Z","caller":"traceutil/trace.go:171","msg":"trace[1828931450] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:582; }","duration":"223.552432ms","start":"2026-09-09T20:15:54.96367Z","end":"2026-09-09T20:15:55.187222Z","steps":["trace[1828931450] 'agreement among raft nodes before linearized reading' (duration: 223.453669ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:55.187131Z","caller":"traceutil/trace.go:171","msg":"trace[1377299419] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"344.0841ms","start":"2026-09-09T20:15:54.843017Z","end":"2026-09-09T20:15:55.187101Z","steps":["trace[1377299419] 'process raft request' (duration: 343.785361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:55.187278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:54.839561Z","time spent":"347.673631ms","remote":"127.0.0.1:33576","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-09T20:15:55.187385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.117255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T20:15:55.18745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:54.842985Z","time spent":"344.346508ms","remote":"127.0.0.1:33806","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-09-09T20:15:55.187492Z","caller":"traceutil/trace.go:171","msg":"trace[1047529856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"290.330912ms","start":"2026-09-09T20:15:54.897136Z","end":"2026-09-09T20:15:55.187467Z","steps":["trace[1047529856] 'agreement among raft nodes before linearized reading' (duration: 289.985622ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:59.468161Z","caller":"traceutil/trace.go:171","msg":"trace[2117563211] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"265.818278ms","start":"2026-09-09T20:15:59.202306Z","end":"2026-09-09T20:15:59.468125Z","steps":["trace[2117563211] 'read index received' (duration: 169.597977ms)","trace[2117563211] 'applied index is now lower than readState.Index' (duration: 96.21863ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:15:59.468476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.147058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-09T20:15:59.468542Z","caller":"traceutil/trace.go:171","msg":"trace[1719170096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"266.288832ms","start":"2026-09-09T20:15:59.202242Z","end":"2026-09-09T20:15:59.468531Z","steps":["trace[1719170096] 'agreement among raft nodes before linearized reading' (duration: 266.042084ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:15:59.492794Z","caller":"traceutil/trace.go:171","msg":"trace[887291164] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"193.880005ms","start":"2026-09-09T20:15:59.298878Z","end":"2026-09-09T20:15:59.492758Z","steps":["trace[887291164] 'process raft request' (duration: 193.148373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:59.492962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.900549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T20:15:59.493038Z","caller":"traceutil/trace.go:171","msg":"trace[1123708946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"113.069035ms","start":"2026-09-09T20:15:59.379949Z","end":"2026-09-09T20:15:59.493018Z","steps":["trace[1123708946] 'agreement among raft nodes before linearized reading' (duration: 112.813547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:15:59.493776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.817042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-09T20:15:59.493828Z","caller":"traceutil/trace.go:171","msg":"trace[2024712692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"269.913345ms","start":"2026-09-09T20:15:59.223901Z","end":"2026-09-09T20:15:59.493815Z","steps":["trace[2024712692] 'agreement among raft nodes before linearized reading' (duration: 268.773379ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:00.237676Z","caller":"traceutil/trace.go:171","msg":"trace[233448993] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"276.056274ms","start":"2026-09-09T20:15:59.961599Z","end":"2026-09-09T20:16:00.237655Z","steps":["trace[233448993] 'read index received' (duration: 275.889669ms)","trace[233448993] 'applied index is now lower than readState.Index' (duration: 165.445µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:16:00.237924Z","caller":"traceutil/trace.go:171","msg":"trace[1921306509] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"644.068537ms","start":"2026-09-09T20:15:59.593818Z","end":"2026-09-09T20:16:00.237887Z","steps":["trace[1921306509] 'process raft request' (duration: 643.700216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:00.237931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.312762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cg7xs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-09T20:16:00.238044Z","caller":"traceutil/trace.go:171","msg":"trace[161914420] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cg7xs; range_end:; response_count:1; response_revision:615; }","duration":"276.486807ms","start":"2026-09-09T20:15:59.961545Z","end":"2026-09-09T20:16:00.238032Z","steps":["trace[161914420] 'agreement among raft nodes before linearized reading' (duration: 276.210708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:00.238129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:15:59.593787Z","time spent":"644.248553ms","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T20:16:14.046389Z","caller":"traceutil/trace.go:171","msg":"trace[1961587744] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"105.527371ms","start":"2026-09-09T20:16:13.940836Z","end":"2026-09-09T20:16:14.046364Z","steps":["trace[1961587744] 'read index received' (duration: 105.299184ms)","trace[1961587744] 'applied index is now lower than readState.Index' (duration: 227.207µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:16:14.046434Z","caller":"traceutil/trace.go:171","msg":"trace[347949023] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"154.63204ms","start":"2026-09-09T20:16:13.891778Z","end":"2026-09-09T20:16:14.04641Z","steps":["trace[347949023] 'process raft request' (duration: 154.435544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:14.046684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.769849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.183\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T20:16:14.04674Z","caller":"traceutil/trace.go:171","msg":"trace[1769631731] range","detail":"{range_begin:/registry/masterleases/162.253.55.183; range_end:; response_count:1; response_revision:764; }","duration":"105.939855ms","start":"2026-09-09T20:16:13.940785Z","end":"2026-09-09T20:16:14.046724Z","steps":["trace[1769631731] 'agreement among raft nodes before linearized reading' (duration: 105.680027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:17.132258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.028041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:16:17.132365Z","caller":"traceutil/trace.go:171","msg":"trace[289997763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"397.201646ms","start":"2026-09-09T20:16:16.735138Z","end":"2026-09-09T20:16:17.13234Z","steps":["trace[289997763] 'range keys from in-memory index tree' (duration: 396.947418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:17.132324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.931305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-09T20:16:17.132422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:16:16.735113Z","time spent":"397.29373ms","remote":"127.0.0.1:33526","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-09-09T20:16:17.132438Z","caller":"traceutil/trace.go:171","msg":"trace[1306891482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"199.115132ms","start":"2026-09-09T20:16:16.933302Z","end":"2026-09-09T20:16:17.132417Z","steps":["trace[1306891482] 'range keys from in-memory index tree' (duration: 198.737249ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.528232Z","caller":"traceutil/trace.go:171","msg":"trace[310953466] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"124.966824ms","start":"2026-09-09T20:16:37.403237Z","end":"2026-09-09T20:16:37.528204Z","steps":["trace[310953466] 'process raft request' (duration: 122.807675ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.528376Z","caller":"traceutil/trace.go:171","msg":"trace[1800811805] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"121.72312ms","start":"2026-09-09T20:16:37.406625Z","end":"2026-09-09T20:16:37.528348Z","steps":["trace[1800811805] 'process raft request' (duration: 121.512133ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.545457Z","caller":"traceutil/trace.go:171","msg":"trace[1748450075] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"100.251803ms","start":"2026-09-09T20:16:37.445181Z","end":"2026-09-09T20:16:37.545432Z","steps":["trace[1748450075] 'process raft request' (duration: 100.035196ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.69666Z","caller":"traceutil/trace.go:171","msg":"trace[606920475] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"165.465842ms","start":"2026-09-09T20:16:37.531165Z","end":"2026-09-09T20:16:37.696631Z","steps":["trace[606920475] 'process raft request' (duration: 123.979812ms)","trace[606920475] 'compare' (duration: 41.304504ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:16:37.714845Z","caller":"traceutil/trace.go:171","msg":"trace[1929402457] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"164.941406ms","start":"2026-09-09T20:16:37.54989Z","end":"2026-09-09T20:16:37.714831Z","steps":["trace[1929402457] 'process raft request' (duration: 164.759661ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.714989Z","caller":"traceutil/trace.go:171","msg":"trace[1347431438] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"164.957026ms","start":"2026-09-09T20:16:37.550007Z","end":"2026-09-09T20:16:37.714964Z","steps":["trace[1347431438] 'process raft request' (duration: 164.723179ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.7152Z","caller":"traceutil/trace.go:171","msg":"trace[44129785] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"110.749718ms","start":"2026-09-09T20:16:37.604435Z","end":"2026-09-09T20:16:37.715184Z","steps":["trace[44129785] 'process raft request' (duration: 110.634855ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:37.715255Z","caller":"traceutil/trace.go:171","msg":"trace[1931593282] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"113.983602ms","start":"2026-09-09T20:16:37.601252Z","end":"2026-09-09T20:16:37.715235Z","steps":["trace[1931593282] 'process raft request' (duration: 113.564009ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.01068Z","caller":"traceutil/trace.go:171","msg":"trace[1000915675] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"104.622394ms","start":"2026-09-09T20:16:37.906038Z","end":"2026-09-09T20:16:38.010661Z","steps":["trace[1000915675] 'process raft request' (duration: 104.51764ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.010728Z","caller":"traceutil/trace.go:171","msg":"trace[2046866409] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"104.756557ms","start":"2026-09-09T20:16:37.905919Z","end":"2026-09-09T20:16:38.010676Z","steps":["trace[2046866409] 'process raft request' (duration: 104.477578ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.011076Z","caller":"traceutil/trace.go:171","msg":"trace[1140667591] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"104.951093ms","start":"2026-09-09T20:16:37.906104Z","end":"2026-09-09T20:16:38.011056Z","steps":["trace[1140667591] 'process raft request' (duration: 104.52193ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.011167Z","caller":"traceutil/trace.go:171","msg":"trace[543900122] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"101.917436ms","start":"2026-09-09T20:16:37.909229Z","end":"2026-09-09T20:16:38.011146Z","steps":["trace[543900122] 'process raft request' (duration: 101.819213ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.01136Z","caller":"traceutil/trace.go:171","msg":"trace[240714435] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"102.584167ms","start":"2026-09-09T20:16:37.908759Z","end":"2026-09-09T20:16:38.011343Z","steps":["trace[240714435] 'process raft request' (duration: 102.05255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:38.011526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.07301ms","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-09-09T20:16:38.01136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.110364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4640"} {"level":"warn","ts":"2026-09-09T20:16:38.011558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.813865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-09-09T20:16:38.011632Z","caller":"traceutil/trace.go:171","msg":"trace[119018303] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1067; }","duration":"142.421145ms","start":"2026-09-09T20:16:37.869196Z","end":"2026-09-09T20:16:38.011617Z","steps":["trace[119018303] 'agreement among raft nodes before linearized reading' (duration: 141.939098ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.011644Z","caller":"traceutil/trace.go:171","msg":"trace[974881670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"126.208914ms","start":"2026-09-09T20:16:37.885396Z","end":"2026-09-09T20:16:38.011605Z","steps":["trace[974881670] 'agreement among raft nodes before linearized reading' (duration: 125.976216ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:38.011652Z","caller":"traceutil/trace.go:171","msg":"trace[1571965047] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1067; }","duration":"141.95788ms","start":"2026-09-09T20:16:37.869673Z","end":"2026-09-09T20:16:38.011631Z","steps":["trace[1571965047] 'agreement among raft nodes before linearized reading' (duration: 141.728482ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:50.23137Z","caller":"traceutil/trace.go:171","msg":"trace[1561579221] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"122.344585ms","start":"2026-09-09T20:16:50.108995Z","end":"2026-09-09T20:16:50.23134Z","steps":["trace[1561579221] 'process raft request' (duration: 121.965013ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:16:50.717002Z","caller":"traceutil/trace.go:171","msg":"trace[720567934] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"343.328418ms","start":"2026-09-09T20:16:50.373642Z","end":"2026-09-09T20:16:50.716971Z","steps":["trace[720567934] 'process raft request' (duration: 343.168953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:16:50.717164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:16:50.373617Z","time spent":"343.458633ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T20:16:50.717107Z","caller":"traceutil/trace.go:171","msg":"trace[902997094] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1233; }","duration":"110.081609ms","start":"2026-09-09T20:16:50.606982Z","end":"2026-09-09T20:16:50.717064Z","steps":["trace[902997094] 'read index received' (duration: 110.068129ms)","trace[902997094] 'applied index is now lower than readState.Index' (duration: 10.78µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:16:50.717756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.377998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T20:16:50.717854Z","caller":"traceutil/trace.go:171","msg":"trace[1332249568] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1194; }","duration":"110.878906ms","start":"2026-09-09T20:16:50.606929Z","end":"2026-09-09T20:16:50.717808Z","steps":["trace[1332249568] 'agreement among raft nodes before linearized reading' (duration: 110.290156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:17:09.191705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.620471ms","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-09-09T20:17:09.191847Z","caller":"traceutil/trace.go:171","msg":"trace[984619031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1339; }","duration":"126.789977ms","start":"2026-09-09T20:17:09.065039Z","end":"2026-09-09T20:17:09.191829Z","steps":["trace[984619031] 'range keys from in-memory index tree' (duration: 126.6011ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:17:43.684572Z","caller":"traceutil/trace.go:171","msg":"trace[1965727644] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"129.324053ms","start":"2026-09-09T20:17:43.555136Z","end":"2026-09-09T20:17:43.68446Z","steps":["trace[1965727644] 'process raft request' (duration: 129.056684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:17:44.084657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.455995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.183\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T20:17:44.08474Z","caller":"traceutil/trace.go:171","msg":"trace[1714814599] range","detail":"{range_begin:/registry/masterleases/162.253.55.183; range_end:; response_count:1; response_revision:1888; }","duration":"139.583679ms","start":"2026-09-09T20:17:43.945138Z","end":"2026-09-09T20:17:44.084721Z","steps":["trace[1714814599] 'range keys from in-memory index tree' (duration: 139.309059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:17:44.084727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.758449ms","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-09-09T20:17:44.084796Z","caller":"traceutil/trace.go:171","msg":"trace[1417362463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1888; }","duration":"291.876354ms","start":"2026-09-09T20:17:43.792901Z","end":"2026-09-09T20:17:44.084778Z","steps":["trace[1417362463] 'range keys from in-memory index tree' (duration: 291.625325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:17:50.325542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.835573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T20:17:50.325789Z","caller":"traceutil/trace.go:171","msg":"trace[332324818] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"123.869178ms","start":"2026-09-09T20:17:50.201888Z","end":"2026-09-09T20:17:50.325757Z","steps":["trace[332324818] 'process raft request' (duration: 123.845258ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:17:50.325875Z","caller":"traceutil/trace.go:171","msg":"trace[469841905] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"124.898553ms","start":"2026-09-09T20:17:50.200963Z","end":"2026-09-09T20:17:50.325862Z","steps":["trace[469841905] 'process raft request' (duration: 124.719267ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:17:50.32586Z","caller":"traceutil/trace.go:171","msg":"trace[754873140] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"127.458307ms","start":"2026-09-09T20:17:50.198376Z","end":"2026-09-09T20:17:50.325835Z","steps":["trace[754873140] 'process raft request' (duration: 127.24332ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:17:50.326239Z","caller":"traceutil/trace.go:171","msg":"trace[966897856] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"128.408608ms","start":"2026-09-09T20:17:50.197338Z","end":"2026-09-09T20:17:50.325746Z","steps":["trace[966897856] 'process raft request' (duration: 13.276746ms)","trace[966897856] 'compare' (duration: 114.565564ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:17:56.605111Z","caller":"traceutil/trace.go:171","msg":"trace[645467506] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"121.444034ms","start":"2026-09-09T20:17:56.483635Z","end":"2026-09-09T20:17:56.605079Z","steps":["trace[645467506] 'process raft request' (duration: 120.42238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:17:56.931868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.27249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:17:56.931944Z","caller":"traceutil/trace.go:171","msg":"trace[1792788402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2004; }","duration":"197.385474ms","start":"2026-09-09T20:17:56.73454Z","end":"2026-09-09T20:17:56.931925Z","steps":["trace[1792788402] 'range keys from in-memory index tree' (duration: 197.086354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:12.671096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.445165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-09T20:18:12.671717Z","caller":"traceutil/trace.go:171","msg":"trace[1577787528] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2093; }","duration":"155.089617ms","start":"2026-09-09T20:18:12.516568Z","end":"2026-09-09T20:18:12.671658Z","steps":["trace[1577787528] 'range keys from in-memory index tree' (duration: 154.30677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:12.671314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.545306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T20:18:12.672172Z","caller":"traceutil/trace.go:171","msg":"trace[156662833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2093; }","duration":"144.449777ms","start":"2026-09-09T20:18:12.527709Z","end":"2026-09-09T20:18:12.672159Z","steps":["trace[156662833] 'range keys from in-memory index tree' (duration: 143.427813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:12.671545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.277343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-09T20:18:12.672403Z","caller":"traceutil/trace.go:171","msg":"trace[996851959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2093; }","duration":"142.158332ms","start":"2026-09-09T20:18:12.530233Z","end":"2026-09-09T20:18:12.672391Z","steps":["trace[996851959] 'range keys from in-memory index tree' (duration: 141.156789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:12.671542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.708656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-09T20:18:12.672691Z","caller":"traceutil/trace.go:171","msg":"trace[278461419] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2093; }","duration":"145.804582ms","start":"2026-09-09T20:18:12.526772Z","end":"2026-09-09T20:18:12.672577Z","steps":["trace[278461419] 'range keys from in-memory index tree' (duration: 144.600752ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:18.967824Z","caller":"traceutil/trace.go:171","msg":"trace[403055397] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2207; }","duration":"187.906759ms","start":"2026-09-09T20:18:18.779892Z","end":"2026-09-09T20:18:18.967799Z","steps":["trace[403055397] 'read index received' (duration: 133.611484ms)","trace[403055397] 'applied index is now lower than readState.Index' (duration: 54.294345ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:18:18.96811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.088309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T20:18:18.96815Z","caller":"traceutil/trace.go:171","msg":"trace[1190688642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2125; }","duration":"196.162311ms","start":"2026-09-09T20:18:18.771976Z","end":"2026-09-09T20:18:18.968138Z","steps":["trace[1190688642] 'agreement among raft nodes before linearized reading' (duration: 195.963494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:18.968294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.263478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:18:18.968419Z","caller":"traceutil/trace.go:171","msg":"trace[1143944784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2125; }","duration":"187.409943ms","start":"2026-09-09T20:18:18.78099Z","end":"2026-09-09T20:18:18.9684Z","steps":["trace[1143944784] 'agreement among raft nodes before linearized reading' (duration: 187.253168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:18.968619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.487663ms","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-09-09T20:18:18.968693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.329049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-09T20:18:18.968827Z","caller":"traceutil/trace.go:171","msg":"trace[552559898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2125; }","duration":"197.495714ms","start":"2026-09-09T20:18:18.771312Z","end":"2026-09-09T20:18:18.968807Z","steps":["trace[552559898] 'agreement among raft nodes before linearized reading' (duration: 196.899085ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:18.968696Z","caller":"traceutil/trace.go:171","msg":"trace[1651631137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2125; }","duration":"148.614477ms","start":"2026-09-09T20:18:18.820057Z","end":"2026-09-09T20:18:18.968672Z","steps":["trace[1651631137] 'agreement among raft nodes before linearized reading' (duration: 148.198674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:18.968599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.944636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-09T20:18:18.969271Z","caller":"traceutil/trace.go:171","msg":"trace[491464496] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2125; }","duration":"155.633768ms","start":"2026-09-09T20:18:18.813619Z","end":"2026-09-09T20:18:18.969253Z","steps":["trace[491464496] 'agreement among raft nodes before linearized reading' (duration: 154.723719ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:18.969518Z","caller":"traceutil/trace.go:171","msg":"trace[415345271] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"207.110701ms","start":"2026-09-09T20:18:18.762389Z","end":"2026-09-09T20:18:18.969499Z","steps":["trace[415345271] 'process raft request' (duration: 151.14443ms)","trace[415345271] 'compare' (duration: 54.162791ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:18:18.970268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.792064ms","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-09-09T20:18:18.970362Z","caller":"traceutil/trace.go:171","msg":"trace[148209782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2125; }","duration":"201.30385ms","start":"2026-09-09T20:18:18.769023Z","end":"2026-09-09T20:18:18.970327Z","steps":["trace[148209782] 'agreement among raft nodes before linearized reading' (duration: 199.092128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:19.60525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.582726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T20:18:19.605346Z","caller":"traceutil/trace.go:171","msg":"trace[616205742] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2131; }","duration":"438.73076ms","start":"2026-09-09T20:18:19.166593Z","end":"2026-09-09T20:18:19.605323Z","steps":["trace[616205742] 'count revisions from in-memory index tree' (duration: 438.469952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:19.605403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:18:19.166572Z","time spent":"438.813713ms","remote":"127.0.0.1:33908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-09T20:18:19.626482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.459192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T20:18:19.626709Z","caller":"traceutil/trace.go:171","msg":"trace[1933097937] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"135.647951ms","start":"2026-09-09T20:18:19.491045Z","end":"2026-09-09T20:18:19.626693Z","steps":["trace[1933097937] 'process raft request' (duration: 135.526217ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:19.626818Z","caller":"traceutil/trace.go:171","msg":"trace[749159231] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2214; }","duration":"215.209639ms","start":"2026-09-09T20:18:19.411572Z","end":"2026-09-09T20:18:19.626781Z","steps":["trace[749159231] 'read index received' (duration: 101.381815ms)","trace[749159231] 'applied index is now lower than readState.Index' (duration: 113.823484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T20:18:19.627084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.490058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-09-09T20:18:19.627131Z","caller":"traceutil/trace.go:171","msg":"trace[1208767730] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2132; }","duration":"215.583921ms","start":"2026-09-09T20:18:19.41153Z","end":"2026-09-09T20:18:19.627114Z","steps":["trace[1208767730] 'agreement among raft nodes before linearized reading' (duration: 215.365914ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:19.886963Z","caller":"traceutil/trace.go:171","msg":"trace[1556801338] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"167.384586ms","start":"2026-09-09T20:18:19.719558Z","end":"2026-09-09T20:18:19.886943Z","steps":["trace[1556801338] 'process raft request' (duration: 167.1981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:18:19.887301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.069572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-09T20:18:19.887369Z","caller":"traceutil/trace.go:171","msg":"trace[1149183701] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2133; }","duration":"166.182026ms","start":"2026-09-09T20:18:19.721171Z","end":"2026-09-09T20:18:19.887353Z","steps":["trace[1149183701] 'agreement among raft nodes before linearized reading' (duration: 166.00713ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:18:19.887404Z","caller":"traceutil/trace.go:171","msg":"trace[428282903] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2216; }","duration":"165.672659ms","start":"2026-09-09T20:18:19.721222Z","end":"2026-09-09T20:18:19.886895Z","steps":["trace[428282903] 'read index received' (duration: 165.467452ms)","trace[428282903] 'applied index is now lower than readState.Index' (duration: 204.247µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:18:20.155732Z","caller":"traceutil/trace.go:171","msg":"trace[1342295933] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"290.475795ms","start":"2026-09-09T20:18:19.865235Z","end":"2026-09-09T20:18:20.155711Z","steps":["trace[1342295933] 'process raft request' (duration: 289.882195ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:19:17.434424Z","caller":"traceutil/trace.go:171","msg":"trace[1403240336] transaction","detail":"{read_only:false; response_revision:2487; number_of_response:1; }","duration":"138.248161ms","start":"2026-09-09T20:19:17.29615Z","end":"2026-09-09T20:19:17.434399Z","steps":["trace[1403240336] 'process raft request' (duration: 138.102887ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:19:17.437901Z","caller":"traceutil/trace.go:171","msg":"trace[2106242199] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"103.777658ms","start":"2026-09-09T20:19:17.334098Z","end":"2026-09-09T20:19:17.437875Z","steps":["trace[2106242199] 'process raft request' (duration: 103.634835ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:20:09.955324Z","caller":"traceutil/trace.go:171","msg":"trace[782525435] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"104.854549ms","start":"2026-09-09T20:20:09.850449Z","end":"2026-09-09T20:20:09.955304Z","steps":["trace[782525435] 'process raft request' (duration: 98.785804ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:20:25.611812Z","caller":"traceutil/trace.go:171","msg":"trace[1445992843] linearizableReadLoop","detail":"{readStateIndex:3023; appliedIndex:3022; }","duration":"121.843528ms","start":"2026-09-09T20:20:25.48994Z","end":"2026-09-09T20:20:25.611784Z","steps":["trace[1445992843] 'read index received' (duration: 121.656502ms)","trace[1445992843] 'applied index is now lower than readState.Index' (duration: 185.715µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:20:25.612049Z","caller":"traceutil/trace.go:171","msg":"trace[230887458] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"143.035016ms","start":"2026-09-09T20:20:25.468971Z","end":"2026-09-09T20:20:25.612006Z","steps":["trace[230887458] 'process raft request' (duration: 142.637395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:20:25.61278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.802676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T20:20:25.612834Z","caller":"traceutil/trace.go:171","msg":"trace[1616316926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2909; }","duration":"122.925649ms","start":"2026-09-09T20:20:25.489895Z","end":"2026-09-09T20:20:25.612821Z","steps":["trace[1616316926] 'agreement among raft nodes before linearized reading' (duration: 122.038093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:20:25.943421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.027435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-09T20:20:25.943511Z","caller":"traceutil/trace.go:171","msg":"trace[1261378048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2911; }","duration":"144.17112ms","start":"2026-09-09T20:20:25.799318Z","end":"2026-09-09T20:20:25.943489Z","steps":["trace[1261378048] 'range keys from in-memory index tree' (duration: 143.876281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:22:18.320445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.316227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T20:22:18.320828Z","caller":"traceutil/trace.go:171","msg":"trace[1674622106] transaction","detail":"{read_only:false; response_revision:3489; number_of_response:1; }","duration":"129.42217ms","start":"2026-09-09T20:22:18.191377Z","end":"2026-09-09T20:22:18.320799Z","steps":["trace[1674622106] 'process raft request' (duration: 28.648498ms)","trace[1674622106] 'compare' (duration: 100.038779ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:24:33.932081Z","caller":"traceutil/trace.go:171","msg":"trace[618066761] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"303.149189ms","start":"2026-09-09T20:24:33.628908Z","end":"2026-09-09T20:24:33.932057Z","steps":["trace[618066761] 'read index received' (duration: 303.075486ms)","trace[618066761] 'applied index is now lower than readState.Index' (duration: 72.503µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:24:33.932186Z","caller":"traceutil/trace.go:171","msg":"trace[1126046802] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"305.349469ms","start":"2026-09-09T20:24:33.626824Z","end":"2026-09-09T20:24:33.932173Z","steps":["trace[1126046802] 'process raft request' (duration: 305.045429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:33.932304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:33.626799Z","time spent":"305.410351ms","remote":"127.0.0.1:37964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T20:24:33.932485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.553912ms","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-09-09T20:24:33.932646Z","caller":"traceutil/trace.go:171","msg":"trace[1531042704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4898; }","duration":"303.732478ms","start":"2026-09-09T20:24:33.628895Z","end":"2026-09-09T20:24:33.932627Z","steps":["trace[1531042704] 'agreement among raft nodes before linearized reading' (duration: 303.47045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:33.932684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:33.628888Z","time spent":"303.7868ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T20:24:33.988977Z","caller":"traceutil/trace.go:171","msg":"trace[983330923] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"191.336863ms","start":"2026-09-09T20:24:33.797612Z","end":"2026-09-09T20:24:33.988948Z","steps":["trace[983330923] 'process raft request' (duration: 190.194046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:33.989111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.838785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-09T20:24:33.989148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.392931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-09T20:24:33.989163Z","caller":"traceutil/trace.go:171","msg":"trace[138194292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4900; }","duration":"350.908187ms","start":"2026-09-09T20:24:33.638243Z","end":"2026-09-09T20:24:33.989151Z","steps":["trace[138194292] 'agreement among raft nodes before linearized reading' (duration: 350.772143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:33.989202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:33.638238Z","time spent":"350.955459ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T20:24:33.989204Z","caller":"traceutil/trace.go:171","msg":"trace[223097086] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4900; }","duration":"355.461973ms","start":"2026-09-09T20:24:33.633726Z","end":"2026-09-09T20:24:33.989187Z","steps":["trace[223097086] 'agreement among raft nodes before linearized reading' (duration: 355.305509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:33.989246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:33.63372Z","time spent":"355.513705ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T20:24:33.99198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.423701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-09T20:24:33.992044Z","caller":"traceutil/trace.go:171","msg":"trace[312138896] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4900; }","duration":"149.527934ms","start":"2026-09-09T20:24:33.842499Z","end":"2026-09-09T20:24:33.992027Z","steps":["trace[312138896] 'agreement among raft nodes before linearized reading' (duration: 146.486017ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:24:35.176452Z","caller":"traceutil/trace.go:171","msg":"trace[886516956] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"121.588897ms","start":"2026-09-09T20:24:35.054826Z","end":"2026-09-09T20:24:35.176415Z","steps":["trace[886516956] 'process raft request' (duration: 121.089441ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:24:40.724205Z","caller":"traceutil/trace.go:171","msg":"trace[1542625725] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"172.464689ms","start":"2026-09-09T20:24:40.551714Z","end":"2026-09-09T20:24:40.724179Z","steps":["trace[1542625725] 'process raft request' (duration: 172.303373ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:24:40.92294Z","caller":"traceutil/trace.go:171","msg":"trace[1507162388] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5143; }","duration":"322.149281ms","start":"2026-09-09T20:24:40.600746Z","end":"2026-09-09T20:24:40.922895Z","steps":["trace[1507162388] 'read index received' (duration: 123.275283ms)","trace[1507162388] 'applied index is now lower than readState.Index' (duration: 198.872098ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T20:24:40.923153Z","caller":"traceutil/trace.go:171","msg":"trace[1766146255] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"358.780108ms","start":"2026-09-09T20:24:40.564343Z","end":"2026-09-09T20:24:40.923123Z","steps":["trace[1766146255] 'process raft request' (duration: 358.165927ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:24:40.923228Z","caller":"traceutil/trace.go:171","msg":"trace[2131894764] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"261.288275ms","start":"2026-09-09T20:24:40.661912Z","end":"2026-09-09T20:24:40.9232Z","steps":["trace[2131894764] 'process raft request' (duration: 260.887681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:40.923327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:40.56431Z","time spent":"358.933062ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T20:24:40.923881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.086311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-09T20:24:40.924011Z","caller":"traceutil/trace.go:171","msg":"trace[630895995] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4951; }","duration":"323.248056ms","start":"2026-09-09T20:24:40.600736Z","end":"2026-09-09T20:24:40.923984Z","steps":["trace[630895995] 'agreement among raft nodes before linearized reading' (duration: 322.618577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T20:24:40.924171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T20:24:40.600712Z","time spent":"323.436792ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-09T20:24:40.929329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.5315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T20:24:40.929403Z","caller":"traceutil/trace.go:171","msg":"trace[107665418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"194.662504ms","start":"2026-09-09T20:24:40.734721Z","end":"2026-09-09T20:24:40.929384Z","steps":["trace[107665418] 'agreement among raft nodes before linearized reading' (duration: 194.560131ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:25:09.139382Z","caller":"traceutil/trace.go:171","msg":"trace[1408607287] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"117.53532ms","start":"2026-09-09T20:25:09.021823Z","end":"2026-09-09T20:25:09.139359Z","steps":["trace[1408607287] 'process raft request' (duration: 117.406676ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:25:10.642759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-09-09T20:25:10.72395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"79.825517ms","hash":2331530978,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":27238400,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T20:25:10.724019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2331530978,"revision":2832,"compact-revision":-1} {"level":"info","ts":"2026-09-09T20:29:23.03002Z","caller":"traceutil/trace.go:171","msg":"trace[258010115] transaction","detail":"{read_only:false; response_revision:7095; number_of_response:1; }","duration":"126.547215ms","start":"2026-09-09T20:29:22.903447Z","end":"2026-09-09T20:29:23.029994Z","steps":["trace[258010115] 'process raft request' (duration: 126.411811ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:29:23.070902Z","caller":"traceutil/trace.go:171","msg":"trace[58446777] transaction","detail":"{read_only:false; response_revision:7096; number_of_response:1; }","duration":"165.153892ms","start":"2026-09-09T20:29:22.905729Z","end":"2026-09-09T20:29:23.070883Z","steps":["trace[58446777] 'process raft request' (duration: 164.992187ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T20:30:10.650081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5266} {"level":"info","ts":"2026-09-09T20:30:10.764913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5266,"took":"113.42557ms","hash":2842229957,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":18579456,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-09T20:30:10.764972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2842229957,"revision":5266,"compact-revision":2832}