{"level":"warn","ts":"2026-04-13T21:10:45.744119Z","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-13T21:10:45.74421Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.166:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.166:2380","--initial-cluster=instance=https://199.19.213.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.166: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-13T21:10:45.744284Z","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-13T21:10:45.744296Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.166:2380"]} {"level":"info","ts":"2026-04-13T21:10:45.744324Z","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-13T21:10:45.744954Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"]} {"level":"info","ts":"2026-04-13T21:10:45.745028Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.166: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-13T21:10:45.751833Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.600137ms"} {"level":"info","ts":"2026-04-13T21:10:45.761191Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a8829716614d67b","cluster-id":"95303c3f9d508ee2"} {"level":"info","ts":"2026-04-13T21:10:45.761333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T21:10:45.761359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became follower at term 0"} {"level":"info","ts":"2026-04-13T21:10:45.761406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a8829716614d67b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T21:10:45.761415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became follower at term 1"} {"level":"info","ts":"2026-04-13T21:10:45.761512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=(7676431131873695355)"} {"level":"warn","ts":"2026-04-13T21:10:45.7742Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T21:10:45.775899Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T21:10:45.77722Z","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-13T21:10:45.779075Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a8829716614d67b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T21:10:45.779863Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a8829716614d67b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T21:10:45.7801Z","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-13T21:10:45.780232Z","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-13T21:10:45.780263Z","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-13T21:10:45.782762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=(7676431131873695355)"} {"level":"info","ts":"2026-04-13T21:10:45.78292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95303c3f9d508ee2","local-member-id":"6a8829716614d67b","added-peer-id":"6a8829716614d67b","added-peer-peer-urls":["https://199.19.213.166:2380"]} {"level":"info","ts":"2026-04-13T21:10:45.785252Z","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-13T21:10:45.785357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-04-13T21:10:45.785372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-04-13T21:10:45.785726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a8829716614d67b","initial-advertise-peer-urls":["https://199.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T21:10:45.785788Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T21:10:46.462292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T21:10:46.462334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T21:10:46.462347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b received MsgPreVoteResp from 6a8829716614d67b at term 1"} {"level":"info","ts":"2026-04-13T21:10:46.462358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became candidate at term 2"} {"level":"info","ts":"2026-04-13T21:10:46.462363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b received MsgVoteResp from 6a8829716614d67b at term 2"} {"level":"info","ts":"2026-04-13T21:10:46.462371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became leader at term 2"} {"level":"info","ts":"2026-04-13T21:10:46.46239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a8829716614d67b elected leader 6a8829716614d67b at term 2"} {"level":"info","ts":"2026-04-13T21:10:46.4656Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:46.46788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:10:46.467954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:10:46.469766Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95303c3f9d508ee2","local-member-id":"6a8829716614d67b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:46.469831Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:46.46985Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:46.470396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T21:10:46.470418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T21:10:46.471037Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.166:2379"} {"level":"info","ts":"2026-04-13T21:10:46.46787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a8829716614d67b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.166:2379]}","request-path":"/0/members/6a8829716614d67b/attributes","cluster-id":"95303c3f9d508ee2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T21:10:46.471541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-13T21:11:14.131414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.274619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:14.131553Z","caller":"traceutil/trace.go:171","msg":"trace[1276240445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"155.467194ms","start":"2026-04-13T21:11:13.976065Z","end":"2026-04-13T21:11:14.131533Z","steps":["trace[1276240445] 'range keys from in-memory index tree' (duration: 155.142607ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.102325Z","caller":"traceutil/trace.go:171","msg":"trace[1745246233] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"133.143463ms","start":"2026-04-13T21:11:16.969147Z","end":"2026-04-13T21:11:17.102291Z","steps":["trace[1745246233] 'process raft request' (duration: 125.487435ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.102355Z","caller":"traceutil/trace.go:171","msg":"trace[332412616] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"132.832256ms","start":"2026-04-13T21:11:16.969496Z","end":"2026-04-13T21:11:17.102329Z","steps":["trace[332412616] 'process raft request' (duration: 132.713404ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:18.71663Z","caller":"traceutil/trace.go:171","msg":"trace[321340127] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"145.124763ms","start":"2026-04-13T21:11:18.571482Z","end":"2026-04-13T21:11:18.716607Z","steps":["trace[321340127] 'process raft request' (duration: 145.003991ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:18.716759Z","caller":"traceutil/trace.go:171","msg":"trace[982394083] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:491; }","duration":"142.880591ms","start":"2026-04-13T21:11:18.573849Z","end":"2026-04-13T21:11:18.716729Z","steps":["trace[982394083] 'read index received' (duration: 142.87127ms)","trace[982394083] 'applied index is now lower than readState.Index' (duration: 7.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:18.716911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.038923ms","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-04-13T21:11:18.716945Z","caller":"traceutil/trace.go:171","msg":"trace[1839124066] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:472; }","duration":"143.129646ms","start":"2026-04-13T21:11:18.573806Z","end":"2026-04-13T21:11:18.716935Z","steps":["trace[1839124066] 'agreement among raft nodes before linearized reading' (duration: 143.028183ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.262518Z","caller":"traceutil/trace.go:171","msg":"trace[67273476] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"341.882355ms","start":"2026-04-13T21:11:18.920613Z","end":"2026-04-13T21:11:19.262495Z","steps":["trace[67273476] 'process raft request' (duration: 341.726272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.263223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.920594Z","time spent":"342.014137ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:19.272999Z","caller":"traceutil/trace.go:171","msg":"trace[105997135] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"340.379807ms","start":"2026-04-13T21:11:18.932601Z","end":"2026-04-13T21:11:19.272981Z","steps":["trace[105997135] 'process raft request' (duration: 340.155581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.273088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.932581Z","time spent":"340.466518ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:19.273258Z","caller":"traceutil/trace.go:171","msg":"trace[1781389636] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"317.968223ms","start":"2026-04-13T21:11:18.955273Z","end":"2026-04-13T21:11:19.273241Z","steps":["trace[1781389636] 'process raft request' (duration: 317.737249ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273282Z","caller":"traceutil/trace.go:171","msg":"trace[968580120] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"321.195727ms","start":"2026-04-13T21:11:18.952065Z","end":"2026-04-13T21:11:19.273261Z","steps":["trace[968580120] 'process raft request' (duration: 320.83903ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273313Z","caller":"traceutil/trace.go:171","msg":"trace[2081396452] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"339.606111ms","start":"2026-04-13T21:11:18.93369Z","end":"2026-04-13T21:11:19.273296Z","steps":["trace[2081396452] 'process raft request' (duration: 339.177663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.273354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.955266Z","time spent":"318.039705ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:11:19.273398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.933682Z","time spent":"339.673674ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:11:19.273407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.952049Z","time spent":"321.300738ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:19.273474Z","caller":"traceutil/trace.go:171","msg":"trace[811313957] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:492; }","duration":"156.971373ms","start":"2026-04-13T21:11:19.116488Z","end":"2026-04-13T21:11:19.273459Z","steps":["trace[811313957] 'read index received' (duration: 146.055332ms)","trace[811313957] 'applied index is now lower than readState.Index' (duration: 10.915391ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:19.273445Z","caller":"traceutil/trace.go:171","msg":"trace[1584729472] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"321.321199ms","start":"2026-04-13T21:11:18.952114Z","end":"2026-04-13T21:11:19.273435Z","steps":["trace[1584729472] 'process raft request' (duration: 320.85981ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273511Z","caller":"traceutil/trace.go:171","msg":"trace[210651434] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"306.217917ms","start":"2026-04-13T21:11:18.967281Z","end":"2026-04-13T21:11:19.273499Z","steps":["trace[210651434] 'process raft request' (duration: 305.81311ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273537Z","caller":"traceutil/trace.go:171","msg":"trace[432372497] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"317.895232ms","start":"2026-04-13T21:11:18.95563Z","end":"2026-04-13T21:11:19.273525Z","steps":["trace[432372497] 'process raft request' (duration: 317.408453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.27355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.95211Z","time spent":"321.418971ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:11:19.273555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.96725Z","time spent":"306.284088ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:11:19.27358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.085725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T21:11:19.273586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.955618Z","time spent":"317.942663ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:19.27361Z","caller":"traceutil/trace.go:171","msg":"trace[614869382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"157.159276ms","start":"2026-04-13T21:11:19.116441Z","end":"2026-04-13T21:11:19.2736Z","steps":["trace[614869382] 'agreement among raft nodes before linearized reading' (duration: 157.062954ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273625Z","caller":"traceutil/trace.go:171","msg":"trace[49240782] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"317.579756ms","start":"2026-04-13T21:11:18.956037Z","end":"2026-04-13T21:11:19.273617Z","steps":["trace[49240782] 'process raft request' (duration: 317.029416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.273675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.95603Z","time spent":"317.620987ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:19.273701Z","caller":"traceutil/trace.go:171","msg":"trace[1512011328] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"188.565854ms","start":"2026-04-13T21:11:19.085127Z","end":"2026-04-13T21:11:19.273693Z","steps":["trace[1512011328] 'process raft request' (duration: 188.211207ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273779Z","caller":"traceutil/trace.go:171","msg":"trace[2108627019] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"306.253767ms","start":"2026-04-13T21:11:18.967518Z","end":"2026-04-13T21:11:19.273771Z","steps":["trace[2108627019] 'process raft request' (duration: 305.675917ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:19.273776Z","caller":"traceutil/trace.go:171","msg":"trace[319515418] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"286.778111ms","start":"2026-04-13T21:11:18.986982Z","end":"2026-04-13T21:11:19.27376Z","steps":["trace[319515418] 'process raft request' (duration: 286.275952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:19.273811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:18.967497Z","time spent":"306.296439ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:22.46738Z","caller":"traceutil/trace.go:171","msg":"trace[410469504] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"130.753976ms","start":"2026-04-13T21:11:22.33661Z","end":"2026-04-13T21:11:22.467364Z","steps":["trace[410469504] 'process raft request' (duration: 130.633384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:23.228455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15455119756624594371,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:11:23.279461Z","caller":"traceutil/trace.go:171","msg":"trace[1860469455] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"552.004305ms","start":"2026-04-13T21:11:22.727391Z","end":"2026-04-13T21:11:23.279395Z","steps":["trace[1860469455] 'read index received' (duration: 546.59604ms)","trace[1860469455] 'applied index is now lower than readState.Index' (duration: 5.406715ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:23.279654Z","caller":"traceutil/trace.go:171","msg":"trace[546279171] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"597.010944ms","start":"2026-04-13T21:11:22.682627Z","end":"2026-04-13T21:11:23.279638Z","steps":["trace[546279171] 'process raft request' (duration: 592.063769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:23.279993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:22.682592Z","time spent":"597.095395ms","remote":"127.0.0.1:51078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:11:23.280499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.092236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T21:11:23.28055Z","caller":"traceutil/trace.go:171","msg":"trace[1108507041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"553.192098ms","start":"2026-04-13T21:11:22.727343Z","end":"2026-04-13T21:11:23.280535Z","steps":["trace[1108507041] 'agreement among raft nodes before linearized reading' (duration: 553.048335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:23.280599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:22.727321Z","time spent":"553.268919ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:11:23.280501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.175956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:23.280768Z","caller":"traceutil/trace.go:171","msg":"trace[1179711741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"305.463982ms","start":"2026-04-13T21:11:22.975272Z","end":"2026-04-13T21:11:23.280736Z","steps":["trace[1179711741] 'agreement among raft nodes before linearized reading' (duration: 305.067254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:23.280981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:22.975256Z","time spent":"305.696456ms","remote":"127.0.0.1:50968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:11:24.986287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.739806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:11:24.987076Z","caller":"traceutil/trace.go:171","msg":"trace[135914915] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"583.500064ms","start":"2026-04-13T21:11:24.403554Z","end":"2026-04-13T21:11:24.987054Z","steps":["trace[135914915] 'process raft request' (duration: 216.812679ms)","trace[135914915] 'compare' (duration: 365.562283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:24.987552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:24.403529Z","time spent":"583.973402ms","remote":"127.0.0.1:51292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T21:11:24.987178Z","caller":"traceutil/trace.go:171","msg":"trace[2028308730] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"515.921469ms","start":"2026-04-13T21:11:24.471229Z","end":"2026-04-13T21:11:24.987151Z","steps":["trace[2028308730] 'read index received' (duration: 149.097312ms)","trace[2028308730] 'applied index is now lower than readState.Index' (duration: 366.821197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:24.994037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.981613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.99407Z","caller":"traceutil/trace.go:171","msg":"trace[1933855954] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:569; }","duration":"158.038893ms","start":"2026-04-13T21:11:24.836021Z","end":"2026-04-13T21:11:24.99406Z","steps":["trace[1933855954] 'agreement among raft nodes before linearized reading' (duration: 157.988762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.994276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.051696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:11:24.994289Z","caller":"traceutil/trace.go:171","msg":"trace[928925597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"523.099187ms","start":"2026-04-13T21:11:24.471186Z","end":"2026-04-13T21:11:24.994285Z","steps":["trace[928925597] 'agreement among raft nodes before linearized reading' (duration: 516.049911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.994305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:24.471164Z","time spent":"523.133828ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:11:24.99479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.0714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.994807Z","caller":"traceutil/trace.go:171","msg":"trace[1206949321] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:569; }","duration":"105.120991ms","start":"2026-04-13T21:11:24.889681Z","end":"2026-04-13T21:11:24.994802Z","steps":["trace[1206949321] 'agreement among raft nodes before linearized reading' (duration: 105.096721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.994875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.265243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.994887Z","caller":"traceutil/trace.go:171","msg":"trace[1214200102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:569; }","duration":"105.292344ms","start":"2026-04-13T21:11:24.889591Z","end":"2026-04-13T21:11:24.994883Z","steps":["trace[1214200102] 'agreement among raft nodes before linearized reading' (duration: 105.271374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.994939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.394315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.994963Z","caller":"traceutil/trace.go:171","msg":"trace[1383756799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:569; }","duration":"105.458207ms","start":"2026-04-13T21:11:24.8895Z","end":"2026-04-13T21:11:24.994959Z","steps":["trace[1383756799] 'agreement among raft nodes before linearized reading' (duration: 105.428026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.995015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.95051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.995028Z","caller":"traceutil/trace.go:171","msg":"trace[880454450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:569; }","duration":"158.968871ms","start":"2026-04-13T21:11:24.836054Z","end":"2026-04-13T21:11:24.995023Z","steps":["trace[880454450] 'agreement among raft nodes before linearized reading' (duration: 158.950771ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:26.602068Z","caller":"traceutil/trace.go:171","msg":"trace[368309606] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"204.722115ms","start":"2026-04-13T21:11:26.397311Z","end":"2026-04-13T21:11:26.602033Z","steps":["trace[368309606] 'process raft request' (duration: 204.188765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:27.188201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.911054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:11:27.188342Z","caller":"traceutil/trace.go:171","msg":"trace[1279809769] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"571.363759ms","start":"2026-04-13T21:11:26.616954Z","end":"2026-04-13T21:11:27.188318Z","steps":["trace[1279809769] 'process raft request' (duration: 432.227941ms)","trace[1279809769] 'compare' (duration: 138.765011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:27.188425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:26.616936Z","time spent":"571.44826ms","remote":"127.0.0.1:51162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:11:27.61324Z","caller":"traceutil/trace.go:171","msg":"trace[2108446091] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"637.188221ms","start":"2026-04-13T21:11:26.976022Z","end":"2026-04-13T21:11:27.61321Z","steps":["trace[2108446091] 'read index received' (duration: 73.170394ms)","trace[2108446091] 'applied index is now lower than readState.Index' (duration: 564.016557ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:27.613249Z","caller":"traceutil/trace.go:171","msg":"trace[1442805175] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"950.756959ms","start":"2026-04-13T21:11:26.662464Z","end":"2026-04-13T21:11:27.61322Z","steps":["trace[1442805175] 'process raft request' (duration: 929.976479ms)","trace[1442805175] 'compare' (duration: 20.407083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:27.613451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:26.662437Z","time spent":"950.954974ms","remote":"127.0.0.1:51444","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-04-13T21:11:27.613504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.600321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:11:27.613564Z","caller":"traceutil/trace.go:171","msg":"trace[1243563466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"604.690703ms","start":"2026-04-13T21:11:27.008856Z","end":"2026-04-13T21:11:27.613547Z","steps":["trace[1243563466] 'agreement among raft nodes before linearized reading' (duration: 604.572571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:27.613612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:27.008846Z","time spent":"604.757754ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:11:27.613668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.350165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:27.613732Z","caller":"traceutil/trace.go:171","msg":"trace[281604267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"637.723711ms","start":"2026-04-13T21:11:26.975987Z","end":"2026-04-13T21:11:27.613711Z","steps":["trace[281604267] 'agreement among raft nodes before linearized reading' (duration: 637.293222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:27.613778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:26.975968Z","time spent":"637.795243ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:11:27.61384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.13522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T21:11:27.61389Z","caller":"traceutil/trace.go:171","msg":"trace[199693383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"311.218892ms","start":"2026-04-13T21:11:27.302658Z","end":"2026-04-13T21:11:27.613877Z","steps":["trace[199693383] 'agreement among raft nodes before linearized reading' (duration: 311.062699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:27.613922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:27.302604Z","time spent":"311.310514ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:11:27.614282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.632618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:11:27.61433Z","caller":"traceutil/trace.go:171","msg":"trace[427117832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"272.859303ms","start":"2026-04-13T21:11:27.341459Z","end":"2026-04-13T21:11:27.614318Z","steps":["trace[427117832] 'agreement among raft nodes before linearized reading' (duration: 272.17896ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:43.498965Z","caller":"traceutil/trace.go:171","msg":"trace[724578558] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"193.282495ms","start":"2026-04-13T21:11:43.30566Z","end":"2026-04-13T21:11:43.498942Z","steps":["trace[724578558] 'process raft request' (duration: 193.161242ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:59.687877Z","caller":"traceutil/trace.go:171","msg":"trace[955755357] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"106.680657ms","start":"2026-04-13T21:11:59.581168Z","end":"2026-04-13T21:11:59.687849Z","steps":["trace[955755357] 'compare' (duration: 97.651578ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:59.689504Z","caller":"traceutil/trace.go:171","msg":"trace[788023785] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"104.77617ms","start":"2026-04-13T21:11:59.584713Z","end":"2026-04-13T21:11:59.689489Z","steps":["trace[788023785] 'process raft request' (duration: 104.679818ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:59.992564Z","caller":"traceutil/trace.go:171","msg":"trace[904450812] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"122.251365ms","start":"2026-04-13T21:11:59.87028Z","end":"2026-04-13T21:11:59.992532Z","steps":["trace[904450812] 'process raft request' (duration: 74.663079ms)","trace[904450812] 'compare' (duration: 47.432833ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:59.992578Z","caller":"traceutil/trace.go:171","msg":"trace[1766716586] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"121.345298ms","start":"2026-04-13T21:11:59.871195Z","end":"2026-04-13T21:11:59.99254Z","steps":["trace[1766716586] 'process raft request' (duration: 121.259706ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:17.921096Z","caller":"traceutil/trace.go:171","msg":"trace[1468238584] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"138.066129ms","start":"2026-04-13T21:12:17.783005Z","end":"2026-04-13T21:12:17.921071Z","steps":["trace[1468238584] 'process raft request' (duration: 137.915557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.758333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.425792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:12:18.758437Z","caller":"traceutil/trace.go:171","msg":"trace[1885053319] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"783.232387ms","start":"2026-04-13T21:12:17.975188Z","end":"2026-04-13T21:12:18.75842Z","steps":["trace[1885053319] 'read index received' (duration: 136.537251ms)","trace[1885053319] 'applied index is now lower than readState.Index' (duration: 646.693706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:18.758577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.36579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:18.758588Z","caller":"traceutil/trace.go:171","msg":"trace[520340437] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"935.069054ms","start":"2026-04-13T21:12:17.823495Z","end":"2026-04-13T21:12:18.758564Z","steps":["trace[520340437] 'process raft request' (duration: 288.302746ms)","trace[520340437] 'compare' (duration: 646.164197ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:18.758638Z","caller":"traceutil/trace.go:171","msg":"trace[1462462842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"783.479392ms","start":"2026-04-13T21:12:17.975145Z","end":"2026-04-13T21:12:18.758624Z","steps":["trace[1462462842] 'agreement among raft nodes before linearized reading' (duration: 783.37368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.758649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.842698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T21:12:18.758669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:17.975129Z","time spent":"783.535083ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:12:18.758678Z","caller":"traceutil/trace.go:171","msg":"trace[1396671918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"769.898229ms","start":"2026-04-13T21:12:17.988771Z","end":"2026-04-13T21:12:18.758669Z","steps":["trace[1396671918] 'agreement among raft nodes before linearized reading' (duration: 769.748826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.758704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:17.823473Z","time spent":"935.168466ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:18.758789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.115755ms","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-13T21:12:18.758826Z","caller":"traceutil/trace.go:171","msg":"trace[1961667295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"214.173536ms","start":"2026-04-13T21:12:18.544645Z","end":"2026-04-13T21:12:18.758819Z","steps":["trace[1961667295] 'agreement among raft nodes before linearized reading' (duration: 214.091424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.758709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:17.988761Z","time spent":"769.941329ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:12:18.758993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.503213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T21:12:18.758979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.797153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T21:12:18.759024Z","caller":"traceutil/trace.go:171","msg":"trace[1494048684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"639.574004ms","start":"2026-04-13T21:12:18.119443Z","end":"2026-04-13T21:12:18.759017Z","steps":["trace[1494048684] 'agreement among raft nodes before linearized reading' (duration: 639.475822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.759045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.119391Z","time spent":"639.649045ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:12:18.759034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.376991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:18.759042Z","caller":"traceutil/trace.go:171","msg":"trace[1978830145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"658.900924ms","start":"2026-04-13T21:12:18.100126Z","end":"2026-04-13T21:12:18.759027Z","steps":["trace[1978830145] 'agreement among raft nodes before linearized reading' (duration: 658.716711ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:18.759074Z","caller":"traceutil/trace.go:171","msg":"trace[620471145] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:958; }","duration":"711.449012ms","start":"2026-04-13T21:12:18.047616Z","end":"2026-04-13T21:12:18.759065Z","steps":["trace[620471145] 'agreement among raft nodes before linearized reading' (duration: 711.379841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:18.759095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.047598Z","time spent":"711.491702ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:12:18.759093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.100106Z","time spent":"658.977386ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:12:19.342835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.104955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:12:19.342953Z","caller":"traceutil/trace.go:171","msg":"trace[851361124] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"578.504587ms","start":"2026-04-13T21:12:18.764429Z","end":"2026-04-13T21:12:19.342934Z","steps":["trace[851361124] 'process raft request' (duration: 133.223619ms)","trace[851361124] 'compare' (duration: 444.87505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:19.34301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.764419Z","time spent":"578.564968ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:19.959948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.168945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:12:19.960096Z","caller":"traceutil/trace.go:171","msg":"trace[839515719] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.195407649s","start":"2026-04-13T21:12:18.764669Z","end":"2026-04-13T21:12:19.960076Z","steps":["trace[839515719] 'process raft request' (duration: 1.02404392s)","trace[839515719] 'compare' (duration: 170.720217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:19.960151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.76465Z","time spent":"1.1954742s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:19.960175Z","caller":"traceutil/trace.go:171","msg":"trace[1560471310] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.19545447s","start":"2026-04-13T21:12:18.764711Z","end":"2026-04-13T21:12:19.960165Z","steps":["trace[1560471310] 'process raft request' (duration: 1.195306987s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:19.9602Z","caller":"traceutil/trace.go:171","msg":"trace[1859025813] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"773.275344ms","start":"2026-04-13T21:12:19.186908Z","end":"2026-04-13T21:12:19.960183Z","steps":["trace[1859025813] 'process raft request' (duration: 773.228403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:19.960226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.764702Z","time spent":"1.195496261s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:19.960285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:19.186883Z","time spent":"773.360285ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:19.960342Z","caller":"traceutil/trace.go:171","msg":"trace[1632638152] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.195585623s","start":"2026-04-13T21:12:18.764745Z","end":"2026-04-13T21:12:19.960331Z","steps":["trace[1632638152] 'process raft request' (duration: 1.195317677s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:19.960354Z","caller":"traceutil/trace.go:171","msg":"trace[982571441] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:997; }","duration":"1.098539847s","start":"2026-04-13T21:12:18.861808Z","end":"2026-04-13T21:12:19.960347Z","steps":["trace[982571441] 'read index received' (duration: 35.931989ms)","trace[982571441] 'applied index is now lower than readState.Index' (duration: 1.062606807s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:19.960406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.76473Z","time spent":"1.195636914s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:19.960532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098701409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T21:12:19.96056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.982102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T21:12:19.96056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.833651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:19.9606Z","caller":"traceutil/trace.go:171","msg":"trace[1764639961] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:963; }","duration":"222.057174ms","start":"2026-04-13T21:12:19.73853Z","end":"2026-04-13T21:12:19.960587Z","steps":["trace[1764639961] 'agreement among raft nodes before linearized reading' (duration: 221.996983ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:19.960599Z","caller":"traceutil/trace.go:171","msg":"trace[154271565] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:963; }","duration":"1.098816842s","start":"2026-04-13T21:12:18.861764Z","end":"2026-04-13T21:12:19.960581Z","steps":["trace[154271565] 'agreement among raft nodes before linearized reading' (duration: 1.098651289s)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:19.960621Z","caller":"traceutil/trace.go:171","msg":"trace[1254288520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"984.931052ms","start":"2026-04-13T21:12:18.975671Z","end":"2026-04-13T21:12:19.960602Z","steps":["trace[1254288520] 'agreement among raft nodes before linearized reading' (duration: 984.80124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:19.96064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.861742Z","time spent":"1.098889543s","remote":"127.0.0.1:51308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:12:19.960656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:18.975651Z","time spent":"984.996174ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:12:19.96075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.720957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:12:19.960781Z","caller":"traceutil/trace.go:171","msg":"trace[440783035] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:963; }","duration":"402.784177ms","start":"2026-04-13T21:12:19.557988Z","end":"2026-04-13T21:12:19.960772Z","steps":["trace[440783035] 'agreement among raft nodes before linearized reading' (duration: 402.689685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:19.960804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:19.557965Z","time spent":"402.833849ms","remote":"127.0.0.1:51000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.166\" "} {"level":"warn","ts":"2026-04-13T21:12:19.960878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.583879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:19.960909Z","caller":"traceutil/trace.go:171","msg":"trace[1051771127] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:963; }","duration":"434.616589ms","start":"2026-04-13T21:12:19.526283Z","end":"2026-04-13T21:12:19.9609Z","steps":["trace[1051771127] 'agreement among raft nodes before linearized reading' (duration: 434.535748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:19.960936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:19.526198Z","time spent":"434.730951ms","remote":"127.0.0.1:51306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:12:41.318316Z","caller":"traceutil/trace.go:171","msg":"trace[362074927] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"342.611587ms","start":"2026-04-13T21:12:40.975681Z","end":"2026-04-13T21:12:41.318292Z","steps":["trace[362074927] 'read index received' (duration: 342.390423ms)","trace[362074927] 'applied index is now lower than readState.Index' (duration: 220.214µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:41.318445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.75796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:41.318477Z","caller":"traceutil/trace.go:171","msg":"trace[1646354713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"342.83573ms","start":"2026-04-13T21:12:40.975629Z","end":"2026-04-13T21:12:41.318465Z","steps":["trace[1646354713] 'agreement among raft nodes before linearized reading' (duration: 342.753819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:41.318506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:40.975612Z","time spent":"342.887002ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T21:12:41.318491Z","caller":"traceutil/trace.go:171","msg":"trace[1011535397] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"439.427939ms","start":"2026-04-13T21:12:40.879029Z","end":"2026-04-13T21:12:41.318457Z","steps":["trace[1011535397] 'process raft request' (duration: 439.105704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:41.318662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:40.879004Z","time spent":"439.566291ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:41.755997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.584883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:12:41.756222Z","caller":"traceutil/trace.go:171","msg":"trace[884319158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"286.87453ms","start":"2026-04-13T21:12:41.469332Z","end":"2026-04-13T21:12:41.756207Z","steps":["trace[884319158] 'range keys from in-memory index tree' (duration: 286.446041ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:44.447383Z","caller":"traceutil/trace.go:171","msg":"trace[408298778] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"290.375825ms","start":"2026-04-13T21:12:44.156985Z","end":"2026-04-13T21:12:44.447361Z","steps":["trace[408298778] 'read index received' (duration: 290.252103ms)","trace[408298778] 'applied index is now lower than readState.Index' (duration: 122.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:44.447463Z","caller":"traceutil/trace.go:171","msg":"trace[1217142615] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"332.186343ms","start":"2026-04-13T21:12:44.115226Z","end":"2026-04-13T21:12:44.447412Z","steps":["trace[1217142615] 'process raft request' (duration: 332.00758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:44.447874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:44.115205Z","time spent":"332.55976ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:44.447579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.571708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-13T21:12:44.448264Z","caller":"traceutil/trace.go:171","msg":"trace[1181316158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"291.295602ms","start":"2026-04-13T21:12:44.15694Z","end":"2026-04-13T21:12:44.448235Z","steps":["trace[1181316158] 'agreement among raft nodes before linearized reading' (duration: 290.512748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:45.373199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.551164ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T21:12:45.373294Z","caller":"traceutil/trace.go:171","msg":"trace[893959563] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"397.696502ms","start":"2026-04-13T21:12:44.975581Z","end":"2026-04-13T21:12:45.373277Z","steps":["trace[893959563] 'read index received' (duration: 148.023435ms)","trace[893959563] 'applied index is now lower than readState.Index' (duration: 249.671557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:12:45.373364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.782494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:45.373396Z","caller":"traceutil/trace.go:171","msg":"trace[1405589959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"397.857925ms","start":"2026-04-13T21:12:44.975526Z","end":"2026-04-13T21:12:45.373384Z","steps":["trace[1405589959] 'agreement among raft nodes before linearized reading' (duration: 397.793294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:45.373426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:44.975503Z","time spent":"397.916656ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:12:56.39201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.536924ms","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-04-13T21:12:56.392069Z","caller":"traceutil/trace.go:171","msg":"trace[374329127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1132; }","duration":"191.658006ms","start":"2026-04-13T21:12:56.200396Z","end":"2026-04-13T21:12:56.392054Z","steps":["trace[374329127] 'range keys from in-memory index tree' (duration: 191.33217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.19395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15455119756624595660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T21:12:58.66752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131261646s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T21:12:58.668561Z","caller":"traceutil/trace.go:171","msg":"trace[1806594647] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"975.157218ms","start":"2026-04-13T21:12:57.69338Z","end":"2026-04-13T21:12:58.668537Z","steps":["trace[1806594647] 'read index received' (duration: 974.934374ms)","trace[1806594647] 'applied index is now lower than readState.Index' (duration: 221.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:58.66871Z","caller":"traceutil/trace.go:171","msg":"trace[756032095] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"1.132561051s","start":"2026-04-13T21:12:57.536128Z","end":"2026-04-13T21:12:58.66869Z","steps":["trace[756032095] 'process raft request' (duration: 1.132256125s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.668804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.918415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-13T21:12:58.668838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:57.536102Z","time spent":"1.132664743s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:12:58.668857Z","caller":"traceutil/trace.go:171","msg":"trace[278507351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"791.038487ms","start":"2026-04-13T21:12:57.8778Z","end":"2026-04-13T21:12:58.668839Z","steps":["trace[278507351] 'agreement among raft nodes before linearized reading' (duration: 790.847753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.668841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.392522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T21:12:58.668937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.364852ms","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-13T21:12:58.668979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.479162ms","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-04-13T21:12:58.668994Z","caller":"traceutil/trace.go:171","msg":"trace[686747998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1136; }","duration":"159.461473ms","start":"2026-04-13T21:12:58.509519Z","end":"2026-04-13T21:12:58.66898Z","steps":["trace[686747998] 'agreement among raft nodes before linearized reading' (duration: 159.342131ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:58.669018Z","caller":"traceutil/trace.go:171","msg":"trace[1836907849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1136; }","duration":"267.554204ms","start":"2026-04-13T21:12:58.401456Z","end":"2026-04-13T21:12:58.66901Z","steps":["trace[1836907849] 'agreement among raft nodes before linearized reading' (duration: 267.470482ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:58.668964Z","caller":"traceutil/trace.go:171","msg":"trace[70542761] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1136; }","duration":"975.600866ms","start":"2026-04-13T21:12:57.693337Z","end":"2026-04-13T21:12:58.668938Z","steps":["trace[70542761] 'agreement among raft nodes before linearized reading' (duration: 975.32241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.669109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.730283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T21:12:58.66913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.777165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:12:58.66914Z","caller":"traceutil/trace.go:171","msg":"trace[401796079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1136; }","duration":"485.790434ms","start":"2026-04-13T21:12:58.183342Z","end":"2026-04-13T21:12:58.669132Z","steps":["trace[401796079] 'agreement among raft nodes before linearized reading' (duration: 485.681322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.669166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:58.183321Z","time spent":"485.840325ms","remote":"127.0.0.1:51234","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":"info","ts":"2026-04-13T21:12:58.669164Z","caller":"traceutil/trace.go:171","msg":"trace[950095441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"693.833407ms","start":"2026-04-13T21:12:57.975322Z","end":"2026-04-13T21:12:58.669155Z","steps":["trace[950095441] 'agreement among raft nodes before linearized reading' (duration: 693.778246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:58.669204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:57.975304Z","time spent":"693.893697ms","remote":"127.0.0.1:50956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:12:58.669155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:57.693317Z","time spent":"975.82107ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:12:58.668902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:57.877786Z","time spent":"791.103978ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:13:15.262468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15455119756624595819,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:13:15.275173Z","caller":"traceutil/trace.go:171","msg":"trace[10263563] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"553.209198ms","start":"2026-04-13T21:13:14.721941Z","end":"2026-04-13T21:13:15.27515Z","steps":["trace[10263563] 'process raft request' (duration: 553.069405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:15.275302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.721919Z","time spent":"553.305371ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:15.494897Z","caller":"traceutil/trace.go:171","msg":"trace[1197578523] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"771.001069ms","start":"2026-04-13T21:13:14.723823Z","end":"2026-04-13T21:13:15.494824Z","steps":["trace[1197578523] 'process raft request' (duration: 739.99698ms)","trace[1197578523] 'compare' (duration: 30.787295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:15.495155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.723806Z","time spent":"771.209813ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:15.602414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.691413ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T21:13:15.602492Z","caller":"traceutil/trace.go:171","msg":"trace[496335520] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1260; }","duration":"840.501633ms","start":"2026-04-13T21:13:14.761974Z","end":"2026-04-13T21:13:15.602475Z","steps":["trace[496335520] 'read index received' (duration: 513.04998ms)","trace[496335520] 'applied index is now lower than readState.Index' (duration: 327.450663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:15.602636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.658616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T21:13:15.602641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.848297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T21:13:15.602651Z","caller":"traceutil/trace.go:171","msg":"trace[1453659880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"840.725517ms","start":"2026-04-13T21:13:14.761919Z","end":"2026-04-13T21:13:15.602644Z","steps":["trace[1453659880] 'agreement among raft nodes before linearized reading' (duration: 840.619995ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:15.602666Z","caller":"traceutil/trace.go:171","msg":"trace[26299562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"830.895197ms","start":"2026-04-13T21:13:14.771764Z","end":"2026-04-13T21:13:15.602659Z","steps":["trace[26299562] 'agreement among raft nodes before linearized reading' (duration: 830.820996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:15.60267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.761901Z","time spent":"840.765428ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:13:15.602686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.771753Z","time spent":"830.925718ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:13:15.602887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.004661ms","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":"warn","ts":"2026-04-13T21:13:15.602918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.467276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:13:15.602967Z","caller":"traceutil/trace.go:171","msg":"trace[377765158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"831.116303ms","start":"2026-04-13T21:13:14.77183Z","end":"2026-04-13T21:13:15.602947Z","steps":["trace[377765158] 'agreement among raft nodes before linearized reading' (duration: 830.780485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:15.603026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.771825Z","time spent":"831.191874ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T21:13:15.60302Z","caller":"traceutil/trace.go:171","msg":"trace[993881996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"835.65335ms","start":"2026-04-13T21:13:14.767341Z","end":"2026-04-13T21:13:15.602994Z","steps":["trace[993881996] 'agreement among raft nodes before linearized reading' (duration: 835.250251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:15.603084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.767325Z","time spent":"835.748032ms","remote":"127.0.0.1:51234","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-04-13T21:13:15.602927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.608661ms","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-13T21:13:15.603175Z","caller":"traceutil/trace.go:171","msg":"trace[1368011047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"831.905148ms","start":"2026-04-13T21:13:14.771248Z","end":"2026-04-13T21:13:15.603153Z","steps":["trace[1368011047] 'agreement among raft nodes before linearized reading' (duration: 831.429758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:15.603225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:14.771236Z","time spent":"831.978799ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T21:13:15.821932Z","caller":"traceutil/trace.go:171","msg":"trace[593789556] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"214.330994ms","start":"2026-04-13T21:13:15.60757Z","end":"2026-04-13T21:13:15.821901Z","steps":["trace[593789556] 'process raft request' (duration: 214.215662ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:15.822022Z","caller":"traceutil/trace.go:171","msg":"trace[2120479005] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"213.998679ms","start":"2026-04-13T21:13:15.607991Z","end":"2026-04-13T21:13:15.821989Z","steps":["trace[2120479005] 'process raft request' (duration: 213.846726ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:15.822202Z","caller":"traceutil/trace.go:171","msg":"trace[2043756772] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"215.583789ms","start":"2026-04-13T21:13:15.606591Z","end":"2026-04-13T21:13:15.822175Z","steps":["trace[2043756772] 'process raft request' (duration: 215.028949ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:15.822835Z","caller":"traceutil/trace.go:171","msg":"trace[1316378501] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"212.368407ms","start":"2026-04-13T21:13:15.609582Z","end":"2026-04-13T21:13:15.82195Z","steps":["trace[1316378501] 'process raft request' (duration: 212.289496ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.541592Z","caller":"traceutil/trace.go:171","msg":"trace[163518319] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"262.485025ms","start":"2026-04-13T21:13:17.279081Z","end":"2026-04-13T21:13:17.541566Z","steps":["trace[163518319] 'process raft request' (duration: 262.303272ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.983398Z","caller":"traceutil/trace.go:171","msg":"trace[1464571721] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"136.205454ms","start":"2026-04-13T21:13:17.847173Z","end":"2026-04-13T21:13:17.983378Z","steps":["trace[1464571721] 'process raft request' (duration: 136.078292ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.983415Z","caller":"traceutil/trace.go:171","msg":"trace[1671667712] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"137.085861ms","start":"2026-04-13T21:13:17.846291Z","end":"2026-04-13T21:13:17.983377Z","steps":["trace[1671667712] 'process raft request' (duration: 102.159796ms)","trace[1671667712] 'compare' (duration: 34.685021ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:17.983712Z","caller":"traceutil/trace.go:171","msg":"trace[1647684614] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"102.666745ms","start":"2026-04-13T21:13:17.881022Z","end":"2026-04-13T21:13:17.983688Z","steps":["trace[1647684614] 'process raft request' (duration: 102.617964ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.983773Z","caller":"traceutil/trace.go:171","msg":"trace[220257589] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"103.349008ms","start":"2026-04-13T21:13:17.880416Z","end":"2026-04-13T21:13:17.983765Z","steps":["trace[220257589] 'process raft request' (duration: 102.89978ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.983827Z","caller":"traceutil/trace.go:171","msg":"trace[2150313] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"103.397799ms","start":"2026-04-13T21:13:17.880417Z","end":"2026-04-13T21:13:17.983814Z","steps":["trace[2150313] 'process raft request' (duration: 103.138635ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:17.983928Z","caller":"traceutil/trace.go:171","msg":"trace[951154356] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"103.38541ms","start":"2026-04-13T21:13:17.879961Z","end":"2026-04-13T21:13:17.983347Z","steps":["trace[951154356] 'process raft request' (duration: 103.330249ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:19.710025Z","caller":"traceutil/trace.go:171","msg":"trace[1786830397] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"148.812777ms","start":"2026-04-13T21:13:19.561198Z","end":"2026-04-13T21:13:19.710011Z","steps":["trace[1786830397] 'read index received' (duration: 148.683585ms)","trace[1786830397] 'applied index is now lower than readState.Index' (duration: 128.452µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:19.710102Z","caller":"traceutil/trace.go:171","msg":"trace[374013709] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"164.251487ms","start":"2026-04-13T21:13:19.545819Z","end":"2026-04-13T21:13:19.710071Z","steps":["trace[374013709] 'process raft request' (duration: 164.063813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:19.710206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.001331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:13:19.710265Z","caller":"traceutil/trace.go:171","msg":"trace[1768844695] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:1229; }","duration":"149.109123ms","start":"2026-04-13T21:13:19.561146Z","end":"2026-04-13T21:13:19.710255Z","steps":["trace[1768844695] 'agreement among raft nodes before linearized reading' (duration: 148.96348ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:20.013355Z","caller":"traceutil/trace.go:171","msg":"trace[283739512] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"299.971741ms","start":"2026-04-13T21:13:19.713363Z","end":"2026-04-13T21:13:20.013334Z","steps":["trace[283739512] 'process raft request' (duration: 288.895757ms)","trace[283739512] 'compare' (duration: 10.949461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:20.013466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:19.713348Z","time spent":"300.060503ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:20.021735Z","caller":"traceutil/trace.go:171","msg":"trace[1662757089] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"262.579998ms","start":"2026-04-13T21:13:19.759145Z","end":"2026-04-13T21:13:20.021725Z","steps":["trace[1662757089] 'process raft request' (duration: 262.420964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:29.916568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15455119756624595997,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:13:30.009208Z","caller":"traceutil/trace.go:171","msg":"trace[1061343582] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"612.092797ms","start":"2026-04-13T21:13:29.397086Z","end":"2026-04-13T21:13:30.009179Z","steps":["trace[1061343582] 'process raft request' (duration: 611.911534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.009405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.397066Z","time spent":"612.26716ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:30.827099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.330599ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:30.827205Z","caller":"traceutil/trace.go:171","msg":"trace[1992623386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1302; }","duration":"751.493732ms","start":"2026-04-13T21:13:30.075695Z","end":"2026-04-13T21:13:30.827189Z","steps":["trace[1992623386] 'range keys from in-memory index tree' (duration: 751.309019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.82797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.236287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:13:30.82814Z","caller":"traceutil/trace.go:171","msg":"trace[164479138] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"1.412041727s","start":"2026-04-13T21:13:29.416076Z","end":"2026-04-13T21:13:30.828118Z","steps":["trace[164479138] 'read index received' (duration: 592.874856ms)","trace[164479138] 'applied index is now lower than readState.Index' (duration: 819.164881ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:30.828305Z","caller":"traceutil/trace.go:171","msg":"trace[1862153882] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"1.073822845s","start":"2026-04-13T21:13:29.754469Z","end":"2026-04-13T21:13:30.828292Z","steps":["trace[1862153882] 'process raft request' (duration: 956.155969ms)","trace[1862153882] 'compare' (duration: 116.910781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:30.82838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.754446Z","time spent":"1.073896427s","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:13:30.828512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266681835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.166\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T21:13:30.828547Z","caller":"traceutil/trace.go:171","msg":"trace[1631138776] range","detail":"{range_begin:/registry/masterleases/199.19.213.166; range_end:; response_count:1; response_revision:1303; }","duration":"1.266748586s","start":"2026-04-13T21:13:29.56179Z","end":"2026-04-13T21:13:30.828539Z","steps":["trace[1631138776] 'agreement among raft nodes before linearized reading' (duration: 1.266627254s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.82857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.561773Z","time spent":"1.266791997s","remote":"127.0.0.1:51000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.166\" "} {"level":"warn","ts":"2026-04-13T21:13:30.828676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.97425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-13T21:13:30.828713Z","caller":"traceutil/trace.go:171","msg":"trace[1836930211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"725.059932ms","start":"2026-04-13T21:13:30.103644Z","end":"2026-04-13T21:13:30.828704Z","steps":["trace[1836930211] 'agreement among raft nodes before linearized reading' (duration: 724.940099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.828748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.10362Z","time spent":"725.121592ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:13:30.828687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.621705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T21:13:30.828839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.556631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T21:13:30.828903Z","caller":"traceutil/trace.go:171","msg":"trace[2094153123] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1303; }","duration":"303.667982ms","start":"2026-04-13T21:13:30.525221Z","end":"2026-04-13T21:13:30.828889Z","steps":["trace[2094153123] 'agreement among raft nodes before linearized reading' (duration: 303.520239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.828908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.684318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T21:13:30.82894Z","caller":"traceutil/trace.go:171","msg":"trace[1045829295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"627.76245ms","start":"2026-04-13T21:13:30.201171Z","end":"2026-04-13T21:13:30.828933Z","steps":["trace[1045829295] 'agreement among raft nodes before linearized reading' (duration: 627.688898ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:30.828854Z","caller":"traceutil/trace.go:171","msg":"trace[908592328] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1303; }","duration":"117.835529ms","start":"2026-04-13T21:13:30.710998Z","end":"2026-04-13T21:13:30.828833Z","steps":["trace[908592328] 'agreement among raft nodes before linearized reading' (duration: 117.615405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.828966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.20116Z","time spent":"627.8012ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T21:13:30.828964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.303772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T21:13:30.828999Z","caller":"traceutil/trace.go:171","msg":"trace[57059112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"715.339283ms","start":"2026-04-13T21:13:30.113651Z","end":"2026-04-13T21:13:30.82899Z","steps":["trace[57059112] 'agreement among raft nodes before linearized reading' (duration: 715.16617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.828961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.822174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-13T21:13:30.829089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412982134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:13:30.829102Z","caller":"traceutil/trace.go:171","msg":"trace[525897483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"713.965207ms","start":"2026-04-13T21:13:30.115098Z","end":"2026-04-13T21:13:30.829063Z","steps":["trace[525897483] 'agreement among raft nodes before linearized reading' (duration: 713.478338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.82918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.115093Z","time spent":"714.075289ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T21:13:30.828752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252829097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:30.829627Z","caller":"traceutil/trace.go:171","msg":"trace[810585824] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1303; }","duration":"1.253724065s","start":"2026-04-13T21:13:29.57589Z","end":"2026-04-13T21:13:30.829614Z","steps":["trace[810585824] 'agreement among raft nodes before linearized reading' (duration: 1.252843458s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.829716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.575876Z","time spent":"1.253822667s","remote":"127.0.0.1:51072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:13:30.828942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.525193Z","time spent":"303.740104ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-13T21:13:30.829107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.680277ms","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-04-13T21:13:30.830038Z","caller":"traceutil/trace.go:171","msg":"trace[1152132645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"720.665726ms","start":"2026-04-13T21:13:30.10936Z","end":"2026-04-13T21:13:30.830026Z","steps":["trace[1152132645] 'agreement among raft nodes before linearized reading' (duration: 719.696417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.829037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.113574Z","time spent":"715.456006ms","remote":"127.0.0.1:51234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:13:30.830127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:30.109326Z","time spent":"720.789799ms","remote":"127.0.0.1:51234","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-04-13T21:13:30.828721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165830216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:30.830202Z","caller":"traceutil/trace.go:171","msg":"trace[1092647664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1303; }","duration":"1.167342124s","start":"2026-04-13T21:13:29.662851Z","end":"2026-04-13T21:13:30.830193Z","steps":["trace[1092647664] 'agreement among raft nodes before linearized reading' (duration: 1.165843815s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.830258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.662835Z","time spent":"1.167385785s","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:13:30.829154Z","caller":"traceutil/trace.go:171","msg":"trace[268522708] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1303; }","duration":"1.413105836s","start":"2026-04-13T21:13:29.416039Z","end":"2026-04-13T21:13:30.829144Z","steps":["trace[268522708] 'agreement among raft nodes before linearized reading' (duration: 1.412880462s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:30.830381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:29.416023Z","time spent":"1.414348741s","remote":"127.0.0.1:51152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-13T21:14:05.359249Z","caller":"traceutil/trace.go:171","msg":"trace[860734081] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"247.060743ms","start":"2026-04-13T21:14:05.112158Z","end":"2026-04-13T21:14:05.359219Z","steps":["trace[860734081] 'process raft request' (duration: 246.825839ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:05.359614Z","caller":"traceutil/trace.go:171","msg":"trace[709195853] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"247.379249ms","start":"2026-04-13T21:14:05.112196Z","end":"2026-04-13T21:14:05.359575Z","steps":["trace[709195853] 'process raft request' (duration: 246.969971ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:05.360315Z","caller":"traceutil/trace.go:171","msg":"trace[1969943715] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"248.254125ms","start":"2026-04-13T21:14:05.112048Z","end":"2026-04-13T21:14:05.360302Z","steps":["trace[1969943715] 'process raft request' (duration: 247.37993ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:05.360502Z","caller":"traceutil/trace.go:171","msg":"trace[323898602] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"248.078062ms","start":"2026-04-13T21:14:05.112393Z","end":"2026-04-13T21:14:05.360471Z","steps":["trace[323898602] 'process raft request' (duration: 247.833088ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:05.360549Z","caller":"traceutil/trace.go:171","msg":"trace[1906257691] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"247.255377ms","start":"2026-04-13T21:14:05.11325Z","end":"2026-04-13T21:14:05.360505Z","steps":["trace[1906257691] 'process raft request' (duration: 247.208646ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:05.360727Z","caller":"traceutil/trace.go:171","msg":"trace[1632850492] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"247.906909ms","start":"2026-04-13T21:14:05.112808Z","end":"2026-04-13T21:14:05.360715Z","steps":["trace[1632850492] 'process raft request' (duration: 247.580553ms)"],"step_count":1}