{"level":"warn","ts":"2026-04-01T05:09:30.649113Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T05:09:30.649237Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-04-01T05:09:30.649478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T05:09:30.649496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-01T05:09:30.649526Z","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-04-01T05:09:30.650381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-01T05:09:30.650472Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-04-01T05:09:30.655322Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.427959ms"} {"level":"info","ts":"2026-04-01T05:09:30.663829Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-01T05:09:30.663919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T05:09:30.663945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-01T05:09:30.663964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T05:09:30.663974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-01T05:09:30.664029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-01T05:09:30.675062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T05:09:30.67778Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T05:09:30.679043Z","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-04-01T05:09:30.681389Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T05:09:30.68162Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T05:09:30.681647Z","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-04-01T05:09:30.6818Z","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-04-01T05:09:30.681809Z","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-04-01T05:09:30.683603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-01T05:09:30.683967Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-01T05:09:30.686395Z","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-04-01T05:09:30.686542Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-01T05:09:30.686616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T05:09:30.686648Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T05:09:30.68662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-01T05:09:31.06444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T05:09:31.064521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T05:09:31.064564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-01T05:09:31.064592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-01T05:09:31.064609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-01T05:09:31.064632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-01T05:09:31.064651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-01T05:09:31.076022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T05:09:31.076204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T05:09:31.076315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T05:09:31.076719Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T05:09:31.07701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T05:09:31.077071Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T05:09:31.078859Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T05:09:31.079441Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T05:09:31.079653Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T05:09:31.079785Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T05:09:31.080327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-01T05:09:33.690177Z","caller":"traceutil/trace.go:171","msg":"trace[1627321079] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"295.401036ms","start":"2026-04-01T05:09:33.394731Z","end":"2026-04-01T05:09:33.690132Z","steps":["trace[1627321079] 'read index received' (duration: 295.144128ms)","trace[1627321079] 'applied index is now lower than readState.Index' (duration: 255.728µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:09:33.690213Z","caller":"traceutil/trace.go:171","msg":"trace[632991384] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"297.104242ms","start":"2026-04-01T05:09:33.39307Z","end":"2026-04-01T05:09:33.690174Z","steps":["trace[632991384] 'process raft request' (duration: 296.921528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:09:33.690565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.792608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T05:09:33.690822Z","caller":"traceutil/trace.go:171","msg":"trace[1169146353] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"296.108175ms","start":"2026-04-01T05:09:33.394699Z","end":"2026-04-01T05:09:33.690807Z","steps":["trace[1169146353] 'agreement among raft nodes before linearized reading' (duration: 295.654773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:09:33.690649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.238096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T05:09:33.690936Z","caller":"traceutil/trace.go:171","msg":"trace[838566696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"197.521104ms","start":"2026-04-01T05:09:33.493379Z","end":"2026-04-01T05:09:33.6909Z","steps":["trace[838566696] 'agreement among raft nodes before linearized reading' (duration: 197.249897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:09:34.444691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.363821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-01T05:09:34.444745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.576653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T05:09:34.444802Z","caller":"traceutil/trace.go:171","msg":"trace[1400581634] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:72; }","duration":"749.514235ms","start":"2026-04-01T05:09:33.695266Z","end":"2026-04-01T05:09:34.44478Z","steps":["trace[1400581634] 'range keys from in-memory index tree' (duration: 749.305719ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:09:34.444824Z","caller":"traceutil/trace.go:171","msg":"trace[173554300] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:72; }","duration":"750.706697ms","start":"2026-04-01T05:09:33.694097Z","end":"2026-04-01T05:09:34.444803Z","steps":["trace[173554300] 'range keys from in-memory index tree' (duration: 750.483861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:09:34.444865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:09:33.694024Z","time spent":"750.83407ms","remote":"127.0.0.1:49238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-04-01T05:09:34.444854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:09:33.695257Z","time spent":"749.586547ms","remote":"127.0.0.1:49258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"info","ts":"2026-04-01T05:09:34.642797Z","caller":"traceutil/trace.go:171","msg":"trace[119958467] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"193.269028ms","start":"2026-04-01T05:09:34.449506Z","end":"2026-04-01T05:09:34.642775Z","steps":["trace[119958467] 'read index received' (duration: 193.097944ms)","trace[119958467] 'applied index is now lower than readState.Index' (duration: 170.284µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:09:34.642853Z","caller":"traceutil/trace.go:171","msg":"trace[903501284] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"193.766103ms","start":"2026-04-01T05:09:34.449051Z","end":"2026-04-01T05:09:34.642817Z","steps":["trace[903501284] 'process raft request' (duration: 193.563517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:09:34.643005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.456003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T05:09:34.643073Z","caller":"traceutil/trace.go:171","msg":"trace[1349840873] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"193.571637ms","start":"2026-04-01T05:09:34.449472Z","end":"2026-04-01T05:09:34.643044Z","steps":["trace[1349840873] 'agreement among raft nodes before linearized reading' (duration: 193.414872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.36437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.607251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T05:11:02.364685Z","caller":"traceutil/trace.go:171","msg":"trace[264387114] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"482.737355ms","start":"2026-04-01T05:11:01.881904Z","end":"2026-04-01T05:11:02.364641Z","steps":["trace[264387114] 'process raft request' (duration: 311.161391ms)","trace[264387114] 'compare' (duration: 170.440498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:11:02.364908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.881871Z","time spent":"482.911441ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.365187Z","caller":"traceutil/trace.go:171","msg":"trace[997094300] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"450.120414ms","start":"2026-04-01T05:11:01.915045Z","end":"2026-04-01T05:11:02.365166Z","steps":["trace[997094300] 'process raft request' (duration: 450.052462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.365296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.915019Z","time spent":"450.228826ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T05:11:02.365367Z","caller":"traceutil/trace.go:171","msg":"trace[360288638] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"471.401618ms","start":"2026-04-01T05:11:01.893946Z","end":"2026-04-01T05:11:02.365348Z","steps":["trace[360288638] 'process raft request' (duration: 470.67875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.365443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.893932Z","time spent":"471.47513ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.365539Z","caller":"traceutil/trace.go:171","msg":"trace[1060163136] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"469.659677ms","start":"2026-04-01T05:11:01.895866Z","end":"2026-04-01T05:11:02.365525Z","steps":["trace[1060163136] 'process raft request' (duration: 468.832097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.365615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.895855Z","time spent":"469.718238ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.365664Z","caller":"traceutil/trace.go:171","msg":"trace[1730859836] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"480.057103ms","start":"2026-04-01T05:11:01.885596Z","end":"2026-04-01T05:11:02.365653Z","steps":["trace[1730859836] 'process raft request' (duration: 478.901705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.365727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.885584Z","time spent":"480.115164ms","remote":"127.0.0.1:49452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T05:11:02.365758Z","caller":"traceutil/trace.go:171","msg":"trace[209287114] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"467.419603ms","start":"2026-04-01T05:11:01.898327Z","end":"2026-04-01T05:11:02.365747Z","steps":["trace[209287114] 'process raft request' (duration: 466.43524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.36582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.898318Z","time spent":"467.473364ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.36595Z","caller":"traceutil/trace.go:171","msg":"trace[1980851627] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"465.20329ms","start":"2026-04-01T05:11:01.900731Z","end":"2026-04-01T05:11:02.365934Z","steps":["trace[1980851627] 'process raft request' (duration: 464.097985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.366041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.900707Z","time spent":"465.289893ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.366147Z","caller":"traceutil/trace.go:171","msg":"trace[666130216] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"454.969158ms","start":"2026-04-01T05:11:01.911168Z","end":"2026-04-01T05:11:02.366137Z","steps":["trace[666130216] 'process raft request' (duration: 453.75187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.366205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.911152Z","time spent":"455.025579ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:02.450767Z","caller":"traceutil/trace.go:171","msg":"trace[2101245354] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:697; }","duration":"411.380725ms","start":"2026-04-01T05:11:02.039358Z","end":"2026-04-01T05:11:02.450739Z","steps":["trace[2101245354] 'read index received' (duration: 153.796203ms)","trace[2101245354] 'applied index is now lower than readState.Index' (duration: 257.583532ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:02.45089Z","caller":"traceutil/trace.go:171","msg":"trace[1903155623] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"405.806653ms","start":"2026-04-01T05:11:02.045069Z","end":"2026-04-01T05:11:02.450876Z","steps":["trace[1903155623] 'process raft request' (duration: 405.569598ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:02.450896Z","caller":"traceutil/trace.go:171","msg":"trace[1098837499] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"522.461927ms","start":"2026-04-01T05:11:01.928385Z","end":"2026-04-01T05:11:02.450847Z","steps":["trace[1098837499] 'process raft request' (duration: 521.952695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.451048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:02.045051Z","time spent":"405.912936ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T05:11:02.451144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:01.928371Z","time spent":"522.650061ms","remote":"127.0.0.1:48896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T05:11:02.451412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.044461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T05:11:02.451456Z","caller":"traceutil/trace.go:171","msg":"trace[1758496027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"412.152303ms","start":"2026-04-01T05:11:02.039291Z","end":"2026-04-01T05:11:02.451444Z","steps":["trace[1758496027] 'agreement among raft nodes before linearized reading' (duration: 412.041081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.451487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:02.039264Z","time spent":"412.216555ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T05:11:02.459116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.684207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:02.45919Z","caller":"traceutil/trace.go:171","msg":"trace[1863367056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"274.82007ms","start":"2026-04-01T05:11:02.184353Z","end":"2026-04-01T05:11:02.459173Z","steps":["trace[1863367056] 'agreement among raft nodes before linearized reading' (duration: 274.690867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:02.459885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.77232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:02.459959Z","caller":"traceutil/trace.go:171","msg":"trace[609499068] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:674; }","duration":"139.847302ms","start":"2026-04-01T05:11:02.320102Z","end":"2026-04-01T05:11:02.459949Z","steps":["trace[609499068] 'agreement among raft nodes before linearized reading' (duration: 139.75983ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:08.598289Z","caller":"traceutil/trace.go:171","msg":"trace[313788886] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"592.856045ms","start":"2026-04-01T05:11:08.005391Z","end":"2026-04-01T05:11:08.598247Z","steps":["trace[313788886] 'process raft request' (duration: 592.078466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:08.598525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.005361Z","time spent":"593.04591ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:08.652926Z","caller":"traceutil/trace.go:171","msg":"trace[2119474357] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:782; }","duration":"468.1446ms","start":"2026-04-01T05:11:08.184751Z","end":"2026-04-01T05:11:08.652895Z","steps":["trace[2119474357] 'read index received' (duration: 412.766958ms)","trace[2119474357] 'applied index is now lower than readState.Index' (duration: 55.376792ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:08.653008Z","caller":"traceutil/trace.go:171","msg":"trace[1346335355] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"621.616296ms","start":"2026-04-01T05:11:08.031346Z","end":"2026-04-01T05:11:08.652962Z","steps":["trace[1346335355] 'process raft request' (duration: 621.015022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:08.653132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.362605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:08.653164Z","caller":"traceutil/trace.go:171","msg":"trace[211388789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"468.466277ms","start":"2026-04-01T05:11:08.18469Z","end":"2026-04-01T05:11:08.653156Z","steps":["trace[211388789] 'agreement among raft nodes before linearized reading' (duration: 468.359235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:08.653189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.184663Z","time spent":"468.520819ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T05:11:08.653286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.004539ms","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-04-01T05:11:08.653392Z","caller":"traceutil/trace.go:171","msg":"trace[1521057909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"165.193963ms","start":"2026-04-01T05:11:08.488174Z","end":"2026-04-01T05:11:08.653368Z","steps":["trace[1521057909] 'agreement among raft nodes before linearized reading' (duration: 164.960608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:08.653427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.0313Z","time spent":"621.842961ms","remote":"127.0.0.1:48944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T05:11:08.653639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.87606ms","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-04-01T05:11:08.653754Z","caller":"traceutil/trace.go:171","msg":"trace[57353236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"150.030833ms","start":"2026-04-01T05:11:08.503684Z","end":"2026-04-01T05:11:08.653715Z","steps":["trace[57353236] 'agreement among raft nodes before linearized reading' (duration: 149.664034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.371919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310389537615517,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T05:11:09.594483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.902195ms","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-04-01T05:11:09.594638Z","caller":"traceutil/trace.go:171","msg":"trace[589152516] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:748; }","duration":"143.080349ms","start":"2026-04-01T05:11:09.451533Z","end":"2026-04-01T05:11:09.594614Z","steps":["trace[589152516] 'range keys from in-memory index tree' (duration: 142.879294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.595903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.909248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T05:11:09.59616Z","caller":"traceutil/trace.go:171","msg":"trace[1711282113] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:784; }","duration":"724.298469ms","start":"2026-04-01T05:11:08.871841Z","end":"2026-04-01T05:11:09.59614Z","steps":["trace[1711282113] 'read index received' (duration: 717.151379ms)","trace[1711282113] 'applied index is now lower than readState.Index' (duration: 7.14596ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:09.596205Z","caller":"traceutil/trace.go:171","msg":"trace[614739318] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"936.30445ms","start":"2026-04-01T05:11:08.65987Z","end":"2026-04-01T05:11:09.596174Z","steps":["trace[614739318] 'process raft request' (duration: 936.157257ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:09.596229Z","caller":"traceutil/trace.go:171","msg":"trace[911992592] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"875.287536ms","start":"2026-04-01T05:11:08.720875Z","end":"2026-04-01T05:11:09.596163Z","steps":["trace[911992592] 'process raft request' (duration: 875.212284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.596328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.564547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T05:11:09.596358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.659854Z","time spent":"936.404283ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:09.596403Z","caller":"traceutil/trace.go:171","msg":"trace[837712030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"410.666369ms","start":"2026-04-01T05:11:09.185716Z","end":"2026-04-01T05:11:09.596382Z","steps":["trace[837712030] 'agreement among raft nodes before linearized reading' (duration: 410.562927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.596424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.585256ms","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-04-01T05:11:09.596448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:09.185697Z","time spent":"410.742251ms","remote":"127.0.0.1:48844","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-04-01T05:11:09.596461Z","caller":"traceutil/trace.go:171","msg":"trace[1624435756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"724.666018ms","start":"2026-04-01T05:11:08.871784Z","end":"2026-04-01T05:11:09.59645Z","steps":["trace[1624435756] 'agreement among raft nodes before linearized reading' (duration: 724.491814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.596502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.871756Z","time spent":"724.736899ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T05:11:09.596579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.72084Z","time spent":"875.523562ms","remote":"127.0.0.1:49242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T05:11:09.596111Z","caller":"traceutil/trace.go:171","msg":"trace[1745684585] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"936.902655ms","start":"2026-04-01T05:11:08.659183Z","end":"2026-04-01T05:11:09.596086Z","steps":["trace[1745684585] 'compare' (duration: 926.751695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.596772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:08.659156Z","time spent":"937.548271ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:09.800299Z","caller":"traceutil/trace.go:171","msg":"trace[1355759829] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"186.131089ms","start":"2026-04-01T05:11:09.614138Z","end":"2026-04-01T05:11:09.800269Z","steps":["trace[1355759829] 'read index received' (duration: 175.904147ms)","trace[1355759829] 'applied index is now lower than readState.Index' (duration: 10.225862ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:09.800314Z","caller":"traceutil/trace.go:171","msg":"trace[748915643] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"196.822734ms","start":"2026-04-01T05:11:09.603463Z","end":"2026-04-01T05:11:09.800286Z","steps":["trace[748915643] 'process raft request' (duration: 196.67285ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:09.800375Z","caller":"traceutil/trace.go:171","msg":"trace[1914832051] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"198.587865ms","start":"2026-04-01T05:11:09.60178Z","end":"2026-04-01T05:11:09.800367Z","steps":["trace[1914832051] 'process raft request' (duration: 188.136307ms)","trace[1914832051] 'compare' (duration: 10.054878ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:09.800496Z","caller":"traceutil/trace.go:171","msg":"trace[805397116] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"146.413128ms","start":"2026-04-01T05:11:09.654049Z","end":"2026-04-01T05:11:09.800462Z","steps":["trace[805397116] 'process raft request' (duration: 146.127341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:09.800565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.417637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:09.800593Z","caller":"traceutil/trace.go:171","msg":"trace[1806195070] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:755; }","duration":"186.462678ms","start":"2026-04-01T05:11:09.61412Z","end":"2026-04-01T05:11:09.800583Z","steps":["trace[1806195070] 'agreement among raft nodes before linearized reading' (duration: 186.381836ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:10.440921Z","caller":"traceutil/trace.go:171","msg":"trace[1452602035] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"187.700557ms","start":"2026-04-01T05:11:10.253185Z","end":"2026-04-01T05:11:10.440886Z","steps":["trace[1452602035] 'process raft request' (duration: 186.850497ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:10.601755Z","caller":"traceutil/trace.go:171","msg":"trace[348205460] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"104.398973ms","start":"2026-04-01T05:11:10.497332Z","end":"2026-04-01T05:11:10.601731Z","steps":["trace[348205460] 'compare' (duration: 96.032825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:11.399881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.358129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:11.400031Z","caller":"traceutil/trace.go:171","msg":"trace[2107960373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"214.544333ms","start":"2026-04-01T05:11:11.185454Z","end":"2026-04-01T05:11:11.399998Z","steps":["trace[2107960373] 'range keys from in-memory index tree' (duration: 214.167434ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:11.414956Z","caller":"traceutil/trace.go:171","msg":"trace[1086874865] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"227.663553ms","start":"2026-04-01T05:11:11.187235Z","end":"2026-04-01T05:11:11.414899Z","steps":["trace[1086874865] 'process raft request' (duration: 227.199373ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:16.187868Z","caller":"traceutil/trace.go:171","msg":"trace[401451503] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"124.448158ms","start":"2026-04-01T05:11:16.063372Z","end":"2026-04-01T05:11:16.18782Z","steps":["trace[401451503] 'process raft request' (duration: 124.184312ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:16.563081Z","caller":"traceutil/trace.go:171","msg":"trace[318576973] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"198.084593ms","start":"2026-04-01T05:11:16.364969Z","end":"2026-04-01T05:11:16.563053Z","steps":["trace[318576973] 'process raft request' (duration: 197.890519ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:16.711065Z","caller":"traceutil/trace.go:171","msg":"trace[1283663546] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"138.684606ms","start":"2026-04-01T05:11:16.572345Z","end":"2026-04-01T05:11:16.71103Z","steps":["trace[1283663546] 'process raft request' (duration: 138.348269ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:16.759753Z","caller":"traceutil/trace.go:171","msg":"trace[158531808] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"149.190954ms","start":"2026-04-01T05:11:16.610543Z","end":"2026-04-01T05:11:16.759734Z","steps":["trace[158531808] 'process raft request' (duration: 148.336604ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:16.760238Z","caller":"traceutil/trace.go:171","msg":"trace[182961195] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"104.397013ms","start":"2026-04-01T05:11:16.655789Z","end":"2026-04-01T05:11:16.760186Z","steps":["trace[182961195] 'process raft request' (duration: 103.269547ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:23.900052Z","caller":"traceutil/trace.go:171","msg":"trace[1158920160] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"225.580755ms","start":"2026-04-01T05:11:23.674441Z","end":"2026-04-01T05:11:23.900022Z","steps":["trace[1158920160] 'process raft request' (duration: 225.37898ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:11:23.904851Z","caller":"traceutil/trace.go:171","msg":"trace[568146394] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"202.308254ms","start":"2026-04-01T05:11:23.702514Z","end":"2026-04-01T05:11:23.904822Z","steps":["trace[568146394] 'read index received' (duration: 197.358136ms)","trace[568146394] 'applied index is now lower than readState.Index' (duration: 4.948468ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:23.904998Z","caller":"traceutil/trace.go:171","msg":"trace[1608662194] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"229.910346ms","start":"2026-04-01T05:11:23.675023Z","end":"2026-04-01T05:11:23.904934Z","steps":["trace[1608662194] 'process raft request' (duration: 229.65127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:23.905094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.547679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T05:11:23.905146Z","caller":"traceutil/trace.go:171","msg":"trace[5456960] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:898; }","duration":"202.661192ms","start":"2026-04-01T05:11:23.702472Z","end":"2026-04-01T05:11:23.905133Z","steps":["trace[5456960] 'agreement among raft nodes before linearized reading' (duration: 202.547509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:27.594585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.017002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T05:11:27.594773Z","caller":"traceutil/trace.go:171","msg":"trace[1292659842] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"410.748562ms","start":"2026-04-01T05:11:27.184Z","end":"2026-04-01T05:11:27.594749Z","steps":["trace[1292659842] 'read index received' (duration: 268.973792ms)","trace[1292659842] 'applied index is now lower than readState.Index' (duration: 141.77271ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:27.594883Z","caller":"traceutil/trace.go:171","msg":"trace[1339302657] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"466.481152ms","start":"2026-04-01T05:11:27.128389Z","end":"2026-04-01T05:11:27.59487Z","steps":["trace[1339302657] 'process raft request' (duration: 324.544078ms)","trace[1339302657] 'compare' (duration: 140.722264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:11:27.595141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.072949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:11:27.595211Z","caller":"traceutil/trace.go:171","msg":"trace[1022257360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"411.265453ms","start":"2026-04-01T05:11:27.183925Z","end":"2026-04-01T05:11:27.59519Z","steps":["trace[1022257360] 'agreement among raft nodes before linearized reading' (duration: 411.039808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:11:27.595252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:27.183896Z","time spent":"411.346445ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T05:11:27.595677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:11:27.128371Z","time spent":"466.551773ms","remote":"127.0.0.1:49138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:11:27.758501Z","caller":"traceutil/trace.go:171","msg":"trace[1691368518] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"150.431623ms","start":"2026-04-01T05:11:27.608021Z","end":"2026-04-01T05:11:27.758453Z","steps":["trace[1691368518] 'process raft request' (duration: 92.559863ms)","trace[1691368518] 'compare' (duration: 57.706976ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:11:32.933752Z","caller":"traceutil/trace.go:171","msg":"trace[1083595629] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"143.894639ms","start":"2026-04-01T05:11:32.789825Z","end":"2026-04-01T05:11:32.93372Z","steps":["trace[1083595629] 'process raft request' (duration: 136.143516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:03.024592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310389537616388,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T05:12:03.31654Z","caller":"traceutil/trace.go:171","msg":"trace[1925450312] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"792.370168ms","start":"2026-04-01T05:12:02.52414Z","end":"2026-04-01T05:12:03.31651Z","steps":["trace[1925450312] 'read index received' (duration: 792.143183ms)","trace[1925450312] 'applied index is now lower than readState.Index' (duration: 225.995µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:12:03.31699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.833669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:12:03.317036Z","caller":"traceutil/trace.go:171","msg":"trace[761697970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"792.940281ms","start":"2026-04-01T05:12:02.524085Z","end":"2026-04-01T05:12:03.317025Z","steps":["trace[761697970] 'agreement among raft nodes before linearized reading' (duration: 792.852359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:03.317071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:02.524058Z","time spent":"793.006183ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T05:12:03.317049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.795579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:12:03.317158Z","caller":"traceutil/trace.go:171","msg":"trace[1697383733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"131.958743ms","start":"2026-04-01T05:12:03.185175Z","end":"2026-04-01T05:12:03.317133Z","steps":["trace[1697383733] 'agreement among raft nodes before linearized reading' (duration: 131.767417ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:12:03.317276Z","caller":"traceutil/trace.go:171","msg":"trace[637180696] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"802.695923ms","start":"2026-04-01T05:12:02.514569Z","end":"2026-04-01T05:12:03.317265Z","steps":["trace[637180696] 'process raft request' (duration: 801.78206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:03.317378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:02.514539Z","time spent":"802.772164ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:12:03.58869Z","caller":"traceutil/trace.go:171","msg":"trace[1125104279] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"265.322017ms","start":"2026-04-01T05:12:03.323331Z","end":"2026-04-01T05:12:03.588653Z","steps":["trace[1125104279] 'process raft request' (duration: 262.107181ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:12:27.511082Z","caller":"traceutil/trace.go:171","msg":"trace[707394310] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"103.051638ms","start":"2026-04-01T05:12:27.408001Z","end":"2026-04-01T05:12:27.511052Z","steps":["trace[707394310] 'process raft request' (duration: 102.885684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:28.191566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.58639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T05:12:28.191671Z","caller":"traceutil/trace.go:171","msg":"trace[860167234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"477.764584ms","start":"2026-04-01T05:12:27.713884Z","end":"2026-04-01T05:12:28.191648Z","steps":["trace[860167234] 'range keys from in-memory index tree' (duration: 477.372155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:28.19172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:27.713858Z","time spent":"477.852427ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-01T05:12:29.721904Z","caller":"traceutil/trace.go:171","msg":"trace[6604856] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"204.381176ms","start":"2026-04-01T05:12:29.517487Z","end":"2026-04-01T05:12:29.721869Z","steps":["trace[6604856] 'process raft request' (duration: 204.054188ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:12:41.991703Z","caller":"traceutil/trace.go:171","msg":"trace[1503245729] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"167.79305ms","start":"2026-04-01T05:12:41.823877Z","end":"2026-04-01T05:12:41.99167Z","steps":["trace[1503245729] 'process raft request' (duration: 140.32492ms)","trace[1503245729] 'compare' (duration: 27.321357ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:12:43.003022Z","caller":"traceutil/trace.go:171","msg":"trace[1648477373] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"488.669851ms","start":"2026-04-01T05:12:42.514321Z","end":"2026-04-01T05:12:43.002991Z","steps":["trace[1648477373] 'read index received' (duration: 488.499357ms)","trace[1648477373] 'applied index is now lower than readState.Index' (duration: 169.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:12:43.00312Z","caller":"traceutil/trace.go:171","msg":"trace[1364131451] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"489.606074ms","start":"2026-04-01T05:12:42.513477Z","end":"2026-04-01T05:12:43.003083Z","steps":["trace[1364131451] 'process raft request' (duration: 489.338408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.003269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:42.513459Z","time spent":"489.730128ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T05:12:43.003336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.238824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T05:12:43.003387Z","caller":"traceutil/trace.go:171","msg":"trace[129401669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"486.340297ms","start":"2026-04-01T05:12:42.517023Z","end":"2026-04-01T05:12:43.003363Z","steps":["trace[129401669] 'agreement among raft nodes before linearized reading' (duration: 486.142422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.003363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.02437ms","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-04-01T05:12:43.003433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:42.517011Z","time spent":"486.412968ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T05:12:43.003457Z","caller":"traceutil/trace.go:171","msg":"trace[442699160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"489.181803ms","start":"2026-04-01T05:12:42.514251Z","end":"2026-04-01T05:12:43.003433Z","steps":["trace[442699160] 'agreement among raft nodes before linearized reading' (duration: 488.860416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.003512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:42.514224Z","time spent":"489.278146ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T05:12:43.003435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.757987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T05:12:43.003562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.030387ms","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-04-01T05:12:43.003582Z","caller":"traceutil/trace.go:171","msg":"trace[1529476923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"480.064168ms","start":"2026-04-01T05:12:42.523511Z","end":"2026-04-01T05:12:43.003575Z","steps":["trace[1529476923] 'agreement among raft nodes before linearized reading' (duration: 479.961445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.003598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:42.523503Z","time spent":"480.092708ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T05:12:43.003579Z","caller":"traceutil/trace.go:171","msg":"trace[1283618138] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1319; }","duration":"175.940783ms","start":"2026-04-01T05:12:42.827609Z","end":"2026-04-01T05:12:43.00355Z","steps":["trace[1283618138] 'agreement among raft nodes before linearized reading' (duration: 175.775429ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:12:43.541759Z","caller":"traceutil/trace.go:171","msg":"trace[1275086351] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"530.528432ms","start":"2026-04-01T05:12:43.011205Z","end":"2026-04-01T05:12:43.541733Z","steps":["trace[1275086351] 'process raft request' (duration: 530.305777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.541925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:43.011166Z","time spent":"530.668275ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:12:43.542941Z","caller":"traceutil/trace.go:171","msg":"trace[2038748891] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1380; }","duration":"389.604108ms","start":"2026-04-01T05:12:43.153315Z","end":"2026-04-01T05:12:43.542919Z","steps":["trace[2038748891] 'read index received' (duration: 388.729537ms)","trace[2038748891] 'applied index is now lower than readState.Index' (duration: 873.451µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:12:43.543056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.743462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:12:43.543091Z","caller":"traceutil/trace.go:171","msg":"trace[629517578] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1322; }","duration":"389.836554ms","start":"2026-04-01T05:12:43.153242Z","end":"2026-04-01T05:12:43.543079Z","steps":["trace[629517578] 'agreement among raft nodes before linearized reading' (duration: 389.753662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.543122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:43.153206Z","time spent":"389.907595ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-01T05:12:43.543388Z","caller":"traceutil/trace.go:171","msg":"trace[2011622365] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"531.795723ms","start":"2026-04-01T05:12:43.011581Z","end":"2026-04-01T05:12:43.543377Z","steps":["trace[2011622365] 'process raft request' (duration: 531.204939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.543458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:43.011553Z","time spent":"531.858713ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:12:43.543648Z","caller":"traceutil/trace.go:171","msg":"trace[133730705] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"532.026407ms","start":"2026-04-01T05:12:43.011605Z","end":"2026-04-01T05:12:43.543631Z","steps":["trace[133730705] 'process raft request' (duration: 531.26773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:12:43.543707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:12:43.011571Z","time spent":"532.102839ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:13:00.616629Z","caller":"traceutil/trace.go:171","msg":"trace[1509965685] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"215.861822ms","start":"2026-04-01T05:13:00.400737Z","end":"2026-04-01T05:13:00.616599Z","steps":["trace[1509965685] 'process raft request' (duration: 215.675008ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:01.701677Z","caller":"traceutil/trace.go:171","msg":"trace[1590477874] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"519.443225ms","start":"2026-04-01T05:13:01.182206Z","end":"2026-04-01T05:13:01.701649Z","steps":["trace[1590477874] 'read index received' (duration: 441.240877ms)","trace[1590477874] 'applied index is now lower than readState.Index' (duration: 78.200938ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:13:01.701825Z","caller":"traceutil/trace.go:171","msg":"trace[852580783] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"567.512805ms","start":"2026-04-01T05:13:01.134298Z","end":"2026-04-01T05:13:01.701811Z","steps":["trace[852580783] 'process raft request' (duration: 489.153743ms)","trace[852580783] 'compare' (duration: 78.040394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:13:01.701943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.134284Z","time spent":"567.568456ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T05:13:01.701992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.385821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:13:01.702069Z","caller":"traceutil/trace.go:171","msg":"trace[1394379417] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1400; }","duration":"507.512794ms","start":"2026-04-01T05:13:01.194536Z","end":"2026-04-01T05:13:01.702049Z","steps":["trace[1394379417] 'agreement among raft nodes before linearized reading' (duration: 507.392451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:01.702115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.19451Z","time spent":"507.593006ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-01T05:13:01.702396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.186582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:13:01.702444Z","caller":"traceutil/trace.go:171","msg":"trace[863838198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"520.271384ms","start":"2026-04-01T05:13:01.182161Z","end":"2026-04-01T05:13:01.702432Z","steps":["trace[863838198] 'agreement among raft nodes before linearized reading' (duration: 520.210763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:01.702472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.182139Z","time spent":"520.327206ms","remote":"127.0.0.1:48844","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-04-01T05:13:01.985807Z","caller":"traceutil/trace.go:171","msg":"trace[1841944128] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"277.837097ms","start":"2026-04-01T05:13:01.707938Z","end":"2026-04-01T05:13:01.985775Z","steps":["trace[1841944128] 'process raft request' (duration: 263.207784ms)","trace[1841944128] 'compare' (duration: 13.790514ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:13:02.010503Z","caller":"traceutil/trace.go:171","msg":"trace[455054716] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1464; }","duration":"236.945717ms","start":"2026-04-01T05:13:01.77353Z","end":"2026-04-01T05:13:02.010476Z","steps":["trace[455054716] 'read index received' (duration: 197.574482ms)","trace[455054716] 'applied index is now lower than readState.Index' (duration: 39.369835ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:13:02.010762Z","caller":"traceutil/trace.go:171","msg":"trace[1071116583] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"302.341904ms","start":"2026-04-01T05:13:01.708403Z","end":"2026-04-01T05:13:02.010744Z","steps":["trace[1071116583] 'process raft request' (duration: 301.878863ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:02.010859Z","caller":"traceutil/trace.go:171","msg":"trace[1425443163] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"301.861081ms","start":"2026-04-01T05:13:01.708988Z","end":"2026-04-01T05:13:02.010849Z","steps":["trace[1425443163] 'process raft request' (duration: 301.329109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:02.010871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.307225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T05:13:02.010963Z","caller":"traceutil/trace.go:171","msg":"trace[1915294938] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1406; }","duration":"237.454059ms","start":"2026-04-01T05:13:01.773486Z","end":"2026-04-01T05:13:02.01094Z","steps":["trace[1915294938] 'agreement among raft nodes before linearized reading' (duration: 237.281315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:02.010999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.708983Z","time spent":"301.913593ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:13:02.01105Z","caller":"traceutil/trace.go:171","msg":"trace[802484012] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"129.343419ms","start":"2026-04-01T05:13:01.881693Z","end":"2026-04-01T05:13:02.011036Z","steps":["trace[802484012] 'process raft request' (duration: 128.715454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:02.011026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.708396Z","time spent":"302.469997ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T05:13:02.011254Z","caller":"traceutil/trace.go:171","msg":"trace[725412165] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"303.03184ms","start":"2026-04-01T05:13:01.708209Z","end":"2026-04-01T05:13:02.011241Z","steps":["trace[725412165] 'process raft request' (duration: 301.922924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:02.011367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:01.708178Z","time spent":"303.149853ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T05:13:02.010806Z","caller":"traceutil/trace.go:171","msg":"trace[1874932023] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"148.775826ms","start":"2026-04-01T05:13:01.862005Z","end":"2026-04-01T05:13:02.010781Z","steps":["trace[1874932023] 'process raft request' (duration: 148.345686ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:02.758966Z","caller":"traceutil/trace.go:171","msg":"trace[1706695246] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"131.997831ms","start":"2026-04-01T05:13:02.626938Z","end":"2026-04-01T05:13:02.758936Z","steps":["trace[1706695246] 'process raft request' (duration: 131.728325ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:04.131888Z","caller":"traceutil/trace.go:171","msg":"trace[709157815] linearizableReadLoop","detail":"{readStateIndex:1477; appliedIndex:1476; }","duration":"116.160859ms","start":"2026-04-01T05:13:04.015701Z","end":"2026-04-01T05:13:04.131861Z","steps":["trace[709157815] 'read index received' (duration: 115.979955ms)","trace[709157815] 'applied index is now lower than readState.Index' (duration: 179.424µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:13:04.132134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.407894ms","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-04-01T05:13:04.132172Z","caller":"traceutil/trace.go:171","msg":"trace[77024556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1413; }","duration":"116.488548ms","start":"2026-04-01T05:13:04.015673Z","end":"2026-04-01T05:13:04.132162Z","steps":["trace[77024556] 'agreement among raft nodes before linearized reading' (duration: 116.315693ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:04.132233Z","caller":"traceutil/trace.go:171","msg":"trace[1528761396] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"136.529757ms","start":"2026-04-01T05:13:03.995558Z","end":"2026-04-01T05:13:04.132088Z","steps":["trace[1528761396] 'process raft request' (duration: 136.120668ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:04.245231Z","caller":"traceutil/trace.go:171","msg":"trace[1708411094] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"113.229741ms","start":"2026-04-01T05:13:04.131961Z","end":"2026-04-01T05:13:04.245191Z","steps":["trace[1708411094] 'read index received' (duration: 61.633048ms)","trace[1708411094] 'applied index is now lower than readState.Index' (duration: 51.595233ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:13:04.245594Z","caller":"traceutil/trace.go:171","msg":"trace[1107620804] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"108.755296ms","start":"2026-04-01T05:13:04.136812Z","end":"2026-04-01T05:13:04.245567Z","steps":["trace[1107620804] 'process raft request' (duration: 108.343266ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:04.245413Z","caller":"traceutil/trace.go:171","msg":"trace[2052020842] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"227.598347ms","start":"2026-04-01T05:13:04.017693Z","end":"2026-04-01T05:13:04.245291Z","steps":["trace[2052020842] 'process raft request' (duration: 175.848981ms)","trace[2052020842] 'compare' (duration: 51.395808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T05:13:04.245764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.662346ms","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-04-01T05:13:04.245869Z","caller":"traceutil/trace.go:171","msg":"trace[1041300718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1415; }","duration":"229.83156ms","start":"2026-04-01T05:13:04.016009Z","end":"2026-04-01T05:13:04.245841Z","steps":["trace[1041300718] 'agreement among raft nodes before linearized reading' (duration: 229.463802ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:16.111298Z","caller":"traceutil/trace.go:171","msg":"trace[430418996] linearizableReadLoop","detail":"{readStateIndex:1559; appliedIndex:1558; }","duration":"279.405255ms","start":"2026-04-01T05:13:15.831855Z","end":"2026-04-01T05:13:16.111261Z","steps":["trace[430418996] 'read index received' (duration: 279.090298ms)","trace[430418996] 'applied index is now lower than readState.Index' (duration: 313.617µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T05:13:16.111426Z","caller":"traceutil/trace.go:171","msg":"trace[1738329597] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"558.342099ms","start":"2026-04-01T05:13:15.55296Z","end":"2026-04-01T05:13:16.111303Z","steps":["trace[1738329597] 'process raft request' (duration: 557.990531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T05:13:16.111665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T05:13:15.552937Z","time spent":"558.608075ms","remote":"127.0.0.1:49170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T05:13:16.111752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.627875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-01T05:13:16.111804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.337039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-01T05:13:16.111852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.942167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T05:13:16.11181Z","caller":"traceutil/trace.go:171","msg":"trace[1884702014] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1493; }","duration":"206.745738ms","start":"2026-04-01T05:13:15.905048Z","end":"2026-04-01T05:13:16.111794Z","steps":["trace[1884702014] 'agreement among raft nodes before linearized reading' (duration: 206.572584ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:16.111978Z","caller":"traceutil/trace.go:171","msg":"trace[1910300913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1493; }","duration":"280.139232ms","start":"2026-04-01T05:13:15.831812Z","end":"2026-04-01T05:13:16.111952Z","steps":["trace[1910300913] 'agreement among raft nodes before linearized reading' (duration: 279.61428ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:13:16.111923Z","caller":"traceutil/trace.go:171","msg":"trace[1283863728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1493; }","duration":"275.479732ms","start":"2026-04-01T05:13:15.836429Z","end":"2026-04-01T05:13:16.111908Z","steps":["trace[1283863728] 'agreement among raft nodes before linearized reading' (duration: 275.043332ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:14:05.290397Z","caller":"traceutil/trace.go:171","msg":"trace[154272827] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"103.176068ms","start":"2026-04-01T05:14:05.187183Z","end":"2026-04-01T05:14:05.290359Z","steps":["trace[154272827] 'process raft request' (duration: 102.968563ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T05:16:31.39465Z","caller":"traceutil/trace.go:171","msg":"trace[1785474141] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"125.497371ms","start":"2026-04-01T05:16:31.269087Z","end":"2026-04-01T05:16:31.394584Z","steps":["trace[1785474141] 'process raft request' (duration: 124.927978ms)"],"step_count":1}