{"level":"warn","ts":"2026-03-03T10:49:58.820777Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T10:49:58.821076Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-03-03T10:49:58.82124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T10:49:58.82135Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-03-03T10:49:58.821433Z","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-03-03T10:49:58.82214Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-03-03T10:49:58.822291Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-03-03T10:49:58.862964Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"40.38442ms"} {"level":"info","ts":"2026-03-03T10:49:59.005378Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-03-03T10:49:59.005489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T10:49:59.005545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-03-03T10:49:59.005579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T10:49:59.005602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-03-03T10:49:59.005663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-03-03T10:49:59.101687Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T10:49:59.104283Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T10:49:59.105508Z","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-03-03T10:49:59.111002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T10:49:59.111217Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T10:49:59.11169Z","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-03-03T10:49:59.111802Z","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-03-03T10:49:59.111876Z","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-03-03T10:49:59.11813Z","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-03-03T10:49:59.118298Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-03-03T10:49:59.118329Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-03-03T10:49:59.118362Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T10:49:59.118407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T10:49:59.136088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-03-03T10:49:59.136352Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-03-03T10:49:59.915945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T10:49:59.916067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T10:49:59.916109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-03-03T10:49:59.916138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-03-03T10:49:59.91615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-03-03T10:49:59.916168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-03-03T10:49:59.916182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-03-03T10:49:59.917453Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T10:49:59.917481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:49:59.917636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T10:49:59.917748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T10:49:59.91849Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T10:49:59.918852Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T10:49:59.919048Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:49:59.919183Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:49:59.919221Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T10:49:59.922125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-03-03T10:49:59.92323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-03T10:50:01.351535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.90845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T10:50:01.3516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.510527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T10:50:01.351733Z","caller":"traceutil/trace.go:171","msg":"trace[1463127004] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:3; }","duration":"128.683503ms","start":"2026-03-03T10:50:01.223032Z","end":"2026-03-03T10:50:01.351715Z","steps":["trace[1463127004] 'range keys from in-memory index tree' (duration: 128.473566ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.351652Z","caller":"traceutil/trace.go:171","msg":"trace[788283508] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:0; response_revision:3; }","duration":"130.118897ms","start":"2026-03-03T10:50:01.221509Z","end":"2026-03-03T10:50:01.351627Z","steps":["trace[788283508] 'range keys from in-memory index tree' (duration: 129.824657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:01.353445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.853197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-03T10:50:01.353703Z","caller":"traceutil/trace.go:171","msg":"trace[1969361037] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"129.805585ms","start":"2026-03-03T10:50:01.223879Z","end":"2026-03-03T10:50:01.353685Z","steps":["trace[1969361037] 'read index received' (duration: 17.609859ms)","trace[1969361037] 'applied index is now lower than readState.Index' (duration: 112.194586ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:01.353767Z","caller":"traceutil/trace.go:171","msg":"trace[967581260] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"130.571633ms","start":"2026-03-03T10:50:01.223185Z","end":"2026-03-03T10:50:01.353757Z","steps":["trace[967581260] 'process raft request' (duration: 18.313794ms)","trace[967581260] 'compare' (duration: 109.946263ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:01.354102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.210261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T10:50:01.354335Z","caller":"traceutil/trace.go:171","msg":"trace[1234223993] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:4; }","duration":"130.467961ms","start":"2026-03-03T10:50:01.223857Z","end":"2026-03-03T10:50:01.354325Z","steps":["trace[1234223993] 'agreement among raft nodes before linearized reading' (duration: 130.17037ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.37918Z","caller":"traceutil/trace.go:171","msg":"trace[1510646440] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"139.399575ms","start":"2026-03-03T10:50:01.239769Z","end":"2026-03-03T10:50:01.379169Z","steps":["trace[1510646440] 'process raft request' (duration: 139.362622ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.379144Z","caller":"traceutil/trace.go:171","msg":"trace[1747604055] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"140.842428ms","start":"2026-03-03T10:50:01.238273Z","end":"2026-03-03T10:50:01.379116Z","steps":["trace[1747604055] 'process raft request' (duration: 140.659981ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.379195Z","caller":"traceutil/trace.go:171","msg":"trace[2033898136] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"140.896561ms","start":"2026-03-03T10:50:01.238278Z","end":"2026-03-03T10:50:01.379175Z","steps":["trace[2033898136] 'process raft request' (duration: 140.756565ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.379455Z","caller":"traceutil/trace.go:171","msg":"trace[1027058369] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"140.59873ms","start":"2026-03-03T10:50:01.238561Z","end":"2026-03-03T10:50:01.37916Z","steps":["trace[1027058369] 'process raft request' (duration: 140.502126ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.379286Z","caller":"traceutil/trace.go:171","msg":"trace[1480308897] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"139.978186ms","start":"2026-03-03T10:50:01.239218Z","end":"2026-03-03T10:50:01.379196Z","steps":["trace[1480308897] 'process raft request' (duration: 139.884971ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.379244Z","caller":"traceutil/trace.go:171","msg":"trace[150945858] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"140.444194ms","start":"2026-03-03T10:50:01.238782Z","end":"2026-03-03T10:50:01.379226Z","steps":["trace[150945858] 'process raft request' (duration: 140.299837ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.38177Z","caller":"traceutil/trace.go:171","msg":"trace[1485175866] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"137.674289ms","start":"2026-03-03T10:50:01.244079Z","end":"2026-03-03T10:50:01.381753Z","steps":["trace[1485175866] 'process raft request' (duration: 137.560875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:01.384176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.40301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T10:50:01.384262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.043876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-03T10:50:01.384338Z","caller":"traceutil/trace.go:171","msg":"trace[1285524248] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"110.16552ms","start":"2026-03-03T10:50:01.274145Z","end":"2026-03-03T10:50:01.38431Z","steps":["trace[1285524248] 'agreement among raft nodes before linearized reading' (duration: 110.019944ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:01.385697Z","caller":"traceutil/trace.go:171","msg":"trace[442165471] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"157.940219ms","start":"2026-03-03T10:50:01.227737Z","end":"2026-03-03T10:50:01.385677Z","steps":["trace[442165471] 'agreement among raft nodes before linearized reading' (duration: 155.486547ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:06.34447Z","caller":"traceutil/trace.go:171","msg":"trace[298035162] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"370.711679ms","start":"2026-03-03T10:50:05.973721Z","end":"2026-03-03T10:50:06.344433Z","steps":["trace[298035162] 'process raft request' (duration: 370.462261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:06.344805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:05.973685Z","time spent":"370.920546ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":190,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T10:50:06.877491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.227728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-03T10:50:06.877631Z","caller":"traceutil/trace.go:171","msg":"trace[877680881] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:313; }","duration":"254.429495ms","start":"2026-03-03T10:50:06.623169Z","end":"2026-03-03T10:50:06.877598Z","steps":["trace[877680881] 'range keys from in-memory index tree' (duration: 254.068423ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:07.051703Z","caller":"traceutil/trace.go:171","msg":"trace[1624277439] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"121.888813ms","start":"2026-03-03T10:50:06.929784Z","end":"2026-03-03T10:50:07.051672Z","steps":["trace[1624277439] 'process raft request' (duration: 93.803982ms)","trace[1624277439] 'compare' (duration: 27.932486ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:10.245226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.67291862s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:50:10.245337Z","caller":"traceutil/trace.go:171","msg":"trace[729387562] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"2.962080816s","start":"2026-03-03T10:50:07.283239Z","end":"2026-03-03T10:50:10.24532Z","steps":["trace[729387562] 'read index received' (duration: 288.891488ms)","trace[729387562] 'applied index is now lower than readState.Index' (duration: 2.673187988s)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:10.245365Z","caller":"traceutil/trace.go:171","msg":"trace[251951423] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"3.162519093s","start":"2026-03-03T10:50:07.082812Z","end":"2026-03-03T10:50:10.245332Z","steps":["trace[251951423] 'process raft request' (duration: 489.415517ms)","trace[251951423] 'compare' (duration: 2.672795736s)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:10.245481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.962242751s","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-03-03T10:50:10.245483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:07.082792Z","time spent":"3.162639977s","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T10:50:10.245518Z","caller":"traceutil/trace.go:171","msg":"trace[599039824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:317; }","duration":"2.962317864s","start":"2026-03-03T10:50:07.283189Z","end":"2026-03-03T10:50:10.245507Z","steps":["trace[599039824] 'agreement among raft nodes before linearized reading' (duration: 2.962218831s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:10.245552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:07.28317Z","time spent":"2.962374245s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T10:50:10.245777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.879740814s","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-03-03T10:50:10.24585Z","caller":"traceutil/trace.go:171","msg":"trace[1148643931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:317; }","duration":"2.879800597s","start":"2026-03-03T10:50:07.366022Z","end":"2026-03-03T10:50:10.245823Z","steps":["trace[1148643931] 'agreement among raft nodes before linearized reading' (duration: 2.879700054s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:10.24593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:07.365968Z","time spent":"2.87994027s","remote":"127.0.0.1:43690","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-03-03T10:50:10.246214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162648764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T10:50:10.246248Z","caller":"traceutil/trace.go:171","msg":"trace[1864632842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:317; }","duration":"1.162734577s","start":"2026-03-03T10:50:09.083505Z","end":"2026-03-03T10:50:10.246239Z","steps":["trace[1864632842] 'agreement among raft nodes before linearized reading' (duration: 1.162640174s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:10.246309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:09.083491Z","time spent":"1.162808179s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T10:50:10.246433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.673198546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:50:10.246474Z","caller":"traceutil/trace.go:171","msg":"trace[600500095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"2.673278699s","start":"2026-03-03T10:50:07.573187Z","end":"2026-03-03T10:50:10.246466Z","steps":["trace[600500095] 'agreement among raft nodes before linearized reading' (duration: 2.673220847s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:10.246505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:07.573166Z","time spent":"2.67333284s","remote":"127.0.0.1:43690","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-03-03T10:50:10.75089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350590923,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:50:11.251837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350590923,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:50:11.671991Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.422830685s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T10:50:11.734836Z","caller":"traceutil/trace.go:171","msg":"trace[374720334] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"1.485517814s","start":"2026-03-03T10:50:10.249087Z","end":"2026-03-03T10:50:11.734605Z","steps":["trace[374720334] 'process raft request' (duration: 1.423207716s)","trace[374720334] 'compare' (duration: 60.3976ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:11.73534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:10.24907Z","time spent":"1.485845414s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:12.081097Z","caller":"traceutil/trace.go:171","msg":"trace[574409902] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:326; }","duration":"1.83104061s","start":"2026-03-03T10:50:10.250033Z","end":"2026-03-03T10:50:12.081074Z","steps":["trace[574409902] 'read index received' (duration: 1.422301779s)","trace[574409902] 'applied index is now lower than readState.Index' (duration: 408.737791ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:12.08122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831164434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:50:12.081252Z","caller":"traceutil/trace.go:171","msg":"trace[1675728875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:322; }","duration":"1.831231535s","start":"2026-03-03T10:50:10.25001Z","end":"2026-03-03T10:50:12.081242Z","steps":["trace[1675728875] 'agreement among raft nodes before linearized reading' (duration: 1.831158783s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:12.081286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:10.250004Z","time spent":"1.831275176s","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"info","ts":"2026-03-03T10:50:12.081417Z","caller":"traceutil/trace.go:171","msg":"trace[1162752494] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"1.831232796s","start":"2026-03-03T10:50:10.250155Z","end":"2026-03-03T10:50:12.081388Z","steps":["trace[1162752494] 'process raft request' (duration: 1.830740881s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:12.081557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:10.250136Z","time spent":"1.831343068s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:12.081548Z","caller":"traceutil/trace.go:171","msg":"trace[414241107] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"1.83138209s","start":"2026-03-03T10:50:10.250128Z","end":"2026-03-03T10:50:12.08151Z","steps":["trace[414241107] 'process raft request' (duration: 1.809086067s)","trace[414241107] 'compare' (duration: 21.564592ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:12.081649Z","caller":"traceutil/trace.go:171","msg":"trace[1634939096] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"1.831296197s","start":"2026-03-03T10:50:10.250268Z","end":"2026-03-03T10:50:12.081564Z","steps":["trace[1634939096] 'process raft request' (duration: 1.830698849s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:12.081708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:10.250106Z","time spent":"1.831518513s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:12.081687Z","caller":"traceutil/trace.go:171","msg":"trace[268519363] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"470.223574ms","start":"2026-03-03T10:50:11.611439Z","end":"2026-03-03T10:50:12.081662Z","steps":["trace[268519363] 'process raft request' (duration: 469.593555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:12.081909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:10.250259Z","time spent":"1.831489313s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:50:12.08194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:11.611417Z","time spent":"470.42437ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:50:12.579301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.516608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:50:12.579431Z","caller":"traceutil/trace.go:171","msg":"trace[1007989462] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"409.890609ms","start":"2026-03-03T10:50:12.169512Z","end":"2026-03-03T10:50:12.579402Z","steps":["trace[1007989462] 'process raft request' (duration: 28.209266ms)","trace[1007989462] 'compare' (duration: 381.383184ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:12.579522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:12.1695Z","time spent":"409.983553ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T10:50:13.876555Z","caller":"traceutil/trace.go:171","msg":"trace[538451329] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"100.728044ms","start":"2026-03-03T10:50:13.775791Z","end":"2026-03-03T10:50:13.876519Z","steps":["trace[538451329] 'process raft request' (duration: 35.802434ms)","trace[538451329] 'compare' (duration: 64.523237ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:14.236418Z","caller":"traceutil/trace.go:171","msg":"trace[1467217835] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"151.270391ms","start":"2026-03-03T10:50:14.085067Z","end":"2026-03-03T10:50:14.236337Z","steps":["trace[1467217835] 'read index received' (duration: 70.955864ms)","trace[1467217835] 'applied index is now lower than readState.Index' (duration: 80.313617ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:14.236591Z","caller":"traceutil/trace.go:171","msg":"trace[2088306881] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"192.449443ms","start":"2026-03-03T10:50:14.044123Z","end":"2026-03-03T10:50:14.236573Z","steps":["trace[2088306881] 'process raft request' (duration: 111.91936ms)","trace[2088306881] 'compare' (duration: 80.180944ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:14.23675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.664611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-03T10:50:14.236919Z","caller":"traceutil/trace.go:171","msg":"trace[1469796890] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:350; }","duration":"151.876197ms","start":"2026-03-03T10:50:14.085032Z","end":"2026-03-03T10:50:14.236908Z","steps":["trace[1469796890] 'agreement among raft nodes before linearized reading' (duration: 151.652071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:14.236805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.209249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-03T10:50:14.236884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.078897ms","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-03-03T10:50:14.236986Z","caller":"traceutil/trace.go:171","msg":"trace[1313594406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:350; }","duration":"151.436695ms","start":"2026-03-03T10:50:14.085531Z","end":"2026-03-03T10:50:14.236968Z","steps":["trace[1313594406] 'agreement among raft nodes before linearized reading' (duration: 151.188128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:14.236821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.90544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-03T10:50:14.236861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.021315ms","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-03-03T10:50:14.237066Z","caller":"traceutil/trace.go:171","msg":"trace[638490610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:350; }","duration":"150.237191ms","start":"2026-03-03T10:50:14.086776Z","end":"2026-03-03T10:50:14.237013Z","steps":["trace[638490610] 'agreement among raft nodes before linearized reading' (duration: 150.076367ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.237233Z","caller":"traceutil/trace.go:171","msg":"trace[605410237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:350; }","duration":"150.214561ms","start":"2026-03-03T10:50:14.086817Z","end":"2026-03-03T10:50:14.237032Z","steps":["trace[605410237] 'agreement among raft nodes before linearized reading' (duration: 149.988715ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.237287Z","caller":"traceutil/trace.go:171","msg":"trace[1037378024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"102.163127ms","start":"2026-03-03T10:50:14.134866Z","end":"2026-03-03T10:50:14.237029Z","steps":["trace[1037378024] 'agreement among raft nodes before linearized reading' (duration: 101.882199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:14.637222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.061011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:50:14.637323Z","caller":"traceutil/trace.go:171","msg":"trace[1583587088] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"209.558861ms","start":"2026-03-03T10:50:14.427748Z","end":"2026-03-03T10:50:14.637307Z","steps":["trace[1583587088] 'process raft request' (duration: 105.356137ms)","trace[1583587088] 'compare' (duration: 103.944027ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:14.638931Z","caller":"traceutil/trace.go:171","msg":"trace[127071696] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"208.239545ms","start":"2026-03-03T10:50:14.430672Z","end":"2026-03-03T10:50:14.638912Z","steps":["trace[127071696] 'read index received' (duration: 102.609501ms)","trace[127071696] 'applied index is now lower than readState.Index' (duration: 105.629164ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:14.639072Z","caller":"traceutil/trace.go:171","msg":"trace[1532764549] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"211.29119ms","start":"2026-03-03T10:50:14.427768Z","end":"2026-03-03T10:50:14.639059Z","steps":["trace[1532764549] 'process raft request' (duration: 210.955621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:14.639953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.266633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-hl9r2\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-03-03T10:50:14.640057Z","caller":"traceutil/trace.go:171","msg":"trace[1353310456] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-hl9r2; range_end:; response_count:1; response_revision:373; }","duration":"209.338655ms","start":"2026-03-03T10:50:14.430644Z","end":"2026-03-03T10:50:14.639983Z","steps":["trace[1353310456] 'agreement among raft nodes before linearized reading' (duration: 208.580804ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.640415Z","caller":"traceutil/trace.go:171","msg":"trace[663294045] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"205.95608ms","start":"2026-03-03T10:50:14.434395Z","end":"2026-03-03T10:50:14.640351Z","steps":["trace[663294045] 'process raft request' (duration: 204.446338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:14.641719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.090562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-03T10:50:14.641772Z","caller":"traceutil/trace.go:171","msg":"trace[426975096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"207.146873ms","start":"2026-03-03T10:50:14.434598Z","end":"2026-03-03T10:50:14.641745Z","steps":["trace[426975096] 'agreement among raft nodes before linearized reading' (duration: 204.660534ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.781747Z","caller":"traceutil/trace.go:171","msg":"trace[1519520498] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"129.672308ms","start":"2026-03-03T10:50:14.65202Z","end":"2026-03-03T10:50:14.781693Z","steps":["trace[1519520498] 'process raft request' (duration: 101.113409ms)","trace[1519520498] 'compare' (duration: 28.326492ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:14.781782Z","caller":"traceutil/trace.go:171","msg":"trace[953918570] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"128.901735ms","start":"2026-03-03T10:50:14.652856Z","end":"2026-03-03T10:50:14.781758Z","steps":["trace[953918570] 'process raft request' (duration: 128.745631ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.781825Z","caller":"traceutil/trace.go:171","msg":"trace[234545885] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"101.310094ms","start":"2026-03-03T10:50:14.680499Z","end":"2026-03-03T10:50:14.781809Z","steps":["trace[234545885] 'process raft request' (duration: 101.274453ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:14.782002Z","caller":"traceutil/trace.go:171","msg":"trace[654833373] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"125.572782ms","start":"2026-03-03T10:50:14.656423Z","end":"2026-03-03T10:50:14.781996Z","steps":["trace[654833373] 'process raft request' (duration: 125.236853ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:15.049512Z","caller":"traceutil/trace.go:171","msg":"trace[408662283] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"173.948606ms","start":"2026-03-03T10:50:14.87554Z","end":"2026-03-03T10:50:15.049489Z","steps":["trace[408662283] 'process raft request' (duration: 173.795512ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:15.14381Z","caller":"traceutil/trace.go:171","msg":"trace[1507355644] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"263.738115ms","start":"2026-03-03T10:50:14.880054Z","end":"2026-03-03T10:50:15.143792Z","steps":["trace[1507355644] 'process raft request' (duration: 248.074827ms)","trace[1507355644] 'compare' (duration: 15.217006ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:17.331248Z","caller":"traceutil/trace.go:171","msg":"trace[1593761451] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"171.481817ms","start":"2026-03-03T10:50:17.159749Z","end":"2026-03-03T10:50:17.331231Z","steps":["trace[1593761451] 'process raft request' (duration: 171.321273ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:17.456053Z","caller":"traceutil/trace.go:171","msg":"trace[2079304270] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"113.676301ms","start":"2026-03-03T10:50:17.342349Z","end":"2026-03-03T10:50:17.456026Z","steps":["trace[2079304270] 'process raft request' (duration: 113.017704ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.245168Z","caller":"traceutil/trace.go:171","msg":"trace[1182573845] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"259.834721ms","start":"2026-03-03T10:50:21.985311Z","end":"2026-03-03T10:50:22.245146Z","steps":["trace[1182573845] 'process raft request' (duration: 259.704718ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.481778Z","caller":"traceutil/trace.go:171","msg":"trace[680392836] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"101.679894ms","start":"2026-03-03T10:50:22.380082Z","end":"2026-03-03T10:50:22.481762Z","steps":["trace[680392836] 'read index received' (duration: 101.605272ms)","trace[680392836] 'applied index is now lower than readState.Index' (duration: 73.862µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:22.481877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-03T10:50:22.481895Z","caller":"traceutil/trace.go:171","msg":"trace[1655378421] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"101.868858ms","start":"2026-03-03T10:50:22.38002Z","end":"2026-03-03T10:50:22.481889Z","steps":["trace[1655378421] 'agreement among raft nodes before linearized reading' (duration: 101.802846ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.482103Z","caller":"traceutil/trace.go:171","msg":"trace[2077697320] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"142.255379ms","start":"2026-03-03T10:50:22.339815Z","end":"2026-03-03T10:50:22.482071Z","steps":["trace[2077697320] 'process raft request' (duration: 141.850939ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.821152Z","caller":"traceutil/trace.go:171","msg":"trace[1281980693] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"284.217712ms","start":"2026-03-03T10:50:22.53691Z","end":"2026-03-03T10:50:22.821127Z","steps":["trace[1281980693] 'read index received' (duration: 216.583892ms)","trace[1281980693] 'applied index is now lower than readState.Index' (duration: 67.63273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:22.821322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.397496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:50:22.821295Z","caller":"traceutil/trace.go:171","msg":"trace[351310615] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"324.586102ms","start":"2026-03-03T10:50:22.496649Z","end":"2026-03-03T10:50:22.821236Z","steps":["trace[351310615] 'process raft request' (duration: 256.930052ms)","trace[351310615] 'compare' (duration: 67.439955ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:22.821412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.759209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-03T10:50:22.821392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.728606ms","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-03-03T10:50:22.821407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.547841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:50:22.821458Z","caller":"traceutil/trace.go:171","msg":"trace[494160157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"269.81877ms","start":"2026-03-03T10:50:22.551627Z","end":"2026-03-03T10:50:22.821446Z","steps":["trace[494160157] 'agreement among raft nodes before linearized reading' (duration: 269.744318ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.821468Z","caller":"traceutil/trace.go:171","msg":"trace[1185921082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"278.863038ms","start":"2026-03-03T10:50:22.542591Z","end":"2026-03-03T10:50:22.821454Z","steps":["trace[1185921082] 'agreement among raft nodes before linearized reading' (duration: 278.700895ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.821482Z","caller":"traceutil/trace.go:171","msg":"trace[630098296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"163.651963ms","start":"2026-03-03T10:50:22.657813Z","end":"2026-03-03T10:50:22.821465Z","steps":["trace[630098296] 'agreement among raft nodes before linearized reading' (duration: 163.541601ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:22.821368Z","caller":"traceutil/trace.go:171","msg":"trace[1893781903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"284.501459ms","start":"2026-03-03T10:50:22.536853Z","end":"2026-03-03T10:50:22.821355Z","steps":["trace[1893781903] 'agreement among raft nodes before linearized reading' (duration: 284.399577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:22.821605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:22.496633Z","time spent":"324.820968ms","remote":"127.0.0.1:43598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:24.448288Z","caller":"traceutil/trace.go:171","msg":"trace[1348086075] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"199.950532ms","start":"2026-03-03T10:50:24.248319Z","end":"2026-03-03T10:50:24.448269Z","steps":["trace[1348086075] 'read index received' (duration: 199.646736ms)","trace[1348086075] 'applied index is now lower than readState.Index' (duration: 302.926µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:24.448458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.121556ms","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-03-03T10:50:24.448501Z","caller":"traceutil/trace.go:171","msg":"trace[1289624952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"200.189437ms","start":"2026-03-03T10:50:24.248286Z","end":"2026-03-03T10:50:24.448475Z","steps":["trace[1289624952] 'agreement among raft nodes before linearized reading' (duration: 200.052984ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:24.448521Z","caller":"traceutil/trace.go:171","msg":"trace[966487784] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"206.769951ms","start":"2026-03-03T10:50:24.241717Z","end":"2026-03-03T10:50:24.448487Z","steps":["trace[966487784] 'process raft request' (duration: 206.400283ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:24.626157Z","caller":"traceutil/trace.go:171","msg":"trace[58288732] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"172.040831ms","start":"2026-03-03T10:50:24.454095Z","end":"2026-03-03T10:50:24.626136Z","steps":["trace[58288732] 'process raft request' (duration: 166.711444ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:24.942747Z","caller":"traceutil/trace.go:171","msg":"trace[781385708] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:446; }","duration":"101.669928ms","start":"2026-03-03T10:50:24.841055Z","end":"2026-03-03T10:50:24.942725Z","steps":["trace[781385708] 'read index received' (duration: 70.539256ms)","trace[781385708] 'applied index is now lower than readState.Index' (duration: 31.129472ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:24.9429Z","caller":"traceutil/trace.go:171","msg":"trace[1227332748] transaction","detail":"{read_only:false; number_of_response:1; response_revision:435; }","duration":"133.200539ms","start":"2026-03-03T10:50:24.809685Z","end":"2026-03-03T10:50:24.942886Z","steps":["trace[1227332748] 'process raft request' (duration: 133.003585ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:24.943116Z","caller":"traceutil/trace.go:171","msg":"trace[2010543032] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"136.842369ms","start":"2026-03-03T10:50:24.806025Z","end":"2026-03-03T10:50:24.942867Z","steps":["trace[2010543032] 'process raft request' (duration: 105.557283ms)","trace[2010543032] 'compare' (duration: 30.836546ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:24.943195Z","caller":"traceutil/trace.go:171","msg":"trace[742660285] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"133.189679ms","start":"2026-03-03T10:50:24.809648Z","end":"2026-03-03T10:50:24.942838Z","steps":["trace[742660285] 'process raft request' (duration: 132.982715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:24.943185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.082107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:50:24.943556Z","caller":"traceutil/trace.go:171","msg":"trace[1050571438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:435; }","duration":"102.423344ms","start":"2026-03-03T10:50:24.841029Z","end":"2026-03-03T10:50:24.943453Z","steps":["trace[1050571438] 'agreement among raft nodes before linearized reading' (duration: 101.909082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:24.943564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.489112ms","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-03-03T10:50:24.943711Z","caller":"traceutil/trace.go:171","msg":"trace[993719300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:437; }","duration":"100.664866ms","start":"2026-03-03T10:50:24.843025Z","end":"2026-03-03T10:50:24.94369Z","steps":["trace[993719300] 'agreement among raft nodes before linearized reading' (duration: 100.41162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:24.943887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.361349ms","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-03-03T10:50:24.943951Z","caller":"traceutil/trace.go:171","msg":"trace[126524375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"100.470902ms","start":"2026-03-03T10:50:24.843465Z","end":"2026-03-03T10:50:24.943936Z","steps":["trace[126524375] 'agreement among raft nodes before linearized reading' (duration: 100.3681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:25.19018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.80971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:50:25.190353Z","caller":"traceutil/trace.go:171","msg":"trace[208395984] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"188.157941ms","start":"2026-03-03T10:50:25.002158Z","end":"2026-03-03T10:50:25.190316Z","steps":["trace[208395984] 'process raft request' (duration: 75.118236ms)","trace[208395984] 'compare' (duration: 112.693578ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:25.190435Z","caller":"traceutil/trace.go:171","msg":"trace[123370980] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:456; }","duration":"185.695127ms","start":"2026-03-03T10:50:25.004704Z","end":"2026-03-03T10:50:25.190399Z","steps":["trace[123370980] 'read index received' (duration: 20.223682ms)","trace[123370980] 'applied index is now lower than readState.Index' (duration: 165.470005ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:25.190446Z","caller":"traceutil/trace.go:171","msg":"trace[1807051749] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"187.319662ms","start":"2026-03-03T10:50:25.003104Z","end":"2026-03-03T10:50:25.190424Z","steps":["trace[1807051749] 'process raft request' (duration: 187.19729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:25.190549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.868601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-03-03T10:50:25.190604Z","caller":"traceutil/trace.go:171","msg":"trace[585303209] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:444; }","duration":"185.950602ms","start":"2026-03-03T10:50:25.004639Z","end":"2026-03-03T10:50:25.190589Z","steps":["trace[585303209] 'agreement among raft nodes before linearized reading' (duration: 185.85376ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:25.219185Z","caller":"traceutil/trace.go:171","msg":"trace[1255971523] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"142.81902ms","start":"2026-03-03T10:50:25.076332Z","end":"2026-03-03T10:50:25.219151Z","steps":["trace[1255971523] 'process raft request' (duration: 142.650586ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:25.491383Z","caller":"traceutil/trace.go:171","msg":"trace[2051263930] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"102.774626ms","start":"2026-03-03T10:50:25.388574Z","end":"2026-03-03T10:50:25.491349Z","steps":["trace[2051263930] 'process raft request' (duration: 72.237103ms)","trace[2051263930] 'compare' (duration: 30.38036ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:37.251913Z","caller":"traceutil/trace.go:171","msg":"trace[1818836068] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"122.769214ms","start":"2026-03-03T10:50:37.12912Z","end":"2026-03-03T10:50:37.251889Z","steps":["trace[1818836068] 'read index received' (duration: 122.584901ms)","trace[1818836068] 'applied index is now lower than readState.Index' (duration: 183.413µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:37.25202Z","caller":"traceutil/trace.go:171","msg":"trace[1465542374] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"129.884523ms","start":"2026-03-03T10:50:37.122099Z","end":"2026-03-03T10:50:37.251983Z","steps":["trace[1465542374] 'process raft request' (duration: 129.659029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:37.25208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.951437ms","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-03-03T10:50:37.252122Z","caller":"traceutil/trace.go:171","msg":"trace[693599752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"123.048419ms","start":"2026-03-03T10:50:37.129059Z","end":"2026-03-03T10:50:37.252107Z","steps":["trace[693599752] 'agreement among raft nodes before linearized reading' (duration: 122.962147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:37.252252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.668552ms","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-03-03T10:50:37.252305Z","caller":"traceutil/trace.go:171","msg":"trace[1539534395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"122.776244ms","start":"2026-03-03T10:50:37.129514Z","end":"2026-03-03T10:50:37.25229Z","steps":["trace[1539534395] 'agreement among raft nodes before linearized reading' (duration: 122.673143ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:37.430519Z","caller":"traceutil/trace.go:171","msg":"trace[1198833051] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"173.647614ms","start":"2026-03-03T10:50:37.256843Z","end":"2026-03-03T10:50:37.43049Z","steps":["trace[1198833051] 'process raft request' (duration: 161.598699ms)","trace[1198833051] 'compare' (duration: 11.926024ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:37.494526Z","caller":"traceutil/trace.go:171","msg":"trace[929605565] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"237.277941ms","start":"2026-03-03T10:50:37.257224Z","end":"2026-03-03T10:50:37.494502Z","steps":["trace[929605565] 'process raft request' (duration: 237.150239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:39.101446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350591331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:50:39.214411Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.345878324s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T10:50:39.214816Z","caller":"traceutil/trace.go:171","msg":"trace[1488424626] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.346370531s","start":"2026-03-03T10:50:37.868424Z","end":"2026-03-03T10:50:39.214794Z","steps":["trace[1488424626] 'process raft request' (duration: 1.346216529s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:39.214964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:37.868407Z","time spent":"1.346474603s","remote":"127.0.0.1:43598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:39.261104Z","caller":"traceutil/trace.go:171","msg":"trace[2060465236] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"659.953606ms","start":"2026-03-03T10:50:38.601128Z","end":"2026-03-03T10:50:39.261082Z","steps":["trace[2060465236] 'read index received' (duration: 613.538785ms)","trace[2060465236] 'applied index is now lower than readState.Index' (duration: 46.411231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:39.26154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.23427ms","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-03-03T10:50:39.261679Z","caller":"traceutil/trace.go:171","msg":"trace[1687131701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"660.538465ms","start":"2026-03-03T10:50:38.601069Z","end":"2026-03-03T10:50:39.261607Z","steps":["trace[1687131701] 'agreement among raft nodes before linearized reading' (duration: 660.18676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:39.261735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:38.601046Z","time spent":"660.675647ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T10:50:39.26196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.911166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:50:39.26203Z","caller":"traceutil/trace.go:171","msg":"trace[1393641986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"605.019417ms","start":"2026-03-03T10:50:38.656996Z","end":"2026-03-03T10:50:39.262016Z","steps":["trace[1393641986] 'agreement among raft nodes before linearized reading' (duration: 604.827604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:39.262118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:38.656937Z","time spent":"605.116849ms","remote":"127.0.0.1:43430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T10:50:45.818772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350591418,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T10:50:45.847553Z","caller":"traceutil/trace.go:171","msg":"trace[154921851] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"550.146409ms","start":"2026-03-03T10:50:45.297381Z","end":"2026-03-03T10:50:45.847528Z","steps":["trace[154921851] 'process raft request' (duration: 550.007977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:45.847732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.297365Z","time spent":"550.282401ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:50:46.014318Z","caller":"traceutil/trace.go:171","msg":"trace[1774440392] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"696.249067ms","start":"2026-03-03T10:50:45.318047Z","end":"2026-03-03T10:50:46.014296Z","steps":["trace[1774440392] 'read index received' (duration: 529.323021ms)","trace[1774440392] 'applied index is now lower than readState.Index' (duration: 166.925306ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:46.014377Z","caller":"traceutil/trace.go:171","msg":"trace[1456945449] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"529.726232ms","start":"2026-03-03T10:50:45.484621Z","end":"2026-03-03T10:50:46.014347Z","steps":["trace[1456945449] 'process raft request' (duration: 456.809477ms)","trace[1456945449] 'compare' (duration: 72.779483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:46.014567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.843006ms","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-03-03T10:50:46.014633Z","caller":"traceutil/trace.go:171","msg":"trace[1619727635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"525.934337ms","start":"2026-03-03T10:50:45.488688Z","end":"2026-03-03T10:50:46.014623Z","steps":["trace[1619727635] 'agreement among raft nodes before linearized reading' (duration: 525.844106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.014677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.488675Z","time spent":"525.992728ms","remote":"127.0.0.1:43690","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-03-03T10:50:46.014679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.646771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:50:46.014755Z","caller":"traceutil/trace.go:171","msg":"trace[1442477053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"357.745151ms","start":"2026-03-03T10:50:45.656996Z","end":"2026-03-03T10:50:46.014742Z","steps":["trace[1442477053] 'agreement among raft nodes before linearized reading' (duration: 357.65649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.014789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.656979Z","time spent":"357.801502ms","remote":"127.0.0.1:43430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T10:50:46.014701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.020863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-crwrm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-03T10:50:46.01484Z","caller":"traceutil/trace.go:171","msg":"trace[1966430956] range","detail":"{range_begin:/registry/pods/kube-system/cilium-crwrm; range_end:; response_count:1; response_revision:556; }","duration":"414.184454ms","start":"2026-03-03T10:50:45.600645Z","end":"2026-03-03T10:50:46.014829Z","steps":["trace[1966430956] 'agreement among raft nodes before linearized reading' (duration: 413.955012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.014874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.600632Z","time spent":"414.233345ms","remote":"127.0.0.1:43604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-crwrm\" "} {"level":"warn","ts":"2026-03-03T10:50:46.015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.647198ms","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-03-03T10:50:46.015056Z","caller":"traceutil/trace.go:171","msg":"trace[1937828802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"486.76465ms","start":"2026-03-03T10:50:45.528284Z","end":"2026-03-03T10:50:46.015049Z","steps":["trace[1937828802] 'agreement among raft nodes before linearized reading' (duration: 486.686069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.014459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.37704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:50:46.01512Z","caller":"traceutil/trace.go:171","msg":"trace[592457449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"697.101278ms","start":"2026-03-03T10:50:45.31801Z","end":"2026-03-03T10:50:46.015111Z","steps":["trace[592457449] 'agreement among raft nodes before linearized reading' (duration: 696.368099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.015157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.317988Z","time spent":"697.158748ms","remote":"127.0.0.1:43690","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-03-03T10:50:46.01523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.484602Z","time spent":"529.901734ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:50:46.015749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:45.528263Z","time spent":"486.83702ms","remote":"127.0.0.1:43690","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":"info","ts":"2026-03-03T10:50:46.740889Z","caller":"traceutil/trace.go:171","msg":"trace[1859238104] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"694.389362ms","start":"2026-03-03T10:50:46.046455Z","end":"2026-03-03T10:50:46.740845Z","steps":["trace[1859238104] 'process raft request' (duration: 694.19763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:46.741051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:46.046435Z","time spent":"694.529914ms","remote":"127.0.0.1:43604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:50:48.540174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350591430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:50:49.041387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218229350591430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T10:50:49.299956Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.44560989s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T10:50:49.300439Z","caller":"traceutil/trace.go:171","msg":"trace[575464747] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.446164106s","start":"2026-03-03T10:50:47.854251Z","end":"2026-03-03T10:50:49.300415Z","steps":["trace[575464747] 'process raft request' (duration: 1.445959274s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:49.300616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:47.854233Z","time spent":"1.446290787s","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:50:49.546062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.75358ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-03T10:50:49.546162Z","caller":"traceutil/trace.go:171","msg":"trace[2140710187] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"1.506920728s","start":"2026-03-03T10:50:48.039224Z","end":"2026-03-03T10:50:49.546144Z","steps":["trace[2140710187] 'read index received' (duration: 1.260915177s)","trace[2140710187] 'applied index is now lower than readState.Index' (duration: 246.003851ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:50:49.54635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442786549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-03T10:50:49.546354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.20646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T10:50:49.54641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50718848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:50:49.546386Z","caller":"traceutil/trace.go:171","msg":"trace[1567613041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"1.442867759s","start":"2026-03-03T10:50:48.103508Z","end":"2026-03-03T10:50:49.546376Z","steps":["trace[1567613041] 'agreement among raft nodes before linearized reading' (duration: 1.442725538s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:49.546497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:48.103486Z","time spent":"1.44299883s","remote":"127.0.0.1:43690","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-03-03T10:50:49.546539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442917259s","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-03-03T10:50:49.546444Z","caller":"traceutil/trace.go:171","msg":"trace[1543076898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"889.346102ms","start":"2026-03-03T10:50:48.657079Z","end":"2026-03-03T10:50:49.546425Z","steps":["trace[1543076898] 'agreement among raft nodes before linearized reading' (duration: 889.20021ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:49.546619Z","caller":"traceutil/trace.go:171","msg":"trace[2105173042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"1.443042551s","start":"2026-03-03T10:50:48.103557Z","end":"2026-03-03T10:50:49.546599Z","steps":["trace[2105173042] 'agreement among raft nodes before linearized reading' (duration: 1.442795619s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:49.546637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:48.65706Z","time spent":"889.560744ms","remote":"127.0.0.1:43430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T10:50:49.546666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:48.103543Z","time spent":"1.443114492s","remote":"127.0.0.1:43690","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":"info","ts":"2026-03-03T10:50:49.546494Z","caller":"traceutil/trace.go:171","msg":"trace[1577676512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"1.507340902s","start":"2026-03-03T10:50:48.039132Z","end":"2026-03-03T10:50:49.546473Z","steps":["trace[1577676512] 'agreement among raft nodes before linearized reading' (duration: 1.507081929s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:49.546771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:50:48.0391Z","time spent":"1.507633905s","remote":"127.0.0.1:43690","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-03-03T10:50:51.440675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.850028ms","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-03-03T10:50:51.440756Z","caller":"traceutil/trace.go:171","msg":"trace[272854333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"135.973649ms","start":"2026-03-03T10:50:51.304765Z","end":"2026-03-03T10:50:51.440738Z","steps":["trace[272854333] 'range keys from in-memory index tree' (duration: 135.705887ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:51.575137Z","caller":"traceutil/trace.go:171","msg":"trace[1268681163] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"129.080172ms","start":"2026-03-03T10:50:51.446039Z","end":"2026-03-03T10:50:51.575119Z","steps":["trace[1268681163] 'read index received' (duration: 128.905181ms)","trace[1268681163] 'applied index is now lower than readState.Index' (duration: 174.321µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:50:51.57543Z","caller":"traceutil/trace.go:171","msg":"trace[566546441] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"130.036551ms","start":"2026-03-03T10:50:51.445382Z","end":"2026-03-03T10:50:51.575418Z","steps":["trace[566546441] 'process raft request' (duration: 129.632277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:51.575757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.689748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-03T10:50:51.575815Z","caller":"traceutil/trace.go:171","msg":"trace[2134243243] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:575; }","duration":"129.786709ms","start":"2026-03-03T10:50:51.446018Z","end":"2026-03-03T10:50:51.575805Z","steps":["trace[2134243243] 'agreement among raft nodes before linearized reading' (duration: 129.621567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:50:53.988667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.157496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-03T10:50:53.988762Z","caller":"traceutil/trace.go:171","msg":"trace[254834671] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:632; }","duration":"169.309078ms","start":"2026-03-03T10:50:53.819433Z","end":"2026-03-03T10:50:53.988742Z","steps":["trace[254834671] 'range keys from in-memory index tree' (duration: 168.986485ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:50:55.228228Z","caller":"traceutil/trace.go:171","msg":"trace[1950281750] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"180.066647ms","start":"2026-03-03T10:50:55.048139Z","end":"2026-03-03T10:50:55.228206Z","steps":["trace[1950281750] 'process raft request' (duration: 179.778865ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:51:09.860698Z","caller":"traceutil/trace.go:171","msg":"trace[1908969717] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"196.332096ms","start":"2026-03-03T10:51:09.664238Z","end":"2026-03-03T10:51:09.86057Z","steps":["trace[1908969717] 'process raft request' (duration: 196.147465ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:51:09.885108Z","caller":"traceutil/trace.go:171","msg":"trace[868518305] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:788; }","duration":"196.627916ms","start":"2026-03-03T10:51:09.688464Z","end":"2026-03-03T10:51:09.885092Z","steps":["trace[868518305] 'read index received' (duration: 171.956309ms)","trace[868518305] 'applied index is now lower than readState.Index' (duration: 24.671067ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:51:09.885183Z","caller":"traceutil/trace.go:171","msg":"trace[496671901] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"219.282685ms","start":"2026-03-03T10:51:09.665877Z","end":"2026-03-03T10:51:09.885159Z","steps":["trace[496671901] 'process raft request' (duration: 219.114555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:51:09.885258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.777397ms","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-03-03T10:51:09.885279Z","caller":"traceutil/trace.go:171","msg":"trace[166402120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"196.828428ms","start":"2026-03-03T10:51:09.688444Z","end":"2026-03-03T10:51:09.885272Z","steps":["trace[166402120] 'agreement among raft nodes before linearized reading' (duration: 196.735187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:51:09.885416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.216777ms","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-03-03T10:51:09.885458Z","caller":"traceutil/trace.go:171","msg":"trace[1150647914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"117.295847ms","start":"2026-03-03T10:51:09.768144Z","end":"2026-03-03T10:51:09.88544Z","steps":["trace[1150647914] 'agreement among raft nodes before linearized reading' (duration: 117.117827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:51:09.88542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.242758ms","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-03-03T10:51:09.885515Z","caller":"traceutil/trace.go:171","msg":"trace[2038789338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"153.377988ms","start":"2026-03-03T10:51:09.732113Z","end":"2026-03-03T10:51:09.885491Z","steps":["trace[2038789338] 'agreement among raft nodes before linearized reading' (duration: 153.113127ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:51:38.454627Z","caller":"traceutil/trace.go:171","msg":"trace[1941406714] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"140.533459ms","start":"2026-03-03T10:51:38.314064Z","end":"2026-03-03T10:51:38.454598Z","steps":["trace[1941406714] 'process raft request' (duration: 87.832361ms)","trace[1941406714] 'compare' (duration: 52.555949ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:51:38.462292Z","caller":"traceutil/trace.go:171","msg":"trace[2086654123] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"147.662359ms","start":"2026-03-03T10:51:38.314617Z","end":"2026-03-03T10:51:38.462279Z","steps":["trace[2086654123] 'process raft request' (duration: 147.576019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:51:50.501718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.052283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:51:50.501926Z","caller":"traceutil/trace.go:171","msg":"trace[341546731] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"146.362614ms","start":"2026-03-03T10:51:50.355544Z","end":"2026-03-03T10:51:50.501907Z","steps":["trace[341546731] 'process raft request' (duration: 146.302374ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:51:50.501995Z","caller":"traceutil/trace.go:171","msg":"trace[1593089003] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"146.485093ms","start":"2026-03-03T10:51:50.355473Z","end":"2026-03-03T10:51:50.501958Z","steps":["trace[1593089003] 'process raft request' (duration: 29.103021ms)","trace[1593089003] 'compare' (duration: 116.936364ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:00.694113Z","caller":"traceutil/trace.go:171","msg":"trace[671776227] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"231.807438ms","start":"2026-03-03T10:52:00.462268Z","end":"2026-03-03T10:52:00.694075Z","steps":["trace[671776227] 'process raft request' (duration: 231.55834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:10.741167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.825238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-03-03T10:52:10.741243Z","caller":"traceutil/trace.go:171","msg":"trace[1367843989] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:962; }","duration":"422.980877ms","start":"2026-03-03T10:52:10.318247Z","end":"2026-03-03T10:52:10.741228Z","steps":["trace[1367843989] 'range keys from in-memory index tree' (duration: 422.707079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:10.74127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:10.318214Z","time spent":"423.050016ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":193,"request content":"key:\"/registry/serviceaccounts/default/default\" "} {"level":"warn","ts":"2026-03-03T10:52:10.741362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.950765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T10:52:10.741485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.794435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-03-03T10:52:10.741513Z","caller":"traceutil/trace.go:171","msg":"trace[879466869] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:962; }","duration":"114.188313ms","start":"2026-03-03T10:52:10.627291Z","end":"2026-03-03T10:52:10.74148Z","steps":["trace[879466869] 'count revisions from in-memory index tree' (duration: 113.591077ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:10.741548Z","caller":"traceutil/trace.go:171","msg":"trace[1184885171] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:962; }","duration":"524.899535ms","start":"2026-03-03T10:52:10.216633Z","end":"2026-03-03T10:52:10.741532Z","steps":["trace[1184885171] 'range keys from in-memory index tree' (duration: 524.596756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:10.741602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:10.216613Z","time spent":"524.975334ms","remote":"127.0.0.1:43584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1487,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-03-03T10:52:13.095993Z","caller":"traceutil/trace.go:171","msg":"trace[967336441] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"183.3804ms","start":"2026-03-03T10:52:12.912584Z","end":"2026-03-03T10:52:13.095965Z","steps":["trace[967336441] 'process raft request' (duration: 180.68498ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:13.101066Z","caller":"traceutil/trace.go:171","msg":"trace[219489928] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"159.815746ms","start":"2026-03-03T10:52:12.941234Z","end":"2026-03-03T10:52:13.10105Z","steps":["trace[219489928] 'read index received' (duration: 152.034002ms)","trace[219489928] 'applied index is now lower than readState.Index' (duration: 7.780984ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:13.101112Z","caller":"traceutil/trace.go:171","msg":"trace[1745665043] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"184.80574ms","start":"2026-03-03T10:52:12.916283Z","end":"2026-03-03T10:52:13.101089Z","steps":["trace[1745665043] 'process raft request' (duration: 184.674931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:13.101239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.996585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-03T10:52:13.101816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.308757ms","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-03-03T10:52:13.101807Z","caller":"traceutil/trace.go:171","msg":"trace[1327014388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"160.092754ms","start":"2026-03-03T10:52:12.941182Z","end":"2026-03-03T10:52:13.101275Z","steps":["trace[1327014388] 'agreement among raft nodes before linearized reading' (duration: 159.929196ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:13.101881Z","caller":"traceutil/trace.go:171","msg":"trace[1916821684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"117.412507ms","start":"2026-03-03T10:52:12.984443Z","end":"2026-03-03T10:52:13.101856Z","steps":["trace[1916821684] 'agreement among raft nodes before linearized reading' (duration: 116.835051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:13.101817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.163559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-03T10:52:13.101951Z","caller":"traceutil/trace.go:171","msg":"trace[195875189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"117.309167ms","start":"2026-03-03T10:52:12.98462Z","end":"2026-03-03T10:52:13.10193Z","steps":["trace[195875189] 'agreement among raft nodes before linearized reading' (duration: 116.622023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:13.101817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.743634ms","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-03-03T10:52:13.102041Z","caller":"traceutil/trace.go:171","msg":"trace[770265918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"117.961423ms","start":"2026-03-03T10:52:12.984051Z","end":"2026-03-03T10:52:13.102012Z","steps":["trace[770265918] 'agreement among raft nodes before linearized reading' (duration: 117.108209ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T10:52:21.613647Z","caller":"traceutil/trace.go:171","msg":"trace[1319387319] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"447.150034ms","start":"2026-03-03T10:52:21.166474Z","end":"2026-03-03T10:52:21.613624Z","steps":["trace[1319387319] 'process raft request' (duration: 447.004116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.613805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.166456Z","time spent":"447.272665ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:21.649925Z","caller":"traceutil/trace.go:171","msg":"trace[2061914059] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"483.052849ms","start":"2026-03-03T10:52:21.166842Z","end":"2026-03-03T10:52:21.649894Z","steps":["trace[2061914059] 'read index received' (duration: 446.645559ms)","trace[2061914059] 'applied index is now lower than readState.Index' (duration: 36.40619ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:21.650082Z","caller":"traceutil/trace.go:171","msg":"trace[1774859411] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"483.245178ms","start":"2026-03-03T10:52:21.166815Z","end":"2026-03-03T10:52:21.650061Z","steps":["trace[1774859411] 'process raft request' (duration: 482.871731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.650366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.166795Z","time spent":"483.329138ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T10:52:21.650373Z","caller":"traceutil/trace.go:171","msg":"trace[472261701] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"483.447596ms","start":"2026-03-03T10:52:21.166901Z","end":"2026-03-03T10:52:21.650348Z","steps":["trace[472261701] 'process raft request' (duration: 482.93234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.650505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.857059ms","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-03-03T10:52:21.650552Z","caller":"traceutil/trace.go:171","msg":"trace[69292656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"481.925859ms","start":"2026-03-03T10:52:21.168614Z","end":"2026-03-03T10:52:21.65054Z","steps":["trace[69292656] 'agreement among raft nodes before linearized reading' (duration: 481.78821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.65059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.168602Z","time spent":"481.979278ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T10:52:21.650723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.166867Z","time spent":"483.573236ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:21.650867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.021322ms","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-03-03T10:52:21.65092Z","caller":"traceutil/trace.go:171","msg":"trace[1486941063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"484.089122ms","start":"2026-03-03T10:52:21.166819Z","end":"2026-03-03T10:52:21.650908Z","steps":["trace[1486941063] 'agreement among raft nodes before linearized reading' (duration: 483.354268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.650971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.16681Z","time spent":"484.147911ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T10:52:21.96359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.13196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:52:21.963721Z","caller":"traceutil/trace.go:171","msg":"trace[999713490] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1044; }","duration":"308.489087ms","start":"2026-03-03T10:52:21.655219Z","end":"2026-03-03T10:52:21.963708Z","steps":["trace[999713490] 'read index received' (duration: 78.093879ms)","trace[999713490] 'applied index is now lower than readState.Index' (duration: 230.394518ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:21.963783Z","caller":"traceutil/trace.go:171","msg":"trace[932357347] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"309.265701ms","start":"2026-03-03T10:52:21.654463Z","end":"2026-03-03T10:52:21.963728Z","steps":["trace[932357347] 'process raft request' (duration: 78.923673ms)","trace[932357347] 'compare' (duration: 229.932022ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T10:52:21.963855Z","caller":"traceutil/trace.go:171","msg":"trace[489017726] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"308.929803ms","start":"2026-03-03T10:52:21.654898Z","end":"2026-03-03T10:52:21.963828Z","steps":["trace[489017726] 'process raft request' (duration: 308.770375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.963998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.654889Z","time spent":"309.035853ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:21.964059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.654442Z","time spent":"309.455331ms","remote":"127.0.0.1:43690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:21.963893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.668467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T10:52:21.964139Z","caller":"traceutil/trace.go:171","msg":"trace[1564502724] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1004; }","duration":"308.932195ms","start":"2026-03-03T10:52:21.655195Z","end":"2026-03-03T10:52:21.964128Z","steps":["trace[1564502724] 'agreement among raft nodes before linearized reading' (duration: 308.663037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T10:52:21.964181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:21.655185Z","time spent":"308.984464ms","remote":"127.0.0.1:43580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-03-03T10:52:23.37633Z","caller":"traceutil/trace.go:171","msg":"trace[1710588417] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"484.438154ms","start":"2026-03-03T10:52:22.891859Z","end":"2026-03-03T10:52:23.376297Z","steps":["trace[1710588417] 'process raft request' (duration: 425.324945ms)","trace[1710588417] 'compare' (duration: 58.880071ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T10:52:23.376542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T10:52:22.89184Z","time spent":"484.624732ms","remote":"127.0.0.1:43450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T10:52:24.212665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.483393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T10:52:24.21283Z","caller":"traceutil/trace.go:171","msg":"trace[428868086] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"181.962376ms","start":"2026-03-03T10:52:24.030842Z","end":"2026-03-03T10:52:24.212805Z","steps":["trace[428868086] 'process raft request' (duration: 76.216375ms)","trace[428868086] 'compare' (duration: 105.276335ms)"],"step_count":2}