{"level":"warn","ts":"2026-05-09T16:48:54.377812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:48:54.377889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-05-09T16:48:54.37795Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:48:54.377961Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-09T16:48:54.377976Z","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-05-09T16:48:54.378711Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-09T16:48:54.378784Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-05-09T16:48:54.384487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.492688ms"} {"level":"info","ts":"2026-05-09T16:48:54.392078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-09T16:48:54.392138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:48:54.392162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-09T16:48:54.39218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:48:54.392192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-09T16:48:54.392228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-09T16:48:54.400044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:48:54.401488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:48:54.402588Z","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-05-09T16:48:54.40462Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:48:54.404858Z","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-05-09T16:48:54.405014Z","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-05-09T16:48:54.404979Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:48:54.405033Z","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-05-09T16:48:54.406123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-09T16:48:54.406479Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-09T16:48:54.409506Z","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-05-09T16:48:54.409687Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-09T16:48:54.410021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:48:54.410108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:48:54.409764Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-09T16:48:54.992481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:48:54.992529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:48:54.992559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-09T16:48:54.992574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:48:54.992581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-09T16:48:54.992591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-09T16:48:54.992599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-09T16:48:55.031765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:48:55.032741Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:48:55.032793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:48:55.032719Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:48:55.03302Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:48:55.033096Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:48:55.034496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-09T16:48:55.035672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:48:55.054754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:48:55.054861Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:48:55.054888Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:48:56.439959Z","caller":"traceutil/trace.go:171","msg":"trace[1943476703] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"228.028578ms","start":"2026-05-09T16:48:56.211912Z","end":"2026-05-09T16:48:56.439941Z","steps":["trace[1943476703] 'process raft request' (duration: 227.821996ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.439949Z","caller":"traceutil/trace.go:171","msg":"trace[1740820693] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"226.24511ms","start":"2026-05-09T16:48:56.213682Z","end":"2026-05-09T16:48:56.439927Z","steps":["trace[1740820693] 'read index received' (duration: 226.114219ms)","trace[1740820693] 'applied index is now lower than readState.Index' (duration: 129.701µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:48:56.440282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.580973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:48:56.440362Z","caller":"traceutil/trace.go:171","msg":"trace[1810317423] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"226.689984ms","start":"2026-05-09T16:48:56.213661Z","end":"2026-05-09T16:48:56.440351Z","steps":["trace[1810317423] 'agreement among raft nodes before linearized reading' (duration: 226.313641ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.446885Z","caller":"traceutil/trace.go:171","msg":"trace[745752259] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"152.007113ms","start":"2026-05-09T16:48:56.294851Z","end":"2026-05-09T16:48:56.446859Z","steps":["trace[745752259] 'process raft request' (duration: 151.674189ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.446919Z","caller":"traceutil/trace.go:171","msg":"trace[1089232326] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"151.119453ms","start":"2026-05-09T16:48:56.295755Z","end":"2026-05-09T16:48:56.446875Z","steps":["trace[1089232326] 'process raft request' (duration: 151.059422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:48:56.446997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.254053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-09T16:48:56.447029Z","caller":"traceutil/trace.go:171","msg":"trace[531985134] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"233.304473ms","start":"2026-05-09T16:48:56.213716Z","end":"2026-05-09T16:48:56.447021Z","steps":["trace[531985134] 'agreement among raft nodes before linearized reading' (duration: 233.224643ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.44703Z","caller":"traceutil/trace.go:171","msg":"trace[870073096] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"150.77309ms","start":"2026-05-09T16:48:56.296162Z","end":"2026-05-09T16:48:56.446935Z","steps":["trace[870073096] 'process raft request' (duration: 150.698409ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.4471Z","caller":"traceutil/trace.go:171","msg":"trace[1940257703] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"151.168583ms","start":"2026-05-09T16:48:56.295853Z","end":"2026-05-09T16:48:56.447021Z","steps":["trace[1940257703] 'process raft request' (duration: 150.976581ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.447219Z","caller":"traceutil/trace.go:171","msg":"trace[1852260710] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"151.121373ms","start":"2026-05-09T16:48:56.296092Z","end":"2026-05-09T16:48:56.447214Z","steps":["trace[1852260710] 'process raft request' (duration: 150.75034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:48:56.447211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.585158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:48:56.447266Z","caller":"traceutil/trace.go:171","msg":"trace[1933750322] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"137.613874ms","start":"2026-05-09T16:48:56.309642Z","end":"2026-05-09T16:48:56.447256Z","steps":["trace[1933750322] 'process raft request' (duration: 137.25916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:48:56.447122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.73446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T16:48:56.447311Z","caller":"traceutil/trace.go:171","msg":"trace[809781523] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:0; response_revision:10; }","duration":"153.944022ms","start":"2026-05-09T16:48:56.293362Z","end":"2026-05-09T16:48:56.447306Z","steps":["trace[809781523] 'agreement among raft nodes before linearized reading' (duration: 153.74576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:48:56.447337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.702336ms","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-05-09T16:48:56.447426Z","caller":"traceutil/trace.go:171","msg":"trace[154930645] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"145.774268ms","start":"2026-05-09T16:48:56.301598Z","end":"2026-05-09T16:48:56.447372Z","steps":["trace[154930645] 'agreement among raft nodes before linearized reading' (duration: 145.700777ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.447464Z","caller":"traceutil/trace.go:171","msg":"trace[1249832328] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"137.685314ms","start":"2026-05-09T16:48:56.309772Z","end":"2026-05-09T16:48:56.447458Z","steps":["trace[1249832328] 'process raft request' (duration: 137.145338ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.447457Z","caller":"traceutil/trace.go:171","msg":"trace[146880510] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"145.922329ms","start":"2026-05-09T16:48:56.301525Z","end":"2026-05-09T16:48:56.447447Z","steps":["trace[146880510] 'process raft request' (duration: 145.352163ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:48:56.447274Z","caller":"traceutil/trace.go:171","msg":"trace[535579764] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"152.693609ms","start":"2026-05-09T16:48:56.294563Z","end":"2026-05-09T16:48:56.447257Z","steps":["trace[535579764] 'agreement among raft nodes before linearized reading' (duration: 152.511237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.02607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.210046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-09T16:49:03.026163Z","caller":"traceutil/trace.go:171","msg":"trace[1353477625] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:338; }","duration":"142.330367ms","start":"2026-05-09T16:49:02.883808Z","end":"2026-05-09T16:49:03.026139Z","steps":["trace[1353477625] 'range keys from in-memory index tree' (duration: 142.131955ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:03.5531Z","caller":"traceutil/trace.go:171","msg":"trace[72842776] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"133.799324ms","start":"2026-05-09T16:49:03.419278Z","end":"2026-05-09T16:49:03.553077Z","steps":["trace[72842776] 'process raft request' (duration: 116.987691ms)","trace[72842776] 'compare' (duration: 16.628831ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:03.553413Z","caller":"traceutil/trace.go:171","msg":"trace[2122813722] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:359; }","duration":"119.775078ms","start":"2026-05-09T16:49:03.433611Z","end":"2026-05-09T16:49:03.553386Z","steps":["trace[2122813722] 'read index received' (duration: 102.565671ms)","trace[2122813722] 'applied index is now lower than readState.Index' (duration: 17.208597ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:03.553429Z","caller":"traceutil/trace.go:171","msg":"trace[945681372] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"134.112686ms","start":"2026-05-09T16:49:03.419292Z","end":"2026-05-09T16:49:03.553404Z","steps":["trace[945681372] 'process raft request' (duration: 133.733162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.553577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.952289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-09T16:49:03.553541Z","caller":"traceutil/trace.go:171","msg":"trace[1280246827] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"134.134237ms","start":"2026-05-09T16:49:03.419382Z","end":"2026-05-09T16:49:03.553517Z","steps":["trace[1280246827] 'process raft request' (duration: 133.907605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:03.553619Z","caller":"traceutil/trace.go:171","msg":"trace[54226205] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:352; }","duration":"120.045701ms","start":"2026-05-09T16:49:03.433562Z","end":"2026-05-09T16:49:03.553607Z","steps":["trace[54226205] 'agreement among raft nodes before linearized reading' (duration: 119.95792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.86448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.350872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:49:03.864821Z","caller":"traceutil/trace.go:171","msg":"trace[1057342417] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"302.821618ms","start":"2026-05-09T16:49:03.56195Z","end":"2026-05-09T16:49:03.864772Z","steps":["trace[1057342417] 'process raft request' (duration: 117.499406ms)","trace[1057342417] 'compare' (duration: 184.166191ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:03.864871Z","caller":"traceutil/trace.go:171","msg":"trace[1609606494] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:362; }","duration":"280.674743ms","start":"2026-05-09T16:49:03.584175Z","end":"2026-05-09T16:49:03.86485Z","steps":["trace[1609606494] 'read index received' (duration: 4.939858ms)","trace[1609606494] 'applied index is now lower than readState.Index' (duration: 275.733815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:03.86499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.561928Z","time spent":"302.975679ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:03.865209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.031087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-09T16:49:03.865198Z","caller":"traceutil/trace.go:171","msg":"trace[523640563] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"302.372153ms","start":"2026-05-09T16:49:03.562739Z","end":"2026-05-09T16:49:03.865111Z","steps":["trace[523640563] 'process raft request' (duration: 302.03938ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:03.865322Z","caller":"traceutil/trace.go:171","msg":"trace[450165728] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"303.09597ms","start":"2026-05-09T16:49:03.562199Z","end":"2026-05-09T16:49:03.865294Z","steps":["trace[450165728] 'process raft request' (duration: 302.483684ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:03.865398Z","caller":"traceutil/trace.go:171","msg":"trace[2063479866] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"281.227099ms","start":"2026-05-09T16:49:03.584114Z","end":"2026-05-09T16:49:03.865341Z","steps":["trace[2063479866] 'agreement among raft nodes before linearized reading' (duration: 281.030087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.865499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.146944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-09T16:49:03.86555Z","caller":"traceutil/trace.go:171","msg":"trace[1215624522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:356; }","duration":"231.264005ms","start":"2026-05-09T16:49:03.634271Z","end":"2026-05-09T16:49:03.865535Z","steps":["trace[1215624522] 'agreement among raft nodes before linearized reading' (duration: 231.152944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.86552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.562179Z","time spent":"303.243681ms","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:03.86573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.639236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T16:49:03.865764Z","caller":"traceutil/trace.go:171","msg":"trace[648183446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:356; }","duration":"181.715106ms","start":"2026-05-09T16:49:03.684041Z","end":"2026-05-09T16:49:03.865756Z","steps":["trace[648183446] 'agreement among raft nodes before linearized reading' (duration: 181.658516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:03.866592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.562724Z","time spent":"302.724606ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:04.343068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.532342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:49:04.343284Z","caller":"traceutil/trace.go:171","msg":"trace[22612469] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"472.332246ms","start":"2026-05-09T16:49:03.87092Z","end":"2026-05-09T16:49:04.343252Z","steps":["trace[22612469] 'process raft request' (duration: 225.551842ms)","trace[22612469] 'compare' (duration: 246.370901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:04.343429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.870897Z","time spent":"472.465018ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:04.343539Z","caller":"traceutil/trace.go:171","msg":"trace[1019163783] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"469.56799ms","start":"2026-05-09T16:49:03.873941Z","end":"2026-05-09T16:49:04.343509Z","steps":["trace[1019163783] 'process raft request' (duration: 469.380748ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:04.343587Z","caller":"traceutil/trace.go:171","msg":"trace[1369234094] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"467.692661ms","start":"2026-05-09T16:49:03.875873Z","end":"2026-05-09T16:49:04.343565Z","steps":["trace[1369234094] 'process raft request' (duration: 467.636461ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:04.343531Z","caller":"traceutil/trace.go:171","msg":"trace[537702771] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"470.70297ms","start":"2026-05-09T16:49:03.87273Z","end":"2026-05-09T16:49:04.343433Z","steps":["trace[537702771] 'process raft request' (duration: 470.446688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.343683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.875864Z","time spent":"467.764262ms","remote":"127.0.0.1:52766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:04.34371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.873933Z","time spent":"469.674361ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:04.343898Z","caller":"traceutil/trace.go:171","msg":"trace[1225945876] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"468.993934ms","start":"2026-05-09T16:49:03.874869Z","end":"2026-05-09T16:49:04.343863Z","steps":["trace[1225945876] 'process raft request' (duration: 468.610601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.343867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.872715Z","time spent":"471.027004ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:04.343985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.874861Z","time spent":"469.082765ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:04.343988Z","caller":"traceutil/trace.go:171","msg":"trace[1154580965] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"469.843432ms","start":"2026-05-09T16:49:03.874087Z","end":"2026-05-09T16:49:04.34393Z","steps":["trace[1154580965] 'process raft request' (duration: 469.356927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.344134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:03.874081Z","time spent":"469.978043ms","remote":"127.0.0.1:52796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:04.795537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.45954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:49:04.795824Z","caller":"traceutil/trace.go:171","msg":"trace[462825735] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"448.869701ms","start":"2026-05-09T16:49:04.346914Z","end":"2026-05-09T16:49:04.795784Z","steps":["trace[462825735] 'process raft request' (duration: 197.070388ms)","trace[462825735] 'compare' (duration: 250.834234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:04.795966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.346891Z","time spent":"449.024703ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:04.801492Z","caller":"traceutil/trace.go:171","msg":"trace[1529283775] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"451.968181ms","start":"2026-05-09T16:49:04.349488Z","end":"2026-05-09T16:49:04.801456Z","steps":["trace[1529283775] 'process raft request' (duration: 451.612788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.801779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.349463Z","time spent":"452.115992ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:04.801901Z","caller":"traceutil/trace.go:171","msg":"trace[1079899364] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"452.197593ms","start":"2026-05-09T16:49:04.349631Z","end":"2026-05-09T16:49:04.801829Z","steps":["trace[1079899364] 'process raft request' (duration: 452.023701ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:04.801795Z","caller":"traceutil/trace.go:171","msg":"trace[876421768] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"452.190103ms","start":"2026-05-09T16:49:04.349584Z","end":"2026-05-09T16:49:04.801774Z","steps":["trace[876421768] 'process raft request' (duration: 451.659168ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:04.801926Z","caller":"traceutil/trace.go:171","msg":"trace[199964836] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"452.246613ms","start":"2026-05-09T16:49:04.349662Z","end":"2026-05-09T16:49:04.801908Z","steps":["trace[199964836] 'process raft request' (duration: 452.023411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.802029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.349614Z","time spent":"452.372224ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:04.802121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.34965Z","time spent":"452.383885ms","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:04.80208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.349571Z","time spent":"452.399965ms","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:04.802193Z","caller":"traceutil/trace.go:171","msg":"trace[974912496] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"452.132042ms","start":"2026-05-09T16:49:04.349604Z","end":"2026-05-09T16:49:04.801736Z","steps":["trace[974912496] 'process raft request' (duration: 451.94665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.802289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.349595Z","time spent":"452.666278ms","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:04.803331Z","caller":"traceutil/trace.go:171","msg":"trace[948261636] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:372; }","duration":"408.976269ms","start":"2026-05-09T16:49:04.394335Z","end":"2026-05-09T16:49:04.803311Z","steps":["trace[948261636] 'read index received' (duration: 149.663704ms)","trace[948261636] 'applied index is now lower than readState.Index' (duration: 259.311665ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:04.803552Z","caller":"traceutil/trace.go:171","msg":"trace[1611985690] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"437.956777ms","start":"2026-05-09T16:49:04.365576Z","end":"2026-05-09T16:49:04.803533Z","steps":["trace[1611985690] 'process raft request' (duration: 437.687114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:04.803593Z","caller":"traceutil/trace.go:171","msg":"trace[1432985224] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"450.241264ms","start":"2026-05-09T16:49:04.35332Z","end":"2026-05-09T16:49:04.803562Z","steps":["trace[1432985224] 'process raft request' (duration: 449.879571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.803752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.353314Z","time spent":"450.349335ms","remote":"127.0.0.1:52766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:04.803741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.365532Z","time spent":"438.111368ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:04.803972Z","caller":"traceutil/trace.go:171","msg":"trace[504442249] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"452.465585ms","start":"2026-05-09T16:49:04.351496Z","end":"2026-05-09T16:49:04.803961Z","steps":["trace[504442249] 'process raft request' (duration: 450.216673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.804032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.351408Z","time spent":"452.594377ms","remote":"127.0.0.1:52984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:04.804352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.013199ms","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-05-09T16:49:04.804395Z","caller":"traceutil/trace.go:171","msg":"trace[83265484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"410.093799ms","start":"2026-05-09T16:49:04.39429Z","end":"2026-05-09T16:49:04.804384Z","steps":["trace[83265484] 'agreement among raft nodes before linearized reading' (duration: 410.024109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:04.804424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.394275Z","time spent":"410.14237ms","remote":"127.0.0.1:52780","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-05-09T16:49:05.304231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741977348242238,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:49:05.478061Z","caller":"traceutil/trace.go:171","msg":"trace[579138544] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"678.446692ms","start":"2026-05-09T16:49:04.79958Z","end":"2026-05-09T16:49:05.478027Z","steps":["trace[579138544] 'process raft request' (duration: 604.196907ms)","trace[579138544] 'compare' (duration: 74.036123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:05.478271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.799558Z","time spent":"678.601653ms","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:05.635675Z","caller":"traceutil/trace.go:171","msg":"trace[24112542] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:381; }","duration":"832.264652ms","start":"2026-05-09T16:49:04.80339Z","end":"2026-05-09T16:49:05.635655Z","steps":["trace[24112542] 'read index received' (duration: 600.39678ms)","trace[24112542] 'applied index is now lower than readState.Index' (duration: 231.866882ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:05.635888Z","caller":"traceutil/trace.go:171","msg":"trace[1860424008] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"829.268513ms","start":"2026-05-09T16:49:04.806511Z","end":"2026-05-09T16:49:05.635779Z","steps":["trace[1860424008] 'process raft request' (duration: 828.771258ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:05.635932Z","caller":"traceutil/trace.go:171","msg":"trace[307471271] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"826.666558ms","start":"2026-05-09T16:49:04.809242Z","end":"2026-05-09T16:49:05.635908Z","steps":["trace[307471271] 'process raft request' (duration: 826.199054ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:05.635935Z","caller":"traceutil/trace.go:171","msg":"trace[1978910896] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"827.070342ms","start":"2026-05-09T16:49:04.808845Z","end":"2026-05-09T16:49:05.635916Z","steps":["trace[1978910896] 'process raft request' (duration: 826.550287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.809235Z","time spent":"826.750659ms","remote":"127.0.0.1:52780","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-05-09T16:49:05.636059Z","caller":"traceutil/trace.go:171","msg":"trace[268185779] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"824.820371ms","start":"2026-05-09T16:49:04.811216Z","end":"2026-05-09T16:49:05.636036Z","steps":["trace[268185779] 'process raft request' (duration: 824.391637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.806493Z","time spent":"829.461656ms","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:05.636172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.533955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-pfrjk\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-09T16:49:05.636261Z","caller":"traceutil/trace.go:171","msg":"trace[269903735] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-pfrjk; range_end:; response_count:1; response_revision:377; }","duration":"829.629556ms","start":"2026-05-09T16:49:04.80661Z","end":"2026-05-09T16:49:05.63624Z","steps":["trace[269903735] 'agreement among raft nodes before linearized reading' (duration: 829.524245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.806605Z","time spent":"829.710758ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3462,"request content":"key:\"/registry/pods/kube-system/kube-proxy-pfrjk\" "} {"level":"warn","ts":"2026-05-09T16:49:05.636342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.777793ms","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-05-09T16:49:05.636417Z","caller":"traceutil/trace.go:171","msg":"trace[1222683364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"864.911715ms","start":"2026-05-09T16:49:04.771486Z","end":"2026-05-09T16:49:05.636398Z","steps":["trace[1222683364] 'agreement among raft nodes before linearized reading' (duration: 864.695983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043651897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T16:49:05.636478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.771465Z","time spent":"865.000515ms","remote":"127.0.0.1:52780","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-05-09T16:49:05.636502Z","caller":"traceutil/trace.go:171","msg":"trace[1293294524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"1.043716058s","start":"2026-05-09T16:49:04.592774Z","end":"2026-05-09T16:49:05.63649Z","steps":["trace[1293294524] 'agreement among raft nodes before linearized reading' (duration: 1.043672627s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.592752Z","time spent":"1.043775919s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T16:49:05.636399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.51871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-09T16:49:05.636648Z","caller":"traceutil/trace.go:171","msg":"trace[715985282] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"825.696849ms","start":"2026-05-09T16:49:04.810942Z","end":"2026-05-09T16:49:05.636638Z","steps":["trace[715985282] 'process raft request' (duration: 824.554938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.558558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-09T16:49:05.636691Z","caller":"traceutil/trace.go:171","msg":"trace[242608411] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:377; }","duration":"825.616518ms","start":"2026-05-09T16:49:04.811065Z","end":"2026-05-09T16:49:05.636681Z","steps":["trace[242608411] 'agreement among raft nodes before linearized reading' (duration: 825.521167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.810934Z","time spent":"825.739789ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:05.6367Z","caller":"traceutil/trace.go:171","msg":"trace[1214841736] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:377; }","duration":"628.816192ms","start":"2026-05-09T16:49:05.007845Z","end":"2026-05-09T16:49:05.636662Z","steps":["trace[1214841736] 'agreement among raft nodes before linearized reading' (duration: 628.515069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.636832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.007833Z","time spent":"628.984555ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-09T16:49:05.636129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.808837Z","time spent":"827.213983ms","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:05.636408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.811209Z","time spent":"824.990433ms","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:05.636774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.811035Z","time spent":"825.710259ms","remote":"127.0.0.1:52984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2924,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-09T16:49:05.637363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.599991ms","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-05-09T16:49:05.637584Z","caller":"traceutil/trace.go:171","msg":"trace[33393786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"866.118116ms","start":"2026-05-09T16:49:04.771408Z","end":"2026-05-09T16:49:05.637526Z","steps":["trace[33393786] 'agreement among raft nodes before linearized reading' (duration: 864.523421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:05.637628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:04.771391Z","time spent":"866.224417ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:49:06.146358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741977348242247,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T16:49:06.495136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.259505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:49:06.495272Z","caller":"traceutil/trace.go:171","msg":"trace[521575868] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"853.147634ms","start":"2026-05-09T16:49:05.642068Z","end":"2026-05-09T16:49:06.495215Z","steps":["trace[521575868] 'process raft request' (duration: 684.722157ms)","trace[521575868] 'compare' (duration: 167.590959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:06.495406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.64205Z","time spent":"853.321306ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:06.724198Z","caller":"traceutil/trace.go:171","msg":"trace[1335242966] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.080146483s","start":"2026-05-09T16:49:05.644031Z","end":"2026-05-09T16:49:06.724177Z","steps":["trace[1335242966] 'process raft request' (duration: 1.080066982s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:06.724256Z","caller":"traceutil/trace.go:171","msg":"trace[507232752] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.08205516s","start":"2026-05-09T16:49:05.642158Z","end":"2026-05-09T16:49:06.724213Z","steps":["trace[507232752] 'process raft request' (duration: 1.034148589s)","trace[507232752] 'compare' (duration: 47.664089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:06.724328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.644001Z","time spent":"1.080263114s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:06.724449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.64214Z","time spent":"1.082192382s","remote":"127.0.0.1:52780","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-05-09T16:49:06.724473Z","caller":"traceutil/trace.go:171","msg":"trace[1843218842] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"1.080323814s","start":"2026-05-09T16:49:05.64413Z","end":"2026-05-09T16:49:06.724454Z","steps":["trace[1843218842] 'process raft request' (duration: 1.080007801s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:06.72456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.643673Z","time spent":"1.080842519s","remote":"127.0.0.1:52584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T16:49:06.72485Z","caller":"traceutil/trace.go:171","msg":"trace[1550082049] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:387; }","duration":"1.078552677s","start":"2026-05-09T16:49:05.646285Z","end":"2026-05-09T16:49:06.724837Z","steps":["trace[1550082049] 'read index received' (duration: 680.643979ms)","trace[1550082049] 'applied index is now lower than readState.Index' (duration: 397.907778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:06.724969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078685488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-05-09T16:49:06.725052Z","caller":"traceutil/trace.go:171","msg":"trace[627222568] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:383; }","duration":"1.07876226s","start":"2026-05-09T16:49:05.646234Z","end":"2026-05-09T16:49:06.724996Z","steps":["trace[627222568] 'agreement among raft nodes before linearized reading' (duration: 1.078653218s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:06.72507Z","caller":"traceutil/trace.go:171","msg":"trace[1782844575] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.078271085s","start":"2026-05-09T16:49:05.646758Z","end":"2026-05-09T16:49:06.725029Z","steps":["trace[1782844575] 'process raft request' (duration: 1.077957262s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:06.72514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.646224Z","time spent":"1.078905381s","remote":"127.0.0.1:52984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2924,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-09T16:49:06.72505Z","caller":"traceutil/trace.go:171","msg":"trace[2085325365] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"1.080175183s","start":"2026-05-09T16:49:05.64485Z","end":"2026-05-09T16:49:06.725025Z","steps":["trace[2085325365] 'process raft request' (duration: 1.079527217s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:06.725323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.646751Z","time spent":"1.078446676s","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:06.725405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:05.644845Z","time spent":"1.080484806s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:08.966817Z","caller":"traceutil/trace.go:171","msg":"trace[251831319] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"233.814746ms","start":"2026-05-09T16:49:08.732976Z","end":"2026-05-09T16:49:08.96679Z","steps":["trace[251831319] 'process raft request' (duration: 140.691844ms)","trace[251831319] 'compare' (duration: 92.992581ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:23.013387Z","caller":"traceutil/trace.go:171","msg":"trace[1080928439] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"145.996911ms","start":"2026-05-09T16:49:22.86737Z","end":"2026-05-09T16:49:23.013367Z","steps":["trace[1080928439] 'process raft request' (duration: 145.87931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:27.568691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741977348242502,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:49:27.76751Z","caller":"traceutil/trace.go:171","msg":"trace[1642236703] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"737.533518ms","start":"2026-05-09T16:49:27.029955Z","end":"2026-05-09T16:49:27.767489Z","steps":["trace[1642236703] 'process raft request' (duration: 737.404677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:27.767657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:27.029944Z","time spent":"737.639279ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:49:28.076836Z","caller":"traceutil/trace.go:171","msg":"trace[2139197766] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"1.009009516s","start":"2026-05-09T16:49:27.067802Z","end":"2026-05-09T16:49:28.076812Z","steps":["trace[2139197766] 'read index received' (duration: 699.657687ms)","trace[2139197766] 'applied index is now lower than readState.Index' (duration: 309.350909ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:28.076958Z","caller":"traceutil/trace.go:171","msg":"trace[1797768459] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"621.758164ms","start":"2026-05-09T16:49:27.455164Z","end":"2026-05-09T16:49:28.076922Z","steps":["trace[1797768459] 'process raft request' (duration: 606.938733ms)","trace[1797768459] 'compare' (duration: 14.606599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:28.077015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.822837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-09T16:49:28.077017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.708671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:49:28.077052Z","caller":"traceutil/trace.go:171","msg":"trace[1437497482] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:507; }","duration":"211.900768ms","start":"2026-05-09T16:49:27.865139Z","end":"2026-05-09T16:49:28.07704Z","steps":["trace[1437497482] 'agreement among raft nodes before linearized reading' (duration: 211.832807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.077082Z","caller":"traceutil/trace.go:171","msg":"trace[1780171049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"886.798732ms","start":"2026-05-09T16:49:27.190253Z","end":"2026-05-09T16:49:28.077052Z","steps":["trace[1780171049] 'agreement among raft nodes before linearized reading' (duration: 886.717111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:28.077116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:27.190237Z","time spent":"886.871742ms","remote":"127.0.0.1:52506","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-05-09T16:49:28.077115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:27.45513Z","time spent":"621.896526ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:28.077206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009380589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T16:49:28.077306Z","caller":"traceutil/trace.go:171","msg":"trace[1167825156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"1.00952349s","start":"2026-05-09T16:49:27.067762Z","end":"2026-05-09T16:49:28.077285Z","steps":["trace[1167825156] 'agreement among raft nodes before linearized reading' (duration: 1.009368008s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:28.077333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.103145ms","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-05-09T16:49:28.07735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:27.067744Z","time spent":"1.00959725s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T16:49:28.077378Z","caller":"traceutil/trace.go:171","msg":"trace[705671861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"282.175605ms","start":"2026-05-09T16:49:27.795192Z","end":"2026-05-09T16:49:28.077368Z","steps":["trace[705671861] 'agreement among raft nodes before linearized reading' (duration: 282.090705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:28.07744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.20608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:49:28.077475Z","caller":"traceutil/trace.go:171","msg":"trace[922833164] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:507; }","duration":"862.27444ms","start":"2026-05-09T16:49:27.215189Z","end":"2026-05-09T16:49:28.077464Z","steps":["trace[922833164] 'agreement among raft nodes before linearized reading' (duration: 862.22256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:28.077503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:27.215172Z","time spent":"862.32444ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-09T16:49:28.641772Z","caller":"traceutil/trace.go:171","msg":"trace[2121013685] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"206.145216ms","start":"2026-05-09T16:49:28.435588Z","end":"2026-05-09T16:49:28.641733Z","steps":["trace[2121013685] 'process raft request' (duration: 205.950694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.641922Z","caller":"traceutil/trace.go:171","msg":"trace[728569059] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"215.718804ms","start":"2026-05-09T16:49:28.426147Z","end":"2026-05-09T16:49:28.641865Z","steps":["trace[728569059] 'process raft request' (duration: 145.892573ms)","trace[728569059] 'compare' (duration: 69.312927ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:28.642682Z","caller":"traceutil/trace.go:171","msg":"trace[571890056] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"202.589637ms","start":"2026-05-09T16:49:28.439493Z","end":"2026-05-09T16:49:28.642083Z","steps":["trace[571890056] 'process raft request' (duration: 202.387465ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.642757Z","caller":"traceutil/trace.go:171","msg":"trace[313241718] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"206.085185ms","start":"2026-05-09T16:49:28.435961Z","end":"2026-05-09T16:49:28.642047Z","steps":["trace[313241718] 'process raft request' (duration: 205.866753ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.642799Z","caller":"traceutil/trace.go:171","msg":"trace[1524147286] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"206.931522ms","start":"2026-05-09T16:49:28.435674Z","end":"2026-05-09T16:49:28.642605Z","steps":["trace[1524147286] 'process raft request' (duration: 205.925134ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.642838Z","caller":"traceutil/trace.go:171","msg":"trace[1651124977] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"203.230791ms","start":"2026-05-09T16:49:28.439579Z","end":"2026-05-09T16:49:28.64281Z","steps":["trace[1651124977] 'process raft request' (duration: 202.358584ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.642052Z","caller":"traceutil/trace.go:171","msg":"trace[1031447294] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"206.117065ms","start":"2026-05-09T16:49:28.435895Z","end":"2026-05-09T16:49:28.642012Z","steps":["trace[1031447294] 'process raft request' (duration: 205.801052ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.643084Z","caller":"traceutil/trace.go:171","msg":"trace[2123886738] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"207.118393ms","start":"2026-05-09T16:49:28.435939Z","end":"2026-05-09T16:49:28.643057Z","steps":["trace[2123886738] 'process raft request' (duration: 205.832523ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.643311Z","caller":"traceutil/trace.go:171","msg":"trace[1204031137] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"207.484677ms","start":"2026-05-09T16:49:28.435703Z","end":"2026-05-09T16:49:28.643188Z","steps":["trace[1204031137] 'process raft request' (duration: 205.921214ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.64442Z","caller":"traceutil/trace.go:171","msg":"trace[454194352] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"204.749743ms","start":"2026-05-09T16:49:28.439655Z","end":"2026-05-09T16:49:28.644405Z","steps":["trace[454194352] 'process raft request' (duration: 203.219951ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.645697Z","caller":"traceutil/trace.go:171","msg":"trace[2065782116] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"205.072766ms","start":"2026-05-09T16:49:28.440558Z","end":"2026-05-09T16:49:28.645631Z","steps":["trace[2065782116] 'process raft request' (duration: 203.649215ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.65111Z","caller":"traceutil/trace.go:171","msg":"trace[1218440316] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"205.264498ms","start":"2026-05-09T16:49:28.441676Z","end":"2026-05-09T16:49:28.64694Z","steps":["trace[1218440316] 'process raft request' (duration: 202.880189ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.652165Z","caller":"traceutil/trace.go:171","msg":"trace[1158853441] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"110.09771ms","start":"2026-05-09T16:49:28.542052Z","end":"2026-05-09T16:49:28.65215Z","steps":["trace[1158853441] 'process raft request' (duration: 109.945429ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:28.652311Z","caller":"traceutil/trace.go:171","msg":"trace[1068629368] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"204.441611ms","start":"2026-05-09T16:49:28.447862Z","end":"2026-05-09T16:49:28.652303Z","steps":["trace[1068629368] 'process raft request' (duration: 197.666186ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:29.566485Z","caller":"traceutil/trace.go:171","msg":"trace[114879821] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"131.681032ms","start":"2026-05-09T16:49:29.434782Z","end":"2026-05-09T16:49:29.566463Z","steps":["trace[114879821] 'process raft request' (duration: 126.987434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:35.193723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741977348242756,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T16:49:35.195581Z","caller":"traceutil/trace.go:171","msg":"trace[851191710] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"703.133581ms","start":"2026-05-09T16:49:34.492429Z","end":"2026-05-09T16:49:35.195563Z","steps":["trace[851191710] 'process raft request' (duration: 702.672268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:35.195674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:34.492414Z","time spent":"703.201103ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:36.700827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.505732554s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T16:49:36.701326Z","caller":"traceutil/trace.go:171","msg":"trace[278861141] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"2.007630575s","start":"2026-05-09T16:49:34.693672Z","end":"2026-05-09T16:49:36.701303Z","steps":["trace[278861141] 'read index received' (duration: 501.333545ms)","trace[278861141] 'applied index is now lower than readState.Index' (duration: 1.50629619s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:36.701497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007814147s","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-05-09T16:49:36.701482Z","caller":"traceutil/trace.go:171","msg":"trace[280797467] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.148009194s","start":"2026-05-09T16:49:34.553435Z","end":"2026-05-09T16:49:36.701444Z","steps":["trace[280797467] 'process raft request' (duration: 2.14769033s)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:36.701531Z","caller":"traceutil/trace.go:171","msg":"trace[133868956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"2.007900439s","start":"2026-05-09T16:49:34.69362Z","end":"2026-05-09T16:49:36.70152Z","steps":["trace[133868956] 'agreement among raft nodes before linearized reading' (duration: 2.007803028s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:36.701565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:34.693595Z","time spent":"2.007962499s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T16:49:36.702064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:34.553407Z","time spent":"2.148140495s","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T16:49:36.897417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.506248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:49:36.897619Z","caller":"traceutil/trace.go:171","msg":"trace[1704432731] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"189.840763ms","start":"2026-05-09T16:49:36.70776Z","end":"2026-05-09T16:49:36.897601Z","steps":["trace[1704432731] 'process raft request' (duration: 189.774122ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:49:36.897755Z","caller":"traceutil/trace.go:171","msg":"trace[1532379397] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.626177442s","start":"2026-05-09T16:49:35.271534Z","end":"2026-05-09T16:49:36.897711Z","steps":["trace[1532379397] 'process raft request' (duration: 1.46829169s)","trace[1532379397] 'compare' (duration: 157.289665ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:49:36.897808Z","caller":"traceutil/trace.go:171","msg":"trace[2040999595] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"196.375248ms","start":"2026-05-09T16:49:36.701399Z","end":"2026-05-09T16:49:36.897774Z","steps":["trace[2040999595] 'read index received' (duration: 38.352674ms)","trace[2040999595] 'applied index is now lower than readState.Index' (duration: 158.019004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:49:36.897931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:35.271516Z","time spent":"1.626338694s","remote":"127.0.0.1:53028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:49:36.898155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.096361ms","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-05-09T16:49:36.898441Z","caller":"traceutil/trace.go:171","msg":"trace[597035013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"788.415164ms","start":"2026-05-09T16:49:36.110005Z","end":"2026-05-09T16:49:36.89842Z","steps":["trace[597035013] 'agreement among raft nodes before linearized reading' (duration: 787.984899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:36.898487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:36.109916Z","time spent":"788.559516ms","remote":"127.0.0.1:52780","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-05-09T16:49:36.898192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.017464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T16:49:36.898633Z","caller":"traceutil/trace.go:171","msg":"trace[1992784459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"781.485299ms","start":"2026-05-09T16:49:36.117116Z","end":"2026-05-09T16:49:36.898601Z","steps":["trace[1992784459] 'agreement among raft nodes before linearized reading' (duration: 780.928853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:36.898212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076003821s","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-05-09T16:49:36.898776Z","caller":"traceutil/trace.go:171","msg":"trace[951071213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.076615937s","start":"2026-05-09T16:49:35.822142Z","end":"2026-05-09T16:49:36.898758Z","steps":["trace[951071213] 'agreement among raft nodes before linearized reading' (duration: 1.075876349s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:36.89895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:35.822122Z","time spent":"1.07681249s","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T16:49:36.898208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70767641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T16:49:36.899336Z","caller":"traceutil/trace.go:171","msg":"trace[1409817413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.708835683s","start":"2026-05-09T16:49:35.190464Z","end":"2026-05-09T16:49:36.8993Z","steps":["trace[1409817413] 'agreement among raft nodes before linearized reading' (duration: 1.707629219s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:36.898719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:36.117092Z","time spent":"781.607361ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T16:49:36.899402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:35.190449Z","time spent":"1.708938764s","remote":"127.0.0.1:52506","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-05-09T16:49:38.90189Z","caller":"traceutil/trace.go:171","msg":"trace[978487228] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"257.664873ms","start":"2026-05-09T16:49:38.644202Z","end":"2026-05-09T16:49:38.901867Z","steps":["trace[978487228] 'process raft request' (duration: 257.543638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:52.630324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.466302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T16:49:52.630424Z","caller":"traceutil/trace.go:171","msg":"trace[522068757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"255.617527ms","start":"2026-05-09T16:49:52.374789Z","end":"2026-05-09T16:49:52.630407Z","steps":["trace[522068757] 'range keys from in-memory index tree' (duration: 255.304807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:52.630429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045396105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4fb8w\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-09T16:49:52.630496Z","caller":"traceutil/trace.go:171","msg":"trace[830923469] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4fb8w; range_end:; response_count:1; response_revision:778; }","duration":"1.04550858s","start":"2026-05-09T16:49:51.584967Z","end":"2026-05-09T16:49:52.630476Z","steps":["trace[830923469] 'range keys from in-memory index tree' (duration: 1.04524951s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:49:52.630547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:49:51.584945Z","time spent":"1.045587943s","remote":"127.0.0.1:52714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4fb8w\" "} {"level":"info","ts":"2026-05-09T16:49:53.807926Z","caller":"traceutil/trace.go:171","msg":"trace[859920703] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"128.33792ms","start":"2026-05-09T16:49:53.679562Z","end":"2026-05-09T16:49:53.8079Z","steps":["trace[859920703] 'process raft request' (duration: 127.75436ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.042609Z","caller":"traceutil/trace.go:171","msg":"trace[1738873896] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"169.941956ms","start":"2026-05-09T16:51:27.872651Z","end":"2026-05-09T16:51:28.042593Z","steps":["trace[1738873896] 'read index received' (duration: 169.783493ms)","trace[1738873896] 'applied index is now lower than readState.Index' (duration: 157.843µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:51:28.042744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.088768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T16:51:28.042766Z","caller":"traceutil/trace.go:171","msg":"trace[492870886] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1234; }","duration":"170.153799ms","start":"2026-05-09T16:51:27.872606Z","end":"2026-05-09T16:51:28.042759Z","steps":["trace[492870886] 'agreement among raft nodes before linearized reading' (duration: 170.050708ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:51:28.042851Z","caller":"traceutil/trace.go:171","msg":"trace[783192136] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"181.137083ms","start":"2026-05-09T16:51:27.861504Z","end":"2026-05-09T16:51:28.042641Z","steps":["trace[783192136] 'process raft request' (duration: 181.007371ms)"],"step_count":1}