{"level":"warn","ts":"2026-05-05T10:15:05.011028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:15:05.011127Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.24:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.24:2380","--initial-cluster=instance=https://199.19.213.24:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.24:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.24:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T10:15:05.011204Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T10:15:05.011217Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.24:2380"]} {"level":"info","ts":"2026-05-05T10:15:05.011241Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:15:05.011834Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"]} {"level":"info","ts":"2026-05-05T10:15:05.011907Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.24:2380"],"listen-peer-urls":["https://199.19.213.24:2380"],"advertise-client-urls":["https://199.19.213.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.24:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T10:15:05.016813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.603102ms"} {"level":"info","ts":"2026-05-05T10:15:05.027806Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bd149264c66f18d5","cluster-id":"781da5450c4333a5"} {"level":"info","ts":"2026-05-05T10:15:05.027963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T10:15:05.028718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became follower at term 0"} {"level":"info","ts":"2026-05-05T10:15:05.028737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bd149264c66f18d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T10:15:05.028744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became follower at term 1"} {"level":"info","ts":"2026-05-05T10:15:05.029132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=(13624675734226147541)"} {"level":"warn","ts":"2026-05-05T10:15:05.038189Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T10:15:05.039989Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T10:15:05.041161Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T10:15:05.045591Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bd149264c66f18d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T10:15:05.045829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bd149264c66f18d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T10:15:05.046004Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:15:05.046101Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:15:05.046123Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T10:15:05.04735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=(13624675734226147541)"} {"level":"info","ts":"2026-05-05T10:15:05.047812Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"781da5450c4333a5","local-member-id":"bd149264c66f18d5","added-peer-id":"bd149264c66f18d5","added-peer-peer-urls":["https://199.19.213.24:2380"]} {"level":"info","ts":"2026-05-05T10:15:05.050411Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T10:15:05.050616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bd149264c66f18d5","initial-advertise-peer-urls":["https://199.19.213.24:2380"],"listen-peer-urls":["https://199.19.213.24:2380"],"advertise-client-urls":["https://199.19.213.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T10:15:05.050662Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T10:15:05.050711Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.24:2380"} {"level":"info","ts":"2026-05-05T10:15:05.050732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.24:2380"} {"level":"info","ts":"2026-05-05T10:15:05.92939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T10:15:05.929552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T10:15:05.929595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 received MsgPreVoteResp from bd149264c66f18d5 at term 1"} {"level":"info","ts":"2026-05-05T10:15:05.929613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became candidate at term 2"} {"level":"info","ts":"2026-05-05T10:15:05.929632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 received MsgVoteResp from bd149264c66f18d5 at term 2"} {"level":"info","ts":"2026-05-05T10:15:05.929647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became leader at term 2"} {"level":"info","ts":"2026-05-05T10:15:05.929661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bd149264c66f18d5 elected leader bd149264c66f18d5 at term 2"} {"level":"info","ts":"2026-05-05T10:15:05.930953Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bd149264c66f18d5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.24:2379]}","request-path":"/0/members/bd149264c66f18d5/attributes","cluster-id":"781da5450c4333a5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T10:15:05.930996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:15:05.931009Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:05.931179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T10:15:05.931531Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T10:15:05.931555Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T10:15:05.932603Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.24:2379"} {"level":"info","ts":"2026-05-05T10:15:05.9341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T10:15:05.940068Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"781da5450c4333a5","local-member-id":"bd149264c66f18d5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:05.940131Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T10:15:05.940148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T10:15:10.112974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.345493ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-05T10:15:10.113252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:09.587659Z","time spent":"525.585882ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T10:15:10.113436Z","caller":"traceutil/trace.go:171","msg":"trace[380940784] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:219; }","duration":"513.493095ms","start":"2026-05-05T10:15:09.599925Z","end":"2026-05-05T10:15:10.113418Z","steps":["trace[380940784] 'read index received' (duration: 13.573153ms)","trace[380940784] 'applied index is now lower than readState.Index' (duration: 499.918612ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:15:10.113612Z","caller":"traceutil/trace.go:171","msg":"trace[1816391957] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"522.568968ms","start":"2026-05-05T10:15:09.591011Z","end":"2026-05-05T10:15:10.11358Z","steps":["trace[1816391957] 'process raft request' (duration: 522.081674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:10.113841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.870793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:15:10.11382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.114129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:15:10.113895Z","caller":"traceutil/trace.go:171","msg":"trace[202721301] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:215; }","duration":"513.99972ms","start":"2026-05-05T10:15:09.599881Z","end":"2026-05-05T10:15:10.113881Z","steps":["trace[202721301] 'agreement among raft nodes before linearized reading' (duration: 513.702895ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:10.113914Z","caller":"traceutil/trace.go:171","msg":"trace[49728019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:215; }","duration":"431.319879ms","start":"2026-05-05T10:15:09.682573Z","end":"2026-05-05T10:15:10.113892Z","steps":["trace[49728019] 'agreement among raft nodes before linearized reading' (duration: 431.076217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:10.113969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:09.599863Z","time spent":"514.094356ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-05-05T10:15:10.113997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:09.682551Z","time spent":"431.435316ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:15:10.114012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.418228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-05-05T10:15:10.114115Z","caller":"traceutil/trace.go:171","msg":"trace[367824571] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:215; }","duration":"176.654951ms","start":"2026-05-05T10:15:09.937443Z","end":"2026-05-05T10:15:10.114098Z","steps":["trace[367824571] 'agreement among raft nodes before linearized reading' (duration: 176.414198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:10.115438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:09.590986Z","time spent":"522.726566ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:15:10.462981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.221944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:15:10.463101Z","caller":"traceutil/trace.go:171","msg":"trace[471202241] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"347.96695ms","start":"2026-05-05T10:15:10.11511Z","end":"2026-05-05T10:15:10.463077Z","steps":["trace[471202241] 'process raft request' (duration: 216.573547ms)","trace[471202241] 'compare' (duration: 131.107749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:10.463367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:10.11509Z","time spent":"348.205781ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T10:15:10.53074Z","caller":"traceutil/trace.go:171","msg":"trace[166899427] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"411.777354ms","start":"2026-05-05T10:15:10.118937Z","end":"2026-05-05T10:15:10.530715Z","steps":["trace[166899427] 'process raft request' (duration: 411.548132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:10.530911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.439616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T10:15:10.5309Z","caller":"traceutil/trace.go:171","msg":"trace[603496254] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"409.558373ms","start":"2026-05-05T10:15:10.121306Z","end":"2026-05-05T10:15:10.530865Z","steps":["trace[603496254] 'process raft request' (duration: 409.324561ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:10.530965Z","caller":"traceutil/trace.go:171","msg":"trace[1717825451] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:218; }","duration":"411.541291ms","start":"2026-05-05T10:15:10.119414Z","end":"2026-05-05T10:15:10.530955Z","steps":["trace[1717825451] 'agreement among raft nodes before linearized reading' (duration: 411.385034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:10.530909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:10.118924Z","time spent":"411.897189ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:15:10.531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:10.119398Z","time spent":"411.593704ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-05T10:15:10.530724Z","caller":"traceutil/trace.go:171","msg":"trace[601081038] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:221; }","duration":"411.234566ms","start":"2026-05-05T10:15:10.119458Z","end":"2026-05-05T10:15:10.530693Z","steps":["trace[601081038] 'read index received' (duration: 212.212271ms)","trace[601081038] 'applied index is now lower than readState.Index' (duration: 199.021405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:10.531078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:10.121292Z","time spent":"409.690369ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:33.807862Z","caller":"traceutil/trace.go:171","msg":"trace[632327781] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"160.218104ms","start":"2026-05-05T10:15:33.647616Z","end":"2026-05-05T10:15:33.807834Z","steps":["trace[632327781] 'process raft request' (duration: 160.092281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814194Z","caller":"traceutil/trace.go:171","msg":"trace[131539417] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"162.817699ms","start":"2026-05-05T10:15:33.651359Z","end":"2026-05-05T10:15:33.814177Z","steps":["trace[131539417] 'process raft request' (duration: 162.617354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814477Z","caller":"traceutil/trace.go:171","msg":"trace[69988340] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"153.789762ms","start":"2026-05-05T10:15:33.660663Z","end":"2026-05-05T10:15:33.814453Z","steps":["trace[69988340] 'process raft request' (duration: 153.452032ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814574Z","caller":"traceutil/trace.go:171","msg":"trace[293876564] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"161.892762ms","start":"2026-05-05T10:15:33.652637Z","end":"2026-05-05T10:15:33.81453Z","steps":["trace[293876564] 'process raft request' (duration: 161.436039ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814604Z","caller":"traceutil/trace.go:171","msg":"trace[1833274070] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"147.134253ms","start":"2026-05-05T10:15:33.667462Z","end":"2026-05-05T10:15:33.814596Z","steps":["trace[1833274070] 'process raft request' (duration: 146.68891ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814666Z","caller":"traceutil/trace.go:171","msg":"trace[1234988693] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"147.080502ms","start":"2026-05-05T10:15:33.667578Z","end":"2026-05-05T10:15:33.814658Z","steps":["trace[1234988693] 'process raft request' (duration: 146.612869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.814795Z","caller":"traceutil/trace.go:171","msg":"trace[1554354403] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"143.624782ms","start":"2026-05-05T10:15:33.671163Z","end":"2026-05-05T10:15:33.814787Z","steps":["trace[1554354403] 'process raft request' (duration: 143.17344ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.815615Z","caller":"traceutil/trace.go:171","msg":"trace[269680728] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"136.204392ms","start":"2026-05-05T10:15:33.679387Z","end":"2026-05-05T10:15:33.815592Z","steps":["trace[269680728] 'process raft request' (duration: 135.976926ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.815703Z","caller":"traceutil/trace.go:171","msg":"trace[212267412] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"121.261657ms","start":"2026-05-05T10:15:33.694435Z","end":"2026-05-05T10:15:33.815697Z","steps":["trace[212267412] 'process raft request' (duration: 121.080922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.815849Z","caller":"traceutil/trace.go:171","msg":"trace[270904044] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"111.326655ms","start":"2026-05-05T10:15:33.704514Z","end":"2026-05-05T10:15:33.815841Z","steps":["trace[270904044] 'process raft request' (duration: 111.053957ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:33.81883Z","caller":"traceutil/trace.go:171","msg":"trace[286668049] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"100.067294ms","start":"2026-05-05T10:15:33.718754Z","end":"2026-05-05T10:15:33.818821Z","steps":["trace[286668049] 'process raft request' (duration: 96.866104ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:40.038025Z","caller":"traceutil/trace.go:171","msg":"trace[1566532117] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"100.631967ms","start":"2026-05-05T10:15:39.937343Z","end":"2026-05-05T10:15:40.037975Z","steps":["trace[1566532117] 'process raft request' (duration: 92.868008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:40.869788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.352348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:15:40.869908Z","caller":"traceutil/trace.go:171","msg":"trace[368599470] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"550.690281ms","start":"2026-05-05T10:15:40.3192Z","end":"2026-05-05T10:15:40.86989Z","steps":["trace[368599470] 'read index received' (duration: 29.811µs)","trace[368599470] 'applied index is now lower than readState.Index' (duration: 550.65937ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:15:40.869976Z","caller":"traceutil/trace.go:171","msg":"trace[739108148] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"809.656531ms","start":"2026-05-05T10:15:40.060276Z","end":"2026-05-05T10:15:40.869932Z","steps":["trace[739108148] 'process raft request' (duration: 182.068637ms)","trace[739108148] 'compare' (duration: 627.205345ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:40.870182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:40.06024Z","time spent":"809.836745ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:15:40.870214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.008418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:15:40.870312Z","caller":"traceutil/trace.go:171","msg":"trace[510676657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"551.133901ms","start":"2026-05-05T10:15:40.319157Z","end":"2026-05-05T10:15:40.87029Z","steps":["trace[510676657] 'agreement among raft nodes before linearized reading' (duration: 550.806974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:40.870516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:40.31914Z","time spent":"551.270454ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T10:15:40.884414Z","caller":"traceutil/trace.go:171","msg":"trace[1806166328] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"410.13399ms","start":"2026-05-05T10:15:40.474214Z","end":"2026-05-05T10:15:40.884348Z","steps":["trace[1806166328] 'process raft request' (duration: 405.954098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:40.884467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.663397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T10:15:40.884531Z","caller":"traceutil/trace.go:171","msg":"trace[40640065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"201.77743ms","start":"2026-05-05T10:15:40.682738Z","end":"2026-05-05T10:15:40.884516Z","steps":["trace[40640065] 'agreement among raft nodes before linearized reading' (duration: 201.527674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:40.884577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:40.47419Z","time spent":"410.305814ms","remote":"127.0.0.1:42540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:15:41.614585Z","caller":"traceutil/trace.go:171","msg":"trace[2106355988] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"675.198066ms","start":"2026-05-05T10:15:40.939352Z","end":"2026-05-05T10:15:41.61455Z","steps":["trace[2106355988] 'process raft request' (duration: 622.611735ms)","trace[2106355988] 'compare' (duration: 52.164391ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:15:41.614699Z","caller":"traceutil/trace.go:171","msg":"trace[1427307828] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"184.562577ms","start":"2026-05-05T10:15:41.430106Z","end":"2026-05-05T10:15:41.614669Z","steps":["trace[1427307828] 'read index received' (duration: 131.870283ms)","trace[1427307828] 'applied index is now lower than readState.Index' (duration: 52.690554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:15:41.614747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:15:40.939335Z","time spent":"675.333759ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T10:15:41.614915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.799293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T10:15:41.614952Z","caller":"traceutil/trace.go:171","msg":"trace[153135824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"184.890125ms","start":"2026-05-05T10:15:41.430053Z","end":"2026-05-05T10:15:41.614943Z","steps":["trace[153135824] 'agreement among raft nodes before linearized reading' (duration: 184.732101ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:41.615246Z","caller":"traceutil/trace.go:171","msg":"trace[1816445979] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"172.237571ms","start":"2026-05-05T10:15:41.442998Z","end":"2026-05-05T10:15:41.615236Z","steps":["trace[1816445979] 'process raft request' (duration: 171.507643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:15:41.615497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.362123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:15:41.615522Z","caller":"traceutil/trace.go:171","msg":"trace[1433983037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"129.431534ms","start":"2026-05-05T10:15:41.486084Z","end":"2026-05-05T10:15:41.615515Z","steps":["trace[1433983037] 'agreement among raft nodes before linearized reading' (duration: 129.120627ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:15:56.372815Z","caller":"traceutil/trace.go:171","msg":"trace[1966788941] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"221.102817ms","start":"2026-05-05T10:15:56.151688Z","end":"2026-05-05T10:15:56.37279Z","steps":["trace[1966788941] 'process raft request' (duration: 220.926014ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:28.102449Z","caller":"traceutil/trace.go:171","msg":"trace[1006716465] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"248.62956ms","start":"2026-05-05T10:16:27.853798Z","end":"2026-05-05T10:16:28.102428Z","steps":["trace[1006716465] 'process raft request' (duration: 248.503748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:16:28.175623Z","caller":"traceutil/trace.go:171","msg":"trace[364142507] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"319.504796ms","start":"2026-05-05T10:16:27.856092Z","end":"2026-05-05T10:16:28.175597Z","steps":["trace[364142507] 'process raft request' (duration: 317.853715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:16:28.175779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:16:27.856079Z","time spent":"319.617157ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:17:11.920394Z","caller":"traceutil/trace.go:171","msg":"trace[1099016033] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1856; }","duration":"425.439316ms","start":"2026-05-05T10:17:11.494919Z","end":"2026-05-05T10:17:11.920358Z","steps":["trace[1099016033] 'read index received' (duration: 425.182723ms)","trace[1099016033] 'applied index is now lower than readState.Index' (duration: 255.713µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:11.920489Z","caller":"traceutil/trace.go:171","msg":"trace[653755401] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"426.532976ms","start":"2026-05-05T10:17:11.493913Z","end":"2026-05-05T10:17:11.920445Z","steps":["trace[653755401] 'process raft request' (duration: 426.262634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:11.920674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:11.493884Z","time spent":"426.689659ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:17:11.920694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.743198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5e9f3b5-32dd-4bfb-9668-ee418e8ae078\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-05T10:17:11.920738Z","caller":"traceutil/trace.go:171","msg":"trace[480042600] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5e9f3b5-32dd-4bfb-9668-ee418e8ae078; range_end:; response_count:1; response_revision:1802; }","duration":"425.84782ms","start":"2026-05-05T10:17:11.494875Z","end":"2026-05-05T10:17:11.920723Z","steps":["trace[480042600] 'agreement among raft nodes before linearized reading' (duration: 425.635568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:11.920778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:11.494857Z","time spent":"425.91144ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5e9f3b5-32dd-4bfb-9668-ee418e8ae078\" "} {"level":"warn","ts":"2026-05-05T10:17:11.920785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.161278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:11.920887Z","caller":"traceutil/trace.go:171","msg":"trace[1340028590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1802; }","duration":"395.336959ms","start":"2026-05-05T10:17:11.525522Z","end":"2026-05-05T10:17:11.920859Z","steps":["trace[1340028590] 'agreement among raft nodes before linearized reading' (duration: 395.165968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:11.920938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:11.525494Z","time spent":"395.43324ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:17:11.921101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.733292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T10:17:11.92111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.129671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5e9f3b5-32dd-4bfb-9668-ee418e8ae078\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-05-05T10:17:11.921131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.065562ms","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":"info","ts":"2026-05-05T10:17:11.92116Z","caller":"traceutil/trace.go:171","msg":"trace[1301228131] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1802; }","duration":"316.827113ms","start":"2026-05-05T10:17:11.604319Z","end":"2026-05-05T10:17:11.921146Z","steps":["trace[1301228131] 'agreement among raft nodes before linearized reading' (duration: 316.729542ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:11.92117Z","caller":"traceutil/trace.go:171","msg":"trace[1871477236] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1802; }","duration":"162.130722ms","start":"2026-05-05T10:17:11.759029Z","end":"2026-05-05T10:17:11.92116Z","steps":["trace[1871477236] 'agreement among raft nodes before linearized reading' (duration: 162.069321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:11.921171Z","caller":"traceutil/trace.go:171","msg":"trace[599544495] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5e9f3b5-32dd-4bfb-9668-ee418e8ae078; range_end:; response_count:1; response_revision:1802; }","duration":"224.219822ms","start":"2026-05-05T10:17:11.696936Z","end":"2026-05-05T10:17:11.921156Z","steps":["trace[599544495] 'agreement among raft nodes before linearized reading' (duration: 224.05892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:11.921202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:11.6043Z","time spent":"316.889973ms","remote":"127.0.0.1:35268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-05T10:17:14.642469Z","caller":"traceutil/trace.go:171","msg":"trace[1661951418] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"109.137168ms","start":"2026-05-05T10:17:14.533299Z","end":"2026-05-05T10:17:14.642437Z","steps":["trace[1661951418] 'process raft request' (duration: 108.898716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:20.532778Z","caller":"traceutil/trace.go:171","msg":"trace[781777722] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"184.437103ms","start":"2026-05-05T10:17:20.348286Z","end":"2026-05-05T10:17:20.532723Z","steps":["trace[781777722] 'process raft request' (duration: 184.249422ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:20.549175Z","caller":"traceutil/trace.go:171","msg":"trace[630888354] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"129.020469ms","start":"2026-05-05T10:17:20.420121Z","end":"2026-05-05T10:17:20.549142Z","steps":["trace[630888354] 'read index received' (duration: 112.343244ms)","trace[630888354] 'applied index is now lower than readState.Index' (duration: 16.676145ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:20.549323Z","caller":"traceutil/trace.go:171","msg":"trace[1762552678] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"187.29911ms","start":"2026-05-05T10:17:20.361979Z","end":"2026-05-05T10:17:20.549278Z","steps":["trace[1762552678] 'process raft request' (duration: 186.975457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:20.549427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.291381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:17:20.549497Z","caller":"traceutil/trace.go:171","msg":"trace[1607241620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1902; }","duration":"129.410802ms","start":"2026-05-05T10:17:20.420069Z","end":"2026-05-05T10:17:20.54948Z","steps":["trace[1607241620] 'agreement among raft nodes before linearized reading' (duration: 129.20393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:20.549493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.748124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-05T10:17:20.549543Z","caller":"traceutil/trace.go:171","msg":"trace[285629501] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"117.838704ms","start":"2026-05-05T10:17:20.431687Z","end":"2026-05-05T10:17:20.549525Z","steps":["trace[285629501] 'agreement among raft nodes before linearized reading' (duration: 117.631903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:49.619749Z","caller":"traceutil/trace.go:171","msg":"trace[1196498883] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"359.218408ms","start":"2026-05-05T10:17:49.260478Z","end":"2026-05-05T10:17:49.619696Z","steps":["trace[1196498883] 'process raft request' (duration: 358.924085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.620034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.260452Z","time spent":"359.453489ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:17:49.887736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.202614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T10:17:49.887852Z","caller":"traceutil/trace.go:171","msg":"trace[1380558523] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2177; }","duration":"393.240236ms","start":"2026-05-05T10:17:49.494593Z","end":"2026-05-05T10:17:49.887833Z","steps":["trace[1380558523] 'read index received' (duration: 124.831414ms)","trace[1380558523] 'applied index is now lower than readState.Index' (duration: 268.407822ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:17:49.888011Z","caller":"traceutil/trace.go:171","msg":"trace[226321773] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"625.152975ms","start":"2026-05-05T10:17:49.262768Z","end":"2026-05-05T10:17:49.887921Z","steps":["trace[226321773] 'process raft request' (duration: 431.671209ms)","trace[226321773] 'compare' (duration: 193.070657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:17:49.888143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.55294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:17:49.888184Z","caller":"traceutil/trace.go:171","msg":"trace[1074399653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2108; }","duration":"393.645824ms","start":"2026-05-05T10:17:49.494528Z","end":"2026-05-05T10:17:49.888174Z","steps":["trace[1074399653] 'agreement among raft nodes before linearized reading' (duration: 393.395943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.888219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.494494Z","time spent":"393.717348ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T10:17:49.888292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.26275Z","time spent":"625.410137ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:17:49.888609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.158813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-05-05T10:17:49.888626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.077556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-05-05T10:17:49.88862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.138271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T10:17:49.888683Z","caller":"traceutil/trace.go:171","msg":"trace[1659541949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"378.271808ms","start":"2026-05-05T10:17:49.510394Z","end":"2026-05-05T10:17:49.888666Z","steps":["trace[1659541949] 'agreement among raft nodes before linearized reading' (duration: 378.122911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.888736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.510376Z","time spent":"378.349672ms","remote":"127.0.0.1:47476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-05T10:17:49.888746Z","caller":"traceutil/trace.go:171","msg":"trace[942116929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"363.298678ms","start":"2026-05-05T10:17:49.525418Z","end":"2026-05-05T10:17:49.888717Z","steps":["trace[942116929] 'agreement among raft nodes before linearized reading' (duration: 363.091109ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:17:49.888676Z","caller":"traceutil/trace.go:171","msg":"trace[1878144763] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2108; }","duration":"265.16066ms","start":"2026-05-05T10:17:49.623498Z","end":"2026-05-05T10:17:49.888659Z","steps":["trace[1878144763] 'agreement among raft nodes before linearized reading' (duration: 265.013694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.888809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.525401Z","time spent":"363.393533ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T10:17:49.888619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.791555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T10:17:49.888867Z","caller":"traceutil/trace.go:171","msg":"trace[1570213042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2108; }","duration":"390.060988ms","start":"2026-05-05T10:17:49.498796Z","end":"2026-05-05T10:17:49.888857Z","steps":["trace[1570213042] 'agreement among raft nodes before linearized reading' (duration: 389.693381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.888905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.498787Z","time spent":"390.108469ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T10:17:49.889152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.862155ms","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-05-05T10:17:49.889322Z","caller":"traceutil/trace.go:171","msg":"trace[618678831] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2108; }","duration":"388.110427ms","start":"2026-05-05T10:17:49.50119Z","end":"2026-05-05T10:17:49.8893Z","steps":["trace[618678831] 'agreement among raft nodes before linearized reading' (duration: 387.3345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:17:49.889408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:17:49.501171Z","time spent":"388.223713ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T10:19:12.255006Z","caller":"traceutil/trace.go:171","msg":"trace[1058867610] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2881; }","duration":"140.247785ms","start":"2026-05-05T10:19:12.114733Z","end":"2026-05-05T10:19:12.25498Z","steps":["trace[1058867610] 'read index received' (duration: 140.152903ms)","trace[1058867610] 'applied index is now lower than readState.Index' (duration: 93.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:12.25522Z","caller":"traceutil/trace.go:171","msg":"trace[641015186] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"398.900646ms","start":"2026-05-05T10:19:11.856302Z","end":"2026-05-05T10:19:12.255202Z","steps":["trace[641015186] 'process raft request' (duration: 398.368355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:12.25554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:19:11.856283Z","time spent":"399.193933ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:19:12.255356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.580563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:19:12.256546Z","caller":"traceutil/trace.go:171","msg":"trace[1192288339] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2775; }","duration":"141.839741ms","start":"2026-05-05T10:19:12.114648Z","end":"2026-05-05T10:19:12.256488Z","steps":["trace[1192288339] 'agreement among raft nodes before linearized reading' (duration: 140.610733ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:19:48.692235Z","caller":"traceutil/trace.go:171","msg":"trace[2140908820] linearizableReadLoop","detail":"{readStateIndex:3224; appliedIndex:3222; }","duration":"103.226222ms","start":"2026-05-05T10:19:48.588986Z","end":"2026-05-05T10:19:48.692212Z","steps":["trace[2140908820] 'read index received' (duration: 9.598564ms)","trace[2140908820] 'applied index is now lower than readState.Index' (duration: 93.626448ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:48.692369Z","caller":"traceutil/trace.go:171","msg":"trace[1780515177] transaction","detail":"{read_only:false; response_revision:3098; number_of_response:1; }","duration":"172.780198ms","start":"2026-05-05T10:19:48.519575Z","end":"2026-05-05T10:19:48.692355Z","steps":["trace[1780515177] 'process raft request' (duration: 78.996556ms)","trace[1780515177] 'compare' (duration: 93.387035ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:19:48.692561Z","caller":"traceutil/trace.go:171","msg":"trace[700466555] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"152.297577ms","start":"2026-05-05T10:19:48.54024Z","end":"2026-05-05T10:19:48.692538Z","steps":["trace[700466555] 'process raft request' (duration: 151.91521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:19:48.692857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.842793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:19:48.692979Z","caller":"traceutil/trace.go:171","msg":"trace[1184358144] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3099; }","duration":"104.072047ms","start":"2026-05-05T10:19:48.588883Z","end":"2026-05-05T10:19:48.692955Z","steps":["trace[1184358144] 'agreement among raft nodes before linearized reading' (duration: 103.877754ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:29.794361Z","caller":"traceutil/trace.go:171","msg":"trace[459065318] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"156.331235ms","start":"2026-05-05T10:21:29.638005Z","end":"2026-05-05T10:21:29.794336Z","steps":["trace[459065318] 'process raft request' (duration: 156.145522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:29.828192Z","caller":"traceutil/trace.go:171","msg":"trace[2097081757] linearizableReadLoop","detail":"{readStateIndex:4486; appliedIndex:4484; }","duration":"168.605656ms","start":"2026-05-05T10:21:29.659565Z","end":"2026-05-05T10:21:29.828171Z","steps":["trace[2097081757] 'read index received' (duration: 134.646194ms)","trace[2097081757] 'applied index is now lower than readState.Index' (duration: 33.958522ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:29.828299Z","caller":"traceutil/trace.go:171","msg":"trace[1964529302] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"180.443031ms","start":"2026-05-05T10:21:29.647816Z","end":"2026-05-05T10:21:29.828259Z","steps":["trace[1964529302] 'process raft request' (duration: 180.169257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:29.828468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.043283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b23dc099-a2a9-43f5-afe4-252b2c347fbb\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-05-05T10:21:29.828496Z","caller":"traceutil/trace.go:171","msg":"trace[1120491547] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b23dc099-a2a9-43f5-afe4-252b2c347fbb; range_end:; response_count:1; response_revision:4299; }","duration":"113.107704ms","start":"2026-05-05T10:21:29.715382Z","end":"2026-05-05T10:21:29.828489Z","steps":["trace[1120491547] 'agreement among raft nodes before linearized reading' (duration: 112.991312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:29.828508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.923581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aca43262abd702\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-05-05T10:21:29.828565Z","caller":"traceutil/trace.go:171","msg":"trace[2123919008] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aca43262abd702; range_end:; response_count:1; response_revision:4299; }","duration":"169.203045ms","start":"2026-05-05T10:21:29.659344Z","end":"2026-05-05T10:21:29.828547Z","steps":["trace[2123919008] 'agreement among raft nodes before linearized reading' (duration: 169.018622ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:34.239146Z","caller":"traceutil/trace.go:171","msg":"trace[1601588224] linearizableReadLoop","detail":"{readStateIndex:4602; appliedIndex:4601; }","duration":"172.930052ms","start":"2026-05-05T10:21:34.066166Z","end":"2026-05-05T10:21:34.239096Z","steps":["trace[1601588224] 'read index received' (duration: 116.718335ms)","trace[1601588224] 'applied index is now lower than readState.Index' (duration: 56.208167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:34.239515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.315537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-05-05T10:21:34.239569Z","caller":"traceutil/trace.go:171","msg":"trace[242409397] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4409; }","duration":"173.428728ms","start":"2026-05-05T10:21:34.066125Z","end":"2026-05-05T10:21:34.239554Z","steps":["trace[242409397] 'agreement among raft nodes before linearized reading' (duration: 173.119144ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:34.999341Z","caller":"traceutil/trace.go:171","msg":"trace[1418914609] transaction","detail":"{read_only:false; response_revision:4424; number_of_response:1; }","duration":"212.033023ms","start":"2026-05-05T10:21:34.787287Z","end":"2026-05-05T10:21:34.99932Z","steps":["trace[1418914609] 'process raft request' (duration: 211.79501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:38.910959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.182302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.24\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T10:21:38.911036Z","caller":"traceutil/trace.go:171","msg":"trace[1863750141] range","detail":"{range_begin:/registry/masterleases/199.19.213.24; range_end:; response_count:1; response_revision:4512; }","duration":"159.314454ms","start":"2026-05-05T10:21:38.751703Z","end":"2026-05-05T10:21:38.911017Z","steps":["trace[1863750141] 'range keys from in-memory index tree' (duration: 158.942889ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:39.203946Z","caller":"traceutil/trace.go:171","msg":"trace[656668191] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4707; }","duration":"273.479703ms","start":"2026-05-05T10:21:38.930442Z","end":"2026-05-05T10:21:39.203922Z","steps":["trace[656668191] 'read index received' (duration: 8.795231ms)","trace[656668191] 'applied index is now lower than readState.Index' (duration: 264.681292ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:39.204067Z","caller":"traceutil/trace.go:171","msg":"trace[956288389] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"285.407887ms","start":"2026-05-05T10:21:38.918646Z","end":"2026-05-05T10:21:39.204053Z","steps":["trace[956288389] 'process raft request' (duration: 263.994882ms)","trace[956288389] 'compare' (duration: 21.15018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:39.204297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.833798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:21:39.204397Z","caller":"traceutil/trace.go:171","msg":"trace[1128327872] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4513; }","duration":"273.99396ms","start":"2026-05-05T10:21:38.930382Z","end":"2026-05-05T10:21:39.204376Z","steps":["trace[1128327872] 'agreement among raft nodes before linearized reading' (duration: 273.839148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:39.233973Z","caller":"traceutil/trace.go:171","msg":"trace[415654171] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"293.67362ms","start":"2026-05-05T10:21:38.94028Z","end":"2026-05-05T10:21:39.233954Z","steps":["trace[415654171] 'process raft request' (duration: 293.438507ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:39.234066Z","caller":"traceutil/trace.go:171","msg":"trace[880469741] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"175.983581ms","start":"2026-05-05T10:21:39.058055Z","end":"2026-05-05T10:21:39.234039Z","steps":["trace[880469741] 'process raft request' (duration: 175.806978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:39.234134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.100877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab8b666f-43f6-460d-9bf6-5789b9b19e44\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-05-05T10:21:39.234525Z","caller":"traceutil/trace.go:171","msg":"trace[624889789] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab8b666f-43f6-460d-9bf6-5789b9b19e44; range_end:; response_count:1; response_revision:4515; }","duration":"286.523182ms","start":"2026-05-05T10:21:38.947983Z","end":"2026-05-05T10:21:39.234506Z","steps":["trace[624889789] 'agreement among raft nodes before linearized reading' (duration: 286.021055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:39.234244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.504971ms","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-05-05T10:21:39.234631Z","caller":"traceutil/trace.go:171","msg":"trace[1516737735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4515; }","duration":"217.923616ms","start":"2026-05-05T10:21:39.016694Z","end":"2026-05-05T10:21:39.234617Z","steps":["trace[1516737735] 'agreement among raft nodes before linearized reading' (duration: 217.47671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:39.234227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.96368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T10:21:39.234799Z","caller":"traceutil/trace.go:171","msg":"trace[1997397318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4515; }","duration":"208.547019ms","start":"2026-05-05T10:21:39.026237Z","end":"2026-05-05T10:21:39.234784Z","steps":["trace[1997397318] 'agreement among raft nodes before linearized reading' (duration: 207.738007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:39.234244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.795303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-05T10:21:39.235095Z","caller":"traceutil/trace.go:171","msg":"trace[1431591696] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4515; }","duration":"207.485254ms","start":"2026-05-05T10:21:39.027405Z","end":"2026-05-05T10:21:39.23489Z","steps":["trace[1431591696] 'agreement among raft nodes before linearized reading' (duration: 206.713503ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:40.996496Z","caller":"traceutil/trace.go:171","msg":"trace[921355111] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"100.980007ms","start":"2026-05-05T10:21:40.895484Z","end":"2026-05-05T10:21:40.996464Z","steps":["trace[921355111] 'process raft request' (duration: 91.821151ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:40.996749Z","caller":"traceutil/trace.go:171","msg":"trace[1267538676] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"101.107189ms","start":"2026-05-05T10:21:40.895598Z","end":"2026-05-05T10:21:40.996705Z","steps":["trace[1267538676] 'process raft request' (duration: 100.731484ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:40.996835Z","caller":"traceutil/trace.go:171","msg":"trace[49731673] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"101.254123ms","start":"2026-05-05T10:21:40.895548Z","end":"2026-05-05T10:21:40.996802Z","steps":["trace[49731673] 'process raft request' (duration: 100.731735ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:44.019854Z","caller":"traceutil/trace.go:171","msg":"trace[1614370267] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"108.067051ms","start":"2026-05-05T10:21:43.911759Z","end":"2026-05-05T10:21:44.019826Z","steps":["trace[1614370267] 'process raft request' (duration: 107.889198ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:48.613421Z","caller":"traceutil/trace.go:171","msg":"trace[1749642862] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"191.885806ms","start":"2026-05-05T10:21:48.4215Z","end":"2026-05-05T10:21:48.613386Z","steps":["trace[1749642862] 'process raft request' (duration: 133.171656ms)","trace[1749642862] 'compare' (duration: 58.227184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:52.591071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789510113855827835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T10:21:52.620747Z","caller":"traceutil/trace.go:171","msg":"trace[1727647759] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"667.792394ms","start":"2026-05-05T10:21:51.952923Z","end":"2026-05-05T10:21:52.620716Z","steps":["trace[1727647759] 'process raft request' (duration: 667.547321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.620923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:51.952911Z","time spent":"667.920336ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10790,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T10:21:52.642674Z","caller":"traceutil/trace.go:171","msg":"trace[1096880798] linearizableReadLoop","detail":"{readStateIndex:4918; appliedIndex:4916; }","duration":"551.747048ms","start":"2026-05-05T10:21:52.090899Z","end":"2026-05-05T10:21:52.642647Z","steps":["trace[1096880798] 'read index received' (duration: 529.487505ms)","trace[1096880798] 'applied index is now lower than readState.Index' (duration: 22.258253ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:52.64277Z","caller":"traceutil/trace.go:171","msg":"trace[687316175] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"655.796001ms","start":"2026-05-05T10:21:51.986933Z","end":"2026-05-05T10:21:52.642729Z","steps":["trace[687316175] 'process raft request' (duration: 655.543158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.643005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:51.986912Z","time spent":"655.928273ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T10:21:52.643003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.088632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T10:21:52.643297Z","caller":"traceutil/trace.go:171","msg":"trace[1623420177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4713; }","duration":"552.434156ms","start":"2026-05-05T10:21:52.090843Z","end":"2026-05-05T10:21:52.643278Z","steps":["trace[1623420177] 'agreement among raft nodes before linearized reading' (duration: 552.007311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.643374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:52.090815Z","time spent":"552.543418ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T10:21:52.812385Z","caller":"traceutil/trace.go:171","msg":"trace[1904320015] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"180.316329ms","start":"2026-05-05T10:21:52.632041Z","end":"2026-05-05T10:21:52.812357Z","steps":["trace[1904320015] 'process raft request' (duration: 123.039571ms)","trace[1904320015] 'compare' (duration: 57.039605ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:52.812974Z","caller":"traceutil/trace.go:171","msg":"trace[1235650243] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"179.761273ms","start":"2026-05-05T10:21:52.633178Z","end":"2026-05-05T10:21:52.812939Z","steps":["trace[1235650243] 'process raft request' (duration: 179.132104ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:52.813071Z","caller":"traceutil/trace.go:171","msg":"trace[1618978383] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"178.687588ms","start":"2026-05-05T10:21:52.634341Z","end":"2026-05-05T10:21:52.813029Z","steps":["trace[1618978383] 'process raft request' (duration: 178.596777ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:52.813056Z","caller":"traceutil/trace.go:171","msg":"trace[745570562] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4918; }","duration":"170.276784ms","start":"2026-05-05T10:21:52.642749Z","end":"2026-05-05T10:21:52.813026Z","steps":["trace[745570562] 'read index received' (duration: 112.347066ms)","trace[745570562] 'applied index is now lower than readState.Index' (duration: 57.926418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:52.813267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.040588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-05T10:21:52.813309Z","caller":"traceutil/trace.go:171","msg":"trace[155322549] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4718; }","duration":"562.17853ms","start":"2026-05-05T10:21:52.25112Z","end":"2026-05-05T10:21:52.813298Z","steps":["trace[155322549] 'agreement among raft nodes before linearized reading' (duration: 562.041548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.813305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.823864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-05T10:21:52.813336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:52.251094Z","time spent":"562.23362ms","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T10:21:52.813342Z","caller":"traceutil/trace.go:171","msg":"trace[1678192384] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4718; }","duration":"700.923075ms","start":"2026-05-05T10:21:52.112407Z","end":"2026-05-05T10:21:52.81333Z","steps":["trace[1678192384] 'agreement among raft nodes before linearized reading' (duration: 700.823514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.813371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:52.112367Z","time spent":"700.997146ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-05T10:21:52.813006Z","caller":"traceutil/trace.go:171","msg":"trace[494887849] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"179.358787ms","start":"2026-05-05T10:21:52.633634Z","end":"2026-05-05T10:21:52.812993Z","steps":["trace[494887849] 'process raft request' (duration: 179.247785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.81351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.055375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T10:21:52.813037Z","caller":"traceutil/trace.go:171","msg":"trace[1106873856] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"165.257445ms","start":"2026-05-05T10:21:52.647769Z","end":"2026-05-05T10:21:52.813027Z","steps":["trace[1106873856] 'process raft request' (duration: 165.203994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.813585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.033384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-05-05T10:21:52.813594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.796348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-05-05T10:21:52.813561Z","caller":"traceutil/trace.go:171","msg":"trace[1266093697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4718; }","duration":"720.158906ms","start":"2026-05-05T10:21:52.09339Z","end":"2026-05-05T10:21:52.813549Z","steps":["trace[1266093697] 'agreement among raft nodes before linearized reading' (duration: 719.893932ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:52.813619Z","caller":"traceutil/trace.go:171","msg":"trace[1760645004] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4718; }","duration":"136.84875ms","start":"2026-05-05T10:21:52.676762Z","end":"2026-05-05T10:21:52.813611Z","steps":["trace[1760645004] 'agreement among raft nodes before linearized reading' (duration: 136.778509ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:52.813615Z","caller":"traceutil/trace.go:171","msg":"trace[348293070] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4718; }","duration":"160.106115ms","start":"2026-05-05T10:21:52.6535Z","end":"2026-05-05T10:21:52.813606Z","steps":["trace[348293070] 'agreement among raft nodes before linearized reading' (duration: 160.025224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:52.813637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T10:21:52.093383Z","time spent":"720.243057ms","remote":"127.0.0.1:42710","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-05-05T10:21:53.122965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.596149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-05-05T10:21:53.123052Z","caller":"traceutil/trace.go:171","msg":"trace[339965314] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4722; }","duration":"207.716522ms","start":"2026-05-05T10:21:52.91531Z","end":"2026-05-05T10:21:53.123026Z","steps":["trace[339965314] 'range keys from in-memory index tree' (duration: 207.408157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:53.123184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.911626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-05T10:21:53.123294Z","caller":"traceutil/trace.go:171","msg":"trace[1864577230] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4722; }","duration":"206.067888ms","start":"2026-05-05T10:21:52.917206Z","end":"2026-05-05T10:21:53.123274Z","steps":["trace[1864577230] 'range keys from in-memory index tree' (duration: 205.712884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:53.123542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.179708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-05-05T10:21:53.123584Z","caller":"traceutil/trace.go:171","msg":"trace[985851376] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4722; }","duration":"200.240929ms","start":"2026-05-05T10:21:52.923333Z","end":"2026-05-05T10:21:53.123574Z","steps":["trace[985851376] 'range keys from in-memory index tree' (duration: 200.070046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:53.123665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.17862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T10:21:53.123779Z","caller":"traceutil/trace.go:171","msg":"trace[514536880] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4722; }","duration":"167.403163ms","start":"2026-05-05T10:21:52.956347Z","end":"2026-05-05T10:21:53.12375Z","steps":["trace[514536880] 'range keys from in-memory index tree' (duration: 167.000577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:53.289274Z","caller":"traceutil/trace.go:171","msg":"trace[738864785] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"159.416184ms","start":"2026-05-05T10:21:53.129826Z","end":"2026-05-05T10:21:53.289242Z","steps":["trace[738864785] 'process raft request' (duration: 120.8602ms)","trace[738864785] 'compare' (duration: 38.153238ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:21:53.289301Z","caller":"traceutil/trace.go:171","msg":"trace[1855104606] linearizableReadLoop","detail":"{readStateIndex:4931; appliedIndex:4929; }","duration":"130.613703ms","start":"2026-05-05T10:21:53.158663Z","end":"2026-05-05T10:21:53.289277Z","steps":["trace[1855104606] 'read index received' (duration: 92.09014ms)","trace[1855104606] 'applied index is now lower than readState.Index' (duration: 38.522483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:53.28976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.103198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ab8b666f-43f6-460d-9bf6-5789b9b19e44\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-05T10:21:53.289816Z","caller":"traceutil/trace.go:171","msg":"trace[1972356206] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ab8b666f-43f6-460d-9bf6-5789b9b19e44; range_end:; response_count:1; response_revision:4726; }","duration":"161.184319ms","start":"2026-05-05T10:21:53.128616Z","end":"2026-05-05T10:21:53.2898Z","steps":["trace[1972356206] 'agreement among raft nodes before linearized reading' (duration: 160.989387ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:53.289927Z","caller":"traceutil/trace.go:171","msg":"trace[681000890] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"159.648297ms","start":"2026-05-05T10:21:53.130231Z","end":"2026-05-05T10:21:53.289879Z","steps":["trace[681000890] 'process raft request' (duration: 158.947138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T10:21:53.300704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.252633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T10:21:53.300749Z","caller":"traceutil/trace.go:171","msg":"trace[1015444976] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4727; }","duration":"137.308004ms","start":"2026-05-05T10:21:53.163429Z","end":"2026-05-05T10:21:53.300737Z","steps":["trace[1015444976] 'agreement among raft nodes before linearized reading' (duration: 137.171572ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:53.479715Z","caller":"traceutil/trace.go:171","msg":"trace[1634347938] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4936; }","duration":"106.02846ms","start":"2026-05-05T10:21:53.37366Z","end":"2026-05-05T10:21:53.479689Z","steps":["trace[1634347938] 'read index received' (duration: 75.12294ms)","trace[1634347938] 'applied index is now lower than readState.Index' (duration: 30.90436ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T10:21:53.480125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.435745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T10:21:53.480176Z","caller":"traceutil/trace.go:171","msg":"trace[32677119] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4732; }","duration":"106.508106ms","start":"2026-05-05T10:21:53.373656Z","end":"2026-05-05T10:21:53.480164Z","steps":["trace[32677119] 'agreement among raft nodes before linearized reading' (duration: 106.139651ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T10:21:53.480446Z","caller":"traceutil/trace.go:171","msg":"trace[1568534679] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"135.821775ms","start":"2026-05-05T10:21:53.344393Z","end":"2026-05-05T10:21:53.480215Z","steps":["trace[1568534679] 'process raft request' (duration: 104.49064ms)","trace[1568534679] 'compare' (duration: 30.661446ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T10:25:05.967681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3229} {"level":"info","ts":"2026-05-05T10:25:06.052921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3229,"took":"84.080356ms","hash":4281448006,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T10:25:06.052977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4281448006,"revision":3229,"compact-revision":-1} {"level":"info","ts":"2026-05-05T10:25:58.587119Z","caller":"traceutil/trace.go:171","msg":"trace[1157901946] transaction","detail":"{read_only:false; response_revision:6847; number_of_response:1; }","duration":"105.562147ms","start":"2026-05-05T10:25:58.481515Z","end":"2026-05-05T10:25:58.587077Z","steps":["trace[1157901946] 'process raft request' (duration: 13.556875ms)","trace[1157901946] 'compare' (duration: 91.866369ms)"],"step_count":2}