{"level":"warn","ts":"2026-09-01T18:26:03.281502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:26:03.28162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.95: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://162.253.55.95:2380","--initial-cluster=instance=https://162.253.55.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.95: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-09-01T18:26:03.281713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T18:26:03.28173Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-09-01T18:26:03.281759Z","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-09-01T18:26:03.2825Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"]} {"level":"info","ts":"2026-09-01T18:26:03.28261Z","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":16,"max-cpu-available":16,"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://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.95: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-09-01T18:26:03.289549Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.70427ms"} {"level":"info","ts":"2026-09-01T18:26:03.29576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"282167b6747c9cda","cluster-id":"dc65cfc6e389e361"} {"level":"info","ts":"2026-09-01T18:26:03.295849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:26:03.295869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 0"} {"level":"info","ts":"2026-09-01T18:26:03.29588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 282167b6747c9cda [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:26:03.295897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became follower at term 1"} {"level":"info","ts":"2026-09-01T18:26:03.295936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"warn","ts":"2026-09-01T18:26:03.326332Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:26:03.32968Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:26:03.333622Z","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-09-01T18:26:03.340112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"282167b6747c9cda","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:26:03.340427Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"282167b6747c9cda","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:26:03.340541Z","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-09-01T18:26:03.340816Z","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-09-01T18:26:03.340856Z","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-09-01T18:26:03.343307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda switched to configuration voters=(2891706469084601562)"} {"level":"info","ts":"2026-09-01T18:26:03.346161Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","added-peer-id":"282167b6747c9cda","added-peer-peer-urls":["https://162.253.55.95:2380"]} {"level":"info","ts":"2026-09-01T18:26:03.350562Z","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-09-01T18:26:03.350645Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-09-01T18:26:03.350794Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.95:2380"} {"level":"info","ts":"2026-09-01T18:26:03.35143Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:26:03.351062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"282167b6747c9cda","initial-advertise-peer-urls":["https://162.253.55.95:2380"],"listen-peer-urls":["https://162.253.55.95:2380"],"advertise-client-urls":["https://162.253.55.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:26:03.597031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:26:03.597118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:26:03.597215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgPreVoteResp from 282167b6747c9cda at term 1"} {"level":"info","ts":"2026-09-01T18:26:03.597253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:26:03.597317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda received MsgVoteResp from 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-09-01T18:26:03.597393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"282167b6747c9cda became leader at term 2"} {"level":"info","ts":"2026-09-01T18:26:03.597419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 282167b6747c9cda elected leader 282167b6747c9cda at term 2"} {"level":"info","ts":"2026-09-01T18:26:03.606235Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:26:03.608732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:26:03.608731Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"282167b6747c9cda","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.95:2379]}","request-path":"/0/members/282167b6747c9cda/attributes","cluster-id":"dc65cfc6e389e361","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:26:03.608797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:26:03.609126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:26:03.609218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:26:03.611638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dc65cfc6e389e361","local-member-id":"282167b6747c9cda","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:26:03.611856Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:26:03.611913Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:26:03.61204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:26:03.613699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.95:2379"} {"level":"warn","ts":"2026-09-01T18:26:23.42706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.791094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-01T18:26:23.427193Z","caller":"traceutil/trace.go:171","msg":"trace[532678563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"146.974769ms","start":"2026-09-01T18:26:23.28019Z","end":"2026-09-01T18:26:23.427165Z","steps":["trace[532678563] 'range keys from in-memory index tree' (duration: 146.63279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:42.064462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.721128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:26:42.064716Z","caller":"traceutil/trace.go:171","msg":"trace[1623238016] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"200.731761ms","start":"2026-09-01T18:26:41.863948Z","end":"2026-09-01T18:26:42.064679Z","steps":["trace[1623238016] 'process raft request' (duration: 34.016335ms)","trace[1623238016] 'compare' (duration: 165.508493ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:42.064934Z","caller":"traceutil/trace.go:171","msg":"trace[1383681528] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"199.094376ms","start":"2026-09-01T18:26:41.865817Z","end":"2026-09-01T18:26:42.064911Z","steps":["trace[1383681528] 'process raft request' (duration: 198.830639ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:42.065119Z","caller":"traceutil/trace.go:171","msg":"trace[1247814691] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"197.869541ms","start":"2026-09-01T18:26:41.867238Z","end":"2026-09-01T18:26:42.065107Z","steps":["trace[1247814691] 'process raft request' (duration: 197.598824ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:42.071947Z","caller":"traceutil/trace.go:171","msg":"trace[715485998] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"132.778933ms","start":"2026-09-01T18:26:41.939155Z","end":"2026-09-01T18:26:42.071934Z","steps":["trace[715485998] 'process raft request' (duration: 132.696951ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:42.332288Z","caller":"traceutil/trace.go:171","msg":"trace[373028625] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"113.64179ms","start":"2026-09-01T18:26:42.218616Z","end":"2026-09-01T18:26:42.332258Z","steps":["trace[373028625] 'process raft request' (duration: 112.684843ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:42.340916Z","caller":"traceutil/trace.go:171","msg":"trace[28702935] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"119.651437ms","start":"2026-09-01T18:26:42.221244Z","end":"2026-09-01T18:26:42.340895Z","steps":["trace[28702935] 'process raft request' (duration: 119.284267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:49.681609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.107104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:26:49.681807Z","caller":"traceutil/trace.go:171","msg":"trace[773017715] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"342.874273ms","start":"2026-09-01T18:26:49.338904Z","end":"2026-09-01T18:26:49.681778Z","steps":["trace[773017715] 'process raft request' (duration: 160.440091ms)","trace[773017715] 'compare' (duration: 181.713762ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:26:49.681911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:49.338876Z","time spent":"342.982426ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:26:49.721313Z","caller":"traceutil/trace.go:171","msg":"trace[785668049] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"129.157711ms","start":"2026-09-01T18:26:49.592123Z","end":"2026-09-01T18:26:49.721281Z","steps":["trace[785668049] 'read index received' (duration: 128.913335ms)","trace[785668049] 'applied index is now lower than readState.Index' (duration: 242.216µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:49.721363Z","caller":"traceutil/trace.go:171","msg":"trace[1495375819] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"326.115637ms","start":"2026-09-01T18:26:49.395201Z","end":"2026-09-01T18:26:49.721317Z","steps":["trace[1495375819] 'process raft request' (duration: 325.86827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:49.721757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:49.395173Z","time spent":"326.280042ms","remote":"127.0.0.1:59974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:26:49.722374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.229601ms","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-09-01T18:26:49.722524Z","caller":"traceutil/trace.go:171","msg":"trace[570026902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"130.417327ms","start":"2026-09-01T18:26:49.592051Z","end":"2026-09-01T18:26:49.722468Z","steps":["trace[570026902] 'agreement among raft nodes before linearized reading' (duration: 129.356617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:50.395718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.141207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r9hfb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-01T18:26:50.396027Z","caller":"traceutil/trace.go:171","msg":"trace[684547144] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r9hfb; range_end:; response_count:1; response_revision:635; }","duration":"577.526868ms","start":"2026-09-01T18:26:49.818467Z","end":"2026-09-01T18:26:50.395994Z","steps":["trace[684547144] 'range keys from in-memory index tree' (duration: 576.935392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:50.396082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:49.81844Z","time spent":"577.628281ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-r9hfb\" "} {"level":"info","ts":"2026-09-01T18:26:50.447786Z","caller":"traceutil/trace.go:171","msg":"trace[1429009877] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"312.757976ms","start":"2026-09-01T18:26:50.13499Z","end":"2026-09-01T18:26:50.447748Z","steps":["trace[1429009877] 'process raft request' (duration: 311.945853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:50.44806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:26:50.134954Z","time spent":"312.966172ms","remote":"127.0.0.1:36084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:27:06.00487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.589923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:06.005018Z","caller":"traceutil/trace.go:171","msg":"trace[338310747] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"181.99706ms","start":"2026-09-01T18:27:05.822999Z","end":"2026-09-01T18:27:06.004996Z","steps":["trace[338310747] 'read index received' (duration: 79.247213ms)","trace[338310747] 'applied index is now lower than readState.Index' (duration: 102.748557ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:06.00506Z","caller":"traceutil/trace.go:171","msg":"trace[121808981] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"185.056985ms","start":"2026-09-01T18:27:05.819976Z","end":"2026-09-01T18:27:06.005033Z","steps":["trace[121808981] 'process raft request' (duration: 82.205435ms)","trace[121808981] 'compare' (duration: 102.46979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:06.005254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.244747ms","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-09-01T18:27:06.00531Z","caller":"traceutil/trace.go:171","msg":"trace[1899322501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"182.319938ms","start":"2026-09-01T18:27:05.822976Z","end":"2026-09-01T18:27:06.005296Z","steps":["trace[1899322501] 'agreement among raft nodes before linearized reading' (duration: 182.134903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:06.41027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.019215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:06.410477Z","caller":"traceutil/trace.go:171","msg":"trace[545987053] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:842; }","duration":"316.169021ms","start":"2026-09-01T18:27:06.094288Z","end":"2026-09-01T18:27:06.410457Z","steps":["trace[545987053] 'read index received' (duration: 107.945861ms)","trace[545987053] 'applied index is now lower than readState.Index' (duration: 208.22226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:06.410573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.286464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:27:06.410607Z","caller":"traceutil/trace.go:171","msg":"trace[1585770368] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:811; }","duration":"316.352946ms","start":"2026-09-01T18:27:06.094245Z","end":"2026-09-01T18:27:06.410598Z","steps":["trace[1585770368] 'agreement among raft nodes before linearized reading' (duration: 316.287265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:06.410654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:06.094226Z","time spent":"316.417498ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-09-01T18:27:06.410617Z","caller":"traceutil/trace.go:171","msg":"trace[1479363076] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"398.249783ms","start":"2026-09-01T18:27:06.012312Z","end":"2026-09-01T18:27:06.410562Z","steps":["trace[1479363076] 'process raft request' (duration: 398.069568ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:06.410687Z","caller":"traceutil/trace.go:171","msg":"trace[297366102] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"400.010782ms","start":"2026-09-01T18:27:06.010657Z","end":"2026-09-01T18:27:06.410668Z","steps":["trace[297366102] 'process raft request' (duration: 191.510094ms)","trace[297366102] 'compare' (duration: 207.906962ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:06.41077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:06.010626Z","time spent":"400.101725ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:27:06.410892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:06.01229Z","time spent":"398.467139ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:24.724321Z","caller":"traceutil/trace.go:171","msg":"trace[63871738] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"227.062769ms","start":"2026-09-01T18:28:24.497236Z","end":"2026-09-01T18:28:24.724299Z","steps":["trace[63871738] 'read index received' (duration: 226.995637ms)","trace[63871738] 'applied index is now lower than readState.Index' (duration: 66.252µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:24.724361Z","caller":"traceutil/trace.go:171","msg":"trace[2073143206] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"391.531494ms","start":"2026-09-01T18:28:24.332808Z","end":"2026-09-01T18:28:24.724339Z","steps":["trace[2073143206] 'process raft request' (duration: 391.37431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:24.724584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.328207ms","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-09-01T18:28:24.724697Z","caller":"traceutil/trace.go:171","msg":"trace[527336356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1884; }","duration":"227.496511ms","start":"2026-09-01T18:28:24.497181Z","end":"2026-09-01T18:28:24.724677Z","steps":["trace[527336356] 'agreement among raft nodes before linearized reading' (duration: 227.226094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:24.724816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.33279Z","time spent":"391.795411ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:24.820335Z","caller":"traceutil/trace.go:171","msg":"trace[1932746228] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"132.05249ms","start":"2026-09-01T18:28:24.688263Z","end":"2026-09-01T18:28:24.820316Z","steps":["trace[1932746228] 'process raft request' (duration: 131.913626ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:24.820335Z","caller":"traceutil/trace.go:171","msg":"trace[1108165072] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"223.10259ms","start":"2026-09-01T18:28:24.597176Z","end":"2026-09-01T18:28:24.820278Z","steps":["trace[1108165072] 'process raft request' (duration: 175.364114ms)","trace[1108165072] 'compare' (duration: 47.486409ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:24.820861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.646022ms","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-09-01T18:28:24.820922Z","caller":"traceutil/trace.go:171","msg":"trace[2233922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1887; }","duration":"278.740135ms","start":"2026-09-01T18:28:24.542164Z","end":"2026-09-01T18:28:24.820904Z","steps":["trace[2233922] 'agreement among raft nodes before linearized reading' (duration: 278.20809ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:25.233077Z","caller":"traceutil/trace.go:171","msg":"trace[83857403] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"340.9771ms","start":"2026-09-01T18:28:24.892068Z","end":"2026-09-01T18:28:25.233045Z","steps":["trace[83857403] 'process raft request' (duration: 248.693606ms)","trace[83857403] 'compare' (duration: 91.736799ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:28:25.233238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.892058Z","time spent":"341.088044ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:25.233432Z","caller":"traceutil/trace.go:171","msg":"trace[972023170] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1968; }","duration":"281.876041ms","start":"2026-09-01T18:28:24.951529Z","end":"2026-09-01T18:28:25.233405Z","steps":["trace[972023170] 'read index received' (duration: 189.109694ms)","trace[972023170] 'applied index is now lower than readState.Index' (duration: 92.765187ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:25.233693Z","caller":"traceutil/trace.go:171","msg":"trace[28801350] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"341.170476ms","start":"2026-09-01T18:28:24.892421Z","end":"2026-09-01T18:28:25.233591Z","steps":["trace[28801350] 'process raft request' (duration: 340.869658ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:25.233481Z","caller":"traceutil/trace.go:171","msg":"trace[6584924] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"341.253448ms","start":"2026-09-01T18:28:24.892191Z","end":"2026-09-01T18:28:25.233445Z","steps":["trace[6584924] 'process raft request' (duration: 340.758934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.233832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.892391Z","time spent":"341.376182ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:28:25.233866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.443423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-823bbc49-71c7-46f1-8257-6d13cd9acd2e\" ","response":"range_response_count:1 size:3743"} {"level":"warn","ts":"2026-09-01T18:28:25.233907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:28:24.892184Z","time spent":"341.608307ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:28:25.233939Z","caller":"traceutil/trace.go:171","msg":"trace[1453375419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-823bbc49-71c7-46f1-8257-6d13cd9acd2e; range_end:; response_count:1; response_revision:1893; }","duration":"123.575547ms","start":"2026-09-01T18:28:25.110343Z","end":"2026-09-01T18:28:25.233919Z","steps":["trace[1453375419] 'agreement among raft nodes before linearized reading' (duration: 123.300759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:25.234137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.58121ms","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-09-01T18:28:25.234198Z","caller":"traceutil/trace.go:171","msg":"trace[802377961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"282.686283ms","start":"2026-09-01T18:28:24.951496Z","end":"2026-09-01T18:28:25.234183Z","steps":["trace[802377961] 'agreement among raft nodes before linearized reading' (duration: 282.071066ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:37.037036Z","caller":"traceutil/trace.go:171","msg":"trace[1180249526] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2072; }","duration":"159.499087ms","start":"2026-09-01T18:28:36.87751Z","end":"2026-09-01T18:28:37.03701Z","steps":["trace[1180249526] 'read index received' (duration: 63.630114ms)","trace[1180249526] 'applied index is now lower than readState.Index' (duration: 95.864833ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:37.037168Z","caller":"traceutil/trace.go:171","msg":"trace[1215469623] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"171.762635ms","start":"2026-09-01T18:28:36.865358Z","end":"2026-09-01T18:28:37.03712Z","steps":["trace[1215469623] 'process raft request' (duration: 171.484937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:37.03738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.000362ms","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-09-01T18:28:37.037439Z","caller":"traceutil/trace.go:171","msg":"trace[1840992825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1993; }","duration":"151.111916ms","start":"2026-09-01T18:28:36.886315Z","end":"2026-09-01T18:28:37.037426Z","steps":["trace[1840992825] 'agreement among raft nodes before linearized reading' (duration: 150.93116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:37.037609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.94345ms","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-09-01T18:28:37.037717Z","caller":"traceutil/trace.go:171","msg":"trace[2002344501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1993; }","duration":"160.226718ms","start":"2026-09-01T18:28:36.877474Z","end":"2026-09-01T18:28:37.037701Z","steps":["trace[2002344501] 'agreement among raft nodes before linearized reading' (duration: 159.710953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:39.824615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.358665ms","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-09-01T18:28:39.824708Z","caller":"traceutil/trace.go:171","msg":"trace[2142364158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2021; }","duration":"134.539569ms","start":"2026-09-01T18:28:39.690149Z","end":"2026-09-01T18:28:39.824689Z","steps":["trace[2142364158] 'range keys from in-memory index tree' (duration: 134.156047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:13.586402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.84914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:29:13.58656Z","caller":"traceutil/trace.go:171","msg":"trace[1695620518] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"313.532444ms","start":"2026-09-01T18:29:13.272987Z","end":"2026-09-01T18:29:13.58652Z","steps":["trace[1695620518] 'process raft request' (duration: 212.451937ms)","trace[1695620518] 'compare' (duration: 100.586844ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:29:13.586757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:13.27297Z","time spent":"313.691918ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:14.124934Z","caller":"traceutil/trace.go:171","msg":"trace[90304445] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"121.709405ms","start":"2026-09-01T18:29:14.003179Z","end":"2026-09-01T18:29:14.124888Z","steps":["trace[90304445] 'process raft request' (duration: 121.51654ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:14.307794Z","caller":"traceutil/trace.go:171","msg":"trace[1123892665] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"177.038041ms","start":"2026-09-01T18:29:14.130707Z","end":"2026-09-01T18:29:14.307745Z","steps":["trace[1123892665] 'process raft request' (duration: 170.13154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:19.796634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.815315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:29:19.797174Z","caller":"traceutil/trace.go:171","msg":"trace[242952032] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"232.188202ms","start":"2026-09-01T18:29:19.564944Z","end":"2026-09-01T18:29:19.797132Z","steps":["trace[242952032] 'process raft request' (duration: 185.392372ms)","trace[242952032] 'compare' (duration: 46.506582ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:19.797207Z","caller":"traceutil/trace.go:171","msg":"trace[2007203029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2237; }","duration":"263.384752ms","start":"2026-09-01T18:29:19.533783Z","end":"2026-09-01T18:29:19.797167Z","steps":["trace[2007203029] 'range keys from in-memory index tree' (duration: 262.718343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.389036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.621873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:29:20.389228Z","caller":"traceutil/trace.go:171","msg":"trace[1378645456] linearizableReadLoop","detail":"{readStateIndex:2330; appliedIndex:2328; }","duration":"769.755981ms","start":"2026-09-01T18:29:19.619454Z","end":"2026-09-01T18:29:20.38921Z","steps":["trace[1378645456] 'read index received' (duration: 130.799806ms)","trace[1378645456] 'applied index is now lower than readState.Index' (duration: 638.954875ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:20.389322Z","caller":"traceutil/trace.go:171","msg":"trace[70386562] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"780.311343ms","start":"2026-09-01T18:29:19.608999Z","end":"2026-09-01T18:29:20.38931Z","steps":["trace[70386562] 'process raft request' (duration: 514.34527ms)","trace[70386562] 'compare' (duration: 265.49305ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:29:20.389383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.608965Z","time spent":"780.384155ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:20.38953Z","caller":"traceutil/trace.go:171","msg":"trace[1795951031] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"698.855117ms","start":"2026-09-01T18:29:19.690666Z","end":"2026-09-01T18:29:20.389521Z","steps":["trace[1795951031] 'process raft request' (duration: 698.477927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.38962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.690646Z","time spent":"698.910458ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:29:20.389833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.375767ms","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-09-01T18:29:20.389868Z","caller":"traceutil/trace.go:171","msg":"trace[1114901669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2240; }","duration":"770.45796ms","start":"2026-09-01T18:29:19.619401Z","end":"2026-09-01T18:29:20.389859Z","steps":["trace[1114901669] 'agreement among raft nodes before linearized reading' (duration: 770.339737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.389902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.61937Z","time spent":"770.523371ms","remote":"127.0.0.1:36232","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-09-01T18:29:20.390038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.26426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:29:20.390116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.7126ms","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-09-01T18:29:20.390134Z","caller":"traceutil/trace.go:171","msg":"trace[999861823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2240; }","duration":"591.427234ms","start":"2026-09-01T18:29:19.798684Z","end":"2026-09-01T18:29:20.390111Z","steps":["trace[999861823] 'agreement among raft nodes before linearized reading' (duration: 591.249639ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:20.390153Z","caller":"traceutil/trace.go:171","msg":"trace[1972394600] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2240; }","duration":"724.785111ms","start":"2026-09-01T18:29:19.665355Z","end":"2026-09-01T18:29:20.39014Z","steps":["trace[1972394600] 'agreement among raft nodes before linearized reading' (duration: 724.638247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.390195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.665342Z","time spent":"724.846503ms","remote":"127.0.0.1:51016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-01T18:29:20.390174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.853557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T18:29:20.390255Z","caller":"traceutil/trace.go:171","msg":"trace[413688121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2240; }","duration":"242.032152ms","start":"2026-09-01T18:29:20.148202Z","end":"2026-09-01T18:29:20.390235Z","steps":["trace[413688121] 'agreement among raft nodes before linearized reading' (duration: 241.683852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.390254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.568889ms","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":"warn","ts":"2026-09-01T18:29:20.390197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.798666Z","time spent":"591.522067ms","remote":"127.0.0.1:36012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T18:29:20.390367Z","caller":"traceutil/trace.go:171","msg":"trace[850938604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2240; }","duration":"743.759194ms","start":"2026-09-01T18:29:19.646582Z","end":"2026-09-01T18:29:20.390342Z","steps":["trace[850938604] 'agreement among raft nodes before linearized reading' (duration: 743.26905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:20.390419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:19.646541Z","time spent":"743.868926ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-01T18:30:34.905637Z","caller":"traceutil/trace.go:171","msg":"trace[444030114] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"105.003836ms","start":"2026-09-01T18:30:34.8006Z","end":"2026-09-01T18:30:34.905603Z","steps":["trace[444030114] 'process raft request' (duration: 104.745369ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:25.374107Z","caller":"traceutil/trace.go:171","msg":"trace[1567465653] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"124.211338ms","start":"2026-09-01T18:35:25.249868Z","end":"2026-09-01T18:35:25.374079Z","steps":["trace[1567465653] 'process raft request' (duration: 124.053854ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:25.700399Z","caller":"traceutil/trace.go:171","msg":"trace[650560579] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"194.419521ms","start":"2026-09-01T18:35:25.505954Z","end":"2026-09-01T18:35:25.700374Z","steps":["trace[650560579] 'process raft request' (duration: 194.239296ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:25.700517Z","caller":"traceutil/trace.go:171","msg":"trace[1753338806] linearizableReadLoop","detail":"{readStateIndex:5273; appliedIndex:5273; }","duration":"180.525089ms","start":"2026-09-01T18:35:25.51997Z","end":"2026-09-01T18:35:25.700495Z","steps":["trace[1753338806] 'read index received' (duration: 180.516609ms)","trace[1753338806] 'applied index is now lower than readState.Index' (duration: 6.85µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:25.70072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.733705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T18:35:25.70076Z","caller":"traceutil/trace.go:171","msg":"trace[1686782941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5076; }","duration":"180.837468ms","start":"2026-09-01T18:35:25.51991Z","end":"2026-09-01T18:35:25.700747Z","steps":["trace[1686782941] 'agreement among raft nodes before linearized reading' (duration: 180.676494ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:25.729139Z","caller":"traceutil/trace.go:171","msg":"trace[10846531] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"206.490455ms","start":"2026-09-01T18:35:25.52259Z","end":"2026-09-01T18:35:25.729081Z","steps":["trace[10846531] 'process raft request' (duration: 193.304271ms)","trace[10846531] 'compare' (duration: 12.951117ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:25.729275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.059069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"warn","ts":"2026-09-01T18:35:25.729401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.912885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:35:25.729512Z","caller":"traceutil/trace.go:171","msg":"trace[1894060305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5077; }","duration":"195.369258ms","start":"2026-09-01T18:35:25.534121Z","end":"2026-09-01T18:35:25.72949Z","steps":["trace[1894060305] 'agreement among raft nodes before linearized reading' (duration: 194.886915ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:25.72938Z","caller":"traceutil/trace.go:171","msg":"trace[1113704421] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5077; }","duration":"187.218804ms","start":"2026-09-01T18:35:25.542133Z","end":"2026-09-01T18:35:25.729352Z","steps":["trace[1113704421] 'agreement among raft nodes before linearized reading' (duration: 186.947807ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.376944Z","caller":"traceutil/trace.go:171","msg":"trace[1122362910] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"312.648867ms","start":"2026-09-01T18:35:32.064263Z","end":"2026-09-01T18:35:32.376912Z","steps":["trace[1122362910] 'process raft request' (duration: 312.438551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.377188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:35:32.064229Z","time spent":"312.828762ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:35:32.418276Z","caller":"traceutil/trace.go:171","msg":"trace[698540325] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5509; }","duration":"350.397985ms","start":"2026-09-01T18:35:32.06785Z","end":"2026-09-01T18:35:32.418248Z","steps":["trace[698540325] 'read index received' (duration: 308.855272ms)","trace[698540325] 'applied index is now lower than readState.Index' (duration: 41.541513ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:32.418367Z","caller":"traceutil/trace.go:171","msg":"trace[1100434782] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"351.787103ms","start":"2026-09-01T18:35:32.066536Z","end":"2026-09-01T18:35:32.418323Z","steps":["trace[1100434782] 'process raft request' (duration: 351.572697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.418534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.668473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6a639a8-ddf4-41ff-b412-8cee443e1844\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-01T18:35:32.418886Z","caller":"traceutil/trace.go:171","msg":"trace[1566766406] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e6a639a8-ddf4-41ff-b412-8cee443e1844; range_end:; response_count:1; response_revision:5307; }","duration":"351.076305ms","start":"2026-09-01T18:35:32.067795Z","end":"2026-09-01T18:35:32.418871Z","steps":["trace[1566766406] 'agreement among raft nodes before linearized reading' (duration: 350.55284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.41893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:35:32.067782Z","time spent":"351.137086ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6a639a8-ddf4-41ff-b412-8cee443e1844\" "} {"level":"warn","ts":"2026-09-01T18:35:32.418923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:35:32.066505Z","time spent":"352.261737ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:35:32.685987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.203799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:35:32.68607Z","caller":"traceutil/trace.go:171","msg":"trace[1782202392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5307; }","duration":"152.338743ms","start":"2026-09-01T18:35:32.533708Z","end":"2026-09-01T18:35:32.686047Z","steps":["trace[1782202392] 'range keys from in-memory index tree' (duration: 152.010814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.686061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.635707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T18:35:32.686145Z","caller":"traceutil/trace.go:171","msg":"trace[1642415652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5307; }","duration":"158.780011ms","start":"2026-09-01T18:35:32.527346Z","end":"2026-09-01T18:35:32.686126Z","steps":["trace[1642415652] 'range keys from in-memory index tree' (duration: 158.409361ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.691387Z","caller":"traceutil/trace.go:171","msg":"trace[1079266279] linearizableReadLoop","detail":"{readStateIndex:5512; appliedIndex:5511; }","duration":"105.852154ms","start":"2026-09-01T18:35:32.585505Z","end":"2026-09-01T18:35:32.691357Z","steps":["trace[1079266279] 'read index received' (duration: 98.688126ms)","trace[1079266279] 'applied index is now lower than readState.Index' (duration: 7.161828ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:35:32.691597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.0916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:35:32.691573Z","caller":"traceutil/trace.go:171","msg":"trace[670349887] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"100.185128ms","start":"2026-09-01T18:35:32.591361Z","end":"2026-09-01T18:35:32.691546Z","steps":["trace[670349887] 'process raft request' (duration: 99.944232ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:32.691622Z","caller":"traceutil/trace.go:171","msg":"trace[475944820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5308; }","duration":"106.168563ms","start":"2026-09-01T18:35:32.585446Z","end":"2026-09-01T18:35:32.691615Z","steps":["trace[475944820] 'agreement among raft nodes before linearized reading' (duration: 106.012089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.707315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.498893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T18:35:32.707376Z","caller":"traceutil/trace.go:171","msg":"trace[1556851295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5311; }","duration":"118.608876ms","start":"2026-09-01T18:35:32.588753Z","end":"2026-09-01T18:35:32.707362Z","steps":["trace[1556851295] 'agreement among raft nodes before linearized reading' (duration: 118.40504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:32.707485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.790985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-nxn65\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-09-01T18:35:32.707515Z","caller":"traceutil/trace.go:171","msg":"trace[283764671] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-nxn65; range_end:; response_count:1; response_revision:5311; }","duration":"116.839457ms","start":"2026-09-01T18:35:32.590666Z","end":"2026-09-01T18:35:32.707506Z","steps":["trace[283764671] 'agreement among raft nodes before linearized reading' (duration: 116.760845ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:34.901303Z","caller":"traceutil/trace.go:171","msg":"trace[823317945] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5558; }","duration":"188.799578ms","start":"2026-09-01T18:35:34.712467Z","end":"2026-09-01T18:35:34.901266Z","steps":["trace[823317945] 'read index received' (duration: 124.682352ms)","trace[823317945] 'applied index is now lower than readState.Index' (duration: 64.115486ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:34.901361Z","caller":"traceutil/trace.go:171","msg":"trace[1795630948] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"204.794217ms","start":"2026-09-01T18:35:34.696522Z","end":"2026-09-01T18:35:34.901316Z","steps":["trace[1795630948] 'process raft request' (duration: 140.61278ms)","trace[1795630948] 'compare' (duration: 63.851918ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:34.901398Z","caller":"traceutil/trace.go:171","msg":"trace[2109395559] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"204.516669ms","start":"2026-09-01T18:35:34.696847Z","end":"2026-09-01T18:35:34.901363Z","steps":["trace[2109395559] 'process raft request' (duration: 204.343795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:34.90158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.096856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T18:35:34.901615Z","caller":"traceutil/trace.go:171","msg":"trace[227770551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5353; }","duration":"189.184338ms","start":"2026-09-01T18:35:34.712422Z","end":"2026-09-01T18:35:34.901606Z","steps":["trace[227770551] 'agreement among raft nodes before linearized reading' (duration: 189.033064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:34.901676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.064775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:35:34.90172Z","caller":"traceutil/trace.go:171","msg":"trace[106030658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5353; }","duration":"189.195319ms","start":"2026-09-01T18:35:34.712513Z","end":"2026-09-01T18:35:34.901708Z","steps":["trace[106030658] 'agreement among raft nodes before linearized reading' (duration: 188.970963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:34.901722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.655246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T18:35:34.901758Z","caller":"traceutil/trace.go:171","msg":"trace[871589604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5353; }","duration":"183.707118ms","start":"2026-09-01T18:35:34.718039Z","end":"2026-09-01T18:35:34.901746Z","steps":["trace[871589604] 'agreement among raft nodes before linearized reading' (duration: 183.571084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:34.901775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.179779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T18:35:34.901872Z","caller":"traceutil/trace.go:171","msg":"trace[820003192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5353; }","duration":"180.337884ms","start":"2026-09-01T18:35:34.721514Z","end":"2026-09-01T18:35:34.901851Z","steps":["trace[820003192] 'agreement among raft nodes before linearized reading' (duration: 179.993434ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:39.123848Z","caller":"traceutil/trace.go:171","msg":"trace[72318226] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"210.484364ms","start":"2026-09-01T18:35:38.913337Z","end":"2026-09-01T18:35:39.123821Z","steps":["trace[72318226] 'process raft request' (duration: 204.508389ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:39.135542Z","caller":"traceutil/trace.go:171","msg":"trace[608042371] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5631; }","duration":"176.387446ms","start":"2026-09-01T18:35:38.959129Z","end":"2026-09-01T18:35:39.135516Z","steps":["trace[608042371] 'read index received' (duration: 158.587846ms)","trace[608042371] 'applied index is now lower than readState.Index' (duration: 17.79858ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:39.135656Z","caller":"traceutil/trace.go:171","msg":"trace[690630684] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"220.52306ms","start":"2026-09-01T18:35:38.915082Z","end":"2026-09-01T18:35:39.135605Z","steps":["trace[690630684] 'process raft request' (duration: 220.215942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:39.135801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.868796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:35:39.135842Z","caller":"traceutil/trace.go:171","msg":"trace[1587301678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5423; }","duration":"157.975288ms","start":"2026-09-01T18:35:38.977852Z","end":"2026-09-01T18:35:39.135828Z","steps":["trace[1587301678] 'agreement among raft nodes before linearized reading' (duration: 157.802993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:39.135941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.666942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T18:35:39.136041Z","caller":"traceutil/trace.go:171","msg":"trace[163205865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5423; }","duration":"156.804306ms","start":"2026-09-01T18:35:38.979215Z","end":"2026-09-01T18:35:39.13602Z","steps":["trace[163205865] 'agreement among raft nodes before linearized reading' (duration: 156.57404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:39.136099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.963491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-01T18:35:39.135941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.012973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T18:35:39.136158Z","caller":"traceutil/trace.go:171","msg":"trace[1575962081] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5423; }","duration":"177.053034ms","start":"2026-09-01T18:35:38.959088Z","end":"2026-09-01T18:35:39.136141Z","steps":["trace[1575962081] 'agreement among raft nodes before linearized reading' (duration: 176.572591ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:39.136235Z","caller":"traceutil/trace.go:171","msg":"trace[612070249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5423; }","duration":"156.312512ms","start":"2026-09-01T18:35:38.979878Z","end":"2026-09-01T18:35:39.13619Z","steps":["trace[612070249] 'agreement among raft nodes before linearized reading' (duration: 155.86096ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:48.942776Z","caller":"traceutil/trace.go:171","msg":"trace[1935758615] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"142.39398ms","start":"2026-09-01T18:35:48.800348Z","end":"2026-09-01T18:35:48.942742Z","steps":["trace[1935758615] 'process raft request' (duration: 53.074981ms)","trace[1935758615] 'compare' (duration: 89.176625ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:57.289835Z","caller":"traceutil/trace.go:171","msg":"trace[675881481] linearizableReadLoop","detail":"{readStateIndex:5942; appliedIndex:5941; }","duration":"347.820674ms","start":"2026-09-01T18:35:56.941991Z","end":"2026-09-01T18:35:57.289811Z","steps":["trace[675881481] 'read index received' (duration: 347.597718ms)","trace[675881481] 'applied index is now lower than readState.Index' (duration: 221.586µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:35:57.289897Z","caller":"traceutil/trace.go:171","msg":"trace[898945479] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"381.726359ms","start":"2026-09-01T18:35:56.908141Z","end":"2026-09-01T18:35:57.289867Z","steps":["trace[898945479] 'process raft request' (duration: 381.433881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:57.290056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:35:56.908119Z","time spent":"381.849611ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:35:57.290071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.06964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-01T18:35:57.290109Z","caller":"traceutil/trace.go:171","msg":"trace[1163500467] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5726; }","duration":"348.161113ms","start":"2026-09-01T18:35:56.941936Z","end":"2026-09-01T18:35:57.290097Z","steps":["trace[1163500467] 'agreement among raft nodes before linearized reading' (duration: 348.016389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:57.290139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:35:56.941921Z","time spent":"348.209225ms","remote":"127.0.0.1:36136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-09-01T18:35:57.349153Z","caller":"traceutil/trace.go:171","msg":"trace[251298305] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"150.243776ms","start":"2026-09-01T18:35:57.198861Z","end":"2026-09-01T18:35:57.349104Z","steps":["trace[251298305] 'process raft request' (duration: 149.739402ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.349292Z","caller":"traceutil/trace.go:171","msg":"trace[1509667768] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"148.255691ms","start":"2026-09-01T18:35:57.200995Z","end":"2026-09-01T18:35:57.349251Z","steps":["trace[1509667768] 'process raft request' (duration: 147.813449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:35:57.349574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.365569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-01T18:35:57.349863Z","caller":"traceutil/trace.go:171","msg":"trace[1641751528] range","detail":"{range_begin:/registry/masterleases/162.253.55.95; range_end:; response_count:1; response_revision:5728; }","duration":"267.705839ms","start":"2026-09-01T18:35:57.08213Z","end":"2026-09-01T18:35:57.349836Z","steps":["trace[1641751528] 'agreement among raft nodes before linearized reading' (duration: 266.864476ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.550389Z","caller":"traceutil/trace.go:171","msg":"trace[1064950096] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"164.630702ms","start":"2026-09-01T18:35:57.385729Z","end":"2026-09-01T18:35:57.550359Z","steps":["trace[1064950096] 'process raft request' (duration: 164.494708ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.550576Z","caller":"traceutil/trace.go:171","msg":"trace[151870289] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"195.947894ms","start":"2026-09-01T18:35:57.354604Z","end":"2026-09-01T18:35:57.550552Z","steps":["trace[151870289] 'process raft request' (duration: 195.510542ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.550689Z","caller":"traceutil/trace.go:171","msg":"trace[894592136] transaction","detail":"{read_only:false; response_revision:5731; number_of_response:1; }","duration":"196.004775ms","start":"2026-09-01T18:35:57.354634Z","end":"2026-09-01T18:35:57.550638Z","steps":["trace[894592136] 'process raft request' (duration: 195.521382ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.550783Z","caller":"traceutil/trace.go:171","msg":"trace[556103093] transaction","detail":"{read_only:false; response_revision:5732; number_of_response:1; }","duration":"195.839171ms","start":"2026-09-01T18:35:57.354927Z","end":"2026-09-01T18:35:57.550766Z","steps":["trace[556103093] 'process raft request' (duration: 195.260805ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.550855Z","caller":"traceutil/trace.go:171","msg":"trace[2057164637] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"196.245782ms","start":"2026-09-01T18:35:57.35455Z","end":"2026-09-01T18:35:57.550796Z","steps":["trace[2057164637] 'process raft request' (duration: 195.381108ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:35:57.570834Z","caller":"traceutil/trace.go:171","msg":"trace[963097766] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"113.914716ms","start":"2026-09-01T18:35:57.456887Z","end":"2026-09-01T18:35:57.570802Z","steps":["trace[963097766] 'process raft request' (duration: 102.725068ms)","trace[963097766] 'compare' (duration: 11.010783ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:36:03.639325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-09-01T18:36:03.734846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"93.922095ms","hash":4069194008,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":29962240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T18:36:03.73494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4069194008,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:36:12.257898Z","caller":"traceutil/trace.go:171","msg":"trace[1135583113] linearizableReadLoop","detail":"{readStateIndex:6170; appliedIndex:6169; }","duration":"130.489095ms","start":"2026-09-01T18:36:12.127379Z","end":"2026-09-01T18:36:12.257868Z","steps":["trace[1135583113] 'read index received' (duration: 130.234168ms)","trace[1135583113] 'applied index is now lower than readState.Index' (duration: 253.657µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:36:12.257967Z","caller":"traceutil/trace.go:171","msg":"trace[493885598] transaction","detail":"{read_only:false; response_revision:5947; number_of_response:1; }","duration":"138.324281ms","start":"2026-09-01T18:36:12.119611Z","end":"2026-09-01T18:36:12.257936Z","steps":["trace[493885598] 'process raft request' (duration: 138.022282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:36:12.258139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.734371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-01T18:36:12.258183Z","caller":"traceutil/trace.go:171","msg":"trace[281816461] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5947; }","duration":"130.815984ms","start":"2026-09-01T18:36:12.127352Z","end":"2026-09-01T18:36:12.258168Z","steps":["trace[281816461] 'agreement among raft nodes before linearized reading' (duration: 130.624609ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:36:12.884663Z","caller":"traceutil/trace.go:171","msg":"trace[1111001944] linearizableReadLoop","detail":"{readStateIndex:6175; appliedIndex:6174; }","duration":"107.282595ms","start":"2026-09-01T18:36:12.777354Z","end":"2026-09-01T18:36:12.884636Z","steps":["trace[1111001944] 'read index received' (duration: 107.120989ms)","trace[1111001944] 'applied index is now lower than readState.Index' (duration: 160.326µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:36:12.88479Z","caller":"traceutil/trace.go:171","msg":"trace[389983969] transaction","detail":"{read_only:false; response_revision:5951; number_of_response:1; }","duration":"144.660484ms","start":"2026-09-01T18:36:12.7401Z","end":"2026-09-01T18:36:12.88476Z","steps":["trace[389983969] 'process raft request' (duration: 144.392176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:36:12.884829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.45066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:36:12.884869Z","caller":"traceutil/trace.go:171","msg":"trace[1474340443] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5951; }","duration":"107.533382ms","start":"2026-09-01T18:36:12.777326Z","end":"2026-09-01T18:36:12.88486Z","steps":["trace[1474340443] 'agreement among raft nodes before linearized reading' (duration: 107.417469ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:36:12.997785Z","caller":"traceutil/trace.go:171","msg":"trace[79358417] transaction","detail":"{read_only:false; response_revision:5954; number_of_response:1; }","duration":"103.032147ms","start":"2026-09-01T18:36:12.894728Z","end":"2026-09-01T18:36:12.99776Z","steps":["trace[79358417] 'process raft request' (duration: 102.951255ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:36:12.997825Z","caller":"traceutil/trace.go:171","msg":"trace[1848738977] transaction","detail":"{read_only:false; response_revision:5953; number_of_response:1; }","duration":"107.011977ms","start":"2026-09-01T18:36:12.890783Z","end":"2026-09-01T18:36:12.997795Z","steps":["trace[1848738977] 'process raft request' (duration: 97.697211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:01.337502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.16848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-01T18:37:01.337632Z","caller":"traceutil/trace.go:171","msg":"trace[965575499] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6341; }","duration":"120.334365ms","start":"2026-09-01T18:37:01.217268Z","end":"2026-09-01T18:37:01.337603Z","steps":["trace[965575499] 'agreement among raft nodes before linearized reading' (duration: 70.186023ms)","trace[965575499] 'range keys from in-memory index tree' (duration: 49.663477ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:41:03.649037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5850} {"level":"info","ts":"2026-09-01T18:41:03.744722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5850,"took":"93.466638ms","hash":451960485,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":26333184,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T18:41:03.744803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":451960485,"revision":5850,"compact-revision":3002} {"level":"info","ts":"2026-09-01T18:42:24.531996Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233c.wal"} {"level":"info","ts":"2026-09-01T18:42:29.96702Z","caller":"traceutil/trace.go:171","msg":"trace[1735879247] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"170.303517ms","start":"2026-09-01T18:42:29.796692Z","end":"2026-09-01T18:42:29.966996Z","steps":["trace[1735879247] 'process raft request' (duration: 170.174494ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:29.969231Z","caller":"traceutil/trace.go:171","msg":"trace[589652734] linearizableReadLoop","detail":"{readStateIndex:9050; appliedIndex:9048; }","duration":"169.125603ms","start":"2026-09-01T18:42:29.800074Z","end":"2026-09-01T18:42:29.9692Z","steps":["trace[589652734] 'read index received' (duration: 166.83415ms)","trace[589652734] 'applied index is now lower than readState.Index' (duration: 2.290093ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:42:29.969318Z","caller":"traceutil/trace.go:171","msg":"trace[984564383] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"171.764207ms","start":"2026-09-01T18:42:29.797534Z","end":"2026-09-01T18:42:29.969298Z","steps":["trace[984564383] 'process raft request' (duration: 171.50388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:42:29.96957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.750378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T18:42:29.969621Z","caller":"traceutil/trace.go:171","msg":"trace[273493950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8737; }","duration":"154.809209ms","start":"2026-09-01T18:42:29.814796Z","end":"2026-09-01T18:42:29.969605Z","steps":["trace[273493950] 'agreement among raft nodes before linearized reading' (duration: 154.673695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:42:29.969674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.560846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:42:29.969726Z","caller":"traceutil/trace.go:171","msg":"trace[1964957459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8737; }","duration":"169.695449ms","start":"2026-09-01T18:42:29.800019Z","end":"2026-09-01T18:42:29.969714Z","steps":["trace[1964957459] 'agreement among raft nodes before linearized reading' (duration: 169.318559ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:40.40062Z","caller":"traceutil/trace.go:171","msg":"trace[525281164] transaction","detail":"{read_only:false; response_revision:8788; number_of_response:1; }","duration":"285.528445ms","start":"2026-09-01T18:42:40.115051Z","end":"2026-09-01T18:42:40.400579Z","steps":["trace[525281164] 'process raft request' (duration: 253.121096ms)","trace[525281164] 'compare' (duration: 32.220744ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:42:40.4087Z","caller":"traceutil/trace.go:171","msg":"trace[1044500830] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9102; }","duration":"253.146186ms","start":"2026-09-01T18:42:40.155531Z","end":"2026-09-01T18:42:40.408678Z","steps":["trace[1044500830] 'read index received' (duration: 212.658194ms)","trace[1044500830] 'applied index is now lower than readState.Index' (duration: 40.487342ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:42:40.408886Z","caller":"traceutil/trace.go:171","msg":"trace[1355854885] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"293.525577ms","start":"2026-09-01T18:42:40.115352Z","end":"2026-09-01T18:42:40.408878Z","steps":["trace[1355854885] 'process raft request' (duration: 293.139095ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:40.409019Z","caller":"traceutil/trace.go:171","msg":"trace[903499551] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"293.475345ms","start":"2026-09-01T18:42:40.115536Z","end":"2026-09-01T18:42:40.409012Z","steps":["trace[903499551] 'process raft request' (duration: 293.062153ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:40.409107Z","caller":"traceutil/trace.go:171","msg":"trace[1968057807] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"182.854089ms","start":"2026-09-01T18:42:40.226246Z","end":"2026-09-01T18:42:40.4091Z","steps":["trace[1968057807] 'process raft request' (duration: 182.375185ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:40.409201Z","caller":"traceutil/trace.go:171","msg":"trace[886577711] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"177.768868ms","start":"2026-09-01T18:42:40.231425Z","end":"2026-09-01T18:42:40.409194Z","steps":["trace[886577711] 'process raft request' (duration: 177.229683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:42:40.409324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.784965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:42:40.409378Z","caller":"traceutil/trace.go:171","msg":"trace[534077715] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8792; }","duration":"253.878258ms","start":"2026-09-01T18:42:40.155472Z","end":"2026-09-01T18:42:40.40935Z","steps":["trace[534077715] 'agreement among raft nodes before linearized reading' (duration: 253.804526ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:41.198074Z","caller":"traceutil/trace.go:171","msg":"trace[1785736666] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"127.649607ms","start":"2026-09-01T18:42:41.070385Z","end":"2026-09-01T18:42:41.198034Z","steps":["trace[1785736666] 'process raft request' (duration: 108.222619ms)","trace[1785736666] 'compare' (duration: 19.006307ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:10.566335Z","caller":"traceutil/trace.go:171","msg":"trace[1843824984] transaction","detail":"{read_only:false; response_revision:9004; number_of_response:1; }","duration":"244.417226ms","start":"2026-09-01T18:43:10.321889Z","end":"2026-09-01T18:43:10.566306Z","steps":["trace[1843824984] 'process raft request' (duration: 244.253941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.074509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.352717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:43:11.074793Z","caller":"traceutil/trace.go:171","msg":"trace[1412319546] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9327; }","duration":"462.905341ms","start":"2026-09-01T18:43:10.611861Z","end":"2026-09-01T18:43:11.074766Z","steps":["trace[1412319546] 'read index received' (duration: 338.215965ms)","trace[1412319546] 'applied index is now lower than readState.Index' (duration: 124.688256ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:11.074868Z","caller":"traceutil/trace.go:171","msg":"trace[1457853146] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"564.097236ms","start":"2026-09-01T18:43:10.510696Z","end":"2026-09-01T18:43:11.074793Z","steps":["trace[1457853146] 'process raft request' (duration: 439.364599ms)","trace[1457853146] 'compare' (duration: 123.859833ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:11.074876Z","caller":"traceutil/trace.go:171","msg":"trace[227402221] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"507.493527ms","start":"2026-09-01T18:43:10.567333Z","end":"2026-09-01T18:43:11.074827Z","steps":["trace[227402221] 'process raft request' (duration: 507.314642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.075045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.510665Z","time spent":"564.289011ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:43:11.075049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.567312Z","time spent":"507.680582ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:43:11.075642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.836282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T18:43:11.075715Z","caller":"traceutil/trace.go:171","msg":"trace[785671659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9007; }","duration":"448.949565ms","start":"2026-09-01T18:43:10.626743Z","end":"2026-09-01T18:43:11.075693Z","steps":["trace[785671659] 'agreement among raft nodes before linearized reading' (duration: 448.688847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.075588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.865183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T18:43:11.075759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.626725Z","time spent":"449.022747ms","remote":"127.0.0.1:36232","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-09-01T18:43:11.075821Z","caller":"traceutil/trace.go:171","msg":"trace[1352140792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9007; }","duration":"449.099879ms","start":"2026-09-01T18:43:10.62668Z","end":"2026-09-01T18:43:11.07578Z","steps":["trace[1352140792] 'agreement among raft nodes before linearized reading' (duration: 448.747778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.075702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.070823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-01T18:43:11.075888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.626668Z","time spent":"449.199872ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T18:43:11.075586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.664652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:43:11.076083Z","caller":"traceutil/trace.go:171","msg":"trace[1581659668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9007; }","duration":"451.049623ms","start":"2026-09-01T18:43:10.624885Z","end":"2026-09-01T18:43:11.075935Z","steps":["trace[1581659668] 'agreement among raft nodes before linearized reading' (duration: 450.554488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.075094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.219569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:43:11.076269Z","caller":"traceutil/trace.go:171","msg":"trace[1632888131] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:9006; }","duration":"464.402642ms","start":"2026-09-01T18:43:10.611812Z","end":"2026-09-01T18:43:11.076214Z","steps":["trace[1632888131] 'agreement among raft nodes before linearized reading' (duration: 463.125507ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:11.076209Z","caller":"traceutil/trace.go:171","msg":"trace[615076932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9007; }","duration":"451.302719ms","start":"2026-09-01T18:43:10.624574Z","end":"2026-09-01T18:43:11.075876Z","steps":["trace[615076932] 'agreement among raft nodes before linearized reading' (duration: 450.856966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:11.076333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.611788Z","time spent":"464.526986ms","remote":"127.0.0.1:60058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-09-01T18:43:11.076319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.624875Z","time spent":"451.279929ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-01T18:43:11.076401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:10.624545Z","time spent":"451.836424ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T18:43:13.556542Z","caller":"traceutil/trace.go:171","msg":"trace[910900875] linearizableReadLoop","detail":"{readStateIndex:9349; appliedIndex:9348; }","duration":"145.02216ms","start":"2026-09-01T18:43:13.411496Z","end":"2026-09-01T18:43:13.556518Z","steps":["trace[910900875] 'read index received' (duration: 144.783204ms)","trace[910900875] 'applied index is now lower than readState.Index' (duration: 237.766µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:13.556634Z","caller":"traceutil/trace.go:171","msg":"trace[1548470018] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"153.348421ms","start":"2026-09-01T18:43:13.403262Z","end":"2026-09-01T18:43:13.556611Z","steps":["trace[1548470018] 'process raft request' (duration: 152.974891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:13.556785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.278727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lbkn2\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-01T18:43:13.556838Z","caller":"traceutil/trace.go:171","msg":"trace[1045227046] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lbkn2; range_end:; response_count:1; response_revision:9025; }","duration":"145.37498ms","start":"2026-09-01T18:43:13.411449Z","end":"2026-09-01T18:43:13.556824Z","steps":["trace[1045227046] 'agreement among raft nodes before linearized reading' (duration: 145.215885ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:15.232189Z","caller":"traceutil/trace.go:171","msg":"trace[2000213398] transaction","detail":"{read_only:false; response_revision:9027; number_of_response:1; }","duration":"140.428633ms","start":"2026-09-01T18:43:15.091736Z","end":"2026-09-01T18:43:15.232165Z","steps":["trace[2000213398] 'process raft request' (duration: 128.820491ms)","trace[2000213398] 'compare' (duration: 11.513819ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:15.24759Z","caller":"traceutil/trace.go:171","msg":"trace[900278481] linearizableReadLoop","detail":"{readStateIndex:9352; appliedIndex:9350; }","duration":"148.248909ms","start":"2026-09-01T18:43:15.099321Z","end":"2026-09-01T18:43:15.24757Z","steps":["trace[900278481] 'read index received' (duration: 121.172748ms)","trace[900278481] 'applied index is now lower than readState.Index' (duration: 27.075261ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:43:15.247779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.461825ms","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-09-01T18:43:15.24775Z","caller":"traceutil/trace.go:171","msg":"trace[877179453] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"152.015414ms","start":"2026-09-01T18:43:15.095678Z","end":"2026-09-01T18:43:15.247694Z","steps":["trace[877179453] 'process raft request' (duration: 151.811978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:15.247871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.53964ms","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-09-01T18:43:15.247893Z","caller":"traceutil/trace.go:171","msg":"trace[1357010606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9028; }","duration":"147.589651ms","start":"2026-09-01T18:43:15.100298Z","end":"2026-09-01T18:43:15.247887Z","steps":["trace[1357010606] 'agreement among raft nodes before linearized reading' (duration: 147.530999ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:15.247813Z","caller":"traceutil/trace.go:171","msg":"trace[651649845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9028; }","duration":"148.62136ms","start":"2026-09-01T18:43:15.099179Z","end":"2026-09-01T18:43:15.2478Z","steps":["trace[651649845] 'agreement among raft nodes before linearized reading' (duration: 148.499886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:15.248026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.68748ms","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-09-01T18:43:15.248044Z","caller":"traceutil/trace.go:171","msg":"trace[1149601801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9028; }","duration":"140.734371ms","start":"2026-09-01T18:43:15.107305Z","end":"2026-09-01T18:43:15.248039Z","steps":["trace[1149601801] 'agreement among raft nodes before linearized reading' (duration: 140.669299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:15.248179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.313648ms","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-09-01T18:43:15.248218Z","caller":"traceutil/trace.go:171","msg":"trace[631781347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9028; }","duration":"145.39421ms","start":"2026-09-01T18:43:15.102814Z","end":"2026-09-01T18:43:15.248208Z","steps":["trace[631781347] 'agreement among raft nodes before linearized reading' (duration: 145.229465ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:24.343321Z","caller":"traceutil/trace.go:171","msg":"trace[1006663268] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"102.769729ms","start":"2026-09-01T18:43:24.240505Z","end":"2026-09-01T18:43:24.343275Z","steps":["trace[1006663268] 'process raft request' (duration: 65.906656ms)","trace[1006663268] 'compare' (duration: 36.292506ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:43:24.639964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.193084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:43:24.640055Z","caller":"traceutil/trace.go:171","msg":"trace[1565816295] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:0; response_revision:9081; }","duration":"115.322387ms","start":"2026-09-01T18:43:24.524713Z","end":"2026-09-01T18:43:24.640036Z","steps":["trace[1565816295] 'range keys from in-memory index tree' (duration: 115.127612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:24.640144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.728187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:43:24.640318Z","caller":"traceutil/trace.go:171","msg":"trace[1314351659] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:9081; }","duration":"151.994094ms","start":"2026-09-01T18:43:24.488283Z","end":"2026-09-01T18:43:24.640277Z","steps":["trace[1314351659] 'count revisions from in-memory index tree' (duration: 151.586533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:24.859248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.36101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:499"} {"level":"info","ts":"2026-09-01T18:43:24.859403Z","caller":"traceutil/trace.go:171","msg":"trace[102938892] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9083; }","duration":"114.528245ms","start":"2026-09-01T18:43:24.744816Z","end":"2026-09-01T18:43:24.859344Z","steps":["trace[102938892] 'range keys from in-memory index tree' (duration: 114.263207ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:24.859357Z","caller":"traceutil/trace.go:171","msg":"trace[2082501565] linearizableReadLoop","detail":"{readStateIndex:9411; appliedIndex:9410; }","duration":"112.461097ms","start":"2026-09-01T18:43:24.746856Z","end":"2026-09-01T18:43:24.859317Z","steps":["trace[2082501565] 'read index received' (duration: 98.250214ms)","trace[2082501565] 'applied index is now lower than readState.Index' (duration: 14.208173ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:24.859554Z","caller":"traceutil/trace.go:171","msg":"trace[1477773758] transaction","detail":"{read_only:false; response_revision:9084; number_of_response:1; }","duration":"113.112685ms","start":"2026-09-01T18:43:24.746418Z","end":"2026-09-01T18:43:24.859531Z","steps":["trace[1477773758] 'process raft request' (duration: 98.581343ms)","trace[1477773758] 'compare' (duration: 13.830613ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:43:24.859713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.867749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wtkcs\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-09-01T18:43:24.859788Z","caller":"traceutil/trace.go:171","msg":"trace[411579554] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wtkcs; range_end:; response_count:1; response_revision:9084; }","duration":"112.980281ms","start":"2026-09-01T18:43:24.746776Z","end":"2026-09-01T18:43:24.859756Z","steps":["trace[411579554] 'agreement among raft nodes before linearized reading' (duration: 112.802806ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:25.115734Z","caller":"traceutil/trace.go:171","msg":"trace[590483095] linearizableReadLoop","detail":"{readStateIndex:9412; appliedIndex:9411; }","duration":"256.232612ms","start":"2026-09-01T18:43:24.859476Z","end":"2026-09-01T18:43:25.115709Z","steps":["trace[590483095] 'read index received' (duration: 256.16725ms)","trace[590483095] 'applied index is now lower than readState.Index' (duration: 64.222µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:25.115832Z","caller":"traceutil/trace.go:171","msg":"trace[184760015] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"366.22169ms","start":"2026-09-01T18:43:24.749575Z","end":"2026-09-01T18:43:25.115796Z","steps":["trace[184760015] 'process raft request' (duration: 365.988764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.116126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.711929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wtkcs\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-09-01T18:43:25.1162Z","caller":"traceutil/trace.go:171","msg":"trace[1423511597] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wtkcs; range_end:; response_count:1; response_revision:9085; }","duration":"360.855493ms","start":"2026-09-01T18:43:24.755328Z","end":"2026-09-01T18:43:25.116183Z","steps":["trace[1423511597] 'agreement among raft nodes before linearized reading' (duration: 360.644476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.115917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.792204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:43:25.116257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.755299Z","time spent":"360.945955ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4658,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wtkcs\" "} {"level":"info","ts":"2026-09-01T18:43:25.116292Z","caller":"traceutil/trace.go:171","msg":"trace[159119810] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9085; }","duration":"342.220507ms","start":"2026-09-01T18:43:24.774047Z","end":"2026-09-01T18:43:25.116267Z","steps":["trace[159119810] 'agreement among raft nodes before linearized reading' (duration: 341.791494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.116087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.838504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"warn","ts":"2026-09-01T18:43:25.116357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.77402Z","time spent":"342.31966ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-01T18:43:25.116435Z","caller":"traceutil/trace.go:171","msg":"trace[1695182042] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9085; }","duration":"360.237265ms","start":"2026-09-01T18:43:24.756156Z","end":"2026-09-01T18:43:25.116394Z","steps":["trace[1695182042] 'agreement among raft nodes before linearized reading' (duration: 359.742151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.116547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.756132Z","time spent":"360.377509ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3089,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-09-01T18:43:25.116754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.749536Z","time spent":"366.630782ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4620,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:43:25.225804Z","caller":"traceutil/trace.go:171","msg":"trace[194151997] transaction","detail":"{read_only:false; response_revision:9086; number_of_response:1; }","duration":"364.69928ms","start":"2026-09-01T18:43:24.861072Z","end":"2026-09-01T18:43:25.225771Z","steps":["trace[194151997] 'process raft request' (duration: 344.286024ms)","trace[194151997] 'compare' (duration: 19.981234ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:25.22588Z","caller":"traceutil/trace.go:171","msg":"trace[300815055] linearizableReadLoop","detail":"{readStateIndex:9414; appliedIndex:9412; }","duration":"105.746521ms","start":"2026-09-01T18:43:25.120116Z","end":"2026-09-01T18:43:25.225862Z","steps":["trace[300815055] 'read index received' (duration: 85.182221ms)","trace[300815055] 'applied index is now lower than readState.Index' (duration: 20.56309ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:43:25.225979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.861046Z","time spent":"364.841663ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:43:25.226001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.876475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:43:25.225971Z","caller":"traceutil/trace.go:171","msg":"trace[2082250460] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9086; }","duration":"355.250697ms","start":"2026-09-01T18:43:24.870676Z","end":"2026-09-01T18:43:25.225927Z","steps":["trace[2082250460] 'process raft request' (duration: 355.024581ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:25.22604Z","caller":"traceutil/trace.go:171","msg":"trace[692225941] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9086; }","duration":"105.954137ms","start":"2026-09-01T18:43:25.120073Z","end":"2026-09-01T18:43:25.226027Z","steps":["trace[692225941] 'agreement among raft nodes before linearized reading' (duration: 105.874655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.226123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.797963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-09-01T18:43:25.226185Z","caller":"traceutil/trace.go:171","msg":"trace[2014123183] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9086; }","duration":"105.916256ms","start":"2026-09-01T18:43:25.120254Z","end":"2026-09-01T18:43:25.22617Z","steps":["trace[2014123183] 'agreement among raft nodes before linearized reading' (duration: 105.71446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:25.226208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:24.870645Z","time spent":"355.432982ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4669,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:44:24.413027Z","caller":"traceutil/trace.go:171","msg":"trace[347680681] transaction","detail":"{read_only:false; response_revision:9642; number_of_response:1; }","duration":"101.75401ms","start":"2026-09-01T18:44:24.311242Z","end":"2026-09-01T18:44:24.412996Z","steps":["trace[347680681] 'process raft request' (duration: 101.633637ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:25.448352Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T18:44:25.477808Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T18:44:25.477915Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-01T18:44:55.902326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.629829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:44:55.902456Z","caller":"traceutil/trace.go:171","msg":"trace[407499381] transaction","detail":"{read_only:false; response_revision:9945; number_of_response:1; }","duration":"211.017282ms","start":"2026-09-01T18:44:55.691418Z","end":"2026-09-01T18:44:55.902435Z","steps":["trace[407499381] 'process raft request' (duration: 83.187927ms)","trace[407499381] 'compare' (duration: 127.462365ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:55.938029Z","caller":"traceutil/trace.go:171","msg":"trace[933129908] linearizableReadLoop","detail":"{readStateIndex:10301; appliedIndex:10300; }","duration":"107.575834ms","start":"2026-09-01T18:44:55.830418Z","end":"2026-09-01T18:44:55.937994Z","steps":["trace[933129908] 'read index received' (duration: 107.4132ms)","trace[933129908] 'applied index is now lower than readState.Index' (duration: 161.704µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:55.938076Z","caller":"traceutil/trace.go:171","msg":"trace[1477555562] transaction","detail":"{read_only:false; response_revision:9946; number_of_response:1; }","duration":"188.012313ms","start":"2026-09-01T18:44:55.750021Z","end":"2026-09-01T18:44:55.938033Z","steps":["trace[1477555562] 'process raft request' (duration: 187.828958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:55.93843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.961644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T18:44:55.9385Z","caller":"traceutil/trace.go:171","msg":"trace[729805399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9946; }","duration":"108.13759ms","start":"2026-09-01T18:44:55.830344Z","end":"2026-09-01T18:44:55.938482Z","steps":["trace[729805399] 'agreement among raft nodes before linearized reading' (duration: 107.79962ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:46:03.656548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8056} {"level":"info","ts":"2026-09-01T18:46:03.734276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8056,"took":"75.405401ms","hash":296718201,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":28143616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:46:03.734347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":296718201,"revision":8056,"compact-revision":5850} {"level":"info","ts":"2026-09-01T18:49:09.388659Z","caller":"traceutil/trace.go:171","msg":"trace[615855614] transaction","detail":"{read_only:false; response_revision:11576; number_of_response:1; }","duration":"113.909258ms","start":"2026-09-01T18:49:09.274719Z","end":"2026-09-01T18:49:09.388629Z","steps":["trace[615855614] 'process raft request' (duration: 90.094822ms)","trace[615855614] 'compare' (duration: 23.563839ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:50:02.301037Z","caller":"traceutil/trace.go:171","msg":"trace[317597179] transaction","detail":"{read_only:false; response_revision:12086; number_of_response:1; }","duration":"120.510899ms","start":"2026-09-01T18:50:02.180485Z","end":"2026-09-01T18:50:02.300996Z","steps":["trace[317597179] 'process raft request' (duration: 97.412743ms)","trace[317597179] 'compare' (duration: 22.767787ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:50:02.3012Z","caller":"traceutil/trace.go:171","msg":"trace[1919851069] transaction","detail":"{read_only:false; response_revision:12087; number_of_response:1; }","duration":"114.34161ms","start":"2026-09-01T18:50:02.186826Z","end":"2026-09-01T18:50:02.301168Z","steps":["trace[1919851069] 'process raft request' (duration: 114.111014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:02.3013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.844833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:50:02.301264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.331242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:50:02.3014Z","caller":"traceutil/trace.go:171","msg":"trace[2136990438] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:12087; }","duration":"119.004217ms","start":"2026-09-01T18:50:02.182366Z","end":"2026-09-01T18:50:02.30137Z","steps":["trace[2136990438] 'agreement among raft nodes before linearized reading' (duration: 118.863524ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:50:02.301476Z","caller":"traceutil/trace.go:171","msg":"trace[1413591147] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:12087; }","duration":"117.545878ms","start":"2026-09-01T18:50:02.183845Z","end":"2026-09-01T18:50:02.30139Z","steps":["trace[1413591147] 'agreement among raft nodes before linearized reading' (duration: 117.26347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:02.301884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.331578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111932"} {"level":"info","ts":"2026-09-01T18:50:02.301952Z","caller":"traceutil/trace.go:171","msg":"trace[708268272] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:12087; }","duration":"143.447691ms","start":"2026-09-01T18:50:02.158489Z","end":"2026-09-01T18:50:02.301936Z","steps":["trace[708268272] 'agreement among raft nodes before linearized reading' (duration: 143.04102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:02.302877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.54386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-01T18:50:02.302996Z","caller":"traceutil/trace.go:171","msg":"trace[1894793581] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:12087; }","duration":"116.700225ms","start":"2026-09-01T18:50:02.18627Z","end":"2026-09-01T18:50:02.30297Z","steps":["trace[1894793581] 'agreement among raft nodes before linearized reading' (duration: 115.279555ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:50:37.199361Z","caller":"traceutil/trace.go:171","msg":"trace[885715922] transaction","detail":"{read_only:false; response_revision:12391; number_of_response:1; }","duration":"217.83809ms","start":"2026-09-01T18:50:36.981476Z","end":"2026-09-01T18:50:37.199314Z","steps":["trace[885715922] 'process raft request' (duration: 217.600483ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:50:37.632382Z","caller":"traceutil/trace.go:171","msg":"trace[481926324] linearizableReadLoop","detail":"{readStateIndex:12831; appliedIndex:12830; }","duration":"156.201432ms","start":"2026-09-01T18:50:37.476157Z","end":"2026-09-01T18:50:37.632358Z","steps":["trace[481926324] 'read index received' (duration: 156.009367ms)","trace[481926324] 'applied index is now lower than readState.Index' (duration: 190.985µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:50:37.632609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.193292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:50:37.632662Z","caller":"traceutil/trace.go:171","msg":"trace[2084017461] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12396; }","duration":"156.286844ms","start":"2026-09-01T18:50:37.476362Z","end":"2026-09-01T18:50:37.632649Z","steps":["trace[2084017461] 'agreement among raft nodes before linearized reading' (duration: 156.160921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:37.632654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.138491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:50:37.632708Z","caller":"traceutil/trace.go:171","msg":"trace[191139596] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12396; }","duration":"156.298765ms","start":"2026-09-01T18:50:37.476396Z","end":"2026-09-01T18:50:37.632694Z","steps":["trace[191139596] 'agreement among raft nodes before linearized reading' (duration: 156.177532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:37.632762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.574662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-09-01T18:50:37.632868Z","caller":"traceutil/trace.go:171","msg":"trace[29033054] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12396; }","duration":"156.721766ms","start":"2026-09-01T18:50:37.476122Z","end":"2026-09-01T18:50:37.632844Z","steps":["trace[29033054] 'agreement among raft nodes before linearized reading' (duration: 156.384617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:50:37.632825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.564462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:50:37.633043Z","caller":"traceutil/trace.go:171","msg":"trace[1583264510] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12396; }","duration":"156.8685ms","start":"2026-09-01T18:50:37.476152Z","end":"2026-09-01T18:50:37.63302Z","steps":["trace[1583264510] 'agreement among raft nodes before linearized reading' (duration: 156.402628ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:50:37.632481Z","caller":"traceutil/trace.go:171","msg":"trace[2003453457] transaction","detail":"{read_only:false; response_revision:12396; number_of_response:1; }","duration":"161.587661ms","start":"2026-09-01T18:50:37.47087Z","end":"2026-09-01T18:50:37.632457Z","steps":["trace[2003453457] 'process raft request' (duration: 161.329654ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:50:38.310748Z","caller":"traceutil/trace.go:171","msg":"trace[10355069] transaction","detail":"{read_only:false; response_revision:12401; number_of_response:1; }","duration":"109.161027ms","start":"2026-09-01T18:50:38.201561Z","end":"2026-09-01T18:50:38.310722Z","steps":["trace[10355069] 'process raft request' (duration: 108.997023ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:03.663688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10414} {"level":"info","ts":"2026-09-01T18:51:03.751232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10414,"took":"85.007832ms","hash":2176725514,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":25919488,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T18:51:03.751304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2176725514,"revision":10414,"compact-revision":8056} {"level":"info","ts":"2026-09-01T18:56:03.673561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12582} {"level":"info","ts":"2026-09-01T18:56:03.756872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12582,"took":"80.85976ms","hash":2562446616,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":27459584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:56:03.756928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2562446616,"revision":12582,"compact-revision":10414} {"level":"info","ts":"2026-09-01T18:56:49.345867Z","caller":"traceutil/trace.go:171","msg":"trace[1315467871] transaction","detail":"{read_only:false; response_revision:14904; number_of_response:1; }","duration":"128.278469ms","start":"2026-09-01T18:56:49.217563Z","end":"2026-09-01T18:56:49.345842Z","steps":["trace[1315467871] 'process raft request' (duration: 128.186576ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:09.555877Z","caller":"traceutil/trace.go:171","msg":"trace[637735003] transaction","detail":"{read_only:false; response_revision:15007; number_of_response:1; }","duration":"113.347487ms","start":"2026-09-01T18:57:09.442486Z","end":"2026-09-01T18:57:09.555833Z","steps":["trace[637735003] 'process raft request' (duration: 113.162451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.469839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.029414ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:57:28.46998Z","caller":"traceutil/trace.go:171","msg":"trace[2142130267] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15114; }","duration":"168.196849ms","start":"2026-09-01T18:57:28.301764Z","end":"2026-09-01T18:57:28.469961Z","steps":["trace[2142130267] 'range keys from in-memory index tree' (duration: 168.006784ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:28.470221Z","caller":"traceutil/trace.go:171","msg":"trace[565996036] linearizableReadLoop","detail":"{readStateIndex:15649; appliedIndex:15648; }","duration":"285.446673ms","start":"2026-09-01T18:57:28.184756Z","end":"2026-09-01T18:57:28.470203Z","steps":["trace[565996036] 'read index received' (duration: 249.035979ms)","trace[565996036] 'applied index is now lower than readState.Index' (duration: 36.409554ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:57:28.470305Z","caller":"traceutil/trace.go:171","msg":"trace[472610361] transaction","detail":"{read_only:false; response_revision:15115; number_of_response:1; }","duration":"301.67053ms","start":"2026-09-01T18:57:28.168623Z","end":"2026-09-01T18:57:28.470293Z","steps":["trace[472610361] 'process raft request' (duration: 265.106081ms)","trace[472610361] 'compare' (duration: 35.996083ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:57:28.470393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.168596Z","time spent":"301.733082ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:57:28.470569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.818254ms","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-09-01T18:57:28.470622Z","caller":"traceutil/trace.go:171","msg":"trace[221523763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15115; }","duration":"285.916986ms","start":"2026-09-01T18:57:28.184689Z","end":"2026-09-01T18:57:28.470606Z","steps":["trace[221523763] 'agreement among raft nodes before linearized reading' (duration: 285.764262ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:28.659127Z","caller":"traceutil/trace.go:171","msg":"trace[1258169870] transaction","detail":"{read_only:false; response_revision:15116; number_of_response:1; }","duration":"385.458422ms","start":"2026-09-01T18:57:28.273635Z","end":"2026-09-01T18:57:28.659094Z","steps":["trace[1258169870] 'process raft request' (duration: 374.924211ms)","trace[1258169870] 'compare' (duration: 10.191032ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:57:28.659294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.273602Z","time spent":"385.599436ms","remote":"127.0.0.1:47664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416359,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:57:28.659504Z","caller":"traceutil/trace.go:171","msg":"trace[307508757] linearizableReadLoop","detail":"{readStateIndex:15651; appliedIndex:15649; }","duration":"189.213659ms","start":"2026-09-01T18:57:28.470263Z","end":"2026-09-01T18:57:28.659476Z","steps":["trace[307508757] 'read index received' (duration: 178.240367ms)","trace[307508757] 'applied index is now lower than readState.Index' (duration: 10.968632ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:57:28.659646Z","caller":"traceutil/trace.go:171","msg":"trace[401137898] transaction","detail":"{read_only:false; response_revision:15117; number_of_response:1; }","duration":"380.961798ms","start":"2026-09-01T18:57:28.278669Z","end":"2026-09-01T18:57:28.659631Z","steps":["trace[401137898] 'process raft request' (duration: 380.348701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.659711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.147591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lbkn2\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-09-01T18:57:28.659761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.278645Z","time spent":"381.03344ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:57:28.659771Z","caller":"traceutil/trace.go:171","msg":"trace[955127449] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lbkn2; range_end:; response_count:1; response_revision:15117; }","duration":"299.253654ms","start":"2026-09-01T18:57:28.360501Z","end":"2026-09-01T18:57:28.659754Z","steps":["trace[955127449] 'agreement among raft nodes before linearized reading' (duration: 299.041418ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:28.660092Z","caller":"traceutil/trace.go:171","msg":"trace[814231381] transaction","detail":"{read_only:false; response_revision:15118; number_of_response:1; }","duration":"184.852089ms","start":"2026-09-01T18:57:28.475223Z","end":"2026-09-01T18:57:28.660075Z","steps":["trace[814231381] 'process raft request' (duration: 184.121279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.660392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.984656ms","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-09-01T18:57:28.660476Z","caller":"traceutil/trace.go:171","msg":"trace[1868203480] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15118; }","duration":"465.078758ms","start":"2026-09-01T18:57:28.195377Z","end":"2026-09-01T18:57:28.660456Z","steps":["trace[1868203480] 'agreement among raft nodes before linearized reading' (duration: 464.199464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.660508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.195342Z","time spent":"465.15924ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-01T18:57:28.660747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.663209ms","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-09-01T18:57:28.660803Z","caller":"traceutil/trace.go:171","msg":"trace[763600253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15119; }","duration":"463.73655ms","start":"2026-09-01T18:57:28.197047Z","end":"2026-09-01T18:57:28.660784Z","steps":["trace[763600253] 'agreement among raft nodes before linearized reading' (duration: 462.559928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.660844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.197036Z","time spent":"463.794882ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T18:57:28.661041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.935803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-xlx62\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-01T18:57:28.661093Z","caller":"traceutil/trace.go:171","msg":"trace[188085837] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-xlx62; range_end:; response_count:1; response_revision:15118; }","duration":"304.025675ms","start":"2026-09-01T18:57:28.357052Z","end":"2026-09-01T18:57:28.661077Z","steps":["trace[188085837] 'agreement among raft nodes before linearized reading' (duration: 302.541355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:28.661333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:57:28.357028Z","time spent":"304.288303ms","remote":"127.0.0.1:36148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12383,"request content":"key:\"/registry/pods/openstack/openvswitch-xlx62\" "} {"level":"info","ts":"2026-09-01T18:57:52.103036Z","caller":"traceutil/trace.go:171","msg":"trace[1295568032] linearizableReadLoop","detail":"{readStateIndex:15981; appliedIndex:15980; }","duration":"120.633216ms","start":"2026-09-01T18:57:51.982376Z","end":"2026-09-01T18:57:52.103009Z","steps":["trace[1295568032] 'read index received' (duration: 110.763395ms)","trace[1295568032] 'applied index is now lower than readState.Index' (duration: 9.868891ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:57:52.103317Z","caller":"traceutil/trace.go:171","msg":"trace[1208504819] transaction","detail":"{read_only:false; response_revision:15437; number_of_response:1; }","duration":"258.280693ms","start":"2026-09-01T18:57:51.845018Z","end":"2026-09-01T18:57:52.103298Z","steps":["trace[1208504819] 'process raft request' (duration: 248.145434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:57:52.103653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.249494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:57:52.103682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.200013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:57:52.103735Z","caller":"traceutil/trace.go:171","msg":"trace[675453756] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:15437; }","duration":"121.347617ms","start":"2026-09-01T18:57:51.982367Z","end":"2026-09-01T18:57:52.103714Z","steps":["trace[675453756] 'agreement among raft nodes before linearized reading' (duration: 121.195813ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:57:52.103794Z","caller":"traceutil/trace.go:171","msg":"trace[273001076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15437; }","duration":"121.339957ms","start":"2026-09-01T18:57:51.982378Z","end":"2026-09-01T18:57:52.103718Z","steps":["trace[273001076] 'agreement among raft nodes before linearized reading' (duration: 121.201313ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:46.014994Z","caller":"traceutil/trace.go:171","msg":"trace[333154684] transaction","detail":"{read_only:false; response_revision:16074; number_of_response:1; }","duration":"105.907011ms","start":"2026-09-01T18:58:45.909052Z","end":"2026-09-01T18:58:46.014959Z","steps":["trace[333154684] 'process raft request' (duration: 105.733407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:59:09.234125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.756449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T18:59:09.234207Z","caller":"traceutil/trace.go:171","msg":"trace[1617429063] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:16194; }","duration":"121.888602ms","start":"2026-09-01T18:59:09.112298Z","end":"2026-09-01T18:59:09.234187Z","steps":["trace[1617429063] 'count revisions from in-memory index tree' (duration: 121.674256ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:01:03.680535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14586} {"level":"info","ts":"2026-09-01T19:01:03.767803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14586,"took":"84.379937ms","hash":4290344770,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31387648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T19:01:03.76787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4290344770,"revision":14586,"compact-revision":12582} {"level":"info","ts":"2026-09-01T19:05:37.471558Z","caller":"traceutil/trace.go:171","msg":"trace[1771147108] transaction","detail":"{read_only:false; response_revision:19048; number_of_response:1; }","duration":"129.382611ms","start":"2026-09-01T19:05:37.342159Z","end":"2026-09-01T19:05:37.471541Z","steps":["trace[1771147108] 'process raft request' (duration: 112.825244ms)","trace[1771147108] 'compare' (duration: 16.425323ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:05:37.471706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.0308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T19:05:37.471756Z","caller":"traceutil/trace.go:171","msg":"trace[1574777683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19048; }","duration":"100.144103ms","start":"2026-09-01T19:05:37.371599Z","end":"2026-09-01T19:05:37.471743Z","steps":["trace[1574777683] 'agreement among raft nodes before linearized reading' (duration: 99.988299ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:05:45.235233Z","caller":"traceutil/trace.go:171","msg":"trace[1292088866] transaction","detail":"{read_only:false; response_revision:19105; number_of_response:1; }","duration":"154.144484ms","start":"2026-09-01T19:05:45.081055Z","end":"2026-09-01T19:05:45.235199Z","steps":["trace[1292088866] 'process raft request' (duration: 153.890676ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:05:45.235599Z","caller":"traceutil/trace.go:171","msg":"trace[609623096] linearizableReadLoop","detail":"{readStateIndex:19767; appliedIndex:19767; }","duration":"150.298838ms","start":"2026-09-01T19:05:45.085256Z","end":"2026-09-01T19:05:45.235555Z","steps":["trace[609623096] 'read index received' (duration: 150.274897ms)","trace[609623096] 'applied index is now lower than readState.Index' (duration: 20.491µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:05:45.235848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.549555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:05:45.235928Z","caller":"traceutil/trace.go:171","msg":"trace[232721320] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:19105; }","duration":"150.662278ms","start":"2026-09-01T19:05:45.085248Z","end":"2026-09-01T19:05:45.23591Z","steps":["trace[232721320] 'agreement among raft nodes before linearized reading' (duration: 150.397701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:05:45.625411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-01T19:05:45.625532Z","caller":"traceutil/trace.go:171","msg":"trace[1312514376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19107; }","duration":"109.562534ms","start":"2026-09-01T19:05:45.515914Z","end":"2026-09-01T19:05:45.625477Z","steps":["trace[1312514376] 'range keys from in-memory index tree' (duration: 109.211374ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:05:46.487546Z","caller":"traceutil/trace.go:171","msg":"trace[1105107878] transaction","detail":"{read_only:false; response_revision:19110; number_of_response:1; }","duration":"207.27595ms","start":"2026-09-01T19:05:46.280243Z","end":"2026-09-01T19:05:46.487519Z","steps":["trace[1105107878] 'process raft request' (duration: 206.984482ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:05:59.585318Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":20004,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:05:59.592033Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-09-01T19:05:59.592246Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-09-01T19:06:03.690761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16946} {"level":"info","ts":"2026-09-01T19:06:03.789679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16946,"took":"95.583728ms","hash":2623643021,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T19:06:03.789765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2623643021,"revision":16946,"compact-revision":14586} {"level":"info","ts":"2026-09-01T19:11:03.699804Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19364} {"level":"info","ts":"2026-09-01T19:11:03.823742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19364,"took":"120.463979ms","hash":1972157317,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30601216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T19:11:03.823854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1972157317,"revision":19364,"compact-revision":16946} {"level":"info","ts":"2026-09-01T19:11:53.895685Z","caller":"traceutil/trace.go:171","msg":"trace[696042220] transaction","detail":"{read_only:false; response_revision:21926; number_of_response:1; }","duration":"151.948751ms","start":"2026-09-01T19:11:53.743692Z","end":"2026-09-01T19:11:53.895641Z","steps":["trace[696042220] 'process raft request' (duration: 151.747225ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:53.905417Z","caller":"traceutil/trace.go:171","msg":"trace[848674818] transaction","detail":"{read_only:false; response_revision:21927; number_of_response:1; }","duration":"160.666313ms","start":"2026-09-01T19:11:53.744733Z","end":"2026-09-01T19:11:53.905399Z","steps":["trace[848674818] 'process raft request' (duration: 160.319353ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:59.496508Z","caller":"traceutil/trace.go:171","msg":"trace[1576209850] transaction","detail":"{read_only:false; response_revision:21977; number_of_response:1; }","duration":"386.341746ms","start":"2026-09-01T19:11:59.110136Z","end":"2026-09-01T19:11:59.496478Z","steps":["trace[1576209850] 'process raft request' (duration: 386.173962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:59.49669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:11:59.110113Z","time spent":"386.500301ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2537,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:11:59.906613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.678937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:11:59.906726Z","caller":"traceutil/trace.go:171","msg":"trace[137853545] linearizableReadLoop","detail":"{readStateIndex:22735; appliedIndex:22733; }","duration":"627.923132ms","start":"2026-09-01T19:11:59.278785Z","end":"2026-09-01T19:11:59.906708Z","steps":["trace[137853545] 'read index received' (duration: 217.619931ms)","trace[137853545] 'applied index is now lower than readState.Index' (duration: 410.301761ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:11:59.90697Z","caller":"traceutil/trace.go:171","msg":"trace[1246316295] transaction","detail":"{read_only:false; response_revision:21978; number_of_response:1; }","duration":"782.619399ms","start":"2026-09-01T19:11:59.124298Z","end":"2026-09-01T19:11:59.906917Z","steps":["trace[1246316295] 'process raft request' (duration: 426.526721ms)","trace[1246316295] 'compare' (duration: 355.433519ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:11:59.907084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.070974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:11:59.907127Z","caller":"traceutil/trace.go:171","msg":"trace[1339844330] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:21978; }","duration":"327.195537ms","start":"2026-09-01T19:11:59.579917Z","end":"2026-09-01T19:11:59.907112Z","steps":["trace[1339844330] 'agreement among raft nodes before linearized reading' (duration: 327.118785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:59.90713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.951726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T19:11:59.907151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:11:59.124271Z","time spent":"782.791834ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:11:59.907162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:11:59.57987Z","time spent":"327.28148ms","remote":"127.0.0.1:47508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-09-01T19:11:59.907142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.335353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-01T19:11:59.907187Z","caller":"traceutil/trace.go:171","msg":"trace[399166937] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:21978; }","duration":"102.059139ms","start":"2026-09-01T19:11:59.805111Z","end":"2026-09-01T19:11:59.90717Z","steps":["trace[399166937] 'agreement among raft nodes before linearized reading' (duration: 101.944026ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:11:59.907229Z","caller":"traceutil/trace.go:171","msg":"trace[2040420443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21978; }","duration":"628.474147ms","start":"2026-09-01T19:11:59.278734Z","end":"2026-09-01T19:11:59.907208Z","steps":["trace[2040420443] 'agreement among raft nodes before linearized reading' (duration: 628.272782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:11:59.907273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:11:59.278708Z","time spent":"628.55585ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-01T19:12:00.192259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.74473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:00.19305Z","caller":"traceutil/trace.go:171","msg":"trace[1451835982] linearizableReadLoop","detail":"{readStateIndex:22736; appliedIndex:22735; }","duration":"274.433415ms","start":"2026-09-01T19:11:59.918594Z","end":"2026-09-01T19:12:00.193027Z","steps":["trace[1451835982] 'read index received' (duration: 97.758719ms)","trace[1451835982] 'applied index is now lower than readState.Index' (duration: 176.673036ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:00.193088Z","caller":"traceutil/trace.go:171","msg":"trace[606085155] transaction","detail":"{read_only:false; response_revision:21979; number_of_response:1; }","duration":"281.033058ms","start":"2026-09-01T19:11:59.912014Z","end":"2026-09-01T19:12:00.193047Z","steps":["trace[606085155] 'process raft request' (duration: 104.411943ms)","trace[606085155] 'compare' (duration: 175.292757ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:00.193214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.621041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-01T19:12:00.193241Z","caller":"traceutil/trace.go:171","msg":"trace[910170321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21979; }","duration":"274.676782ms","start":"2026-09-01T19:11:59.918554Z","end":"2026-09-01T19:12:00.193231Z","steps":["trace[910170321] 'agreement among raft nodes before linearized reading' (duration: 274.551958ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:00.561563Z","caller":"traceutil/trace.go:171","msg":"trace[1634693186] linearizableReadLoop","detail":"{readStateIndex:22738; appliedIndex:22736; }","duration":"368.44536ms","start":"2026-09-01T19:12:00.193078Z","end":"2026-09-01T19:12:00.561524Z","steps":["trace[1634693186] 'read index received' (duration: 28.398957ms)","trace[1634693186] 'applied index is now lower than readState.Index' (duration: 340.044923ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:00.561607Z","caller":"traceutil/trace.go:171","msg":"trace[2069449585] transaction","detail":"{read_only:false; response_revision:21981; number_of_response:1; }","duration":"388.723882ms","start":"2026-09-01T19:12:00.172849Z","end":"2026-09-01T19:12:00.561572Z","steps":["trace[2069449585] 'process raft request' (duration: 388.410403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.561788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.172808Z","time spent":"388.889296ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:00.56181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.468256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-8fcd6.18d147ef183c25e6\" ","response":"range_response_count:1 size:1395"} {"level":"warn","ts":"2026-09-01T19:12:00.561823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.667245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T19:12:00.561847Z","caller":"traceutil/trace.go:171","msg":"trace[1929365506] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-8fcd6.18d147ef183c25e6; range_end:; response_count:1; response_revision:21982; }","duration":"383.563259ms","start":"2026-09-01T19:12:00.178273Z","end":"2026-09-01T19:12:00.561836Z","steps":["trace[1929365506] 'agreement among raft nodes before linearized reading' (duration: 383.351393ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:00.561849Z","caller":"traceutil/trace.go:171","msg":"trace[502533348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21982; }","duration":"635.748937ms","start":"2026-09-01T19:11:59.926092Z","end":"2026-09-01T19:12:00.561841Z","steps":["trace[502533348] 'agreement among raft nodes before linearized reading' (duration: 635.583202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.561877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.178248Z","time spent":"383.6201ms","remote":"127.0.0.1:36062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":1418,"request content":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-8fcd6.18d147ef183c25e6\" "} {"level":"warn","ts":"2026-09-01T19:12:00.561941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:11:59.926082Z","time spent":"635.85119ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-01T19:12:00.561579Z","caller":"traceutil/trace.go:171","msg":"trace[1187837996] transaction","detail":"{read_only:false; response_revision:21982; number_of_response:1; }","duration":"364.248524ms","start":"2026-09-01T19:12:00.197315Z","end":"2026-09-01T19:12:00.561564Z","steps":["trace[1187837996] 'process raft request' (duration: 364.147112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.562142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.197289Z","time spent":"364.723418ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:00.908063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.333223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:00.908417Z","caller":"traceutil/trace.go:171","msg":"trace[83969321] linearizableReadLoop","detail":"{readStateIndex:22740; appliedIndex:22739; }","duration":"346.77275ms","start":"2026-09-01T19:12:00.561629Z","end":"2026-09-01T19:12:00.908402Z","steps":["trace[83969321] 'read index received' (duration: 142.027507ms)","trace[83969321] 'applied index is now lower than readState.Index' (duration: 204.744043ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:00.90846Z","caller":"traceutil/trace.go:171","msg":"trace[1733295378] transaction","detail":"{read_only:false; response_revision:21983; number_of_response:1; }","duration":"623.301243ms","start":"2026-09-01T19:12:00.285127Z","end":"2026-09-01T19:12:00.908428Z","steps":["trace[1733295378] 'process raft request' (duration: 418.531409ms)","trace[1733295378] 'compare' (duration: 204.216919ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:00.908546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.63225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T19:12:00.908553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.091814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:12:00.908572Z","caller":"traceutil/trace.go:171","msg":"trace[1253096317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21983; }","duration":"375.703002ms","start":"2026-09-01T19:12:00.53286Z","end":"2026-09-01T19:12:00.908563Z","steps":["trace[1253096317] 'agreement among raft nodes before linearized reading' (duration: 375.63778ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:00.908596Z","caller":"traceutil/trace.go:171","msg":"trace[467611648] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:21983; }","duration":"358.177936ms","start":"2026-09-01T19:12:00.550407Z","end":"2026-09-01T19:12:00.908585Z","steps":["trace[467611648] 'agreement among raft nodes before linearized reading' (duration: 358.025652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.908601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.532843Z","time spent":"375.752713ms","remote":"127.0.0.1:36004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T19:12:00.908601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.285099Z","time spent":"623.427766ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:00.908622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.550332Z","time spent":"358.282039ms","remote":"127.0.0.1:47492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-09-01T19:12:00.918654Z","caller":"traceutil/trace.go:171","msg":"trace[269159696] transaction","detail":"{read_only:false; response_revision:21984; number_of_response:1; }","duration":"354.302679ms","start":"2026-09-01T19:12:00.564337Z","end":"2026-09-01T19:12:00.91864Z","steps":["trace[269159696] 'process raft request' (duration: 354.154165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.918791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.564317Z","time spent":"354.380461ms","remote":"127.0.0.1:36062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:12:00.918837Z","caller":"traceutil/trace.go:171","msg":"trace[1082154370] transaction","detail":"{read_only:false; response_revision:21986; number_of_response:1; }","duration":"231.358631ms","start":"2026-09-01T19:12:00.687464Z","end":"2026-09-01T19:12:00.918822Z","steps":["trace[1082154370] 'process raft request' (duration: 231.2974ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:00.918865Z","caller":"traceutil/trace.go:171","msg":"trace[1269364543] transaction","detail":"{read_only:false; response_revision:21985; number_of_response:1; }","duration":"347.179101ms","start":"2026-09-01T19:12:00.571663Z","end":"2026-09-01T19:12:00.918842Z","steps":["trace[1269364543] 'process raft request' (duration: 346.941074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:00.918944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.571637Z","time spent":"347.265924ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:12:01.285677Z","caller":"traceutil/trace.go:171","msg":"trace[850825486] linearizableReadLoop","detail":"{readStateIndex:22744; appliedIndex:22740; }","duration":"377.152893ms","start":"2026-09-01T19:12:00.908467Z","end":"2026-09-01T19:12:01.28562Z","steps":["trace[850825486] 'read index received' (duration: 10.113671ms)","trace[850825486] 'applied index is now lower than readState.Index' (duration: 367.037732ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:01.285876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.55587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:12:01.286035Z","caller":"traceutil/trace.go:171","msg":"trace[2039096712] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:21987; }","duration":"497.768684ms","start":"2026-09-01T19:12:00.788246Z","end":"2026-09-01T19:12:01.286015Z","steps":["trace[2039096712] 'agreement among raft nodes before linearized reading' (duration: 497.467326ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.286125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.788225Z","time spent":"497.849697ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-09-01T19:12:01.286348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.61284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-01T19:12:01.286401Z","caller":"traceutil/trace.go:171","msg":"trace[1239327419] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:21987; }","duration":"598.693342ms","start":"2026-09-01T19:12:00.687695Z","end":"2026-09-01T19:12:01.286389Z","steps":["trace[1239327419] 'agreement among raft nodes before linearized reading' (duration: 598.419574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.286476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.687661Z","time spent":"598.801535ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":155,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-09-01T19:12:01.286684Z","caller":"traceutil/trace.go:171","msg":"trace[190702048] transaction","detail":"{read_only:false; response_revision:21987; number_of_response:1; }","duration":"494.352899ms","start":"2026-09-01T19:12:00.792314Z","end":"2026-09-01T19:12:01.286667Z","steps":["trace[190702048] 'process raft request' (duration: 480.406993ms)","trace[190702048] 'compare' (duration: 12.705822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:01.286787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.792303Z","time spent":"494.420772ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:01.287273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.7025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-01T19:12:01.287347Z","caller":"traceutil/trace.go:171","msg":"trace[859818460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21987; }","duration":"629.798933ms","start":"2026-09-01T19:12:00.657507Z","end":"2026-09-01T19:12:01.287306Z","steps":["trace[859818460] 'agreement among raft nodes before linearized reading' (duration: 629.219137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.287386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.657487Z","time spent":"629.889355ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T19:12:01.287644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.353117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-01T19:12:01.287683Z","caller":"traceutil/trace.go:171","msg":"trace[1524351400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21987; }","duration":"653.424689ms","start":"2026-09-01T19:12:00.634249Z","end":"2026-09-01T19:12:01.287673Z","steps":["trace[1524351400] 'agreement among raft nodes before linearized reading' (duration: 653.316006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.287751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.634231Z","time spent":"653.510162ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T19:12:01.287935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.090728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:12:01.287971Z","caller":"traceutil/trace.go:171","msg":"trace[718787431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21987; }","duration":"378.128279ms","start":"2026-09-01T19:12:00.909834Z","end":"2026-09-01T19:12:01.287962Z","steps":["trace[718787431] 'agreement among raft nodes before linearized reading' (duration: 378.073107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.288118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:00.909785Z","time spent":"378.323295ms","remote":"127.0.0.1:36012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T19:12:01.288687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.074232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:12:01.288732Z","caller":"traceutil/trace.go:171","msg":"trace[1905406891] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21987; }","duration":"118.169214ms","start":"2026-09-01T19:12:01.170552Z","end":"2026-09-01T19:12:01.288722Z","steps":["trace[1905406891] 'agreement among raft nodes before linearized reading' (duration: 118.049831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:01.586795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.223901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:01.587149Z","caller":"traceutil/trace.go:171","msg":"trace[1196848635] transaction","detail":"{read_only:false; response_revision:21989; number_of_response:1; }","duration":"294.352107ms","start":"2026-09-01T19:12:01.292781Z","end":"2026-09-01T19:12:01.587133Z","steps":["trace[1196848635] 'process raft request' (duration: 294.11384ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:01.587333Z","caller":"traceutil/trace.go:171","msg":"trace[688525156] transaction","detail":"{read_only:false; response_revision:21988; number_of_response:1; }","duration":"294.831781ms","start":"2026-09-01T19:12:01.292492Z","end":"2026-09-01T19:12:01.587324Z","steps":["trace[688525156] 'process raft request' (duration: 94.986792ms)","trace[688525156] 'compare' (duration: 198.918503ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:16:03.708165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21670} {"level":"info","ts":"2026-09-01T19:16:03.825015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21670,"took":"112.864368ms","hash":3432346548,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30306304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:16:03.825086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3432346548,"revision":21670,"compact-revision":19364} {"level":"info","ts":"2026-09-01T19:16:20.731579Z","caller":"traceutil/trace.go:171","msg":"trace[718297543] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"157.310039ms","start":"2026-09-01T19:16:20.574251Z","end":"2026-09-01T19:16:20.731561Z","steps":["trace[718297543] 'process raft request' (duration: 157.212497ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:16:21.110898Z","caller":"traceutil/trace.go:171","msg":"trace[802931735] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"217.781536ms","start":"2026-09-01T19:16:20.893091Z","end":"2026-09-01T19:16:21.110872Z","steps":["trace[802931735] 'process raft request' (duration: 215.580594ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:16:21.113478Z","caller":"traceutil/trace.go:171","msg":"trace[90495342] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"159.123849ms","start":"2026-09-01T19:16:20.954331Z","end":"2026-09-01T19:16:21.113454Z","steps":["trace[90495342] 'process raft request' (duration: 158.988045ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:18:29.789408Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000683e.wal"} {"level":"info","ts":"2026-09-01T19:21:03.717478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23865} {"level":"info","ts":"2026-09-01T19:21:03.838843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23865,"took":"117.582709ms","hash":1181974424,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":41762816,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T19:21:03.838912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1181974424,"revision":23865,"compact-revision":21670} {"level":"warn","ts":"2026-09-01T19:23:14.29504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.934778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/magnum-system/\" range_end:\"/registry/jobs/magnum-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:23:14.295156Z","caller":"traceutil/trace.go:171","msg":"trace[1961758993] range","detail":"{range_begin:/registry/jobs/magnum-system/; range_end:/registry/jobs/magnum-system0; response_count:0; response_revision:27937; }","duration":"135.103163ms","start":"2026-09-01T19:23:14.160023Z","end":"2026-09-01T19:23:14.295126Z","steps":["trace[1961758993] 'range keys from in-memory index tree' (duration: 134.799674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:23:14.29522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.601863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:16 size:13083"} {"level":"info","ts":"2026-09-01T19:23:14.295331Z","caller":"traceutil/trace.go:171","msg":"trace[2022421613] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:16; response_revision:27937; }","duration":"119.729447ms","start":"2026-09-01T19:23:14.175582Z","end":"2026-09-01T19:23:14.295312Z","steps":["trace[2022421613] 'range keys from in-memory index tree' (duration: 119.369347ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:25:40.597001Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"282167b6747c9cda","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:25:40.605566Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-09-01T19:25:40.605779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-09-01T19:26:03.725797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26982} {"level":"info","ts":"2026-09-01T19:26:03.860249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26982,"took":"130.328051ms","hash":2926904669,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":30007296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:26:03.860313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2926904669,"revision":26982,"compact-revision":23865}