{"level":"warn","ts":"2026-07-27T13:12:40.463412Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T13:12:40.463527Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193: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.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-07-27T13:12:40.463625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-27T13:12:40.463663Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-07-27T13:12:40.463687Z","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-07-27T13:12:40.464998Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-07-27T13:12:40.465108Z","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.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-07-27T13:12:40.469507Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.144515ms"} {"level":"info","ts":"2026-07-27T13:12:40.475186Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-07-27T13:12:40.475259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T13:12:40.475281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-07-27T13:12:40.475296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T13:12:40.475308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-07-27T13:12:40.47536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-07-27T13:12:40.481361Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T13:12:40.482941Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T13:12:40.48405Z","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-07-27T13:12:40.486799Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T13:12:40.486966Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T13:12:40.48716Z","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-07-27T13:12:40.487217Z","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-07-27T13:12:40.487229Z","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-07-27T13:12:40.487759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-07-27T13:12:40.487871Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-07-27T13:12:40.491113Z","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-07-27T13:12:40.491309Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T13:12:40.491345Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T13:12:40.491451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-07-27T13:12:40.491468Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-07-27T13:12:40.676022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T13:12:40.676109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T13:12:40.676136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-07-27T13:12:40.676155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-07-27T13:12:40.676166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-07-27T13:12:40.676178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-07-27T13:12:40.67619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-07-27T13:12:40.67708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T13:12:40.67724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T13:12:40.677292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T13:12:40.677413Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T13:12:40.677444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T13:12:40.677552Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:40.679143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-07-27T13:12:40.679644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T13:12:40.679752Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:40.679859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T13:12:40.679913Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-27T13:12:50.626821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.699293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-27T13:12:50.62691Z","caller":"traceutil/trace.go:171","msg":"trace[1273947081] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:358; }","duration":"142.824948ms","start":"2026-07-27T13:12:50.484069Z","end":"2026-07-27T13:12:50.626894Z","steps":["trace[1273947081] 'range keys from in-memory index tree' (duration: 142.640311ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:12:56.195443Z","caller":"traceutil/trace.go:171","msg":"trace[2121757763] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"190.764455ms","start":"2026-07-27T13:12:56.004656Z","end":"2026-07-27T13:12:56.19542Z","steps":["trace[2121757763] 'process raft request' (duration: 190.61754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:56.800955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722650777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T13:12:56.862735Z","caller":"traceutil/trace.go:171","msg":"trace[535619591] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"562.09953ms","start":"2026-07-27T13:12:56.300611Z","end":"2026-07-27T13:12:56.862711Z","steps":["trace[535619591] 'read index received' (duration: 561.961495ms)","trace[535619591] 'applied index is now lower than readState.Index' (duration: 137.045µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:56.86285Z","caller":"traceutil/trace.go:171","msg":"trace[1860935209] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"656.855189ms","start":"2026-07-27T13:12:56.205953Z","end":"2026-07-27T13:12:56.862808Z","steps":["trace[1860935209] 'process raft request' (duration: 656.618201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:56.862907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.279306ms","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-07-27T13:12:56.862945Z","caller":"traceutil/trace.go:171","msg":"trace[205115258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"562.369659ms","start":"2026-07-27T13:12:56.300564Z","end":"2026-07-27T13:12:56.862934Z","steps":["trace[205115258] 'agreement among raft nodes before linearized reading' (duration: 562.282126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:56.862995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.300546Z","time spent":"562.432231ms","remote":"127.0.0.1:59320","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-07-27T13:12:56.863039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.032807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:12:56.863112Z","caller":"traceutil/trace.go:171","msg":"trace[49302521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"297.156371ms","start":"2026-07-27T13:12:56.565938Z","end":"2026-07-27T13:12:56.863095Z","steps":["trace[49302521] 'agreement among raft nodes before linearized reading' (duration: 297.011196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:56.864651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.205935Z","time spent":"657.010204ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:12:57.175449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.234623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:12:57.175646Z","caller":"traceutil/trace.go:171","msg":"trace[1744810007] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"307.667586ms","start":"2026-07-27T13:12:56.867961Z","end":"2026-07-27T13:12:57.175629Z","steps":["trace[1744810007] 'read index received' (duration: 130.010618ms)","trace[1744810007] 'applied index is now lower than readState.Index' (duration: 177.655668ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:57.175677Z","caller":"traceutil/trace.go:171","msg":"trace[1831693443] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"308.608469ms","start":"2026-07-27T13:12:56.86704Z","end":"2026-07-27T13:12:57.175648Z","steps":["trace[1831693443] 'process raft request' (duration: 130.93064ms)","trace[1831693443] 'compare' (duration: 177.016696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:57.175805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.867023Z","time spent":"308.718493ms","remote":"127.0.0.1:59320","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-07-27T13:12:57.175832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.859714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-07-27T13:12:57.175885Z","caller":"traceutil/trace.go:171","msg":"trace[719449146] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:409; }","duration":"307.932135ms","start":"2026-07-27T13:12:56.86794Z","end":"2026-07-27T13:12:57.175872Z","steps":["trace[719449146] 'agreement among raft nodes before linearized reading' (duration: 307.78127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:57.175923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:56.86793Z","time spent":"307.986338ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-27T13:12:57.993045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722650784,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:12:58.161567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.229483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T13:12:58.161726Z","caller":"traceutil/trace.go:171","msg":"trace[1131524148] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:423; }","duration":"669.616432ms","start":"2026-07-27T13:12:57.492094Z","end":"2026-07-27T13:12:58.161711Z","steps":["trace[1131524148] 'read index received' (duration: 554.167341ms)","trace[1131524148] 'applied index is now lower than readState.Index' (duration: 115.448131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:12:58.162005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.279048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:12:58.162044Z","caller":"traceutil/trace.go:171","msg":"trace[1641886678] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"936.108974ms","start":"2026-07-27T13:12:57.225922Z","end":"2026-07-27T13:12:58.162031Z","steps":["trace[1641886678] 'process raft request' (duration: 820.345032ms)","trace[1641886678] 'compare' (duration: 115.13555ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:12:58.162092Z","caller":"traceutil/trace.go:171","msg":"trace[1033594803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"594.387872ms","start":"2026-07-27T13:12:57.567674Z","end":"2026-07-27T13:12:58.162062Z","steps":["trace[1033594803] 'agreement among raft nodes before linearized reading' (duration: 594.264338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:58.162111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:57.225909Z","time spent":"936.169127ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:12:58.162136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:57.567658Z","time spent":"594.470755ms","remote":"127.0.0.1:59056","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-07-27T13:12:58.162119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.010386ms","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-07-27T13:12:58.162182Z","caller":"traceutil/trace.go:171","msg":"trace[1682942983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"670.081989ms","start":"2026-07-27T13:12:57.492084Z","end":"2026-07-27T13:12:58.162166Z","steps":["trace[1682942983] 'agreement among raft nodes before linearized reading' (duration: 669.960565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:58.162229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:57.492042Z","time spent":"670.176082ms","remote":"127.0.0.1:59320","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":"info","ts":"2026-07-27T13:12:58.162844Z","caller":"traceutil/trace.go:171","msg":"trace[1092794777] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"935.759573ms","start":"2026-07-27T13:12:57.226144Z","end":"2026-07-27T13:12:58.161903Z","steps":["trace[1092794777] 'process raft request' (duration: 935.518554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:12:58.162948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:12:57.226125Z","time spent":"936.775957ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:19.575206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.020631ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-27T13:13:19.582234Z","caller":"traceutil/trace.go:171","msg":"trace[1463490618] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"440.893463ms","start":"2026-07-27T13:13:19.141314Z","end":"2026-07-27T13:13:19.582208Z","steps":["trace[1463490618] 'process raft request' (duration: 440.757849ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582285Z","caller":"traceutil/trace.go:171","msg":"trace[1012487435] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"438.445728ms","start":"2026-07-27T13:13:19.143794Z","end":"2026-07-27T13:13:19.58224Z","steps":["trace[1012487435] 'process raft request' (duration: 438.312084ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582406Z","caller":"traceutil/trace.go:171","msg":"trace[754691679] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"431.405244ms","start":"2026-07-27T13:13:19.150986Z","end":"2026-07-27T13:13:19.582391Z","steps":["trace[754691679] 'process raft request' (duration: 431.146455ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582431Z","caller":"traceutil/trace.go:171","msg":"trace[384727377] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"428.910597ms","start":"2026-07-27T13:13:19.153464Z","end":"2026-07-27T13:13:19.582374Z","steps":["trace[384727377] 'process raft request' (duration: 428.729051ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582478Z","caller":"traceutil/trace.go:171","msg":"trace[1753015240] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"417.627404ms","start":"2026-07-27T13:13:19.164815Z","end":"2026-07-27T13:13:19.582442Z","steps":["trace[1753015240] 'process raft request' (duration: 417.406957ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582324Z","caller":"traceutil/trace.go:171","msg":"trace[1320448041] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"429.192147ms","start":"2026-07-27T13:13:19.153089Z","end":"2026-07-27T13:13:19.582281Z","steps":["trace[1320448041] 'process raft request' (duration: 429.075673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.582563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.150966Z","time spent":"431.499967ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.582501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.143773Z","time spent":"438.639655ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:13:19.582579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.153072Z","time spent":"429.484047ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.582366Z","caller":"traceutil/trace.go:171","msg":"trace[1242455206] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"445.582287ms","start":"2026-07-27T13:13:19.13673Z","end":"2026-07-27T13:13:19.582312Z","steps":["trace[1242455206] 'process raft request' (duration: 445.235355ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:19.582523Z","caller":"traceutil/trace.go:171","msg":"trace[1448903551] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"413.637046ms","start":"2026-07-27T13:13:19.168876Z","end":"2026-07-27T13:13:19.582513Z","steps":["trace[1448903551] 'process raft request' (duration: 413.373187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.582679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.15344Z","time spent":"429.124325ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:13:19.582394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.141302Z","time spent":"441.016868ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.582721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.168869Z","time spent":"413.831732ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.582636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.164791Z","time spent":"417.77345ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.58287Z","caller":"traceutil/trace.go:171","msg":"trace[2095249723] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"398.621494ms","start":"2026-07-27T13:13:19.184239Z","end":"2026-07-27T13:13:19.582861Z","steps":["trace[2095249723] 'process raft request' (duration: 398.141637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.582842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.136713Z","time spent":"445.992032ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:13:19.582911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.184221Z","time spent":"398.672235ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:13:19.583103Z","caller":"traceutil/trace.go:171","msg":"trace[382954747] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"393.372051ms","start":"2026-07-27T13:13:19.189708Z","end":"2026-07-27T13:13:19.58308Z","steps":["trace[382954747] 'process raft request' (duration: 393.107031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.583177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.189696Z","time spent":"393.448384ms","remote":"127.0.0.1:59078","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-07-27T13:13:19.583289Z","caller":"traceutil/trace.go:171","msg":"trace[2003152253] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:543; }","duration":"276.746555ms","start":"2026-07-27T13:13:19.306524Z","end":"2026-07-27T13:13:19.583271Z","steps":["trace[2003152253] 'read index received' (duration: 23.929183ms)","trace[2003152253] 'applied index is now lower than readState.Index' (duration: 252.816552ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:19.583313Z","caller":"traceutil/trace.go:171","msg":"trace[170384200] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"386.304445ms","start":"2026-07-27T13:13:19.196993Z","end":"2026-07-27T13:13:19.583297Z","steps":["trace[170384200] 'process raft request' (duration: 386.017035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.583487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.960472ms","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-07-27T13:13:19.583321Z","caller":"traceutil/trace.go:171","msg":"trace[1704176272] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"257.725304ms","start":"2026-07-27T13:13:19.325586Z","end":"2026-07-27T13:13:19.583312Z","steps":["trace[1704176272] 'process raft request' (duration: 257.589529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:19.583486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:19.19697Z","time spent":"386.405069ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:13:19.583521Z","caller":"traceutil/trace.go:171","msg":"trace[1759886683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"277.033705ms","start":"2026-07-27T13:13:19.306478Z","end":"2026-07-27T13:13:19.583512Z","steps":["trace[1759886683] 'agreement among raft nodes before linearized reading' (duration: 276.917971ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:24.643942Z","caller":"traceutil/trace.go:171","msg":"trace[73406114] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"337.795035ms","start":"2026-07-27T13:13:24.306113Z","end":"2026-07-27T13:13:24.643908Z","steps":["trace[73406114] 'read index received' (duration: 337.65889ms)","trace[73406114] 'applied index is now lower than readState.Index' (duration: 135.265µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:24.643965Z","caller":"traceutil/trace.go:171","msg":"trace[1009674634] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"702.411513ms","start":"2026-07-27T13:13:23.941521Z","end":"2026-07-27T13:13:24.643933Z","steps":["trace[1009674634] 'process raft request' (duration: 702.235297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:24.644108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.741474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-27T13:13:24.64412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:23.9415Z","time spent":"702.537556ms","remote":"127.0.0.1:59066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:24.644139Z","caller":"traceutil/trace.go:171","msg":"trace[1838646699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"331.779916ms","start":"2026-07-27T13:13:24.312352Z","end":"2026-07-27T13:13:24.644132Z","steps":["trace[1838646699] 'agreement among raft nodes before linearized reading' (duration: 331.704563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:24.644108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.987331ms","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-07-27T13:13:24.644316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:24.312292Z","time spent":"331.981172ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T13:13:24.644389Z","caller":"traceutil/trace.go:171","msg":"trace[1051365685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"338.325034ms","start":"2026-07-27T13:13:24.30604Z","end":"2026-07-27T13:13:24.644365Z","steps":["trace[1051365685] 'agreement among raft nodes before linearized reading' (duration: 337.949061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:24.644146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.834491ms","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-07-27T13:13:24.64444Z","caller":"traceutil/trace.go:171","msg":"trace[1780749741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"320.162482ms","start":"2026-07-27T13:13:24.32427Z","end":"2026-07-27T13:13:24.644432Z","steps":["trace[1780749741] 'agreement among raft nodes before linearized reading' (duration: 319.861582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:24.644447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:24.306003Z","time spent":"338.429417ms","remote":"127.0.0.1:59320","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-07-27T13:13:24.644467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:24.324249Z","time spent":"320.211944ms","remote":"127.0.0.1:59320","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-07-27T13:13:26.246208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T13:13:26.465732Z","caller":"traceutil/trace.go:171","msg":"trace[879134205] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"831.743187ms","start":"2026-07-27T13:13:25.633956Z","end":"2026-07-27T13:13:26.465699Z","steps":["trace[879134205] 'process raft request' (duration: 831.450657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:26.465902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:25.633944Z","time spent":"831.874021ms","remote":"127.0.0.1:59320","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-07-27T13:13:26.682534Z","caller":"traceutil/trace.go:171","msg":"trace[1504445699] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"961.794699ms","start":"2026-07-27T13:13:25.720709Z","end":"2026-07-27T13:13:26.682503Z","steps":["trace[1504445699] 'process raft request' (duration: 959.293252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:26.682737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:25.720672Z","time spent":"961.967384ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:26.682705Z","caller":"traceutil/trace.go:171","msg":"trace[974437667] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:643; }","duration":"937.007527ms","start":"2026-07-27T13:13:25.745671Z","end":"2026-07-27T13:13:26.682678Z","steps":["trace[974437667] 'read index received' (duration: 719.866898ms)","trace[974437667] 'applied index is now lower than readState.Index' (duration: 217.139109ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:26.682866Z","caller":"traceutil/trace.go:171","msg":"trace[925742414] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"496.494462ms","start":"2026-07-27T13:13:26.186354Z","end":"2026-07-27T13:13:26.682849Z","steps":["trace[925742414] 'process raft request' (duration: 496.15335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:26.682939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.186307Z","time spent":"496.590184ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:26.683034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.952146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:26.683092Z","caller":"traceutil/trace.go:171","msg":"trace[426571151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"117.04037ms","start":"2026-07-27T13:13:26.566039Z","end":"2026-07-27T13:13:26.683079Z","steps":["trace[426571151] 'agreement among raft nodes before linearized reading' (duration: 116.961437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:26.683138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.40941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gg97w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-27T13:13:26.68322Z","caller":"traceutil/trace.go:171","msg":"trace[1224393186] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gg97w; range_end:; response_count:1; response_revision:623; }","duration":"937.581886ms","start":"2026-07-27T13:13:25.745623Z","end":"2026-07-27T13:13:26.683205Z","steps":["trace[1224393186] 'agreement among raft nodes before linearized reading' (duration: 937.257675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:26.683303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:25.745602Z","time spent":"937.68821ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-gg97w\" "} {"level":"warn","ts":"2026-07-27T13:13:27.21419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:13:27.715455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:13:28.216343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:13:28.425336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.715042892s","expected-duration":"1s"} {"level":"info","ts":"2026-07-27T13:13:28.4256Z","caller":"traceutil/trace.go:171","msg":"trace[1973423551] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.715406724s","start":"2026-07-27T13:13:26.710172Z","end":"2026-07-27T13:13:28.425578Z","steps":["trace[1973423551] 'process raft request' (duration: 1.71530237s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.42569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.710149Z","time spent":"1.715486847s","remote":"127.0.0.1:59256","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-07-27T13:13:28.444986Z","caller":"traceutil/trace.go:171","msg":"trace[889595297] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"1.731918729s","start":"2026-07-27T13:13:26.713049Z","end":"2026-07-27T13:13:28.444968Z","steps":["trace[889595297] 'read index received' (duration: 1.712472263s)","trace[889595297] 'applied index is now lower than readState.Index' (duration: 19.445676ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:28.445019Z","caller":"traceutil/trace.go:171","msg":"trace[454422637] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.706740233s","start":"2026-07-27T13:13:26.738252Z","end":"2026-07-27T13:13:28.444993Z","steps":["trace[454422637] 'process raft request' (duration: 1.706606958s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.44512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.738237Z","time spent":"1.706831267s","remote":"127.0.0.1:59274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-27T13:13:28.44519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732122166s","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-07-27T13:13:28.445217Z","caller":"traceutil/trace.go:171","msg":"trace[315667781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.732180208s","start":"2026-07-27T13:13:26.71303Z","end":"2026-07-27T13:13:28.44521Z","steps":["trace[315667781] 'agreement among raft nodes before linearized reading' (duration: 1.732046803s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.445243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.713024Z","time spent":"1.732213078s","remote":"127.0.0.1:59320","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-07-27T13:13:28.445234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731863177s","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-07-27T13:13:28.445234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.008112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:28.445281Z","caller":"traceutil/trace.go:171","msg":"trace[248581806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.731926079s","start":"2026-07-27T13:13:26.713345Z","end":"2026-07-27T13:13:28.445271Z","steps":["trace[248581806] 'agreement among raft nodes before linearized reading' (duration: 1.731818115s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.44531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.71334Z","time spent":"1.73196264s","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T13:13:28.445297Z","caller":"traceutil/trace.go:171","msg":"trace[531984320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"880.101575ms","start":"2026-07-27T13:13:27.565181Z","end":"2026-07-27T13:13:28.445283Z","steps":["trace[531984320] 'agreement among raft nodes before linearized reading' (duration: 879.990531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.445364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:27.565163Z","time spent":"880.193489ms","remote":"127.0.0.1:59056","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-07-27T13:13:28.445391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728941195s","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-07-27T13:13:28.445413Z","caller":"traceutil/trace.go:171","msg":"trace[545146559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.728971986s","start":"2026-07-27T13:13:26.716435Z","end":"2026-07-27T13:13:28.445407Z","steps":["trace[545146559] 'agreement among raft nodes before linearized reading' (duration: 1.728935715s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:28.44543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:26.716431Z","time spent":"1.728995597s","remote":"127.0.0.1:59320","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":"info","ts":"2026-07-27T13:13:31.219659Z","caller":"traceutil/trace.go:171","msg":"trace[322532307] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"300.304008ms","start":"2026-07-27T13:13:30.919329Z","end":"2026-07-27T13:13:31.219633Z","steps":["trace[322532307] 'process raft request' (duration: 299.989137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:31.219777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:30.919309Z","time spent":"300.413752ms","remote":"127.0.0.1:59564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-27T13:13:33.042335Z","caller":"traceutil/trace.go:171","msg":"trace[1334988069] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"143.525268ms","start":"2026-07-27T13:13:32.898793Z","end":"2026-07-27T13:13:33.042318Z","steps":["trace[1334988069] 'process raft request' (duration: 143.397184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:41.06707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651541,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-27T13:13:41.117539Z","caller":"traceutil/trace.go:171","msg":"trace[2006926988] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"598.210064ms","start":"2026-07-27T13:13:40.51931Z","end":"2026-07-27T13:13:41.11752Z","steps":["trace[2006926988] 'process raft request' (duration: 598.108361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:41.117652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:40.519296Z","time spent":"598.292867ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:41.119546Z","caller":"traceutil/trace.go:171","msg":"trace[687319640] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:806; }","duration":"553.362756ms","start":"2026-07-27T13:13:40.566172Z","end":"2026-07-27T13:13:41.119534Z","steps":["trace[687319640] 'read index received' (duration: 551.564504ms)","trace[687319640] 'applied index is now lower than readState.Index' (duration: 1.797612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:41.119612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.437308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:41.119637Z","caller":"traceutil/trace.go:171","msg":"trace[1826697252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"553.49348ms","start":"2026-07-27T13:13:40.566137Z","end":"2026-07-27T13:13:41.119631Z","steps":["trace[1826697252] 'agreement among raft nodes before linearized reading' (duration: 553.436998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:41.119668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:40.566123Z","time spent":"553.539822ms","remote":"127.0.0.1:59056","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-07-27T13:13:41.119667Z","caller":"traceutil/trace.go:171","msg":"trace[1252809857] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"597.973846ms","start":"2026-07-27T13:13:40.521675Z","end":"2026-07-27T13:13:41.119649Z","steps":["trace[1252809857] 'process raft request' (duration: 597.81065ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:41.119676Z","caller":"traceutil/trace.go:171","msg":"trace[2113176942] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"598.701421ms","start":"2026-07-27T13:13:40.52094Z","end":"2026-07-27T13:13:41.119642Z","steps":["trace[2113176942] 'process raft request' (duration: 598.491603ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:13:41.119693Z","caller":"traceutil/trace.go:171","msg":"trace[1824147039] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"597.930825ms","start":"2026-07-27T13:13:40.521737Z","end":"2026-07-27T13:13:41.119668Z","steps":["trace[1824147039] 'process raft request' (duration: 597.775149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:41.119767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:40.521663Z","time spent":"598.052768ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:41.119825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:40.520922Z","time spent":"598.829605ms","remote":"127.0.0.1:59320","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-07-27T13:13:41.120058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:40.521729Z","time spent":"598.217584ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:44.422994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638038716722651574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-27T13:13:44.480326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342740485s","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-07-27T13:13:44.480403Z","caller":"traceutil/trace.go:171","msg":"trace[1096074815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"1.342846069s","start":"2026-07-27T13:13:43.137537Z","end":"2026-07-27T13:13:44.480383Z","steps":["trace[1096074815] 'range keys from in-memory index tree' (duration: 1.34261172s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.480442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.137523Z","time spent":"1.34291146s","remote":"127.0.0.1:59320","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-07-27T13:13:44.480508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342775126s","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-07-27T13:13:44.480565Z","caller":"traceutil/trace.go:171","msg":"trace[821098579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"1.342844378s","start":"2026-07-27T13:13:43.137703Z","end":"2026-07-27T13:13:44.480547Z","steps":["trace[821098579] 'range keys from in-memory index tree' (duration: 1.342659592s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.4806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.137699Z","time spent":"1.3428933s","remote":"127.0.0.1:59320","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-07-27T13:13:44.48059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.955691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-27T13:13:44.48064Z","caller":"traceutil/trace.go:171","msg":"trace[924851041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"912.046173ms","start":"2026-07-27T13:13:43.568582Z","end":"2026-07-27T13:13:44.480629Z","steps":["trace[924851041] 'range keys from in-memory index tree' (duration: 911.806625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.480666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.56857Z","time spent":"912.092075ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T13:13:44.480819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.862462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T13:13:44.480836Z","caller":"traceutil/trace.go:171","msg":"trace[1323613899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"914.917753ms","start":"2026-07-27T13:13:43.565914Z","end":"2026-07-27T13:13:44.480831Z","steps":["trace[1323613899] 'range keys from in-memory index tree' (duration: 914.792029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.48085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.565893Z","time spent":"914.953675ms","remote":"127.0.0.1:59056","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-07-27T13:13:44.480812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342838788s","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-07-27T13:13:44.481107Z","caller":"traceutil/trace.go:171","msg":"trace[801368271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"1.343137218s","start":"2026-07-27T13:13:43.137958Z","end":"2026-07-27T13:13:44.481095Z","steps":["trace[801368271] 'range keys from in-memory index tree' (duration: 1.342751495s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.481124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342975063s","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-07-27T13:13:44.481165Z","caller":"traceutil/trace.go:171","msg":"trace[745549775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"1.343033315s","start":"2026-07-27T13:13:43.13812Z","end":"2026-07-27T13:13:44.481154Z","steps":["trace[745549775] 'range keys from in-memory index tree' (duration: 1.342884739s)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.481189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.13795Z","time spent":"1.343209662s","remote":"127.0.0.1:59320","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-07-27T13:13:44.48122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.138115Z","time spent":"1.343097387s","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T13:13:44.48134Z","caller":"traceutil/trace.go:171","msg":"trace[11979236] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"627.795409ms","start":"2026-07-27T13:13:43.853511Z","end":"2026-07-27T13:13:44.481306Z","steps":["trace[11979236] 'process raft request' (duration: 576.692454ms)","trace[11979236] 'compare' (duration: 49.763528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T13:13:44.481446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.853486Z","time spent":"627.895422ms","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T13:13:44.486921Z","caller":"traceutil/trace.go:171","msg":"trace[2128176458] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"564.516811ms","start":"2026-07-27T13:13:43.922386Z","end":"2026-07-27T13:13:44.486903Z","steps":["trace[2128176458] 'read index received' (duration: 507.828462ms)","trace[2128176458] 'applied index is now lower than readState.Index' (duration: 56.687599ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T13:13:44.48716Z","caller":"traceutil/trace.go:171","msg":"trace[611741273] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"351.911465ms","start":"2026-07-27T13:13:44.13524Z","end":"2026-07-27T13:13:44.487152Z","steps":["trace[611741273] 'process raft request' (duration: 351.559553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.48724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:44.135218Z","time spent":"351.965307ms","remote":"127.0.0.1:59320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T13:13:44.487349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.956416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T13:13:44.487383Z","caller":"traceutil/trace.go:171","msg":"trace[276663485] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:794; }","duration":"565.022029ms","start":"2026-07-27T13:13:43.922353Z","end":"2026-07-27T13:13:44.487375Z","steps":["trace[276663485] 'agreement among raft nodes before linearized reading' (duration: 564.893774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T13:13:44.487406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T13:13:43.922335Z","time spent":"565.065659ms","remote":"127.0.0.1:59066","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.193\" "} {"level":"info","ts":"2026-07-27T13:15:37.281615Z","caller":"traceutil/trace.go:171","msg":"trace[967359504] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"126.755385ms","start":"2026-07-27T13:15:37.154837Z","end":"2026-07-27T13:15:37.281593Z","steps":["trace[967359504] 'process raft request' (duration: 126.564958ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:37.399586Z","caller":"traceutil/trace.go:171","msg":"trace[1134695831] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"112.737197ms","start":"2026-07-27T13:15:37.286828Z","end":"2026-07-27T13:15:37.399565Z","steps":["trace[1134695831] 'process raft request' (duration: 112.701156ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T13:15:37.399678Z","caller":"traceutil/trace.go:171","msg":"trace[447021833] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"112.980276ms","start":"2026-07-27T13:15:37.286674Z","end":"2026-07-27T13:15:37.399654Z","steps":["trace[447021833] 'process raft request' (duration: 112.738578ms)"],"step_count":1}