{"level":"warn","ts":"2026-07-01T07:21:41.152896Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:21:41.152993Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.225: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.225:2380","--initial-cluster=instance=https://199.19.213.225:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.225:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.225:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:21:41.153071Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:21:41.153086Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-07-01T07:21:41.153104Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:21:41.153772Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"]} {"level":"info","ts":"2026-07-01T07:21:41.153855Z","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.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.225:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:21:41.261683Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"107.560576ms"} {"level":"info","ts":"2026-07-01T07:21:41.26773Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7b89c171c5fe95c","cluster-id":"5746414c29a21eae"} {"level":"info","ts":"2026-07-01T07:21:41.267911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:21:41.267945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 0"} {"level":"info","ts":"2026-07-01T07:21:41.268027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7b89c171c5fe95c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:21:41.268049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 1"} {"level":"info","ts":"2026-07-01T07:21:41.268206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"warn","ts":"2026-07-01T07:21:41.273644Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:21:41.274968Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:21:41.276259Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T07:21:41.278528Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7b89c171c5fe95c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:21:41.278676Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7b89c171c5fe95c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:21:41.279019Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:21:41.279064Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:21:41.279085Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:21:41.28132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"info","ts":"2026-07-01T07:21:41.281657Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","added-peer-id":"b7b89c171c5fe95c","added-peer-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-07-01T07:21:41.283334Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:21:41.283477Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-07-01T07:21:41.283519Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-07-01T07:21:41.283648Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7b89c171c5fe95c","initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:21:41.283705Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:21:42.26951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:21:42.269604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:21:42.269658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgPreVoteResp from b7b89c171c5fe95c at term 1"} {"level":"info","ts":"2026-07-01T07:21:42.269683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:21:42.269703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgVoteResp from b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-07-01T07:21:42.269726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became leader at term 2"} {"level":"info","ts":"2026-07-01T07:21:42.269743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7b89c171c5fe95c elected leader b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-07-01T07:21:42.270498Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:21:42.271443Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7b89c171c5fe95c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.225:2379]}","request-path":"/0/members/b7b89c171c5fe95c/attributes","cluster-id":"5746414c29a21eae","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:21:42.27145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:21:42.271478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:21:42.271726Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:21:42.271756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:21:42.272442Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:21:42.272578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:21:42.272621Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:21:42.274986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.225:2379"} {"level":"info","ts":"2026-07-01T07:21:42.275371Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:21:43.597881Z","caller":"traceutil/trace.go:171","msg":"trace[1547087154] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"103.109745ms","start":"2026-07-01T07:21:43.494745Z","end":"2026-07-01T07:21:43.597855Z","steps":["trace[1547087154] 'process raft request' (duration: 102.886864ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.864873Z","caller":"traceutil/trace.go:171","msg":"trace[342297362] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"369.731968ms","start":"2026-07-01T07:21:43.495111Z","end":"2026-07-01T07:21:43.864843Z","steps":["trace[342297362] 'process raft request' (duration: 333.0536ms)","trace[342297362] 'compare' (duration: 36.461047ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:43.865102Z","caller":"traceutil/trace.go:171","msg":"trace[1372117249] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"298.347026ms","start":"2026-07-01T07:21:43.566726Z","end":"2026-07-01T07:21:43.865073Z","steps":["trace[1372117249] 'process raft request' (duration: 298.027114ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.86535Z","caller":"traceutil/trace.go:171","msg":"trace[432899066] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"298.502657ms","start":"2026-07-01T07:21:43.56682Z","end":"2026-07-01T07:21:43.865323Z","steps":["trace[432899066] 'process raft request' (duration: 298.361246ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.865534Z","caller":"traceutil/trace.go:171","msg":"trace[1001904681] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"362.164985ms","start":"2026-07-01T07:21:43.503308Z","end":"2026-07-01T07:21:43.865473Z","steps":["trace[1001904681] 'read index received' (duration: 94.380653ms)","trace[1001904681] 'applied index is now lower than readState.Index' (duration: 267.783532ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:43.865626Z","caller":"traceutil/trace.go:171","msg":"trace[2027561822] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"298.408607ms","start":"2026-07-01T07:21:43.567201Z","end":"2026-07-01T07:21:43.86561Z","steps":["trace[2027561822] 'process raft request' (duration: 298.151716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.866583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.345307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.866639Z","caller":"traceutil/trace.go:171","msg":"trace[1659161027] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"328.418357ms","start":"2026-07-01T07:21:43.538206Z","end":"2026-07-01T07:21:43.866624Z","steps":["trace[1659161027] 'agreement among raft nodes before linearized reading' (duration: 328.344637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.866686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.538194Z","time spent":"328.482227ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-01T07:21:43.865868Z","caller":"traceutil/trace.go:171","msg":"trace[153530640] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"298.949631ms","start":"2026-07-01T07:21:43.566904Z","end":"2026-07-01T07:21:43.865853Z","steps":["trace[153530640] 'process raft request' (duration: 298.368327ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.865912Z","caller":"traceutil/trace.go:171","msg":"trace[452327693] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"298.92039ms","start":"2026-07-01T07:21:43.56698Z","end":"2026-07-01T07:21:43.8659Z","steps":["trace[452327693] 'process raft request' (duration: 298.323416ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.865954Z","caller":"traceutil/trace.go:171","msg":"trace[1450176622] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"287.216189ms","start":"2026-07-01T07:21:43.578729Z","end":"2026-07-01T07:21:43.865945Z","steps":["trace[1450176622] 'process raft request' (duration: 286.681475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.595952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.867089Z","caller":"traceutil/trace.go:171","msg":"trace[2002365552] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"303.688303ms","start":"2026-07-01T07:21:43.563364Z","end":"2026-07-01T07:21:43.867052Z","steps":["trace[2002365552] 'agreement among raft nodes before linearized reading' (duration: 303.568802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.563355Z","time spent":"303.776273ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-01T07:21:43.867156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.459279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.867201Z","caller":"traceutil/trace.go:171","msg":"trace[1017798816] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:0; response_revision:11; }","duration":"304.545329ms","start":"2026-07-01T07:21:43.562643Z","end":"2026-07-01T07:21:43.867188Z","steps":["trace[1017798816] 'agreement among raft nodes before linearized reading' (duration: 304.481358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.562619Z","time spent":"304.610859ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.225\" "} {"level":"warn","ts":"2026-07-01T07:21:43.867423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.506173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T07:21:43.867453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.623596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.867479Z","caller":"traceutil/trace.go:171","msg":"trace[1388935475] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"232.601055ms","start":"2026-07-01T07:21:43.634863Z","end":"2026-07-01T07:21:43.867464Z","steps":["trace[1388935475] 'agreement among raft nodes before linearized reading' (duration: 232.506164ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.867497Z","caller":"traceutil/trace.go:171","msg":"trace[391351899] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"319.680716ms","start":"2026-07-01T07:21:43.547804Z","end":"2026-07-01T07:21:43.867484Z","steps":["trace[391351899] 'agreement among raft nodes before linearized reading' (duration: 319.607365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.547793Z","time spent":"319.760706ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-01T07:21:43.865985Z","caller":"traceutil/trace.go:171","msg":"trace[249468496] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"298.710939ms","start":"2026-07-01T07:21:43.567265Z","end":"2026-07-01T07:21:43.865976Z","steps":["trace[249468496] 'process raft request' (duration: 298.110375ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:43.866111Z","caller":"traceutil/trace.go:171","msg":"trace[64445434] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"280.387661ms","start":"2026-07-01T07:21:43.585707Z","end":"2026-07-01T07:21:43.866095Z","steps":["trace[64445434] 'process raft request' (duration: 279.734886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.866251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.92979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.867632Z","caller":"traceutil/trace.go:171","msg":"trace[1342196961] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"364.413271ms","start":"2026-07-01T07:21:43.503206Z","end":"2026-07-01T07:21:43.86762Z","steps":["trace[1342196961] 'agreement among raft nodes before linearized reading' (duration: 362.943521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.503198Z","time spent":"364.468531ms","remote":"127.0.0.1:53308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-01T07:21:43.867686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.618807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.86773Z","caller":"traceutil/trace.go:171","msg":"trace[459636700] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"251.662077ms","start":"2026-07-01T07:21:43.616055Z","end":"2026-07-01T07:21:43.867717Z","steps":["trace[459636700] 'agreement among raft nodes before linearized reading' (duration: 251.597637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.15175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:43.867805Z","caller":"traceutil/trace.go:171","msg":"trace[684621511] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"323.268301ms","start":"2026-07-01T07:21:43.544516Z","end":"2026-07-01T07:21:43.867784Z","steps":["trace[684621511] 'agreement among raft nodes before linearized reading' (duration: 323.08121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.867849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.544503Z","time spent":"323.340531ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-01T07:21:43.867957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.84574ms","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-07-01T07:21:43.868018Z","caller":"traceutil/trace.go:171","msg":"trace[402150752] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"301.920831ms","start":"2026-07-01T07:21:43.56608Z","end":"2026-07-01T07:21:43.868001Z","steps":["trace[402150752] 'agreement among raft nodes before linearized reading' (duration: 301.83554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:43.868069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.566041Z","time spent":"302.015243ms","remote":"127.0.0.1:53030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-01T07:21:43.868927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.495099Z","time spent":"369.94143ms","remote":"127.0.0.1:53006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:21:43.868998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.566899Z","time spent":"300.009988ms","remote":"127.0.0.1:53344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:21:43.869216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.566968Z","time spent":"300.010807ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:21:43.869321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:43.567256Z","time spent":"300.739783ms","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:21:44.090606Z","caller":"traceutil/trace.go:171","msg":"trace[1299616512] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"219.679359ms","start":"2026-07-01T07:21:43.870903Z","end":"2026-07-01T07:21:44.090582Z","steps":["trace[1299616512] 'read index received' (duration: 218.581222ms)","trace[1299616512] 'applied index is now lower than readState.Index' (duration: 1.097177ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:44.090643Z","caller":"traceutil/trace.go:171","msg":"trace[633342740] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"220.087853ms","start":"2026-07-01T07:21:43.870538Z","end":"2026-07-01T07:21:44.090626Z","steps":["trace[633342740] 'process raft request' (duration: 218.827405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:44.090799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.88615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:44.090837Z","caller":"traceutil/trace.go:171","msg":"trace[329282308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"219.956582ms","start":"2026-07-01T07:21:43.87087Z","end":"2026-07-01T07:21:44.090827Z","steps":["trace[329282308] 'agreement among raft nodes before linearized reading' (duration: 219.814721ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.098735Z","caller":"traceutil/trace.go:171","msg":"trace[1155334307] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"227.744536ms","start":"2026-07-01T07:21:43.870972Z","end":"2026-07-01T07:21:44.098717Z","steps":["trace[1155334307] 'process raft request' (duration: 227.640115ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.098843Z","caller":"traceutil/trace.go:171","msg":"trace[887404268] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"225.941964ms","start":"2026-07-01T07:21:43.872886Z","end":"2026-07-01T07:21:44.098828Z","steps":["trace[887404268] 'process raft request' (duration: 225.810713ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.098801Z","caller":"traceutil/trace.go:171","msg":"trace[862047955] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"226.8916ms","start":"2026-07-01T07:21:43.871875Z","end":"2026-07-01T07:21:44.098767Z","steps":["trace[862047955] 'process raft request' (duration: 226.79175ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.09891Z","caller":"traceutil/trace.go:171","msg":"trace[750460907] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"224.368052ms","start":"2026-07-01T07:21:43.874502Z","end":"2026-07-01T07:21:44.09887Z","steps":["trace[750460907] 'process raft request' (duration: 224.239211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:44.098985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.012858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-ftwps\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-07-01T07:21:44.099051Z","caller":"traceutil/trace.go:171","msg":"trace[1850011039] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-ftwps; range_end:; response_count:1; response_revision:19; }","duration":"228.106568ms","start":"2026-07-01T07:21:43.87093Z","end":"2026-07-01T07:21:44.099037Z","steps":["trace[1850011039] 'agreement among raft nodes before linearized reading' (duration: 227.997367ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.099109Z","caller":"traceutil/trace.go:171","msg":"trace[1507601906] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"225.223868ms","start":"2026-07-01T07:21:43.873878Z","end":"2026-07-01T07:21:44.099102Z","steps":["trace[1507601906] 'process raft request' (duration: 224.843245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:44.099108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.910307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-01T07:21:44.099154Z","caller":"traceutil/trace.go:171","msg":"trace[1930654995] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"222.295458ms","start":"2026-07-01T07:21:43.876828Z","end":"2026-07-01T07:21:44.099124Z","steps":["trace[1930654995] 'process raft request' (duration: 222.054686ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.099204Z","caller":"traceutil/trace.go:171","msg":"trace[1405098075] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"171.076558ms","start":"2026-07-01T07:21:43.928099Z","end":"2026-07-01T07:21:44.099176Z","steps":["trace[1405098075] 'agreement among raft nodes before linearized reading' (duration: 170.853477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:44.09923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.122679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T07:21:44.09918Z","caller":"traceutil/trace.go:171","msg":"trace[109636302] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"224.353802ms","start":"2026-07-01T07:21:43.874816Z","end":"2026-07-01T07:21:44.099169Z","steps":["trace[109636302] 'process raft request' (duration: 224.03435ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.099203Z","caller":"traceutil/trace.go:171","msg":"trace[591026134] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"224.567614ms","start":"2026-07-01T07:21:43.874558Z","end":"2026-07-01T07:21:44.099126Z","steps":["trace[591026134] 'process raft request' (duration: 224.240262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:44.099311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.629348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-01T07:21:44.099264Z","caller":"traceutil/trace.go:171","msg":"trace[110507257] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"174.19562ms","start":"2026-07-01T07:21:43.925059Z","end":"2026-07-01T07:21:44.099254Z","steps":["trace[110507257] 'agreement among raft nodes before linearized reading' (duration: 174.147979ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.099344Z","caller":"traceutil/trace.go:171","msg":"trace[1089064776] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"226.714259ms","start":"2026-07-01T07:21:43.872622Z","end":"2026-07-01T07:21:44.099336Z","steps":["trace[1089064776] 'agreement among raft nodes before linearized reading' (duration: 226.660649ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:45.094424Z","caller":"traceutil/trace.go:171","msg":"trace[1013541852] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"134.073142ms","start":"2026-07-01T07:21:44.960317Z","end":"2026-07-01T07:21:45.09439Z","steps":["trace[1013541852] 'process raft request' (duration: 112.487763ms)","trace[1013541852] 'compare' (duration: 21.457888ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:22:51.681589Z","caller":"traceutil/trace.go:171","msg":"trace[947744400] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"141.10036ms","start":"2026-07-01T07:22:51.540464Z","end":"2026-07-01T07:22:51.681564Z","steps":["trace[947744400] 'process raft request' (duration: 59.789038ms)","trace[947744400] 'compare' (duration: 80.853381ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:22:56.907082Z","caller":"traceutil/trace.go:171","msg":"trace[777919994] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"126.630282ms","start":"2026-07-01T07:22:56.780423Z","end":"2026-07-01T07:22:56.907054Z","steps":["trace[777919994] 'read index received' (duration: 123.884278ms)","trace[777919994] 'applied index is now lower than readState.Index' (duration: 2.744704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:22:56.907251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.804106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:22:56.907293Z","caller":"traceutil/trace.go:171","msg":"trace[897164084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"126.891248ms","start":"2026-07-01T07:22:56.780388Z","end":"2026-07-01T07:22:56.907279Z","steps":["trace[897164084] 'agreement among raft nodes before linearized reading' (duration: 126.780735ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:22:56.907562Z","caller":"traceutil/trace.go:171","msg":"trace[1704431427] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"136.214314ms","start":"2026-07-01T07:22:56.771167Z","end":"2026-07-01T07:22:56.907382Z","steps":["trace[1704431427] 'process raft request' (duration: 134.083404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:22:57.14759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.807596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:22:57.1478Z","caller":"traceutil/trace.go:171","msg":"trace[857805111] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"187.376754ms","start":"2026-07-01T07:22:56.960403Z","end":"2026-07-01T07:22:57.147779Z","steps":["trace[857805111] 'process raft request' (duration: 68.135378ms)","trace[857805111] 'compare' (duration: 118.663392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:22:57.717898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.614989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hk47s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T07:22:57.718083Z","caller":"traceutil/trace.go:171","msg":"trace[1292603021] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hk47s; range_end:; response_count:1; response_revision:700; }","duration":"177.853814ms","start":"2026-07-01T07:22:57.540211Z","end":"2026-07-01T07:22:57.718064Z","steps":["trace[1292603021] 'range keys from in-memory index tree' (duration: 177.501816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:22:57.718378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.988494ms","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-07-01T07:22:57.718481Z","caller":"traceutil/trace.go:171","msg":"trace[1306044821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"346.146808ms","start":"2026-07-01T07:22:57.372313Z","end":"2026-07-01T07:22:57.71846Z","steps":["trace[1306044821] 'range keys from in-memory index tree' (duration: 345.398651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:22:57.71854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:22:57.37229Z","time spent":"346.23833ms","remote":"127.0.0.1:53186","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-07-01T07:22:57.719178Z","caller":"traceutil/trace.go:171","msg":"trace[1399167907] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"164.725122ms","start":"2026-07-01T07:22:57.554436Z","end":"2026-07-01T07:22:57.719162Z","steps":["trace[1399167907] 'process raft request' (duration: 163.371741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:12.862338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.700415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fv6mf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-01T07:23:12.862418Z","caller":"traceutil/trace.go:171","msg":"trace[1823779962] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fv6mf; range_end:; response_count:1; response_revision:863; }","duration":"226.830207ms","start":"2026-07-01T07:23:12.635572Z","end":"2026-07-01T07:23:12.862402Z","steps":["trace[1823779962] 'range keys from in-memory index tree' (duration: 226.45495ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:43.920875Z","caller":"traceutil/trace.go:171","msg":"trace[435275309] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"150.772106ms","start":"2026-07-01T07:23:43.770071Z","end":"2026-07-01T07:23:43.920843Z","steps":["trace[435275309] 'process raft request' (duration: 150.401189ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:43.92087Z","caller":"traceutil/trace.go:171","msg":"trace[1039116030] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"141.282874ms","start":"2026-07-01T07:23:43.77952Z","end":"2026-07-01T07:23:43.920803Z","steps":["trace[1039116030] 'read index received' (duration: 140.886147ms)","trace[1039116030] 'applied index is now lower than readState.Index' (duration: 395.557µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:23:43.921051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.489618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:23:43.921088Z","caller":"traceutil/trace.go:171","msg":"trace[1595540190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"141.611409ms","start":"2026-07-01T07:23:43.779464Z","end":"2026-07-01T07:23:43.921076Z","steps":["trace[1595540190] 'agreement among raft nodes before linearized reading' (duration: 141.507788ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:24:28.960018Z","caller":"traceutil/trace.go:171","msg":"trace[818434950] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"203.701942ms","start":"2026-07-01T07:24:28.756295Z","end":"2026-07-01T07:24:28.959997Z","steps":["trace[818434950] 'read index received' (duration: 203.423509ms)","trace[818434950] 'applied index is now lower than readState.Index' (duration: 276.883µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:24:28.960091Z","caller":"traceutil/trace.go:171","msg":"trace[1121932413] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"221.713239ms","start":"2026-07-01T07:24:28.738334Z","end":"2026-07-01T07:24:28.960047Z","steps":["trace[1121932413] 'process raft request' (duration: 221.448686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:24:28.960351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.014096ms","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-07-01T07:24:28.960429Z","caller":"traceutil/trace.go:171","msg":"trace[1582896829] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1914; }","duration":"204.163567ms","start":"2026-07-01T07:24:28.756248Z","end":"2026-07-01T07:24:28.960412Z","steps":["trace[1582896829] 'agreement among raft nodes before linearized reading' (duration: 203.922264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:24:29.225433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.345438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:24:29.22566Z","caller":"traceutil/trace.go:171","msg":"trace[1852173784] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"260.113921ms","start":"2026-07-01T07:24:28.965514Z","end":"2026-07-01T07:24:29.225628Z","steps":["trace[1852173784] 'process raft request' (duration: 17.447099ms)","trace[1852173784] 'compare' (duration: 242.130336ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:24:29.225901Z","caller":"traceutil/trace.go:171","msg":"trace[1314605896] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1975; }","duration":"231.532282ms","start":"2026-07-01T07:24:28.994348Z","end":"2026-07-01T07:24:29.22588Z","steps":["trace[1314605896] 'read index received' (duration: 227.582552ms)","trace[1314605896] 'applied index is now lower than readState.Index' (duration: 3.94855ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:24:29.226235Z","caller":"traceutil/trace.go:171","msg":"trace[2124271876] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"260.659568ms","start":"2026-07-01T07:24:28.965534Z","end":"2026-07-01T07:24:29.226194Z","steps":["trace[2124271876] 'process raft request' (duration: 260.01283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:24:29.226286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.928846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-07-01T07:24:29.226299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.748712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"info","ts":"2026-07-01T07:24:29.226324Z","caller":"traceutil/trace.go:171","msg":"trace[1413219056] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1916; }","duration":"231.989317ms","start":"2026-07-01T07:24:28.994321Z","end":"2026-07-01T07:24:29.226311Z","steps":["trace[1413219056] 'agreement among raft nodes before linearized reading' (duration: 231.853565ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:24:29.22634Z","caller":"traceutil/trace.go:171","msg":"trace[1594762152] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1916; }","duration":"195.830754ms","start":"2026-07-01T07:24:29.030498Z","end":"2026-07-01T07:24:29.226329Z","steps":["trace[1594762152] 'agreement among raft nodes before linearized reading' (duration: 195.675322ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:24:29.347369Z","caller":"traceutil/trace.go:171","msg":"trace[1384197303] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"104.092195ms","start":"2026-07-01T07:24:29.243247Z","end":"2026-07-01T07:24:29.347339Z","steps":["trace[1384197303] 'read index received' (duration: 103.914972ms)","trace[1384197303] 'applied index is now lower than readState.Index' (duration: 175.913µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:24:29.34766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.390879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-01T07:24:29.347696Z","caller":"traceutil/trace.go:171","msg":"trace[1010435391] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1918; }","duration":"104.463859ms","start":"2026-07-01T07:24:29.243223Z","end":"2026-07-01T07:24:29.347687Z","steps":["trace[1010435391] 'agreement among raft nodes before linearized reading' (duration: 104.317288ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:24:29.347505Z","caller":"traceutil/trace.go:171","msg":"trace[663522762] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"110.612786ms","start":"2026-07-01T07:24:29.236878Z","end":"2026-07-01T07:24:29.347491Z","steps":["trace[663522762] 'process raft request' (duration: 110.306941ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:11.577772Z","caller":"traceutil/trace.go:171","msg":"trace[1962016650] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"155.864121ms","start":"2026-07-01T07:25:11.421876Z","end":"2026-07-01T07:25:11.57774Z","steps":["trace[1962016650] 'process raft request' (duration: 155.594667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:11.876276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.859891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:25:11.876398Z","caller":"traceutil/trace.go:171","msg":"trace[1126632540] linearizableReadLoop","detail":"{readStateIndex:2336; appliedIndex:2334; }","duration":"373.781841ms","start":"2026-07-01T07:25:11.502592Z","end":"2026-07-01T07:25:11.876374Z","steps":["trace[1126632540] 'read index received' (duration: 74.879201ms)","trace[1126632540] 'applied index is now lower than readState.Index' (duration: 298.90106ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:11.876492Z","caller":"traceutil/trace.go:171","msg":"trace[1496378147] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"454.352945ms","start":"2026-07-01T07:25:11.422128Z","end":"2026-07-01T07:25:11.876481Z","steps":["trace[1496378147] 'process raft request' (duration: 298.166751ms)","trace[1496378147] 'compare' (duration: 155.589938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:11.876535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:11.422112Z","time spent":"454.399915ms","remote":"127.0.0.1:53186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:25:11.877096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.518942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:25:11.877141Z","caller":"traceutil/trace.go:171","msg":"trace[683971343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2256; }","duration":"223.623143ms","start":"2026-07-01T07:25:11.653506Z","end":"2026-07-01T07:25:11.87713Z","steps":["trace[683971343] 'agreement among raft nodes before linearized reading' (duration: 223.37271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:11.877144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.537109ms","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":"info","ts":"2026-07-01T07:25:11.877223Z","caller":"traceutil/trace.go:171","msg":"trace[1162272018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2256; }","duration":"374.676781ms","start":"2026-07-01T07:25:11.502523Z","end":"2026-07-01T07:25:11.877199Z","steps":["trace[1162272018] 'agreement among raft nodes before linearized reading' (duration: 374.255156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:11.877263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:11.502501Z","time spent":"374.752581ms","remote":"127.0.0.1:36252","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-07-01T07:25:12.013865Z","caller":"traceutil/trace.go:171","msg":"trace[323212068] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"132.197219ms","start":"2026-07-01T07:25:11.881639Z","end":"2026-07-01T07:25:12.013837Z","steps":["trace[323212068] 'process raft request' (duration: 132.050158ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:12.021334Z","caller":"traceutil/trace.go:171","msg":"trace[1616952730] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"137.270845ms","start":"2026-07-01T07:25:11.884054Z","end":"2026-07-01T07:25:12.021325Z","steps":["trace[1616952730] 'process raft request' (duration: 137.207704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:29.795761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.903835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:26:29.795961Z","caller":"traceutil/trace.go:171","msg":"trace[1859802984] linearizableReadLoop","detail":"{readStateIndex:3002; appliedIndex:3001; }","duration":"122.170496ms","start":"2026-07-01T07:26:29.673762Z","end":"2026-07-01T07:26:29.795932Z","steps":["trace[1859802984] 'read index received' (duration: 8.835999ms)","trace[1859802984] 'applied index is now lower than readState.Index' (duration: 113.332456ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:29.796034Z","caller":"traceutil/trace.go:171","msg":"trace[1985660900] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"178.695696ms","start":"2026-07-01T07:26:29.617288Z","end":"2026-07-01T07:26:29.795984Z","steps":["trace[1985660900] 'process raft request' (duration: 65.38581ms)","trace[1985660900] 'compare' (duration: 112.679719ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:29.796309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.518835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-01T07:26:29.796382Z","caller":"traceutil/trace.go:171","msg":"trace[673222215] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2889; }","duration":"122.670889ms","start":"2026-07-01T07:26:29.673689Z","end":"2026-07-01T07:26:29.79636Z","steps":["trace[673222215] 'agreement among raft nodes before linearized reading' (duration: 122.32414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:30.015537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.524805ms","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-07-01T07:26:30.015648Z","caller":"traceutil/trace.go:171","msg":"trace[962032692] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2889; }","duration":"108.7277ms","start":"2026-07-01T07:26:29.906893Z","end":"2026-07-01T07:26:30.015621Z","steps":["trace[962032692] 'range keys from in-memory index tree' (duration: 108.272138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:42.879849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.81504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:42.879984Z","caller":"traceutil/trace.go:171","msg":"trace[676748532] linearizableReadLoop","detail":"{readStateIndex:4153; appliedIndex:4152; }","duration":"132.724303ms","start":"2026-07-01T07:28:42.747239Z","end":"2026-07-01T07:28:42.879964Z","steps":["trace[676748532] 'read index received' (duration: 38.951µs)","trace[676748532] 'applied index is now lower than readState.Index' (duration: 132.684202ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:42.880021Z","caller":"traceutil/trace.go:171","msg":"trace[993421742] transaction","detail":"{read_only:false; response_revision:3969; number_of_response:1; }","duration":"259.871512ms","start":"2026-07-01T07:28:42.620114Z","end":"2026-07-01T07:28:42.879986Z","steps":["trace[993421742] 'process raft request' (duration: 102.808548ms)","trace[993421742] 'compare' (duration: 156.636527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:42.880282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.032678ms","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-07-01T07:28:42.880329Z","caller":"traceutil/trace.go:171","msg":"trace[1090711576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3969; }","duration":"133.115498ms","start":"2026-07-01T07:28:42.747202Z","end":"2026-07-01T07:28:42.880318Z","steps":["trace[1090711576] 'agreement among raft nodes before linearized reading' (duration: 132.892915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:42.880493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.542099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:42.880527Z","caller":"traceutil/trace.go:171","msg":"trace[162921344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3969; }","duration":"100.633871ms","start":"2026-07-01T07:28:42.779884Z","end":"2026-07-01T07:28:42.880518Z","steps":["trace[162921344] 'agreement among raft nodes before linearized reading' (duration: 100.5809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:02.31764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.638711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:29:02.317745Z","caller":"traceutil/trace.go:171","msg":"trace[235517479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4686; }","duration":"184.819715ms","start":"2026-07-01T07:29:02.132905Z","end":"2026-07-01T07:29:02.317724Z","steps":["trace[235517479] 'range keys from in-memory index tree' (duration: 184.486649ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:02.629389Z","caller":"traceutil/trace.go:171","msg":"trace[177302185] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"111.329583ms","start":"2026-07-01T07:29:02.517993Z","end":"2026-07-01T07:29:02.629322Z","steps":["trace[177302185] 'process raft request' (duration: 15.193935ms)","trace[177302185] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 95.728081ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:02.629859Z","caller":"traceutil/trace.go:171","msg":"trace[1414613740] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"110.996196ms","start":"2026-07-01T07:29:02.518838Z","end":"2026-07-01T07:29:02.629834Z","steps":["trace[1414613740] 'process raft request' (duration: 110.391366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:02.631302Z","caller":"traceutil/trace.go:171","msg":"trace[1739236137] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"112.105004ms","start":"2026-07-01T07:29:02.519157Z","end":"2026-07-01T07:29:02.631262Z","steps":["trace[1739236137] 'process raft request' (duration: 110.455257ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:14.759387Z","caller":"traceutil/trace.go:171","msg":"trace[890266161] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"216.872218ms","start":"2026-07-01T07:29:14.542491Z","end":"2026-07-01T07:29:14.759363Z","steps":["trace[890266161] 'process raft request' (duration: 216.505253ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:14.759712Z","caller":"traceutil/trace.go:171","msg":"trace[2057603570] linearizableReadLoop","detail":"{readStateIndex:5078; appliedIndex:5077; }","duration":"209.083526ms","start":"2026-07-01T07:29:14.550234Z","end":"2026-07-01T07:29:14.759318Z","steps":["trace[2057603570] 'read index received' (duration: 208.763302ms)","trace[2057603570] 'applied index is now lower than readState.Index' (duration: 319.334µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:29:14.75988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.622525ms","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-07-01T07:29:14.759943Z","caller":"traceutil/trace.go:171","msg":"trace[531091496] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4869; }","duration":"209.731567ms","start":"2026-07-01T07:29:14.550195Z","end":"2026-07-01T07:29:14.759926Z","steps":["trace[531091496] 'agreement among raft nodes before linearized reading' (duration: 209.525884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:14.760316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.361631ms","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":"info","ts":"2026-07-01T07:29:14.760397Z","caller":"traceutil/trace.go:171","msg":"trace[304035982] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4869; }","duration":"158.501004ms","start":"2026-07-01T07:29:14.601876Z","end":"2026-07-01T07:29:14.760377Z","steps":["trace[304035982] 'agreement among raft nodes before linearized reading' (duration: 158.2847ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:14.896964Z","caller":"traceutil/trace.go:171","msg":"trace[476729682] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"128.434223ms","start":"2026-07-01T07:29:14.768504Z","end":"2026-07-01T07:29:14.896938Z","steps":["trace[476729682] 'process raft request' (duration: 85.747164ms)","trace[476729682] 'compare' (duration: 42.528557ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:14.897371Z","caller":"traceutil/trace.go:171","msg":"trace[940935185] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"128.507374ms","start":"2026-07-01T07:29:14.768844Z","end":"2026-07-01T07:29:14.897351Z","steps":["trace[940935185] 'process raft request' (duration: 128.041017ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:14.897946Z","caller":"traceutil/trace.go:171","msg":"trace[1582112598] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"128.964082ms","start":"2026-07-01T07:29:14.768969Z","end":"2026-07-01T07:29:14.897933Z","steps":["trace[1582112598] 'process raft request' (duration: 128.127928ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:14.898158Z","caller":"traceutil/trace.go:171","msg":"trace[331815924] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5078; }","duration":"129.337757ms","start":"2026-07-01T07:29:14.768808Z","end":"2026-07-01T07:29:14.898145Z","steps":["trace[331815924] 'read index received' (duration: 33.278711ms)","trace[331815924] 'applied index is now lower than readState.Index' (duration: 96.058116ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:14.898901Z","caller":"traceutil/trace.go:171","msg":"trace[1435043439] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"129.665702ms","start":"2026-07-01T07:29:14.769216Z","end":"2026-07-01T07:29:14.898882Z","steps":["trace[1435043439] 'process raft request' (duration: 128.668215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:14.899133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.274802ms","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-07-01T07:29:14.899272Z","caller":"traceutil/trace.go:171","msg":"trace[1981570186] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4874; }","duration":"130.452575ms","start":"2026-07-01T07:29:14.76879Z","end":"2026-07-01T07:29:14.899243Z","steps":["trace[1981570186] 'agreement among raft nodes before linearized reading' (duration: 129.410649ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:17.999682Z","caller":"traceutil/trace.go:171","msg":"trace[62178995] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"265.644616ms","start":"2026-07-01T07:29:17.734009Z","end":"2026-07-01T07:29:17.999654Z","steps":["trace[62178995] 'process raft request' (duration: 174.570968ms)","trace[62178995] 'compare' (duration: 90.949817ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:18.000482Z","caller":"traceutil/trace.go:171","msg":"trace[896007937] linearizableReadLoop","detail":"{readStateIndex:5130; appliedIndex:5128; }","duration":"113.421445ms","start":"2026-07-01T07:29:17.887023Z","end":"2026-07-01T07:29:18.000445Z","steps":["trace[896007937] 'read index received' (duration: 21.553055ms)","trace[896007937] 'applied index is now lower than readState.Index' (duration: 91.86716ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:18.000605Z","caller":"traceutil/trace.go:171","msg":"trace[1820706142] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"115.191074ms","start":"2026-07-01T07:29:17.885403Z","end":"2026-07-01T07:29:18.000594Z","steps":["trace[1820706142] 'process raft request' (duration: 114.904689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.000908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.403635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T07:29:18.000967Z","caller":"traceutil/trace.go:171","msg":"trace[1370723964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4919; }","duration":"111.472336ms","start":"2026-07-01T07:29:17.889479Z","end":"2026-07-01T07:29:18.000951Z","steps":["trace[1370723964] 'agreement among raft nodes before linearized reading' (duration: 111.313734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.000967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.922423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T07:29:18.001047Z","caller":"traceutil/trace.go:171","msg":"trace[1697396750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4919; }","duration":"114.039485ms","start":"2026-07-01T07:29:17.886987Z","end":"2026-07-01T07:29:18.001026Z","steps":["trace[1697396750] 'agreement among raft nodes before linearized reading' (duration: 113.819842ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:42.303942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-07-01T07:31:42.389763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"83.985398ms","hash":3263542942,"current-db-size-bytes":57987072,"current-db-size":"58 MB","current-db-size-in-use-bytes":33484800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T07:31:42.38983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3263542942,"revision":2973,"compact-revision":-1}