{"level":"warn","ts":"2026-06-13T06:07:04.849503Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T06:07:04.849636Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-06-13T06:07:04.849772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T06:07:04.849795Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-13T06:07:04.849859Z","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-06-13T06:07:04.850494Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-06-13T06:07:04.850566Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-06-13T06:07:04.854828Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.04991ms"} {"level":"info","ts":"2026-06-13T06:07:04.862634Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-06-13T06:07:04.862794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T06:07:04.862825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-06-13T06:07:04.862835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T06:07:04.86285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-06-13T06:07:04.862979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-06-13T06:07:04.868776Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T06:07:04.879127Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T06:07:04.881328Z","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-06-13T06:07:04.883149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T06:07:04.883256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T06:07:04.883456Z","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-06-13T06:07:04.883494Z","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-06-13T06:07:04.883503Z","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-06-13T06:07:04.885696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-06-13T06:07:04.885907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-13T06:07:04.886655Z","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-06-13T06:07:04.886894Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T06:07:04.886929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T06:07:04.887002Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-13T06:07:04.887019Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-13T06:07:05.364091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T06:07:05.364183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T06:07:05.364642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-06-13T06:07:05.364734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-06-13T06:07:05.364756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-13T06:07:05.364824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-06-13T06:07:05.364887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-13T06:07:05.365772Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T06:07:05.367175Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T06:07:05.367369Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T06:07:05.367391Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T06:07:05.367435Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T06:07:05.36746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T06:07:05.367361Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T06:07:05.367997Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T06:07:05.36835Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T06:07:05.369468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-06-13T06:07:05.375188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T06:07:07.146974Z","caller":"traceutil/trace.go:171","msg":"trace[2096386949] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"462.609949ms","start":"2026-06-13T06:07:06.684319Z","end":"2026-06-13T06:07:07.146929Z","steps":["trace[2096386949] 'process raft request' (duration: 462.326313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.147602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.382199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.147686Z","caller":"traceutil/trace.go:171","msg":"trace[1885877960] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"453.490745ms","start":"2026-06-13T06:07:06.69418Z","end":"2026-06-13T06:07:07.147671Z","steps":["trace[1885877960] 'agreement among raft nodes before linearized reading' (duration: 452.822258ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:07.146911Z","caller":"traceutil/trace.go:171","msg":"trace[809189038] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"452.692661ms","start":"2026-06-13T06:07:06.694194Z","end":"2026-06-13T06:07:07.146887Z","steps":["trace[809189038] 'read index received' (duration: 452.436538ms)","trace[809189038] 'applied index is now lower than readState.Index' (duration: 255.043µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T06:07:07.147732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.694173Z","time spent":"453.541968ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-06-13T06:07:07.147708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.684309Z","time spent":"462.754847ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T06:07:07.160111Z","caller":"traceutil/trace.go:171","msg":"trace[620325352] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"402.507457ms","start":"2026-06-13T06:07:06.757571Z","end":"2026-06-13T06:07:07.160078Z","steps":["trace[620325352] 'process raft request' (duration: 402.315246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.160262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.757557Z","time spent":"402.611081ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:07.160305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.495374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-13T06:07:07.160348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.705777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.160386Z","caller":"traceutil/trace.go:171","msg":"trace[850347725] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"328.820094ms","start":"2026-06-13T06:07:06.831552Z","end":"2026-06-13T06:07:07.160372Z","steps":["trace[850347725] 'agreement among raft nodes before linearized reading' (duration: 328.663305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.160441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.831539Z","time spent":"328.895188ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-13T06:07:07.160441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.785638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-13T06:07:07.160492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.792358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.160504Z","caller":"traceutil/trace.go:171","msg":"trace[2034471186] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"402.816464ms","start":"2026-06-13T06:07:06.757637Z","end":"2026-06-13T06:07:07.160453Z","steps":["trace[2034471186] 'process raft request' (duration: 402.38955ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:07.16053Z","caller":"traceutil/trace.go:171","msg":"trace[1412633508] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"334.901424ms","start":"2026-06-13T06:07:06.825613Z","end":"2026-06-13T06:07:07.160514Z","steps":["trace[1412633508] 'agreement among raft nodes before linearized reading' (duration: 334.809009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.160636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.658223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.160708Z","caller":"traceutil/trace.go:171","msg":"trace[158149708] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"349.740448ms","start":"2026-06-13T06:07:06.810931Z","end":"2026-06-13T06:07:07.160671Z","steps":["trace[158149708] 'agreement among raft nodes before linearized reading' (duration: 349.656393ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:07.160565Z","caller":"traceutil/trace.go:171","msg":"trace[1902208562] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"401.862402ms","start":"2026-06-13T06:07:06.75869Z","end":"2026-06-13T06:07:07.160552Z","steps":["trace[1902208562] 'agreement among raft nodes before linearized reading' (duration: 401.772267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.1607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.8256Z","time spent":"335.082334ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-06-13T06:07:07.160762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.758685Z","time spent":"402.063803ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-13T06:07:07.160753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.810907Z","time spent":"349.834443ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-13T06:07:07.1604Z","caller":"traceutil/trace.go:171","msg":"trace[3782296] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"464.57748ms","start":"2026-06-13T06:07:06.695778Z","end":"2026-06-13T06:07:07.160356Z","steps":["trace[3782296] 'agreement among raft nodes before linearized reading' (duration: 464.427991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.160863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.695772Z","time spent":"465.058845ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-06-13T06:07:07.160896Z","caller":"traceutil/trace.go:171","msg":"trace[730786413] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"385.484924ms","start":"2026-06-13T06:07:06.775343Z","end":"2026-06-13T06:07:07.160828Z","steps":["trace[730786413] 'process raft request' (duration: 384.822367ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:07.160879Z","caller":"traceutil/trace.go:171","msg":"trace[1229216898] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"402.281545ms","start":"2026-06-13T06:07:06.758461Z","end":"2026-06-13T06:07:07.160742Z","steps":["trace[1229216898] 'process raft request' (duration: 401.587167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.829336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.16106Z","caller":"traceutil/trace.go:171","msg":"trace[807804492] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"417.87613ms","start":"2026-06-13T06:07:06.743174Z","end":"2026-06-13T06:07:07.16105Z","steps":["trace[807804492] 'agreement among raft nodes before linearized reading' (duration: 417.787884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.775321Z","time spent":"385.681214ms","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:07.161128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.743169Z","time spent":"417.950144ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-13T06:07:07.161149Z","caller":"traceutil/trace.go:171","msg":"trace[15445476] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"401.82994ms","start":"2026-06-13T06:07:06.759304Z","end":"2026-06-13T06:07:07.161134Z","steps":["trace[15445476] 'process raft request' (duration: 400.797523ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:07.161217Z","caller":"traceutil/trace.go:171","msg":"trace[1526408191] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"385.887857ms","start":"2026-06-13T06:07:06.775323Z","end":"2026-06-13T06:07:07.161211Z","steps":["trace[1526408191] 'process raft request' (duration: 384.812947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.758453Z","time spent":"402.630485ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:07.16125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.775312Z","time spent":"385.925198ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:07.161251Z","caller":"traceutil/trace.go:171","msg":"trace[2144082466] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"401.66325ms","start":"2026-06-13T06:07:06.758516Z","end":"2026-06-13T06:07:07.160179Z","steps":["trace[2144082466] 'process raft request' (duration: 401.561875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.118463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-13T06:07:07.16126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.759297Z","time spent":"401.925846ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:07.161333Z","caller":"traceutil/trace.go:171","msg":"trace[2029777897] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"418.199708ms","start":"2026-06-13T06:07:06.743123Z","end":"2026-06-13T06:07:07.161323Z","steps":["trace[2029777897] 'agreement among raft nodes before linearized reading' (duration: 418.098922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.160711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.757616Z","time spent":"403.006684ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:07.161094Z","caller":"traceutil/trace.go:171","msg":"trace[1917164213] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"403.47393ms","start":"2026-06-13T06:07:06.757612Z","end":"2026-06-13T06:07:07.161086Z","steps":["trace[1917164213] 'process raft request' (duration: 402.385179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.758511Z","time spent":"402.765321ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:07.161394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.743112Z","time spent":"418.273741ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-13T06:07:07.160914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.493361ms","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-06-13T06:07:07.1616Z","caller":"traceutil/trace.go:171","msg":"trace[642178555] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"402.150628ms","start":"2026-06-13T06:07:06.759396Z","end":"2026-06-13T06:07:07.161546Z","steps":["trace[642178555] 'agreement among raft nodes before linearized reading' (duration: 401.490851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.161459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.757599Z","time spent":"403.828599ms","remote":"127.0.0.1:43386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:07.161696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.75939Z","time spent":"402.251904ms","remote":"127.0.0.1:54798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-13T06:07:07.160938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.161596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-13T06:07:07.161816Z","caller":"traceutil/trace.go:171","msg":"trace[254418614] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:0; response_revision:10; }","duration":"405.092428ms","start":"2026-06-13T06:07:06.756709Z","end":"2026-06-13T06:07:07.161801Z","steps":["trace[254418614] 'agreement among raft nodes before linearized reading' (duration: 404.175087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:07.1619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:06.756685Z","time spent":"405.200704ms","remote":"127.0.0.1:54748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-06-13T06:07:40.37385Z","caller":"traceutil/trace.go:171","msg":"trace[1060969929] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"416.054346ms","start":"2026-06-13T06:07:39.957769Z","end":"2026-06-13T06:07:40.373823Z","steps":["trace[1060969929] 'process raft request' (duration: 415.916526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.374015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.957748Z","time spent":"416.197827ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.43778Z","caller":"traceutil/trace.go:171","msg":"trace[1920339464] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"471.907026ms","start":"2026-06-13T06:07:39.965848Z","end":"2026-06-13T06:07:40.437755Z","steps":["trace[1920339464] 'process raft request' (duration: 471.677589ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.437801Z","caller":"traceutil/trace.go:171","msg":"trace[1209643875] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"471.865643ms","start":"2026-06-13T06:07:39.965909Z","end":"2026-06-13T06:07:40.437775Z","steps":["trace[1209643875] 'process raft request' (duration: 471.762386ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.437874Z","caller":"traceutil/trace.go:171","msg":"trace[983810079] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"464.743988ms","start":"2026-06-13T06:07:39.973095Z","end":"2026-06-13T06:07:40.437839Z","steps":["trace[983810079] 'process raft request' (duration: 464.655461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.437891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.965828Z","time spent":"472.005023ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.437891Z","caller":"traceutil/trace.go:171","msg":"trace[1937006768] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"467.910585ms","start":"2026-06-13T06:07:39.969921Z","end":"2026-06-13T06:07:40.437832Z","steps":["trace[1937006768] 'process raft request' (duration: 467.784016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.437939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.965902Z","time spent":"471.980402ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:40.437982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.973074Z","time spent":"464.844947ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.438003Z","caller":"traceutil/trace.go:171","msg":"trace[185123987] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"464.786002ms","start":"2026-06-13T06:07:39.973167Z","end":"2026-06-13T06:07:40.437953Z","steps":["trace[185123987] 'process raft request' (duration: 464.613839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.438053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.969884Z","time spent":"468.100131ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.438076Z","caller":"traceutil/trace.go:171","msg":"trace[695720732] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"464.609299ms","start":"2026-06-13T06:07:39.973459Z","end":"2026-06-13T06:07:40.438068Z","steps":["trace[695720732] 'process raft request' (duration: 464.351449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.438117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.973452Z","time spent":"464.647262ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:40.438124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.97316Z","time spent":"464.912551ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.438152Z","caller":"traceutil/trace.go:171","msg":"trace[2046054026] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"459.103166ms","start":"2026-06-13T06:07:39.97904Z","end":"2026-06-13T06:07:40.438143Z","steps":["trace[2046054026] 'process raft request' (duration: 458.8824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.438193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.979032Z","time spent":"459.144039ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.438278Z","caller":"traceutil/trace.go:171","msg":"trace[372331161] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"449.502436ms","start":"2026-06-13T06:07:39.988762Z","end":"2026-06-13T06:07:40.438264Z","steps":["trace[372331161] 'process raft request' (duration: 449.290821ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.438515Z","caller":"traceutil/trace.go:171","msg":"trace[454057186] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"434.141205ms","start":"2026-06-13T06:07:40.004361Z","end":"2026-06-13T06:07:40.438502Z","steps":["trace[454057186] 'process raft request' (duration: 433.839283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.438574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:40.004333Z","time spent":"434.219741ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:40.438338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:39.988745Z","time spent":"449.569262ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.438691Z","caller":"traceutil/trace.go:171","msg":"trace[603380373] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"417.803875ms","start":"2026-06-13T06:07:40.02088Z","end":"2026-06-13T06:07:40.438684Z","steps":["trace[603380373] 'process raft request' (duration: 417.398274ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.43864Z","caller":"traceutil/trace.go:171","msg":"trace[583148451] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"301.399199ms","start":"2026-06-13T06:07:40.13723Z","end":"2026-06-13T06:07:40.438629Z","steps":["trace[583148451] 'process raft request' (duration: 301.242887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:40.438946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:40.020851Z","time spent":"417.8619ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:40.438995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:40.137196Z","time spent":"301.767926ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T06:07:40.49511Z","caller":"traceutil/trace.go:171","msg":"trace[1438697063] transaction","detail":"{read_only:false; number_of_response:1; response_revision:531; }","duration":"113.975713ms","start":"2026-06-13T06:07:40.381119Z","end":"2026-06-13T06:07:40.495095Z","steps":["trace[1438697063] 'process raft request' (duration: 113.93998ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.495171Z","caller":"traceutil/trace.go:171","msg":"trace[1021940770] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"115.780499ms","start":"2026-06-13T06:07:40.379353Z","end":"2026-06-13T06:07:40.495133Z","steps":["trace[1021940770] 'process raft request' (duration: 115.539681ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:40.495294Z","caller":"traceutil/trace.go:171","msg":"trace[7158480] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"115.447273ms","start":"2026-06-13T06:07:40.379826Z","end":"2026-06-13T06:07:40.495273Z","steps":["trace[7158480] 'process raft request' (duration: 115.166252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:45.979843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.344186ms","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-06-13T06:07:45.979939Z","caller":"traceutil/trace.go:171","msg":"trace[917757333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"162.443833ms","start":"2026-06-13T06:07:45.817465Z","end":"2026-06-13T06:07:45.979909Z","steps":["trace[917757333] 'range keys from in-memory index tree' (duration: 162.210586ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:46.084631Z","caller":"traceutil/trace.go:171","msg":"trace[1036813096] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"100.87388ms","start":"2026-06-13T06:07:45.983728Z","end":"2026-06-13T06:07:46.084602Z","steps":["trace[1036813096] 'process raft request' (duration: 100.711468ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:46.347092Z","caller":"traceutil/trace.go:171","msg":"trace[1427104038] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"286.249766ms","start":"2026-06-13T06:07:46.06081Z","end":"2026-06-13T06:07:46.347059Z","steps":["trace[1427104038] 'process raft request' (duration: 285.555622ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:07:47.239725Z","caller":"traceutil/trace.go:171","msg":"trace[997477935] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"248.007371ms","start":"2026-06-13T06:07:46.991691Z","end":"2026-06-13T06:07:47.239698Z","steps":["trace[997477935] 'read index received' (duration: 247.212798ms)","trace[997477935] 'applied index is now lower than readState.Index' (duration: 793.363µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:07:47.239761Z","caller":"traceutil/trace.go:171","msg":"trace[1867880661] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"437.085007ms","start":"2026-06-13T06:07:46.80264Z","end":"2026-06-13T06:07:47.239725Z","steps":["trace[1867880661] 'process raft request' (duration: 436.539924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:47.23992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:46.802617Z","time spent":"437.216367ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T06:07:47.239939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.226488ms","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-06-13T06:07:47.239977Z","caller":"traceutil/trace.go:171","msg":"trace[747050575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"248.308724ms","start":"2026-06-13T06:07:46.991656Z","end":"2026-06-13T06:07:47.239964Z","steps":["trace[747050575] 'agreement among raft nodes before linearized reading' (duration: 248.144451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:47.828015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899891256730721,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T06:07:48.170084Z","caller":"traceutil/trace.go:171","msg":"trace[511880114] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"842.852845ms","start":"2026-06-13T06:07:47.327209Z","end":"2026-06-13T06:07:48.170062Z","steps":["trace[511880114] 'read index received' (duration: 842.612397ms)","trace[511880114] 'applied index is now lower than readState.Index' (duration: 239.568µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:07:48.170206Z","caller":"traceutil/trace.go:171","msg":"trace[2045968771] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"873.624601ms","start":"2026-06-13T06:07:47.296566Z","end":"2026-06-13T06:07:48.170191Z","steps":["trace[2045968771] 'process raft request' (duration: 873.321437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:48.170423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.806894ms","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-06-13T06:07:48.170483Z","caller":"traceutil/trace.go:171","msg":"trace[1179850894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"790.88565ms","start":"2026-06-13T06:07:47.379583Z","end":"2026-06-13T06:07:48.170469Z","steps":["trace[1179850894] 'agreement among raft nodes before linearized reading' (duration: 790.678345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:48.170473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:47.296538Z","time spent":"873.86982ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T06:07:48.170518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:47.379511Z","time spent":"790.99989ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-13T06:07:48.170501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.261837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T06:07:48.170639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.40049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8kk82\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-13T06:07:48.170785Z","caller":"traceutil/trace.go:171","msg":"trace[609617419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"843.551501ms","start":"2026-06-13T06:07:47.327124Z","end":"2026-06-13T06:07:48.170676Z","steps":["trace[609617419] 'agreement among raft nodes before linearized reading' (duration: 843.243078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:48.170918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:47.327109Z","time spent":"843.794901ms","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T06:07:48.170812Z","caller":"traceutil/trace.go:171","msg":"trace[1940373253] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8kk82; range_end:; response_count:1; response_revision:627; }","duration":"759.614266ms","start":"2026-06-13T06:07:47.411168Z","end":"2026-06-13T06:07:48.170782Z","steps":["trace[1940373253] 'agreement among raft nodes before linearized reading' (duration: 759.310303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:48.171049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:47.411153Z","time spent":"759.878258ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-8kk82\" "} {"level":"warn","ts":"2026-06-13T06:07:48.170647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.358282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T06:07:48.171152Z","caller":"traceutil/trace.go:171","msg":"trace[1982196346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"717.933257ms","start":"2026-06-13T06:07:47.453202Z","end":"2026-06-13T06:07:48.171135Z","steps":["trace[1982196346] 'agreement among raft nodes before linearized reading' (duration: 717.226222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:07:48.171248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:07:47.453184Z","time spent":"718.048907ms","remote":"127.0.0.1:54938","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-06-13T06:07:59.76626Z","caller":"traceutil/trace.go:171","msg":"trace[1753791794] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"228.092241ms","start":"2026-06-13T06:07:59.538087Z","end":"2026-06-13T06:07:59.766179Z","steps":["trace[1753791794] 'process raft request' (duration: 226.70891ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:02.407157Z","caller":"traceutil/trace.go:171","msg":"trace[1077284371] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"120.727391ms","start":"2026-06-13T06:08:02.286404Z","end":"2026-06-13T06:08:02.407132Z","steps":["trace[1077284371] 'read index received' (duration: 120.432878ms)","trace[1077284371] 'applied index is now lower than readState.Index' (duration: 293.573µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:08:02.4073Z","caller":"traceutil/trace.go:171","msg":"trace[1087852891] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"123.941172ms","start":"2026-06-13T06:08:02.283313Z","end":"2026-06-13T06:08:02.407254Z","steps":["trace[1087852891] 'process raft request' (duration: 123.657109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:08:02.407427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.009154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T06:08:02.407474Z","caller":"traceutil/trace.go:171","msg":"trace[1890578186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"121.097451ms","start":"2026-06-13T06:08:02.286363Z","end":"2026-06-13T06:08:02.407461Z","steps":["trace[1890578186] 'agreement among raft nodes before linearized reading' (duration: 120.927978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:08:02.407523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.896435ms","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-06-13T06:08:02.407692Z","caller":"traceutil/trace.go:171","msg":"trace[586628216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"107.108552ms","start":"2026-06-13T06:08:02.300563Z","end":"2026-06-13T06:08:02.407671Z","steps":["trace[586628216] 'agreement among raft nodes before linearized reading' (duration: 106.749074ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:02.822954Z","caller":"traceutil/trace.go:171","msg":"trace[1094687707] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"411.104477ms","start":"2026-06-13T06:08:02.411815Z","end":"2026-06-13T06:08:02.82292Z","steps":["trace[1094687707] 'process raft request' (duration: 404.519945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:08:02.82312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:08:02.411781Z","time spent":"411.26255ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T06:08:02.82675Z","caller":"traceutil/trace.go:171","msg":"trace[461166301] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"414.829898ms","start":"2026-06-13T06:08:02.411852Z","end":"2026-06-13T06:08:02.826682Z","steps":["trace[461166301] 'process raft request' (duration: 414.61278ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:02.826797Z","caller":"traceutil/trace.go:171","msg":"trace[1088399187] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"303.648288ms","start":"2026-06-13T06:08:02.52313Z","end":"2026-06-13T06:08:02.826779Z","steps":["trace[1088399187] 'process raft request' (duration: 303.465952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:08:02.826978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:08:02.523097Z","time spent":"303.771757ms","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T06:08:02.827075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:08:02.411816Z","time spent":"415.036455ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T06:08:36.219068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.496738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-06-13T06:08:36.219212Z","caller":"traceutil/trace.go:171","msg":"trace[311506442] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1206; }","duration":"216.707942ms","start":"2026-06-13T06:08:36.002465Z","end":"2026-06-13T06:08:36.219173Z","steps":["trace[311506442] 'range keys from in-memory index tree' (duration: 216.279354ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:36.353845Z","caller":"traceutil/trace.go:171","msg":"trace[1081487487] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"119.978548ms","start":"2026-06-13T06:08:36.233841Z","end":"2026-06-13T06:08:36.353819Z","steps":["trace[1081487487] 'process raft request' (duration: 119.58375ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:51.914038Z","caller":"traceutil/trace.go:171","msg":"trace[976532310] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"124.447158ms","start":"2026-06-13T06:08:51.789559Z","end":"2026-06-13T06:08:51.914006Z","steps":["trace[976532310] 'read index received' (duration: 124.278799ms)","trace[976532310] 'applied index is now lower than readState.Index' (duration: 166.719µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:08:51.914175Z","caller":"traceutil/trace.go:171","msg":"trace[2017516693] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"172.781346ms","start":"2026-06-13T06:08:51.741348Z","end":"2026-06-13T06:08:51.91413Z","steps":["trace[2017516693] 'process raft request' (duration: 172.452949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:08:51.914302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.712765ms","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-06-13T06:08:51.914342Z","caller":"traceutil/trace.go:171","msg":"trace[372717180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"124.831643ms","start":"2026-06-13T06:08:51.789497Z","end":"2026-06-13T06:08:51.914329Z","steps":["trace[372717180] 'agreement among raft nodes before linearized reading' (duration: 124.638345ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:08:57.877249Z","caller":"traceutil/trace.go:171","msg":"trace[838644352] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"102.432152ms","start":"2026-06-13T06:08:57.774795Z","end":"2026-06-13T06:08:57.877227Z","steps":["trace[838644352] 'process raft request' (duration: 102.301903ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:00.239423Z","caller":"traceutil/trace.go:171","msg":"trace[645042800] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"198.671447ms","start":"2026-06-13T06:09:00.040725Z","end":"2026-06-13T06:09:00.239396Z","steps":["trace[645042800] 'process raft request' (duration: 198.508867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:00.534596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.540265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T06:09:00.534685Z","caller":"traceutil/trace.go:171","msg":"trace[334015274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1474; }","duration":"207.701495ms","start":"2026-06-13T06:09:00.326963Z","end":"2026-06-13T06:09:00.534665Z","steps":["trace[334015274] 'range keys from in-memory index tree' (duration: 207.406985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:00.535139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.413398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-989rn\" ","response":"range_response_count:1 size:4519"} {"level":"info","ts":"2026-06-13T06:09:00.536943Z","caller":"traceutil/trace.go:171","msg":"trace[185672599] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-989rn; range_end:; response_count:1; response_revision:1474; }","duration":"237.25277ms","start":"2026-06-13T06:09:00.299644Z","end":"2026-06-13T06:09:00.536897Z","steps":["trace[185672599] 'range keys from in-memory index tree' (duration: 234.907128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.020524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.732984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9ab44ff-1595-4209-8e62-73eec628f959\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-13T06:09:27.020584Z","caller":"traceutil/trace.go:171","msg":"trace[1585932887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9ab44ff-1595-4209-8e62-73eec628f959; range_end:; response_count:1; response_revision:1898; }","duration":"108.839397ms","start":"2026-06-13T06:09:26.911731Z","end":"2026-06-13T06:09:27.020571Z","steps":["trace[1585932887] 'range keys from in-memory index tree' (duration: 108.609121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.020595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.87531ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T06:09:27.020836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.849224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-13T06:09:27.020828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.591538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9ab44ff-1595-4209-8e62-73eec628f959\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-13T06:09:27.020853Z","caller":"traceutil/trace.go:171","msg":"trace[1227488094] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"281.893365ms","start":"2026-06-13T06:09:26.738953Z","end":"2026-06-13T06:09:27.020847Z","steps":["trace[1227488094] 'range keys from in-memory index tree' (duration: 281.770492ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:27.020841Z","caller":"traceutil/trace.go:171","msg":"trace[1449308029] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1898; }","duration":"288.121086ms","start":"2026-06-13T06:09:26.73269Z","end":"2026-06-13T06:09:27.020811Z","steps":["trace[1449308029] 'range keys from in-memory index tree' (duration: 287.86009ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:27.020889Z","caller":"traceutil/trace.go:171","msg":"trace[2106397406] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9ab44ff-1595-4209-8e62-73eec628f959; range_end:; response_count:1; response_revision:1898; }","duration":"174.698151ms","start":"2026-06-13T06:09:26.846173Z","end":"2026-06-13T06:09:27.020871Z","steps":["trace[2106397406] 'range keys from in-memory index tree' (duration: 174.439325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.020787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.837994ms","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-06-13T06:09:27.02097Z","caller":"traceutil/trace.go:171","msg":"trace[1933821143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1898; }","duration":"227.028458ms","start":"2026-06-13T06:09:26.793916Z","end":"2026-06-13T06:09:27.020945Z","steps":["trace[1933821143] 'range keys from in-memory index tree' (duration: 226.772622ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:27.145893Z","caller":"traceutil/trace.go:171","msg":"trace[830160523] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"120.035032ms","start":"2026-06-13T06:09:27.025837Z","end":"2026-06-13T06:09:27.145872Z","steps":["trace[830160523] 'process raft request' (duration: 97.515161ms)","trace[830160523] 'compare' (duration: 22.362137ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:09:27.146077Z","caller":"traceutil/trace.go:171","msg":"trace[67469284] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"119.795726ms","start":"2026-06-13T06:09:27.026274Z","end":"2026-06-13T06:09:27.14607Z","steps":["trace[67469284] 'process raft request' (duration: 119.55398ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:27.653219Z","caller":"traceutil/trace.go:171","msg":"trace[1463558230] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"379.189352ms","start":"2026-06-13T06:09:27.274016Z","end":"2026-06-13T06:09:27.653206Z","steps":["trace[1463558230] 'process raft request' (duration: 378.967606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.653417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:09:27.273991Z","time spent":"379.349656ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T06:09:27.653501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.140194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T06:09:27.653609Z","caller":"traceutil/trace.go:171","msg":"trace[879200446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1904; }","duration":"327.305598ms","start":"2026-06-13T06:09:27.326274Z","end":"2026-06-13T06:09:27.65358Z","steps":["trace[879200446] 'agreement among raft nodes before linearized reading' (duration: 327.040571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.653694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:09:27.326255Z","time spent":"327.42385ms","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T06:09:27.653143Z","caller":"traceutil/trace.go:171","msg":"trace[20845291] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"326.793656ms","start":"2026-06-13T06:09:27.326328Z","end":"2026-06-13T06:09:27.653121Z","steps":["trace[20845291] 'read index received' (duration: 326.612531ms)","trace[20845291] 'applied index is now lower than readState.Index' (duration: 178.125µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T06:09:27.653877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.737534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T06:09:27.653938Z","caller":"traceutil/trace.go:171","msg":"trace[1154353175] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1904; }","duration":"322.833867ms","start":"2026-06-13T06:09:27.331087Z","end":"2026-06-13T06:09:27.653921Z","steps":["trace[1154353175] 'agreement among raft nodes before linearized reading' (duration: 322.736884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:09:27.654019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:09:27.331073Z","time spent":"322.929729ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-13T06:09:43.293282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.977866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-13T06:09:43.293332Z","caller":"traceutil/trace.go:171","msg":"trace[152376762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"132.064239ms","start":"2026-06-13T06:09:43.161256Z","end":"2026-06-13T06:09:43.29332Z","steps":["trace[152376762] 'agreement among raft nodes before linearized reading' (duration: 131.933043ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:43.293108Z","caller":"traceutil/trace.go:171","msg":"trace[429187537] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"141.980716ms","start":"2026-06-13T06:09:43.151111Z","end":"2026-06-13T06:09:43.293092Z","steps":["trace[429187537] 'process raft request' (duration: 141.610224ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:09:43.292915Z","caller":"traceutil/trace.go:171","msg":"trace[459748151] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"131.605963ms","start":"2026-06-13T06:09:43.16129Z","end":"2026-06-13T06:09:43.292896Z","steps":["trace[459748151] 'read index received' (duration: 131.362604ms)","trace[459748151] 'applied index is now lower than readState.Index' (duration: 242.489µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:10:00.575718Z","caller":"traceutil/trace.go:171","msg":"trace[394041433] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"232.924017ms","start":"2026-06-13T06:10:00.342771Z","end":"2026-06-13T06:10:00.575695Z","steps":["trace[394041433] 'process raft request' (duration: 232.76165ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:00.585718Z","caller":"traceutil/trace.go:171","msg":"trace[287164945] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"186.082245ms","start":"2026-06-13T06:10:00.39962Z","end":"2026-06-13T06:10:00.585702Z","steps":["trace[287164945] 'read index received' (duration: 176.117833ms)","trace[287164945] 'applied index is now lower than readState.Index' (duration: 9.963742ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:10:00.585914Z","caller":"traceutil/trace.go:171","msg":"trace[1443552152] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"229.56622ms","start":"2026-06-13T06:10:00.356222Z","end":"2026-06-13T06:10:00.585788Z","steps":["trace[1443552152] 'process raft request' (duration: 229.382912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:10:00.586242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.591207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-13T06:10:00.586329Z","caller":"traceutil/trace.go:171","msg":"trace[276640841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"186.722274ms","start":"2026-06-13T06:10:00.399586Z","end":"2026-06-13T06:10:00.586308Z","steps":["trace[276640841] 'agreement among raft nodes before linearized reading' (duration: 186.278345ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:00.695078Z","caller":"traceutil/trace.go:171","msg":"trace[1638954209] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"104.95697ms","start":"2026-06-13T06:10:00.590095Z","end":"2026-06-13T06:10:00.695052Z","steps":["trace[1638954209] 'process raft request' (duration: 104.798862ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:02.725917Z","caller":"traceutil/trace.go:171","msg":"trace[335071908] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"145.281769ms","start":"2026-06-13T06:10:02.580611Z","end":"2026-06-13T06:10:02.725892Z","steps":["trace[335071908] 'process raft request' (duration: 145.161634ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:02.736705Z","caller":"traceutil/trace.go:171","msg":"trace[1537287357] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2221; }","duration":"136.160627ms","start":"2026-06-13T06:10:02.600525Z","end":"2026-06-13T06:10:02.736685Z","steps":["trace[1537287357] 'read index received' (duration: 125.261485ms)","trace[1537287357] 'applied index is now lower than readState.Index' (duration: 10.898561ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:10:02.73678Z","caller":"traceutil/trace.go:171","msg":"trace[2015383517] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"146.20417ms","start":"2026-06-13T06:10:02.590557Z","end":"2026-06-13T06:10:02.736761Z","steps":["trace[2015383517] 'process raft request' (duration: 145.98554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:10:02.736864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.334224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-13T06:10:02.736902Z","caller":"traceutil/trace.go:171","msg":"trace[321870798] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2147; }","duration":"136.417387ms","start":"2026-06-13T06:10:02.600475Z","end":"2026-06-13T06:10:02.736892Z","steps":["trace[321870798] 'agreement among raft nodes before linearized reading' (duration: 136.310173ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:02.737037Z","caller":"traceutil/trace.go:171","msg":"trace[306563505] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"114.993221ms","start":"2026-06-13T06:10:02.622012Z","end":"2026-06-13T06:10:02.737005Z","steps":["trace[306563505] 'process raft request' (duration: 114.641776ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:10:03.317197Z","caller":"traceutil/trace.go:171","msg":"trace[2134653538] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"575.896034ms","start":"2026-06-13T06:10:02.741279Z","end":"2026-06-13T06:10:03.317175Z","steps":["trace[2134653538] 'process raft request' (duration: 575.773299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:10:03.317317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:10:02.741257Z","time spent":"575.997979ms","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T06:11:43.47684Z","caller":"traceutil/trace.go:171","msg":"trace[202444631] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"114.808047ms","start":"2026-06-13T06:11:43.361999Z","end":"2026-06-13T06:11:43.476807Z","steps":["trace[202444631] 'process raft request' (duration: 114.613684ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:12:16.315431Z","caller":"traceutil/trace.go:171","msg":"trace[1527643500] transaction","detail":"{read_only:false; response_revision:2961; number_of_response:1; }","duration":"257.809594ms","start":"2026-06-13T06:12:16.057588Z","end":"2026-06-13T06:12:16.315398Z","steps":["trace[1527643500] 'process raft request' (duration: 188.354577ms)","trace[1527643500] 'compare' (duration: 69.299856ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:15:51.98207Z","caller":"traceutil/trace.go:171","msg":"trace[165882840] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"139.376701ms","start":"2026-06-13T06:15:51.842666Z","end":"2026-06-13T06:15:51.982043Z","steps":["trace[165882840] 'process raft request' (duration: 139.189624ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:15:52.002906Z","caller":"traceutil/trace.go:171","msg":"trace[1059854107] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"126.5456ms","start":"2026-06-13T06:15:51.876343Z","end":"2026-06-13T06:15:52.002888Z","steps":["trace[1059854107] 'process raft request' (duration: 126.244528ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:15:54.798674Z","caller":"traceutil/trace.go:171","msg":"trace[1760405495] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"170.282714ms","start":"2026-06-13T06:15:54.628364Z","end":"2026-06-13T06:15:54.798646Z","steps":["trace[1760405495] 'process raft request' (duration: 170.057155ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:15:54.798852Z","caller":"traceutil/trace.go:171","msg":"trace[346950979] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"100.965059ms","start":"2026-06-13T06:15:54.69782Z","end":"2026-06-13T06:15:54.798785Z","steps":["trace[346950979] 'process raft request' (duration: 100.768941ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:15:54.798803Z","caller":"traceutil/trace.go:171","msg":"trace[257445653] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4959; }","duration":"102.421085ms","start":"2026-06-13T06:15:54.696356Z","end":"2026-06-13T06:15:54.798777Z","steps":["trace[257445653] 'read index received' (duration: 3.191454ms)","trace[257445653] 'applied index is now lower than readState.Index' (duration: 99.227671ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T06:15:54.799122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.738368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-twvls\" ","response":"range_response_count:1 size:9670"} {"level":"info","ts":"2026-06-13T06:15:54.799213Z","caller":"traceutil/trace.go:171","msg":"trace[1971592435] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-twvls; range_end:; response_count:1; response_revision:4779; }","duration":"102.856292ms","start":"2026-06-13T06:15:54.696324Z","end":"2026-06-13T06:15:54.79918Z","steps":["trace[1971592435] 'agreement among raft nodes before linearized reading' (duration: 102.56021ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:15:59.961042Z","caller":"traceutil/trace.go:171","msg":"trace[2078204162] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"217.359844ms","start":"2026-06-13T06:15:59.743657Z","end":"2026-06-13T06:15:59.961017Z","steps":["trace[2078204162] 'process raft request' (duration: 217.168707ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:00.234498Z","caller":"traceutil/trace.go:171","msg":"trace[1470314917] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5039; }","duration":"429.392852ms","start":"2026-06-13T06:15:59.805073Z","end":"2026-06-13T06:16:00.234466Z","steps":["trace[1470314917] 'read index received' (duration: 155.788173ms)","trace[1470314917] 'applied index is now lower than readState.Index' (duration: 273.603729ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:16:00.234669Z","caller":"traceutil/trace.go:171","msg":"trace[2039672474] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"483.03713ms","start":"2026-06-13T06:15:59.751571Z","end":"2026-06-13T06:16:00.234608Z","steps":["trace[2039672474] 'process raft request' (duration: 482.744278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:00.234902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:15:59.751541Z","time spent":"483.165675ms","remote":"127.0.0.1:54886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T06:16:00.235264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.730655ms","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-06-13T06:16:00.235376Z","caller":"traceutil/trace.go:171","msg":"trace[1348785831] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4856; }","duration":"430.318909ms","start":"2026-06-13T06:15:59.805028Z","end":"2026-06-13T06:16:00.235347Z","steps":["trace[1348785831] 'agreement among raft nodes before linearized reading' (duration: 429.582739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:00.23543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:15:59.805013Z","time spent":"430.404022ms","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-13T06:16:00.260825Z","caller":"traceutil/trace.go:171","msg":"trace[1662269489] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"218.165798ms","start":"2026-06-13T06:16:00.042637Z","end":"2026-06-13T06:16:00.260803Z","steps":["trace[1662269489] 'process raft request' (duration: 217.980101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:00.260897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.935637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-13T06:16:00.260935Z","caller":"traceutil/trace.go:171","msg":"trace[142749615] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4858; }","duration":"431.03344ms","start":"2026-06-13T06:15:59.829888Z","end":"2026-06-13T06:16:00.260922Z","steps":["trace[142749615] 'agreement among raft nodes before linearized reading' (duration: 430.879454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:00.260922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.80667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T06:16:00.260955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:15:59.829869Z","time spent":"431.080772ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-13T06:16:00.260982Z","caller":"traceutil/trace.go:171","msg":"trace[647701787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4858; }","duration":"119.922563ms","start":"2026-06-13T06:16:00.141042Z","end":"2026-06-13T06:16:00.260964Z","steps":["trace[647701787] 'agreement among raft nodes before linearized reading' (duration: 119.793978ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:00.261341Z","caller":"traceutil/trace.go:171","msg":"trace[1024459561] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"212.995744ms","start":"2026-06-13T06:16:00.048316Z","end":"2026-06-13T06:16:00.261311Z","steps":["trace[1024459561] 'process raft request' (duration: 212.421631ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:00.900141Z","caller":"traceutil/trace.go:171","msg":"trace[1587066521] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"105.9577ms","start":"2026-06-13T06:16:00.794153Z","end":"2026-06-13T06:16:00.900111Z","steps":["trace[1587066521] 'process raft request' (duration: 105.69429ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:00.90069Z","caller":"traceutil/trace.go:171","msg":"trace[1534648133] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"106.270583ms","start":"2026-06-13T06:16:00.794409Z","end":"2026-06-13T06:16:00.90068Z","steps":["trace[1534648133] 'process raft request' (duration: 105.614177ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:00.901216Z","caller":"traceutil/trace.go:171","msg":"trace[1625059602] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"106.909299ms","start":"2026-06-13T06:16:00.794291Z","end":"2026-06-13T06:16:00.9012Z","steps":["trace[1625059602] 'process raft request' (duration: 105.68548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.224292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.423435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T06:16:07.224375Z","caller":"traceutil/trace.go:171","msg":"trace[1313135215] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5014; }","duration":"148.575701ms","start":"2026-06-13T06:16:07.07578Z","end":"2026-06-13T06:16:07.224356Z","steps":["trace[1313135215] 'count revisions from in-memory index tree' (duration: 148.31453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.224632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.235768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-13T06:16:07.22467Z","caller":"traceutil/trace.go:171","msg":"trace[1942910481] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5014; }","duration":"209.313221ms","start":"2026-06-13T06:16:07.015347Z","end":"2026-06-13T06:16:07.22466Z","steps":["trace[1942910481] 'count revisions from in-memory index tree' (duration: 209.155694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.225165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.902375ms","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-06-13T06:16:07.225339Z","caller":"traceutil/trace.go:171","msg":"trace[1716299160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5014; }","duration":"119.604173ms","start":"2026-06-13T06:16:07.105713Z","end":"2026-06-13T06:16:07.225317Z","steps":["trace[1716299160] 'range keys from in-memory index tree' (duration: 118.76788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.225391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.799508ms","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-06-13T06:16:07.225517Z","caller":"traceutil/trace.go:171","msg":"trace[1879092985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5014; }","duration":"129.992626ms","start":"2026-06-13T06:16:07.0955Z","end":"2026-06-13T06:16:07.225493Z","steps":["trace[1879092985] 'count revisions from in-memory index tree' (duration: 129.703004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.655245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.913785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-13T06:16:07.655291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.178544ms","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-06-13T06:16:07.655305Z","caller":"traceutil/trace.go:171","msg":"trace[1566278637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5015; }","duration":"193.042139ms","start":"2026-06-13T06:16:07.462248Z","end":"2026-06-13T06:16:07.65529Z","steps":["trace[1566278637] 'range keys from in-memory index tree' (duration: 192.754658ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:07.655314Z","caller":"traceutil/trace.go:171","msg":"trace[616850639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5015; }","duration":"267.222466ms","start":"2026-06-13T06:16:07.388085Z","end":"2026-06-13T06:16:07.655307Z","steps":["trace[616850639] 'range keys from in-memory index tree' (duration: 267.103851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.655273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.734948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-06-13T06:16:07.655422Z","caller":"traceutil/trace.go:171","msg":"trace[700015379] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5015; }","duration":"330.894924ms","start":"2026-06-13T06:16:07.324514Z","end":"2026-06-13T06:16:07.655409Z","steps":["trace[700015379] 'range keys from in-memory index tree' (duration: 330.563871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.655491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:16:07.324504Z","time spent":"330.974567ms","remote":"127.0.0.1:54888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1017,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-06-13T06:16:07.655387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.845552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T06:16:07.655581Z","caller":"traceutil/trace.go:171","msg":"trace[1510958353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5015; }","duration":"330.037239ms","start":"2026-06-13T06:16:07.325536Z","end":"2026-06-13T06:16:07.655573Z","steps":["trace[1510958353] 'range keys from in-memory index tree' (duration: 329.7943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T06:16:07.655605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T06:16:07.325502Z","time spent":"330.095451ms","remote":"127.0.0.1:54712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T06:16:13.820607Z","caller":"traceutil/trace.go:171","msg":"trace[1383472444] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5289; }","duration":"102.137887ms","start":"2026-06-13T06:16:13.71844Z","end":"2026-06-13T06:16:13.820577Z","steps":["trace[1383472444] 'read index received' (duration: 101.831445ms)","trace[1383472444] 'applied index is now lower than readState.Index' (duration: 305.172µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T06:16:13.820853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.386079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T06:16:13.820926Z","caller":"traceutil/trace.go:171","msg":"trace[625736121] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5097; }","duration":"102.507854ms","start":"2026-06-13T06:16:13.718398Z","end":"2026-06-13T06:16:13.820906Z","steps":["trace[625736121] 'agreement among raft nodes before linearized reading' (duration: 102.340906ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:16:13.82069Z","caller":"traceutil/trace.go:171","msg":"trace[633393060] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"123.212632ms","start":"2026-06-13T06:16:13.697423Z","end":"2026-06-13T06:16:13.820636Z","steps":["trace[633393060] 'process raft request' (duration: 122.944811ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:17:05.398095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2904} {"level":"info","ts":"2026-06-13T06:17:05.489695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2904,"took":"90.168682ms","hash":4060783562,"current-db-size-bytes":52289536,"current-db-size":"52 MB","current-db-size-in-use-bytes":28647424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-13T06:17:05.489768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4060783562,"revision":2904,"compact-revision":-1} {"level":"info","ts":"2026-06-13T06:20:01.232049Z","caller":"traceutil/trace.go:171","msg":"trace[159984826] transaction","detail":"{read_only:false; response_revision:6726; number_of_response:1; }","duration":"125.426335ms","start":"2026-06-13T06:20:01.10659Z","end":"2026-06-13T06:20:01.232016Z","steps":["trace[159984826] 'process raft request' (duration: 70.325248ms)","trace[159984826] 'compare' (duration: 54.872895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T06:20:01.232269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.324333ms","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-06-13T06:20:01.232325Z","caller":"traceutil/trace.go:171","msg":"trace[1903787731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6726; }","duration":"146.45399ms","start":"2026-06-13T06:20:01.085861Z","end":"2026-06-13T06:20:01.232315Z","steps":["trace[1903787731] 'agreement among raft nodes before linearized reading' (duration: 146.245498ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:20:01.232064Z","caller":"traceutil/trace.go:171","msg":"trace[1044376066] linearizableReadLoop","detail":"{readStateIndex:6973; appliedIndex:6972; }","duration":"124.277002ms","start":"2026-06-13T06:20:01.107723Z","end":"2026-06-13T06:20:01.232Z","steps":["trace[1044376066] 'read index received' (duration: 69.205105ms)","trace[1044376066] 'applied index is now lower than readState.Index' (duration: 55.070496ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:20:01.244385Z","caller":"traceutil/trace.go:171","msg":"trace[1513682655] transaction","detail":"{read_only:false; response_revision:6727; number_of_response:1; }","duration":"131.526596ms","start":"2026-06-13T06:20:01.112844Z","end":"2026-06-13T06:20:01.24437Z","steps":["trace[1513682655] 'process raft request' (duration: 131.44569ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:20:25.173285Z","caller":"traceutil/trace.go:171","msg":"trace[929709335] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"106.015545ms","start":"2026-06-13T06:20:25.067159Z","end":"2026-06-13T06:20:25.173174Z","steps":["trace[929709335] 'process raft request' (duration: 105.415311ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T06:20:25.279955Z","caller":"traceutil/trace.go:171","msg":"trace[368854529] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"100.843692ms","start":"2026-06-13T06:20:25.179063Z","end":"2026-06-13T06:20:25.279907Z","steps":["trace[368854529] 'process raft request' (duration: 47.444824ms)","trace[368854529] 'compare' (duration: 52.88226ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T06:22:05.405723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5522} {"level":"info","ts":"2026-06-13T06:22:05.512078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5522,"took":"104.425534ms","hash":1758135401,"current-db-size-bytes":52289536,"current-db-size":"52 MB","current-db-size-in-use-bytes":18505728,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-13T06:22:05.51216Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1758135401,"revision":5522,"compact-revision":2904}