{"level":"warn","ts":"2026-04-07T15:11:49.911946Z","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-07T15:11:49.912083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102: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.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102: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-07T15:11:49.912169Z","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-07T15:11:49.912188Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-07T15:11:49.912212Z","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-07T15:11:49.913237Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]} {"level":"info","ts":"2026-04-07T15:11:49.913334Z","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.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102: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-07T15:11:49.917382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.757247ms"} {"level":"info","ts":"2026-04-07T15:11:49.924732Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"} {"level":"info","ts":"2026-04-07T15:11:49.924843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:11:49.92487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"} {"level":"info","ts":"2026-04-07T15:11:49.924914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:11:49.924929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"} {"level":"info","ts":"2026-04-07T15:11:49.924995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"warn","ts":"2026-04-07T15:11:49.930062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:11:49.932566Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:11:49.934853Z","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-07T15:11:49.937133Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:11:49.937465Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:11:49.937656Z","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-07T15:11:49.937711Z","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-07T15:11:49.937723Z","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-07T15:11:49.938301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"info","ts":"2026-04-07T15:11:49.938423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-07T15:11:49.940687Z","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-07T15:11:49.940915Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-07T15:11:49.940952Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-07T15:11:49.941135Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:11:49.941175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:11:50.525592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:11:50.525658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:11:50.525682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"} {"level":"info","ts":"2026-04-07T15:11:50.525694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:11:50.5257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-07T15:11:50.525708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"} {"level":"info","ts":"2026-04-07T15:11:50.525715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-07T15:11:50.527487Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:50.529398Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:11:50.52951Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:11:50.529564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:11:50.529874Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:11:50.529902Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:11:50.531462Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"} {"level":"info","ts":"2026-04-07T15:11:50.532481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:11:50.538532Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:50.538746Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:50.538794Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:11:52.146003Z","caller":"traceutil/trace.go:171","msg":"trace[632362366] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"146.307763ms","start":"2026-04-07T15:11:51.999665Z","end":"2026-04-07T15:11:52.145973Z","steps":["trace[632362366] 'read index received' (duration: 122.818747ms)","trace[632362366] 'applied index is now lower than readState.Index' (duration: 23.487786ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:52.146268Z","caller":"traceutil/trace.go:171","msg":"trace[1730215737] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"153.419382ms","start":"2026-04-07T15:11:51.992815Z","end":"2026-04-07T15:11:52.146234Z","steps":["trace[1730215737] 'process raft request' (duration: 153.095558ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.146369Z","caller":"traceutil/trace.go:171","msg":"trace[1638155283] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"154.467151ms","start":"2026-04-07T15:11:51.991865Z","end":"2026-04-07T15:11:52.146333Z","steps":["trace[1638155283] 'process raft request' (duration: 153.919696ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.146481Z","caller":"traceutil/trace.go:171","msg":"trace[1761024527] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"153.703704ms","start":"2026-04-07T15:11:51.992756Z","end":"2026-04-07T15:11:52.14646Z","steps":["trace[1761024527] 'process raft request' (duration: 153.125817ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.146542Z","caller":"traceutil/trace.go:171","msg":"trace[339173479] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"154.086687ms","start":"2026-04-07T15:11:51.992432Z","end":"2026-04-07T15:11:52.146519Z","steps":["trace[339173479] 'process raft request' (duration: 153.39159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.146542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.386795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.147307Z","caller":"traceutil/trace.go:171","msg":"trace[2018818258] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:9; }","duration":"182.191622ms","start":"2026-04-07T15:11:51.965099Z","end":"2026-04-07T15:11:52.147291Z","steps":["trace[2018818258] 'agreement among raft nodes before linearized reading' (duration: 181.351365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.146647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.995797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.147453Z","caller":"traceutil/trace.go:171","msg":"trace[1016752854] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"188.825444ms","start":"2026-04-07T15:11:51.95862Z","end":"2026-04-07T15:11:52.147446Z","steps":["trace[1016752854] 'agreement among raft nodes before linearized reading' (duration: 187.964937ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.146584Z","caller":"traceutil/trace.go:171","msg":"trace[454844534] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"153.949066ms","start":"2026-04-07T15:11:51.99253Z","end":"2026-04-07T15:11:52.146479Z","steps":["trace[454844534] 'process raft request' (duration: 153.322199ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.146676Z","caller":"traceutil/trace.go:171","msg":"trace[461725389] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"154.935865ms","start":"2026-04-07T15:11:51.991727Z","end":"2026-04-07T15:11:52.146662Z","steps":["trace[461725389] 'process raft request' (duration: 130.738121ms)","trace[461725389] 'compare' (duration: 23.121344ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:52.146749Z","caller":"traceutil/trace.go:171","msg":"trace[1399160980] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"147.836408ms","start":"2026-04-07T15:11:51.998906Z","end":"2026-04-07T15:11:52.146742Z","steps":["trace[1399160980] 'process raft request' (duration: 147.03304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.146746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.588112ms","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-04-07T15:11:52.148013Z","caller":"traceutil/trace.go:171","msg":"trace[1812183673] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"154.848244ms","start":"2026-04-07T15:11:51.993122Z","end":"2026-04-07T15:11:52.14797Z","steps":["trace[1812183673] 'agreement among raft nodes before linearized reading' (duration: 153.586973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.146696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.579649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.148453Z","caller":"traceutil/trace.go:171","msg":"trace[2046763405] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:0; response_revision:9; }","duration":"161.297375ms","start":"2026-04-07T15:11:51.987054Z","end":"2026-04-07T15:11:52.148352Z","steps":["trace[2046763405] 'agreement among raft nodes before linearized reading' (duration: 159.355677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.146839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.114148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.14877Z","caller":"traceutil/trace.go:171","msg":"trace[171567985] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"155.992185ms","start":"2026-04-07T15:11:51.992697Z","end":"2026-04-07T15:11:52.148689Z","steps":["trace[171567985] 'agreement among raft nodes before linearized reading' (duration: 154.075187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.158065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.401315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:11:52.158122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.983224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.15818Z","caller":"traceutil/trace.go:171","msg":"trace[573966837] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"114.082826ms","start":"2026-04-07T15:11:52.044085Z","end":"2026-04-07T15:11:52.158168Z","steps":["trace[573966837] 'agreement among raft nodes before linearized reading' (duration: 113.894544ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.158145Z","caller":"traceutil/trace.go:171","msg":"trace[129399720] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"104.540676ms","start":"2026-04-07T15:11:52.053588Z","end":"2026-04-07T15:11:52.158129Z","steps":["trace[129399720] 'agreement among raft nodes before linearized reading' (duration: 104.352805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.158289Z","caller":"traceutil/trace.go:171","msg":"trace[177105638] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"157.647969ms","start":"2026-04-07T15:11:52.000603Z","end":"2026-04-07T15:11:52.158251Z","steps":["trace[177105638] 'process raft request' (duration: 157.165504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.711527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.352253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-07T15:11:52.711667Z","caller":"traceutil/trace.go:171","msg":"trace[983095491] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:22; }","duration":"504.715028ms","start":"2026-04-07T15:11:52.206934Z","end":"2026-04-07T15:11:52.711649Z","steps":["trace[983095491] 'read index received' (duration: 303.445417ms)","trace[983095491] 'applied index is now lower than readState.Index' (duration: 201.268091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:52.711773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.833339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-07T15:11:52.711732Z","caller":"traceutil/trace.go:171","msg":"trace[622689193] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"542.011412ms","start":"2026-04-07T15:11:52.169656Z","end":"2026-04-07T15:11:52.711668Z","steps":["trace[622689193] 'process raft request' (duration: 340.723211ms)","trace[622689193] 'compare' (duration: 200.114221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:52.71193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.169639Z","time spent":"542.188653ms","remote":"127.0.0.1:47114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:11:52.712327Z","caller":"traceutil/trace.go:171","msg":"trace[1972517048] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"500.929843ms","start":"2026-04-07T15:11:52.211378Z","end":"2026-04-07T15:11:52.712308Z","steps":["trace[1972517048] 'process raft request' (duration: 500.829662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.712355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.404085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.712401Z","caller":"traceutil/trace.go:171","msg":"trace[387270801] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:26; }","duration":"504.471795ms","start":"2026-04-07T15:11:52.207916Z","end":"2026-04-07T15:11:52.712388Z","steps":["trace[387270801] 'agreement among raft nodes before linearized reading' (duration: 504.360864ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.712454Z","caller":"traceutil/trace.go:171","msg":"trace[1449656632] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"501.171925ms","start":"2026-04-07T15:11:52.21127Z","end":"2026-04-07T15:11:52.712442Z","steps":["trace[1449656632] 'process raft request' (duration: 500.884452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.712457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.207906Z","time spent":"504.542496ms","remote":"127.0.0.1:47316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-07T15:11:52.712505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.211263Z","time spent":"501.216535ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:52.712508Z","caller":"traceutil/trace.go:171","msg":"trace[1781871095] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"501.243816ms","start":"2026-04-07T15:11:52.211198Z","end":"2026-04-07T15:11:52.712442Z","steps":["trace[1781871095] 'process raft request' (duration: 500.924093ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.712579Z","caller":"traceutil/trace.go:171","msg":"trace[499847441] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"503.030073ms","start":"2026-04-07T15:11:52.20952Z","end":"2026-04-07T15:11:52.71255Z","steps":["trace[499847441] 'process raft request' (duration: 502.401637ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.712556Z","caller":"traceutil/trace.go:171","msg":"trace[1866434594] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"494.972378ms","start":"2026-04-07T15:11:52.217457Z","end":"2026-04-07T15:11:52.712429Z","steps":["trace[1866434594] 'process raft request' (duration: 494.777266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.71271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.209501Z","time spent":"503.144794ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:52.712746Z","caller":"traceutil/trace.go:171","msg":"trace[1505726212] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"501.901411ms","start":"2026-04-07T15:11:52.210834Z","end":"2026-04-07T15:11:52.712736Z","steps":["trace[1505726212] 'process raft request' (duration: 501.232605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.7128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.210798Z","time spent":"501.978683ms","remote":"127.0.0.1:47352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:52.712912Z","caller":"traceutil/trace.go:171","msg":"trace[1481716183] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"504.904369ms","start":"2026-04-07T15:11:52.206896Z","end":"2026-04-07T15:11:52.711801Z","steps":["trace[1481716183] 'agreement among raft nodes before linearized reading' (duration: 504.832328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.712411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.211356Z","time spent":"501.016724ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:52.712964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.206879Z","time spent":"506.07293ms","remote":"127.0.0.1:47042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T15:11:52.713071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.709564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:11:52.713068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.217446Z","time spent":"495.362041ms","remote":"127.0.0.1:47026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:11:52.713167Z","caller":"traceutil/trace.go:171","msg":"trace[2028328255] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:26; }","duration":"349.835105ms","start":"2026-04-07T15:11:52.36331Z","end":"2026-04-07T15:11:52.713145Z","steps":["trace[2028328255] 'agreement among raft nodes before linearized reading' (duration: 349.620113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.713269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.363296Z","time spent":"349.960836ms","remote":"127.0.0.1:47194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-04-07T15:11:52.712706Z","caller":"traceutil/trace.go:171","msg":"trace[330279890] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"501.336617ms","start":"2026-04-07T15:11:52.211358Z","end":"2026-04-07T15:11:52.712695Z","steps":["trace[330279890] 'process raft request' (duration: 500.820632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.713367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.21135Z","time spent":"501.987793ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:52.712693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.211182Z","time spent":"501.412467ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:11:52.713088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.371175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.713683Z","caller":"traceutil/trace.go:171","msg":"trace[118520242] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:26; }","duration":"502.967381ms","start":"2026-04-07T15:11:52.210701Z","end":"2026-04-07T15:11:52.713669Z","steps":["trace[118520242] 'agreement among raft nodes before linearized reading' (duration: 502.333365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:52.713798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:11:52.210679Z","time spent":"503.101583ms","remote":"127.0.0.1:47016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-04-07T15:11:52.875209Z","caller":"traceutil/trace.go:171","msg":"trace[106910350] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"156.023083ms","start":"2026-04-07T15:11:52.719156Z","end":"2026-04-07T15:11:52.875179Z","steps":["trace[106910350] 'process raft request' (duration: 150.902945ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876201Z","caller":"traceutil/trace.go:171","msg":"trace[49160252] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:30; }","duration":"154.701741ms","start":"2026-04-07T15:11:52.721482Z","end":"2026-04-07T15:11:52.876184Z","steps":["trace[49160252] 'read index received' (duration: 148.590194ms)","trace[49160252] 'applied index is now lower than readState.Index' (duration: 6.110577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:52.876327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.824492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:11:52.876365Z","caller":"traceutil/trace.go:171","msg":"trace[2102409664] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"154.909532ms","start":"2026-04-07T15:11:52.721446Z","end":"2026-04-07T15:11:52.876355Z","steps":["trace[2102409664] 'agreement among raft nodes before linearized reading' (duration: 154.799401ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876429Z","caller":"traceutil/trace.go:171","msg":"trace[1320984751] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"154.69992ms","start":"2026-04-07T15:11:52.721659Z","end":"2026-04-07T15:11:52.876359Z","steps":["trace[1320984751] 'process raft request' (duration: 154.309877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876578Z","caller":"traceutil/trace.go:171","msg":"trace[1094511270] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"154.865252ms","start":"2026-04-07T15:11:52.721695Z","end":"2026-04-07T15:11:52.876561Z","steps":["trace[1094511270] 'process raft request' (duration: 154.312657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876616Z","caller":"traceutil/trace.go:171","msg":"trace[671836724] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"154.72921ms","start":"2026-04-07T15:11:52.721878Z","end":"2026-04-07T15:11:52.876608Z","steps":["trace[671836724] 'process raft request' (duration: 154.266936ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876571Z","caller":"traceutil/trace.go:171","msg":"trace[979898646] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"154.73983ms","start":"2026-04-07T15:11:52.721747Z","end":"2026-04-07T15:11:52.876487Z","steps":["trace[979898646] 'process raft request' (duration: 154.330237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876782Z","caller":"traceutil/trace.go:171","msg":"trace[1587773937] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"155.059134ms","start":"2026-04-07T15:11:52.721713Z","end":"2026-04-07T15:11:52.876773Z","steps":["trace[1587773937] 'process raft request' (duration: 154.329517ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876865Z","caller":"traceutil/trace.go:171","msg":"trace[282871857] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"155.428216ms","start":"2026-04-07T15:11:52.721426Z","end":"2026-04-07T15:11:52.876854Z","steps":["trace[282871857] 'process raft request' (duration: 154.078985ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876901Z","caller":"traceutil/trace.go:171","msg":"trace[1196603526] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"155.320286ms","start":"2026-04-07T15:11:52.721569Z","end":"2026-04-07T15:11:52.87689Z","steps":["trace[1196603526] 'process raft request' (duration: 154.349717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:52.876528Z","caller":"traceutil/trace.go:171","msg":"trace[120367048] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"154.72134ms","start":"2026-04-07T15:11:52.721792Z","end":"2026-04-07T15:11:52.876513Z","steps":["trace[120367048] 'process raft request' (duration: 154.316457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:22.688194Z","caller":"traceutil/trace.go:171","msg":"trace[319635652] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"118.322858ms","start":"2026-04-07T15:12:22.569829Z","end":"2026-04-07T15:12:22.688152Z","steps":["trace[319635652] 'process raft request' (duration: 118.132077ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:22.689013Z","caller":"traceutil/trace.go:171","msg":"trace[584737221] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"112.477484ms","start":"2026-04-07T15:12:22.576501Z","end":"2026-04-07T15:12:22.688978Z","steps":["trace[584737221] 'process raft request' (duration: 111.594458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:23.857855Z","caller":"traceutil/trace.go:171","msg":"trace[507554402] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"199.173112ms","start":"2026-04-07T15:12:23.658653Z","end":"2026-04-07T15:12:23.857826Z","steps":["trace[507554402] 'process raft request' (duration: 157.960342ms)","trace[507554402] 'compare' (duration: 41.09029ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:23.858925Z","caller":"traceutil/trace.go:171","msg":"trace[2068099024] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"171.158108ms","start":"2026-04-07T15:12:23.687744Z","end":"2026-04-07T15:12:23.858902Z","steps":["trace[2068099024] 'process raft request' (duration: 170.883637ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:23.859301Z","caller":"traceutil/trace.go:171","msg":"trace[1857091773] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"135.661981ms","start":"2026-04-07T15:12:23.723627Z","end":"2026-04-07T15:12:23.859289Z","steps":["trace[1857091773] 'process raft request' (duration: 135.197169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:23.860389Z","caller":"traceutil/trace.go:171","msg":"trace[2111865463] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:562; }","duration":"150.337847ms","start":"2026-04-07T15:12:23.710024Z","end":"2026-04-07T15:12:23.860362Z","steps":["trace[2111865463] 'read index received' (duration: 106.666322ms)","trace[2111865463] 'applied index is now lower than readState.Index' (duration: 43.670465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:23.860711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.864952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T15:12:23.860721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.687239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T15:12:23.860765Z","caller":"traceutil/trace.go:171","msg":"trace[328849160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"116.972882ms","start":"2026-04-07T15:12:23.743775Z","end":"2026-04-07T15:12:23.860748Z","steps":["trace[328849160] 'agreement among raft nodes before linearized reading' (duration: 116.859722ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:23.860768Z","caller":"traceutil/trace.go:171","msg":"trace[52485286] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:1; response_revision:545; }","duration":"150.80917ms","start":"2026-04-07T15:12:23.709947Z","end":"2026-04-07T15:12:23.860756Z","steps":["trace[52485286] 'agreement among raft nodes before linearized reading' (duration: 150.70373ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:28.633857Z","caller":"traceutil/trace.go:171","msg":"trace[633056932] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"209.509ms","start":"2026-04-07T15:12:28.424327Z","end":"2026-04-07T15:12:28.633836Z","steps":["trace[633056932] 'process raft request' (duration: 208.824785ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:28.633799Z","caller":"traceutil/trace.go:171","msg":"trace[783565618] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"122.006045ms","start":"2026-04-07T15:12:28.51176Z","end":"2026-04-07T15:12:28.633766Z","steps":["trace[783565618] 'read index received' (duration: 121.11018ms)","trace[783565618] 'applied index is now lower than readState.Index' (duration: 893.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:28.6351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.328462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:28.635168Z","caller":"traceutil/trace.go:171","msg":"trace[1809026730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"123.468703ms","start":"2026-04-07T15:12:28.511679Z","end":"2026-04-07T15:12:28.635147Z","steps":["trace[1809026730] 'agreement among raft nodes before linearized reading' (duration: 122.218947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:29.034143Z","caller":"traceutil/trace.go:171","msg":"trace[1970718849] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"269.27359ms","start":"2026-04-07T15:12:28.76484Z","end":"2026-04-07T15:12:29.034113Z","steps":["trace[1970718849] 'read index received' (duration: 268.816007ms)","trace[1970718849] 'applied index is now lower than readState.Index' (duration: 453.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:29.034523Z","caller":"traceutil/trace.go:171","msg":"trace[1766993545] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"316.552921ms","start":"2026-04-07T15:12:28.717729Z","end":"2026-04-07T15:12:29.034282Z","steps":["trace[1766993545] 'process raft request' (duration: 316.030737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:29.034892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:28.717702Z","time spent":"317.022843ms","remote":"127.0.0.1:47284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:12:29.034965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.060784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9kfcg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T15:12:29.035148Z","caller":"traceutil/trace.go:171","msg":"trace[1114693983] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9kfcg; range_end:; response_count:1; response_revision:577; }","duration":"270.331215ms","start":"2026-04-07T15:12:28.764785Z","end":"2026-04-07T15:12:29.035116Z","steps":["trace[1114693983] 'agreement among raft nodes before linearized reading' (duration: 269.722142ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:29.202975Z","caller":"traceutil/trace.go:171","msg":"trace[75721592] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"168.643594ms","start":"2026-04-07T15:12:29.034291Z","end":"2026-04-07T15:12:29.202935Z","steps":["trace[75721592] 'read index received' (duration: 74.804689ms)","trace[75721592] 'applied index is now lower than readState.Index' (duration: 93.831865ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:29.203088Z","caller":"traceutil/trace.go:171","msg":"trace[1715968547] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"152.395047ms","start":"2026-04-07T15:12:29.050664Z","end":"2026-04-07T15:12:29.203059Z","steps":["trace[1715968547] 'process raft request' (duration: 152.203456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:29.2033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.304273ms","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-07T15:12:29.203354Z","caller":"traceutil/trace.go:171","msg":"trace[290222814] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:578; }","duration":"157.415873ms","start":"2026-04-07T15:12:29.045929Z","end":"2026-04-07T15:12:29.203345Z","steps":["trace[290222814] 'agreement among raft nodes before linearized reading' (duration: 157.237412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:29.203537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.906466ms","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-04-07T15:12:29.203734Z","caller":"traceutil/trace.go:171","msg":"trace[893610635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"368.195397ms","start":"2026-04-07T15:12:28.835488Z","end":"2026-04-07T15:12:29.203683Z","steps":["trace[893610635] 'agreement among raft nodes before linearized reading' (duration: 367.545373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:29.203852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:28.835464Z","time spent":"368.375568ms","remote":"127.0.0.1:47194","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":"info","ts":"2026-04-07T15:12:29.664252Z","caller":"traceutil/trace.go:171","msg":"trace[1970577771] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"453.925659ms","start":"2026-04-07T15:12:29.210295Z","end":"2026-04-07T15:12:29.66422Z","steps":["trace[1970577771] 'process raft request' (duration: 396.178592ms)","trace[1970577771] 'compare' (duration: 57.569395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:29.66439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.209066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:12:29.664446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:29.210269Z","time spent":"454.095339ms","remote":"127.0.0.1:47194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:29.664277Z","caller":"traceutil/trace.go:171","msg":"trace[1186889752] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"156.024534ms","start":"2026-04-07T15:12:29.508167Z","end":"2026-04-07T15:12:29.664191Z","steps":["trace[1186889752] 'read index received' (duration: 98.248788ms)","trace[1186889752] 'applied index is now lower than readState.Index' (duration: 57.774846ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:29.664562Z","caller":"traceutil/trace.go:171","msg":"trace[1474037258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"156.315397ms","start":"2026-04-07T15:12:29.508105Z","end":"2026-04-07T15:12:29.664421Z","steps":["trace[1474037258] 'agreement among raft nodes before linearized reading' (duration: 156.237826ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:31.036076Z","caller":"traceutil/trace.go:171","msg":"trace[719614689] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"103.824433ms","start":"2026-04-07T15:12:30.932213Z","end":"2026-04-07T15:12:31.036038Z","steps":["trace[719614689] 'process raft request' (duration: 103.628912ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:31.039912Z","caller":"traceutil/trace.go:171","msg":"trace[1295738320] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"106.195655ms","start":"2026-04-07T15:12:30.933683Z","end":"2026-04-07T15:12:31.039879Z","steps":["trace[1295738320] 'process raft request' (duration: 105.895033ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:45.171405Z","caller":"traceutil/trace.go:171","msg":"trace[391290443] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"168.937782ms","start":"2026-04-07T15:12:45.002439Z","end":"2026-04-07T15:12:45.171377Z","steps":["trace[391290443] 'read index received' (duration: 168.706161ms)","trace[391290443] 'applied index is now lower than readState.Index' (duration: 230.621µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:45.171474Z","caller":"traceutil/trace.go:171","msg":"trace[2025472309] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"296.611881ms","start":"2026-04-07T15:12:44.874839Z","end":"2026-04-07T15:12:45.171451Z","steps":["trace[2025472309] 'process raft request' (duration: 296.38611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:45.171642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.191464ms","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-07T15:12:45.171678Z","caller":"traceutil/trace.go:171","msg":"trace[1626974282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"169.293164ms","start":"2026-04-07T15:12:45.002375Z","end":"2026-04-07T15:12:45.171668Z","steps":["trace[1626974282] 'agreement among raft nodes before linearized reading' (duration: 169.137204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:36.311094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.313231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:36.31117Z","caller":"traceutil/trace.go:171","msg":"trace[1797032934] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1401; }","duration":"112.457356ms","start":"2026-04-07T15:13:36.198698Z","end":"2026-04-07T15:13:36.311155Z","steps":["trace[1797032934] 'count revisions from in-memory index tree' (duration: 112.208458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:36.717495Z","caller":"traceutil/trace.go:171","msg":"trace[1020533053] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"120.964831ms","start":"2026-04-07T15:14:36.596501Z","end":"2026-04-07T15:14:36.717466Z","steps":["trace[1020533053] 'process raft request' (duration: 120.652065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:37.090056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.377867ms","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-07T15:14:37.090265Z","caller":"traceutil/trace.go:171","msg":"trace[613996582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2059; }","duration":"245.658842ms","start":"2026-04-07T15:14:36.844584Z","end":"2026-04-07T15:14:37.090243Z","steps":["trace[613996582] 'range keys from in-memory index tree' (duration: 245.115922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:14.180768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.151978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:16:14.181956Z","caller":"traceutil/trace.go:171","msg":"trace[864209920] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"212.273409ms","start":"2026-04-07T15:16:13.969662Z","end":"2026-04-07T15:16:14.181935Z","steps":["trace[864209920] 'process raft request' (duration: 63.855348ms)","trace[864209920] 'compare' (duration: 147.013837ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:16:19.361776Z","caller":"traceutil/trace.go:171","msg":"trace[111585420] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"130.646836ms","start":"2026-04-07T15:16:19.23109Z","end":"2026-04-07T15:16:19.361737Z","steps":["trace[111585420] 'process raft request' (duration: 130.443244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.233305Z","caller":"traceutil/trace.go:171","msg":"trace[16931733] linearizableReadLoop","detail":"{readStateIndex:4941; appliedIndex:4940; }","duration":"159.598188ms","start":"2026-04-07T15:20:21.073678Z","end":"2026-04-07T15:20:21.233276Z","steps":["trace[16931733] 'read index received' (duration: 159.307443ms)","trace[16931733] 'applied index is now lower than readState.Index' (duration: 289.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:21.234057Z","caller":"traceutil/trace.go:171","msg":"trace[1688091231] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"243.636808ms","start":"2026-04-07T15:20:20.990381Z","end":"2026-04-07T15:20:21.234018Z","steps":["trace[1688091231] 'process raft request' (duration: 242.737961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:21.234737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.76696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:20:21.234801Z","caller":"traceutil/trace.go:171","msg":"trace[1268859994] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4760; }","duration":"147.913774ms","start":"2026-04-07T15:20:21.086872Z","end":"2026-04-07T15:20:21.234786Z","steps":["trace[1268859994] 'agreement among raft nodes before linearized reading' (duration: 147.76003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:21.236256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.536564ms","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-07T15:20:21.238418Z","caller":"traceutil/trace.go:171","msg":"trace[713854425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4760; }","duration":"162.714667ms","start":"2026-04-07T15:20:21.073638Z","end":"2026-04-07T15:20:21.236353Z","steps":["trace[713854425] 'agreement among raft nodes before linearized reading' (duration: 159.917883ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.346237Z","caller":"traceutil/trace.go:171","msg":"trace[678375169] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"100.616068ms","start":"2026-04-07T15:20:21.245582Z","end":"2026-04-07T15:20:21.346199Z","steps":["trace[678375169] 'process raft request' (duration: 82.603243ms)","trace[678375169] 'compare' (duration: 17.7834ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:21.507582Z","caller":"traceutil/trace.go:171","msg":"trace[1848303913] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"117.091753ms","start":"2026-04-07T15:20:21.390454Z","end":"2026-04-07T15:20:21.507546Z","steps":["trace[1848303913] 'process raft request' (duration: 116.654475ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.507751Z","caller":"traceutil/trace.go:171","msg":"trace[684779554] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"111.033367ms","start":"2026-04-07T15:20:21.396685Z","end":"2026-04-07T15:20:21.507718Z","steps":["trace[684779554] 'process raft request' (duration: 110.486627ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.507969Z","caller":"traceutil/trace.go:171","msg":"trace[1063656772] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"117.839048ms","start":"2026-04-07T15:20:21.390119Z","end":"2026-04-07T15:20:21.507959Z","steps":["trace[1063656772] 'process raft request' (duration: 59.016991ms)","trace[1063656772] 'compare' (duration: 57.294668ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:21.507982Z","caller":"traceutil/trace.go:171","msg":"trace[1716746603] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4945; }","duration":"117.510731ms","start":"2026-04-07T15:20:21.390456Z","end":"2026-04-07T15:20:21.507967Z","steps":["trace[1716746603] 'read index received' (duration: 8.327169ms)","trace[1716746603] 'applied index is now lower than readState.Index' (duration: 109.182042ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:21.508072Z","caller":"traceutil/trace.go:171","msg":"trace[1476386351] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"117.754056ms","start":"2026-04-07T15:20:21.39031Z","end":"2026-04-07T15:20:21.508064Z","steps":["trace[1476386351] 'process raft request' (duration: 116.694966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:21.508397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.914708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-07T15:20:21.50845Z","caller":"traceutil/trace.go:171","msg":"trace[1314059765] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4770; }","duration":"118.009181ms","start":"2026-04-07T15:20:21.390423Z","end":"2026-04-07T15:20:21.508432Z","steps":["trace[1314059765] 'agreement among raft nodes before linearized reading' (duration: 117.820147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:21.508581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.716437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-07T15:20:21.508615Z","caller":"traceutil/trace.go:171","msg":"trace[1842247132] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4770; }","duration":"115.778168ms","start":"2026-04-07T15:20:21.392825Z","end":"2026-04-07T15:20:21.508603Z","steps":["trace[1842247132] 'agreement among raft nodes before linearized reading' (duration: 115.647925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.771032Z","caller":"traceutil/trace.go:171","msg":"trace[2033153011] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"208.066857ms","start":"2026-04-07T15:20:21.562928Z","end":"2026-04-07T15:20:21.770995Z","steps":["trace[2033153011] 'process raft request' (duration: 207.945634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:21.771054Z","caller":"traceutil/trace.go:171","msg":"trace[934630560] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"245.79655ms","start":"2026-04-07T15:20:21.525219Z","end":"2026-04-07T15:20:21.771016Z","steps":["trace[934630560] 'process raft request' (duration: 197.371382ms)","trace[934630560] 'compare' (duration: 48.040201ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:21.771529Z","caller":"traceutil/trace.go:171","msg":"trace[1470183604] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4951; }","duration":"245.907072ms","start":"2026-04-07T15:20:21.525568Z","end":"2026-04-07T15:20:21.771475Z","steps":["trace[1470183604] 'read index received' (duration: 197.010445ms)","trace[1470183604] 'applied index is now lower than readState.Index' (duration: 48.876096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:21.771731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.139356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-07T15:20:21.771784Z","caller":"traceutil/trace.go:171","msg":"trace[12641588] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4772; }","duration":"246.215317ms","start":"2026-04-07T15:20:21.525552Z","end":"2026-04-07T15:20:21.771768Z","steps":["trace[12641588] 'agreement among raft nodes before linearized reading' (duration: 246.053014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:21.772167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.483707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57fb1b60-a8ed-41c0-9cc3-a1c73ff20f64\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T15:20:21.77221Z","caller":"traceutil/trace.go:171","msg":"trace[1648384990] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57fb1b60-a8ed-41c0-9cc3-a1c73ff20f64; range_end:; response_count:1; response_revision:4772; }","duration":"144.568119ms","start":"2026-04-07T15:20:21.627631Z","end":"2026-04-07T15:20:21.772199Z","steps":["trace[1648384990] 'agreement among raft nodes before linearized reading' (duration: 144.464327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:29.660597Z","caller":"traceutil/trace.go:171","msg":"trace[1912258633] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"122.904629ms","start":"2026-04-07T15:20:29.537666Z","end":"2026-04-07T15:20:29.660571Z","steps":["trace[1912258633] 'process raft request' (duration: 120.310581ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.736619Z","caller":"traceutil/trace.go:171","msg":"trace[1720740345] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"236.706427ms","start":"2026-04-07T15:20:33.49989Z","end":"2026-04-07T15:20:33.736597Z","steps":["trace[1720740345] 'process raft request' (duration: 236.525863ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.749561Z","caller":"traceutil/trace.go:171","msg":"trace[897468745] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5124; }","duration":"243.546674ms","start":"2026-04-07T15:20:33.505981Z","end":"2026-04-07T15:20:33.749527Z","steps":["trace[897468745] 'read index received' (duration: 230.454202ms)","trace[897468745] 'applied index is now lower than readState.Index' (duration: 13.091292ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:33.749991Z","caller":"traceutil/trace.go:171","msg":"trace[61199694] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"249.565535ms","start":"2026-04-07T15:20:33.500397Z","end":"2026-04-07T15:20:33.749963Z","steps":["trace[61199694] 'process raft request' (duration: 248.977385ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.749986Z","caller":"traceutil/trace.go:171","msg":"trace[1690727464] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"249.687777ms","start":"2026-04-07T15:20:33.500181Z","end":"2026-04-07T15:20:33.749869Z","steps":["trace[1690727464] 'process raft request' (duration: 248.864363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.749955Z","caller":"traceutil/trace.go:171","msg":"trace[661864024] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"249.672578ms","start":"2026-04-07T15:20:33.50026Z","end":"2026-04-07T15:20:33.749933Z","steps":["trace[661864024] 'process raft request' (duration: 249.023746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:33.750307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.644641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:20:33.753663Z","caller":"traceutil/trace.go:171","msg":"trace[1823615367] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4943; }","duration":"243.068235ms","start":"2026-04-07T15:20:33.510571Z","end":"2026-04-07T15:20:33.753639Z","steps":["trace[1823615367] 'agreement among raft nodes before linearized reading' (duration: 239.658312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:33.750586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.610833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:20:33.753899Z","caller":"traceutil/trace.go:171","msg":"trace[626116874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4943; }","duration":"247.918665ms","start":"2026-04-07T15:20:33.505945Z","end":"2026-04-07T15:20:33.753864Z","steps":["trace[626116874] 'agreement among raft nodes before linearized reading' (duration: 243.84663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:33.750625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.985074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T15:20:33.75427Z","caller":"traceutil/trace.go:171","msg":"trace[1449137264] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4943; }","duration":"213.601952ms","start":"2026-04-07T15:20:33.540577Z","end":"2026-04-07T15:20:33.754179Z","steps":["trace[1449137264] 'agreement among raft nodes before linearized reading' (duration: 209.912463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:33.969867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.993004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:20:33.969995Z","caller":"traceutil/trace.go:171","msg":"trace[853106147] linearizableReadLoop","detail":"{readStateIndex:5131; appliedIndex:5129; }","duration":"213.738584ms","start":"2026-04-07T15:20:33.756237Z","end":"2026-04-07T15:20:33.969976Z","steps":["trace[853106147] 'read index received' (duration: 53.155693ms)","trace[853106147] 'applied index is now lower than readState.Index' (duration: 160.58082ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:33.970042Z","caller":"traceutil/trace.go:171","msg":"trace[198317541] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"213.814805ms","start":"2026-04-07T15:20:33.756199Z","end":"2026-04-07T15:20:33.970014Z","steps":["trace[198317541] 'process raft request' (duration: 76.582077ms)","trace[198317541] 'compare' (duration: 136.581625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:33.970285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.024459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:20:33.970389Z","caller":"traceutil/trace.go:171","msg":"trace[1131177622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4944; }","duration":"214.192152ms","start":"2026-04-07T15:20:33.756173Z","end":"2026-04-07T15:20:33.970366Z","steps":["trace[1131177622] 'agreement among raft nodes before linearized reading' (duration: 213.922867ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.979878Z","caller":"traceutil/trace.go:171","msg":"trace[1781588293] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"165.45472ms","start":"2026-04-07T15:20:33.814402Z","end":"2026-04-07T15:20:33.979857Z","steps":["trace[1781588293] 'process raft request' (duration: 165.364008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:33.979922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.618887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c1d92bc1-07c2-4562-a5e8-edaa1d3e939b\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T15:20:33.979972Z","caller":"traceutil/trace.go:171","msg":"trace[1884682649] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c1d92bc1-07c2-4562-a5e8-edaa1d3e939b; range_end:; response_count:1; response_revision:4946; }","duration":"222.687328ms","start":"2026-04-07T15:20:33.757265Z","end":"2026-04-07T15:20:33.979952Z","steps":["trace[1884682649] 'agreement among raft nodes before linearized reading' (duration: 222.545836ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:33.979898Z","caller":"traceutil/trace.go:171","msg":"trace[1213642427] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"169.067116ms","start":"2026-04-07T15:20:33.810813Z","end":"2026-04-07T15:20:33.97988Z","steps":["trace[1213642427] 'process raft request' (duration: 168.835472ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:50.581548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-04-07T15:21:50.656173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"72.661372ms","hash":1993104158,"current-db-size-bytes":53985280,"current-db-size":"54 MB","current-db-size-in-use-bytes":28884992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:21:50.65623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1993104158,"revision":2879,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T15:24:27.469771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.073133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:24:27.470327Z","caller":"traceutil/trace.go:171","msg":"trace[1895330721] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"150.42074ms","start":"2026-04-07T15:24:27.319876Z","end":"2026-04-07T15:24:27.470297Z","steps":["trace[1895330721] 'process raft request' (duration: 46.693028ms)","trace[1895330721] 'compare' (duration: 102.979042ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:26:50.589027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5568} {"level":"info","ts":"2026-04-07T15:26:50.672161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5568,"took":"81.154867ms","hash":2382182993,"current-db-size-bytes":53985280,"current-db-size":"54 MB","current-db-size-in-use-bytes":18350080,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T15:26:50.672623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2382182993,"revision":5568,"compact-revision":2879}