{"level":"warn","ts":"2026-06-04T01:43:02.415251Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T01:43:02.415334Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-04T01:43:02.415393Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T01:43:02.415406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-06-04T01:43:02.415421Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T01:43:02.41619Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-06-04T01:43:02.416258Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-04T01:43:02.418476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.028255ms"} {"level":"info","ts":"2026-06-04T01:43:02.424593Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-06-04T01:43:02.424719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T01:43:02.42475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-06-04T01:43:02.424772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T01:43:02.424795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-06-04T01:43:02.424855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-06-04T01:43:02.430757Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T01:43:02.432775Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T01:43:02.435042Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-04T01:43:02.437554Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T01:43:02.437934Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T01:43:02.43814Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T01:43:02.43823Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T01:43:02.438246Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-04T01:43:02.438582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-06-04T01:43:02.438938Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-06-04T01:43:02.443238Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-04T01:43:02.443433Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T01:43:02.443462Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T01:43:02.443527Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-06-04T01:43:02.443634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-06-04T01:43:02.625753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T01:43:02.625884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T01:43:02.625912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-06-04T01:43:02.625991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-06-04T01:43:02.62601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-06-04T01:43:02.626027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-06-04T01:43:02.626067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-06-04T01:43:02.627174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T01:43:02.627158Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T01:43:02.62723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T01:43:02.627509Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T01:43:02.627539Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T01:43:02.627199Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T01:43:02.630177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-06-04T01:43:02.630468Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T01:43:02.630558Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T01:43:02.630588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T01:43:02.631938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-04T01:43:18.790443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.625944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T01:43:18.790612Z","caller":"traceutil/trace.go:171","msg":"trace[983120070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"102.853921ms","start":"2026-06-04T01:43:18.687734Z","end":"2026-06-04T01:43:18.790588Z","steps":["trace[983120070] 'range keys from in-memory index tree' (duration: 102.47602ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:43:18.939759Z","caller":"traceutil/trace.go:171","msg":"trace[1078816866] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"144.785837ms","start":"2026-06-04T01:43:18.794947Z","end":"2026-06-04T01:43:18.939733Z","steps":["trace[1078816866] 'process raft request' (duration: 144.645113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:19.276277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.79482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:43:19.276372Z","caller":"traceutil/trace.go:171","msg":"trace[1949581616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"266.911923ms","start":"2026-06-04T01:43:19.00944Z","end":"2026-06-04T01:43:19.276352Z","steps":["trace[1949581616] 'range keys from in-memory index tree' (duration: 266.689617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:42.344583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.14722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-04T01:43:42.344798Z","caller":"traceutil/trace.go:171","msg":"trace[2145698745] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"219.690406ms","start":"2026-06-04T01:43:42.125076Z","end":"2026-06-04T01:43:42.344767Z","steps":["trace[2145698745] 'process raft request' (duration: 93.500376ms)","trace[2145698745] 'compare' (duration: 124.860703ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:43:42.434587Z","caller":"traceutil/trace.go:171","msg":"trace[2051731838] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"300.469775ms","start":"2026-06-04T01:43:42.13407Z","end":"2026-06-04T01:43:42.43454Z","steps":["trace[2051731838] 'process raft request' (duration: 300.029883ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:43:42.434656Z","caller":"traceutil/trace.go:171","msg":"trace[1153953332] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"296.486615ms","start":"2026-06-04T01:43:42.138065Z","end":"2026-06-04T01:43:42.434551Z","steps":["trace[1153953332] 'process raft request' (duration: 296.30931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:42.4352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:43:42.134045Z","time spent":"300.733701ms","remote":"127.0.0.1:42008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T01:43:42.832459Z","caller":"traceutil/trace.go:171","msg":"trace[300392094] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"346.965414ms","start":"2026-06-04T01:43:42.485453Z","end":"2026-06-04T01:43:42.832418Z","steps":["trace[300392094] 'process raft request' (duration: 342.528203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:42.832691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:43:42.485424Z","time spent":"347.1524ms","remote":"127.0.0.1:42008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T01:43:42.85466Z","caller":"traceutil/trace.go:171","msg":"trace[1159846689] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"260.199743ms","start":"2026-06-04T01:43:42.594438Z","end":"2026-06-04T01:43:42.854638Z","steps":["trace[1159846689] 'read index received' (duration: 233.566104ms)","trace[1159846689] 'applied index is now lower than readState.Index' (duration: 26.633049ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:43:42.855179Z","caller":"traceutil/trace.go:171","msg":"trace[1899465422] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"298.770157ms","start":"2026-06-04T01:43:42.556398Z","end":"2026-06-04T01:43:42.855168Z","steps":["trace[1899465422] 'process raft request' (duration: 297.372318ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:43:42.855308Z","caller":"traceutil/trace.go:171","msg":"trace[224825658] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"217.294931ms","start":"2026-06-04T01:43:42.638008Z","end":"2026-06-04T01:43:42.855303Z","steps":["trace[224825658] 'process raft request' (duration: 216.130079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:42.855414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.963033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-04T01:43:42.855434Z","caller":"traceutil/trace.go:171","msg":"trace[1015103026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"261.045355ms","start":"2026-06-04T01:43:42.594383Z","end":"2026-06-04T01:43:42.855428Z","steps":["trace[1015103026] 'agreement among raft nodes before linearized reading' (duration: 260.986394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:42.855538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.174074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T01:43:42.855552Z","caller":"traceutil/trace.go:171","msg":"trace[2117035067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"237.189614ms","start":"2026-06-04T01:43:42.618358Z","end":"2026-06-04T01:43:42.855548Z","steps":["trace[2117035067] 'agreement among raft nodes before linearized reading' (duration: 237.160234ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:43:47.686157Z","caller":"traceutil/trace.go:171","msg":"trace[147961906] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"179.939379ms","start":"2026-06-04T01:43:47.506192Z","end":"2026-06-04T01:43:47.686131Z","steps":["trace[147961906] 'process raft request' (duration: 178.924671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:48.195937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.174853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:43:48.196047Z","caller":"traceutil/trace.go:171","msg":"trace[803818686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"185.330697ms","start":"2026-06-04T01:43:48.010694Z","end":"2026-06-04T01:43:48.196025Z","steps":["trace[803818686] 'range keys from in-memory index tree' (duration: 185.10526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:48.196099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.352886ms","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-06-04T01:43:48.196188Z","caller":"traceutil/trace.go:171","msg":"trace[1758075539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"369.4739ms","start":"2026-06-04T01:43:47.826694Z","end":"2026-06-04T01:43:48.196168Z","steps":["trace[1758075539] 'range keys from in-memory index tree' (duration: 369.183332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:43:48.196234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:43:47.826681Z","time spent":"369.544261ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T01:44:03.757099Z","caller":"traceutil/trace.go:171","msg":"trace[1560530901] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"149.778194ms","start":"2026-06-04T01:44:03.607293Z","end":"2026-06-04T01:44:03.757071Z","steps":["trace[1560530901] 'process raft request' (duration: 149.61178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:45:19.916734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.838098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-04T01:45:19.916799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.468064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-345d37a1-078d-4ca8-a6b5-198f446e46a6\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-04T01:45:19.916794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.519807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-345d37a1-078d-4ca8-a6b5-198f446e46a6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-04T01:45:19.916865Z","caller":"traceutil/trace.go:171","msg":"trace[118316660] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-345d37a1-078d-4ca8-a6b5-198f446e46a6; range_end:; response_count:1; response_revision:1874; }","duration":"248.545576ms","start":"2026-06-04T01:45:19.668298Z","end":"2026-06-04T01:45:19.916844Z","steps":["trace[118316660] 'range keys from in-memory index tree' (duration: 248.31013ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:45:19.91687Z","caller":"traceutil/trace.go:171","msg":"trace[1788071640] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-345d37a1-078d-4ca8-a6b5-198f446e46a6; range_end:; response_count:1; response_revision:1874; }","duration":"201.606019ms","start":"2026-06-04T01:45:19.715247Z","end":"2026-06-04T01:45:19.916853Z","steps":["trace[1788071640] 'range keys from in-memory index tree' (duration: 201.346402ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:45:19.916844Z","caller":"traceutil/trace.go:171","msg":"trace[1623900145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1874; }","duration":"274.987702ms","start":"2026-06-04T01:45:19.641829Z","end":"2026-06-04T01:45:19.916816Z","steps":["trace[1623900145] 'range keys from in-memory index tree' (duration: 274.621262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:45:20.330546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.302448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T01:45:20.330917Z","caller":"traceutil/trace.go:171","msg":"trace[851808240] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"118.364356ms","start":"2026-06-04T01:45:20.212533Z","end":"2026-06-04T01:45:20.330897Z","steps":["trace[851808240] 'process raft request' (duration: 118.287074ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:45:20.330959Z","caller":"traceutil/trace.go:171","msg":"trace[378173890] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"140.926514ms","start":"2026-06-04T01:45:20.189988Z","end":"2026-06-04T01:45:20.330914Z","steps":["trace[378173890] 'read index received' (duration: 41.821µs)","trace[378173890] 'applied index is now lower than readState.Index' (duration: 140.877393ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:45:20.330889Z","caller":"traceutil/trace.go:171","msg":"trace[881736768] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"317.811565ms","start":"2026-06-04T01:45:20.013038Z","end":"2026-06-04T01:45:20.330849Z","steps":["trace[881736768] 'process raft request' (duration: 30.106776ms)","trace[881736768] 'compare' (duration: 287.147454ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:45:20.331464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:45:20.013018Z","time spent":"318.35311ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T01:45:20.332303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.300623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-04T01:45:20.332377Z","caller":"traceutil/trace.go:171","msg":"trace[578627801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"142.427356ms","start":"2026-06-04T01:45:20.189932Z","end":"2026-06-04T01:45:20.332359Z","steps":["trace[578627801] 'agreement among raft nodes before linearized reading' (duration: 141.14471ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:46:10.78613Z","caller":"traceutil/trace.go:171","msg":"trace[1853567811] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"122.118678ms","start":"2026-06-04T01:46:10.663913Z","end":"2026-06-04T01:46:10.786031Z","steps":["trace[1853567811] 'process raft request' (duration: 121.868702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:46:11.04355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.23458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T01:46:11.043657Z","caller":"traceutil/trace.go:171","msg":"trace[1775682549] linearizableReadLoop","detail":"{readStateIndex:2294; appliedIndex:2292; }","duration":"350.189354ms","start":"2026-06-04T01:46:10.69345Z","end":"2026-06-04T01:46:11.043639Z","steps":["trace[1775682549] 'read index received' (duration: 92.22125ms)","trace[1775682549] 'applied index is now lower than readState.Index' (duration: 257.967004ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:46:11.043927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.474321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T01:46:11.043966Z","caller":"traceutil/trace.go:171","msg":"trace[422988210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2206; }","duration":"350.544823ms","start":"2026-06-04T01:46:10.693411Z","end":"2026-06-04T01:46:11.043955Z","steps":["trace[422988210] 'agreement among raft nodes before linearized reading' (duration: 350.40672ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:46:11.043962Z","caller":"traceutil/trace.go:171","msg":"trace[1504489796] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"378.435538ms","start":"2026-06-04T01:46:10.665253Z","end":"2026-06-04T01:46:11.043688Z","steps":["trace[1504489796] 'process raft request' (duration: 231.971352ms)","trace[1504489796] 'compare' (duration: 146.121907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:46:11.044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:46:10.693397Z","time spent":"350.595924ms","remote":"127.0.0.1:37982","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-06-04T01:46:11.04401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.172749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T01:46:11.044064Z","caller":"traceutil/trace.go:171","msg":"trace[1946110399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2206; }","duration":"231.285052ms","start":"2026-06-04T01:46:10.812763Z","end":"2026-06-04T01:46:11.044048Z","steps":["trace[1946110399] 'agreement among raft nodes before linearized reading' (duration: 231.107257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:46:11.044127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:46:10.665217Z","time spent":"378.816698ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T01:46:11.215125Z","caller":"traceutil/trace.go:171","msg":"trace[55978671] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"166.161106ms","start":"2026-06-04T01:46:11.04893Z","end":"2026-06-04T01:46:11.215091Z","steps":["trace[55978671] 'process raft request' (duration: 141.309535ms)","trace[55978671] 'compare' (duration: 24.654896ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:47:00.020453Z","caller":"traceutil/trace.go:171","msg":"trace[1862801847] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2750; }","duration":"151.986173ms","start":"2026-06-04T01:46:59.868432Z","end":"2026-06-04T01:47:00.020419Z","steps":["trace[1862801847] 'read index received' (duration: 151.799287ms)","trace[1862801847] 'applied index is now lower than readState.Index' (duration: 185.606µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:47:00.020483Z","caller":"traceutil/trace.go:171","msg":"trace[151088666] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"164.662802ms","start":"2026-06-04T01:46:59.855795Z","end":"2026-06-04T01:47:00.020458Z","steps":["trace[151088666] 'process raft request' (duration: 164.467356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:47:00.020728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.25879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T01:47:00.020836Z","caller":"traceutil/trace.go:171","msg":"trace[746652110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2645; }","duration":"152.405824ms","start":"2026-06-04T01:46:59.868408Z","end":"2026-06-04T01:47:00.020814Z","steps":["trace[746652110] 'agreement among raft nodes before linearized reading' (duration: 152.128097ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:50:09.316319Z","caller":"traceutil/trace.go:171","msg":"trace[1240481856] linearizableReadLoop","detail":"{readStateIndex:4019; appliedIndex:4018; }","duration":"197.085813ms","start":"2026-06-04T01:50:09.119204Z","end":"2026-06-04T01:50:09.316289Z","steps":["trace[1240481856] 'read index received' (duration: 196.961669ms)","trace[1240481856] 'applied index is now lower than readState.Index' (duration: 122.814µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:50:09.316494Z","caller":"traceutil/trace.go:171","msg":"trace[1988764289] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"281.424665ms","start":"2026-06-04T01:50:09.034958Z","end":"2026-06-04T01:50:09.316383Z","steps":["trace[1988764289] 'process raft request' (duration: 281.166788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:50:09.316595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.382601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-04T01:50:09.316648Z","caller":"traceutil/trace.go:171","msg":"trace[1543040634] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3867; }","duration":"197.482325ms","start":"2026-06-04T01:50:09.119149Z","end":"2026-06-04T01:50:09.316631Z","steps":["trace[1543040634] 'agreement among raft nodes before linearized reading' (duration: 197.292719ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:50:09.318036Z","caller":"traceutil/trace.go:171","msg":"trace[2101975937] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"175.317259ms","start":"2026-06-04T01:50:09.142675Z","end":"2026-06-04T01:50:09.317992Z","steps":["trace[2101975937] 'process raft request' (duration: 175.040242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:50:09.318087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.155286ms","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":"warn","ts":"2026-06-04T01:50:09.318138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.792098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T01:50:09.318257Z","caller":"traceutil/trace.go:171","msg":"trace[690635441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3868; }","duration":"190.957813ms","start":"2026-06-04T01:50:09.127276Z","end":"2026-06-04T01:50:09.318234Z","steps":["trace[690635441] 'agreement among raft nodes before linearized reading' (duration: 190.640364ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:50:09.318192Z","caller":"traceutil/trace.go:171","msg":"trace[806777548] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3868; }","duration":"174.31309ms","start":"2026-06-04T01:50:09.143862Z","end":"2026-06-04T01:50:09.318175Z","steps":["trace[806777548] 'agreement among raft nodes before linearized reading' (duration: 174.166476ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:28.0476Z","caller":"traceutil/trace.go:171","msg":"trace[2127747812] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"110.361825ms","start":"2026-06-04T01:51:27.93716Z","end":"2026-06-04T01:51:28.047521Z","steps":["trace[2127747812] 'process raft request' (duration: 110.1727ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:32.178455Z","caller":"traceutil/trace.go:171","msg":"trace[1469756616] linearizableReadLoop","detail":"{readStateIndex:5222; appliedIndex:5221; }","duration":"100.190591ms","start":"2026-06-04T01:51:32.078238Z","end":"2026-06-04T01:51:32.178428Z","steps":["trace[1469756616] 'read index received' (duration: 99.947604ms)","trace[1469756616] 'applied index is now lower than readState.Index' (duration: 241.747µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:51:32.178552Z","caller":"traceutil/trace.go:171","msg":"trace[1410220089] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"109.266863ms","start":"2026-06-04T01:51:32.069255Z","end":"2026-06-04T01:51:32.178522Z","steps":["trace[1410220089] 'process raft request' (duration: 109.008816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:51:32.179055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.768338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T01:51:32.179176Z","caller":"traceutil/trace.go:171","msg":"trace[1548428093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5026; }","duration":"100.951773ms","start":"2026-06-04T01:51:32.078198Z","end":"2026-06-04T01:51:32.17915Z","steps":["trace[1548428093] 'agreement among raft nodes before linearized reading' (duration: 100.360385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:51:33.847278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.721593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-482d4d89-703b-40b1-b8e1-b40e0d0762f2\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-06-04T01:51:33.847367Z","caller":"traceutil/trace.go:171","msg":"trace[1144938174] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-482d4d89-703b-40b1-b8e1-b40e0d0762f2; range_end:; response_count:1; response_revision:5052; }","duration":"142.869657ms","start":"2026-06-04T01:51:33.704479Z","end":"2026-06-04T01:51:33.847348Z","steps":["trace[1144938174] 'range keys from in-memory index tree' (duration: 142.527798ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:46.941257Z","caller":"traceutil/trace.go:171","msg":"trace[1516163809] linearizableReadLoop","detail":"{readStateIndex:5503; appliedIndex:5502; }","duration":"201.980768ms","start":"2026-06-04T01:51:46.739254Z","end":"2026-06-04T01:51:46.941235Z","steps":["trace[1516163809] 'read index received' (duration: 201.736872ms)","trace[1516163809] 'applied index is now lower than readState.Index' (duration: 243.136µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:51:46.9415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.234305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T01:51:46.94154Z","caller":"traceutil/trace.go:171","msg":"trace[1783725960] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5297; }","duration":"202.335987ms","start":"2026-06-04T01:51:46.739193Z","end":"2026-06-04T01:51:46.941529Z","steps":["trace[1783725960] 'agreement among raft nodes before linearized reading' (duration: 202.236144ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:46.941486Z","caller":"traceutil/trace.go:171","msg":"trace[989046449] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"214.058163ms","start":"2026-06-04T01:51:46.727379Z","end":"2026-06-04T01:51:46.941437Z","steps":["trace[989046449] 'process raft request' (duration: 213.698523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:51:46.941718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.550294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:51:46.941813Z","caller":"traceutil/trace.go:171","msg":"trace[1589034634] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5297; }","duration":"125.696419ms","start":"2026-06-04T01:51:46.816091Z","end":"2026-06-04T01:51:46.941788Z","steps":["trace[1589034634] 'agreement among raft nodes before linearized reading' (duration: 125.449073ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:56.426345Z","caller":"traceutil/trace.go:171","msg":"trace[1945096362] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"132.585891ms","start":"2026-06-04T01:51:56.293693Z","end":"2026-06-04T01:51:56.426279Z","steps":["trace[1945096362] 'process raft request' (duration: 132.360945ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:56.426283Z","caller":"traceutil/trace.go:171","msg":"trace[207613942] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5725; }","duration":"110.548949ms","start":"2026-06-04T01:51:56.315703Z","end":"2026-06-04T01:51:56.426252Z","steps":["trace[207613942] 'read index received' (duration: 110.319883ms)","trace[207613942] 'applied index is now lower than readState.Index' (duration: 227.766µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:51:56.426592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.854787ms","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-06-04T01:51:56.426641Z","caller":"traceutil/trace.go:171","msg":"trace[889061225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5515; }","duration":"110.976861ms","start":"2026-06-04T01:51:56.315655Z","end":"2026-06-04T01:51:56.426632Z","steps":["trace[889061225] 'agreement among raft nodes before linearized reading' (duration: 110.770225ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:51:56.638895Z","caller":"traceutil/trace.go:171","msg":"trace[1614879504] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"152.312389ms","start":"2026-06-04T01:51:56.486546Z","end":"2026-06-04T01:51:56.638858Z","steps":["trace[1614879504] 'process raft request' (duration: 60.130769ms)","trace[1614879504] 'compare' (duration: 91.741728ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:51:56.639034Z","caller":"traceutil/trace.go:171","msg":"trace[2000731306] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"107.948508ms","start":"2026-06-04T01:51:56.531054Z","end":"2026-06-04T01:51:56.639002Z","steps":["trace[2000731306] 'process raft request' (duration: 107.545677ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:52:08.184156Z","caller":"traceutil/trace.go:171","msg":"trace[472054338] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"124.729558ms","start":"2026-06-04T01:52:08.059394Z","end":"2026-06-04T01:52:08.184123Z","steps":["trace[472054338] 'process raft request' (duration: 45.214854ms)","trace[472054338] 'compare' (duration: 79.195615ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:53:03.126198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-06-04T01:53:03.210347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"82.289772ms","hash":3542210595,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T01:53:03.21042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3542210595,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-06-04T01:58:03.132337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6084} {"level":"info","ts":"2026-06-04T01:58:03.229581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6084,"took":"94.938195ms","hash":788711992,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":21790720,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-04T01:58:03.229696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":788711992,"revision":6084,"compact-revision":3009} {"level":"info","ts":"2026-06-04T01:58:16.007877Z","caller":"traceutil/trace.go:171","msg":"trace[456226684] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"122.677045ms","start":"2026-06-04T01:58:15.885177Z","end":"2026-06-04T01:58:16.007854Z","steps":["trace[456226684] 'process raft request' (duration: 122.540571ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:16.181376Z","caller":"traceutil/trace.go:171","msg":"trace[1899546802] linearizableReadLoop","detail":"{readStateIndex:8674; appliedIndex:8673; }","duration":"169.903055ms","start":"2026-06-04T01:58:16.011445Z","end":"2026-06-04T01:58:16.181348Z","steps":["trace[1899546802] 'read index received' (duration: 163.23856ms)","trace[1899546802] 'applied index is now lower than readState.Index' (duration: 6.663445ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:58:16.181587Z","caller":"traceutil/trace.go:171","msg":"trace[2070482616] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"222.647179ms","start":"2026-06-04T01:58:15.958897Z","end":"2026-06-04T01:58:16.181544Z","steps":["trace[2070482616] 'process raft request' (duration: 215.819049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:16.181778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.312606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:58:16.181823Z","caller":"traceutil/trace.go:171","msg":"trace[1264602531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8370; }","duration":"170.420279ms","start":"2026-06-04T01:58:16.011391Z","end":"2026-06-04T01:58:16.181811Z","steps":["trace[1264602531] 'agreement among raft nodes before linearized reading' (duration: 170.333136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:16.190242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.197521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-04T01:58:16.190293Z","caller":"traceutil/trace.go:171","msg":"trace[755374968] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:8371; }","duration":"127.313924ms","start":"2026-06-04T01:58:16.062968Z","end":"2026-06-04T01:58:16.190282Z","steps":["trace[755374968] 'agreement among raft nodes before linearized reading' (duration: 127.15447ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:27.552255Z","caller":"traceutil/trace.go:171","msg":"trace[953287806] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"230.96602ms","start":"2026-06-04T01:58:27.321266Z","end":"2026-06-04T01:58:27.552232Z","steps":["trace[953287806] 'process raft request' (duration: 230.525628ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:27.552307Z","caller":"traceutil/trace.go:171","msg":"trace[235105079] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"321.592324ms","start":"2026-06-04T01:58:27.230656Z","end":"2026-06-04T01:58:27.552248Z","steps":["trace[235105079] 'process raft request' (duration: 243.101276ms)","trace[235105079] 'compare' (duration: 77.87656ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:58:27.552511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:58:27.230627Z","time spent":"321.779129ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T01:58:28.454602Z","caller":"traceutil/trace.go:171","msg":"trace[1503649108] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"230.829926ms","start":"2026-06-04T01:58:28.22374Z","end":"2026-06-04T01:58:28.45457Z","steps":["trace[1503649108] 'process raft request' (duration: 230.631421ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:28.47866Z","caller":"traceutil/trace.go:171","msg":"trace[1362143416] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8740; }","duration":"174.904623ms","start":"2026-06-04T01:58:28.303723Z","end":"2026-06-04T01:58:28.478628Z","steps":["trace[1362143416] 'read index received' (duration: 150.688432ms)","trace[1362143416] 'applied index is now lower than readState.Index' (duration: 24.214721ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:58:28.478703Z","caller":"traceutil/trace.go:171","msg":"trace[892468062] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"245.08232ms","start":"2026-06-04T01:58:28.233584Z","end":"2026-06-04T01:58:28.478666Z","steps":["trace[892468062] 'process raft request' (duration: 244.860184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:28.47887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.716778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:58:28.478923Z","caller":"traceutil/trace.go:171","msg":"trace[1438786501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8435; }","duration":"174.835612ms","start":"2026-06-04T01:58:28.30407Z","end":"2026-06-04T01:58:28.478906Z","steps":["trace[1438786501] 'agreement among raft nodes before linearized reading' (duration: 174.730759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:28.47894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.18719ms","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-06-04T01:58:28.479035Z","caller":"traceutil/trace.go:171","msg":"trace[782276390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8435; }","duration":"175.351037ms","start":"2026-06-04T01:58:28.303662Z","end":"2026-06-04T01:58:28.479013Z","steps":["trace[782276390] 'agreement among raft nodes before linearized reading' (duration: 175.069058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:28.479113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.891808ms","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-06-04T01:58:28.479328Z","caller":"traceutil/trace.go:171","msg":"trace[1167511111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8435; }","duration":"173.144345ms","start":"2026-06-04T01:58:28.306158Z","end":"2026-06-04T01:58:28.479303Z","steps":["trace[1167511111] 'agreement among raft nodes before linearized reading' (duration: 172.670661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:28.479701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.66721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5865bd6897-2c5fr.18b5be23b86b6e53\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-06-04T01:58:28.479928Z","caller":"traceutil/trace.go:171","msg":"trace[1643604164] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5865bd6897-2c5fr.18b5be23b86b6e53; range_end:; response_count:1; response_revision:8435; }","duration":"155.888266ms","start":"2026-06-04T01:58:28.324014Z","end":"2026-06-04T01:58:28.479903Z","steps":["trace[1643604164] 'agreement among raft nodes before linearized reading' (duration: 155.546807ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:57.020486Z","caller":"traceutil/trace.go:171","msg":"trace[598469772] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8957; }","duration":"232.818362ms","start":"2026-06-04T01:58:56.787644Z","end":"2026-06-04T01:58:57.020462Z","steps":["trace[598469772] 'read index received' (duration: 225.430257ms)","trace[598469772] 'applied index is now lower than readState.Index' (duration: 7.386785ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:58:57.021328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.656426ms","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-06-04T01:58:57.021435Z","caller":"traceutil/trace.go:171","msg":"trace[495173024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8643; }","duration":"233.84103ms","start":"2026-06-04T01:58:56.787566Z","end":"2026-06-04T01:58:57.021407Z","steps":["trace[495173024] 'agreement among raft nodes before linearized reading' (duration: 233.523831ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:58:57.021434Z","caller":"traceutil/trace.go:171","msg":"trace[2132414938] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"326.616558ms","start":"2026-06-04T01:58:56.694798Z","end":"2026-06-04T01:58:57.021414Z","steps":["trace[2132414938] 'process raft request' (duration: 318.324189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:58:57.021781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T01:58:56.694771Z","time spent":"326.892845ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T01:59:01.051322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.170275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T01:59:01.051598Z","caller":"traceutil/trace.go:171","msg":"trace[1558838216] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8663; }","duration":"155.845533ms","start":"2026-06-04T01:59:00.895729Z","end":"2026-06-04T01:59:01.051574Z","steps":["trace[1558838216] 'range keys from in-memory index tree' (duration: 155.01969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:59:01.227704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.478382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-04T01:59:01.227831Z","caller":"traceutil/trace.go:171","msg":"trace[148383102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8664; }","duration":"164.664047ms","start":"2026-06-04T01:59:01.063138Z","end":"2026-06-04T01:59:01.227802Z","steps":["trace[148383102] 'agreement among raft nodes before linearized reading' (duration: 164.268866ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:59:09.873277Z","caller":"traceutil/trace.go:171","msg":"trace[449148744] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"132.184278ms","start":"2026-06-04T01:59:09.741067Z","end":"2026-06-04T01:59:09.873251Z","steps":["trace[449148744] 'process raft request' (duration: 132.038104ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:59:10.040227Z","caller":"traceutil/trace.go:171","msg":"trace[1552979786] linearizableReadLoop","detail":"{readStateIndex:9076; appliedIndex:9074; }","duration":"239.536538ms","start":"2026-06-04T01:59:09.800659Z","end":"2026-06-04T01:59:10.040196Z","steps":["trace[1552979786] 'read index received' (duration: 72.457705ms)","trace[1552979786] 'applied index is now lower than readState.Index' (duration: 167.077373ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T01:59:10.040566Z","caller":"traceutil/trace.go:171","msg":"trace[646520492] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"275.498095ms","start":"2026-06-04T01:59:09.765053Z","end":"2026-06-04T01:59:10.040551Z","steps":["trace[646520492] 'process raft request' (duration: 252.880329ms)","trace[646520492] 'compare' (duration: 21.869604ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T01:59:10.040842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.173827ms","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-06-04T01:59:10.040876Z","caller":"traceutil/trace.go:171","msg":"trace[1914471518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8755; }","duration":"240.250419ms","start":"2026-06-04T01:59:09.800614Z","end":"2026-06-04T01:59:10.040865Z","steps":["trace[1914471518] 'agreement among raft nodes before linearized reading' (duration: 240.131756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:59:10.041003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.510311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2hklx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-04T01:59:10.041149Z","caller":"traceutil/trace.go:171","msg":"trace[811171166] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2hklx; range_end:; response_count:1; response_revision:8755; }","duration":"186.768559ms","start":"2026-06-04T01:59:09.854331Z","end":"2026-06-04T01:59:10.041099Z","steps":["trace[811171166] 'agreement among raft nodes before linearized reading' (duration: 186.373258ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:59:10.58656Z","caller":"traceutil/trace.go:171","msg":"trace[1048088086] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"118.211941ms","start":"2026-06-04T01:59:10.468318Z","end":"2026-06-04T01:59:10.58653Z","steps":["trace[1048088086] 'process raft request' (duration: 118.005416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T01:59:10.979974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.2529ms","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-06-04T01:59:10.980078Z","caller":"traceutil/trace.go:171","msg":"trace[563836073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8760; }","duration":"166.480267ms","start":"2026-06-04T01:59:10.813575Z","end":"2026-06-04T01:59:10.980055Z","steps":["trace[563836073] 'range keys from in-memory index tree' (duration: 165.967523ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T01:59:25.628311Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002401.wal"} {"level":"warn","ts":"2026-06-04T02:00:20.048913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993766086,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:00:20.549175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993766086,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:00:21.050275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993766086,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:00:21.55088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993766086,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:00:21.626221Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.258471516s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T02:00:21.626811Z","caller":"traceutil/trace.go:171","msg":"trace[724375391] transaction","detail":"{read_only:false; response_revision:9461; number_of_response:1; }","duration":"2.20651619s","start":"2026-06-04T02:00:19.420258Z","end":"2026-06-04T02:00:21.626774Z","steps":["trace[724375391] 'process raft request' (duration: 2.206290194s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.627106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.420233Z","time spent":"2.206737647s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:00:21.627517Z","caller":"traceutil/trace.go:171","msg":"trace[1934283257] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"709.376201ms","start":"2026-06-04T02:00:20.918109Z","end":"2026-06-04T02:00:21.627486Z","steps":["trace[1934283257] 'process raft request' (duration: 709.300029ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:00:21.627535Z","caller":"traceutil/trace.go:171","msg":"trace[2014311672] transaction","detail":"{read_only:false; response_revision:9462; number_of_response:1; }","duration":"1.485588111s","start":"2026-06-04T02:00:20.141909Z","end":"2026-06-04T02:00:21.627497Z","steps":["trace[2014311672] 'process raft request' (duration: 1.485289923s)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:00:21.627565Z","caller":"traceutil/trace.go:171","msg":"trace[1298403770] transaction","detail":"{read_only:false; response_revision:9463; number_of_response:1; }","duration":"1.090186809s","start":"2026-06-04T02:00:20.537345Z","end":"2026-06-04T02:00:21.627532Z","steps":["trace[1298403770] 'process raft request' (duration: 1.090024854s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.627699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.918058Z","time spent":"709.520045ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T02:00:21.627706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.537324Z","time spent":"1.090312031s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T02:00:21.627716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.141875Z","time spent":"1.485748505s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:00:21.627763Z","caller":"traceutil/trace.go:171","msg":"trace[1626374679] transaction","detail":"{read_only:false; response_revision:9465; number_of_response:1; }","duration":"677.497728ms","start":"2026-06-04T02:00:20.950237Z","end":"2026-06-04T02:00:21.627735Z","steps":["trace[1626374679] 'process raft request' (duration: 677.20792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.627894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.950209Z","time spent":"677.624242ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:00:21.630778Z","caller":"traceutil/trace.go:171","msg":"trace[135809614] linearizableReadLoop","detail":"{readStateIndex:9806; appliedIndex:9800; }","duration":"2.082290232s","start":"2026-06-04T02:00:19.548444Z","end":"2026-06-04T02:00:21.630735Z","steps":["trace[135809614] 'read index received' (duration: 2.078185728s)","trace[135809614] 'applied index is now lower than readState.Index' (duration: 4.101834ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:00:21.630978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.082503308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:00:21.631083Z","caller":"traceutil/trace.go:171","msg":"trace[1079970513] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:9465; }","duration":"2.082693464s","start":"2026-06-04T02:00:19.548365Z","end":"2026-06-04T02:00:21.631059Z","steps":["trace[1079970513] 'agreement among raft nodes before linearized reading' (duration: 2.082510849s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.548328Z","time spent":"2.082787607s","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:00:21.631171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.245357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T02:00:21.631227Z","caller":"traceutil/trace.go:171","msg":"trace[1054767275] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:9465; }","duration":"176.397821ms","start":"2026-06-04T02:00:21.454813Z","end":"2026-06-04T02:00:21.63121Z","steps":["trace[1054767275] 'agreement among raft nodes before linearized reading' (duration: 176.219276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837716705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-555756b6c5-sw8wx.18b5be3eafca0ec6\" ","response":"range_response_count:1 size:787"} {"level":"warn","ts":"2026-06-04T02:00:21.63138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74538079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-04T02:00:21.631451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.889933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:00:21.631458Z","caller":"traceutil/trace.go:171","msg":"trace[1837079085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9465; }","duration":"1.745522064s","start":"2026-06-04T02:00:19.885921Z","end":"2026-06-04T02:00:21.631443Z","steps":["trace[1837079085] 'agreement among raft nodes before linearized reading' (duration: 1.745286897s)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:00:21.631492Z","caller":"traceutil/trace.go:171","msg":"trace[1648734149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9465; }","duration":"620.988215ms","start":"2026-06-04T02:00:21.010495Z","end":"2026-06-04T02:00:21.631483Z","steps":["trace[1648734149] 'agreement among raft nodes before linearized reading' (duration: 620.925973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.885896Z","time spent":"1.745600536s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T02:00:21.631518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:21.010464Z","time spent":"621.047807ms","remote":"127.0.0.1:41958","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-06-04T02:00:21.631482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649647371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T02:00:21.631551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982877102s","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-06-04T02:00:21.631191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003711338s","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-06-04T02:00:21.631444Z","caller":"traceutil/trace.go:171","msg":"trace[349454236] range","detail":"{range_begin:/registry/events/openstack/glance-api-555756b6c5-sw8wx.18b5be3eafca0ec6; range_end:; response_count:1; response_revision:9465; }","duration":"1.837864239s","start":"2026-06-04T02:00:19.793552Z","end":"2026-06-04T02:00:21.631416Z","steps":["trace[349454236] 'agreement among raft nodes before linearized reading' (duration: 1.837712735s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650673379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:00:21.6316Z","caller":"traceutil/trace.go:171","msg":"trace[942720387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9465; }","duration":"2.004218572s","start":"2026-06-04T02:00:19.627372Z","end":"2026-06-04T02:00:21.63159Z","steps":["trace[942720387] 'agreement among raft nodes before linearized reading' (duration: 2.003622345s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.793517Z","time spent":"1.838084135s","remote":"127.0.0.1:42054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":811,"request content":"key:\"/registry/events/openstack/glance-api-555756b6c5-sw8wx.18b5be3eafca0ec6\" "} {"level":"info","ts":"2026-06-04T02:00:21.631622Z","caller":"traceutil/trace.go:171","msg":"trace[793442876] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:9465; }","duration":"1.649845126s","start":"2026-06-04T02:00:19.981738Z","end":"2026-06-04T02:00:21.631583Z","steps":["trace[793442876] 'agreement among raft nodes before linearized reading' (duration: 1.649549708s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.627343Z","time spent":"2.004308764s","remote":"127.0.0.1:37982","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":"info","ts":"2026-06-04T02:00:21.63166Z","caller":"traceutil/trace.go:171","msg":"trace[1267604934] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:9465; }","duration":"1.650904515s","start":"2026-06-04T02:00:19.980724Z","end":"2026-06-04T02:00:21.631629Z","steps":["trace[1267604934] 'agreement among raft nodes before linearized reading' (duration: 1.650633177s)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:00:21.631719Z","caller":"traceutil/trace.go:171","msg":"trace[1199707577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9465; }","duration":"1.982946234s","start":"2026-06-04T02:00:19.648638Z","end":"2026-06-04T02:00:21.631584Z","steps":["trace[1199707577] 'agreement among raft nodes before linearized reading' (duration: 1.982839511s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.980699Z","time spent":"1.651013888s","remote":"127.0.0.1:38030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:00:21.63168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.981724Z","time spent":"1.649943259s","remote":"127.0.0.1:56768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:00:21.631758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:19.648626Z","time spent":"1.983125429s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T02:00:21.631832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111580421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2hklx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-04T02:00:21.631865Z","caller":"traceutil/trace.go:171","msg":"trace[562047040] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2hklx; range_end:; response_count:1; response_revision:9465; }","duration":"1.111667753s","start":"2026-06-04T02:00:20.52019Z","end":"2026-06-04T02:00:21.631858Z","steps":["trace[562047040] 'agreement among raft nodes before linearized reading' (duration: 1.111529359s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.631898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.520159Z","time spent":"1.111734305s","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-2hklx\" "} {"level":"warn","ts":"2026-06-04T02:00:21.63208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168837735s","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-06-04T02:00:21.632163Z","caller":"traceutil/trace.go:171","msg":"trace[1857271841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9465; }","duration":"1.168973119s","start":"2026-06-04T02:00:20.463172Z","end":"2026-06-04T02:00:21.632145Z","steps":["trace[1857271841] 'agreement among raft nodes before linearized reading' (duration: 1.167853718s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:00:21.632218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:00:20.463148Z","time spent":"1.169056331s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T02:00:53.057755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T02:00:53.066695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-04T02:00:53.066865Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-04T02:03:03.151987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8167} {"level":"info","ts":"2026-06-04T02:03:03.216298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8167,"took":"62.005433ms","hash":2792364281,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":28430336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-04T02:03:03.216392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2792364281,"revision":8167,"compact-revision":6084} {"level":"info","ts":"2026-06-04T02:05:49.490571Z","caller":"traceutil/trace.go:171","msg":"trace[1585304147] transaction","detail":"{read_only:false; response_revision:11841; number_of_response:1; }","duration":"100.177414ms","start":"2026-06-04T02:05:49.390358Z","end":"2026-06-04T02:05:49.490535Z","steps":["trace[1585304147] 'process raft request' (duration: 99.882636ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:06:07.855807Z","caller":"traceutil/trace.go:171","msg":"trace[1859241014] linearizableReadLoop","detail":"{readStateIndex:12364; appliedIndex:12363; }","duration":"102.055905ms","start":"2026-06-04T02:06:07.753717Z","end":"2026-06-04T02:06:07.855773Z","steps":["trace[1859241014] 'read index received' (duration: 20.325364ms)","trace[1859241014] 'applied index is now lower than readState.Index' (duration: 81.729331ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T02:06:07.855945Z","caller":"traceutil/trace.go:171","msg":"trace[451919286] transaction","detail":"{read_only:false; response_revision:11944; number_of_response:1; }","duration":"105.268055ms","start":"2026-06-04T02:06:07.750615Z","end":"2026-06-04T02:06:07.855883Z","steps":["trace[451919286] 'process raft request' (duration: 23.416251ms)","trace[451919286] 'compare' (duration: 81.582957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:06:07.856069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.318044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-754ddbd5c6-xzn86.18b5be8bf2971267\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-06-04T02:06:07.856152Z","caller":"traceutil/trace.go:171","msg":"trace[1940145344] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-754ddbd5c6-xzn86.18b5be8bf2971267; range_end:; response_count:1; response_revision:11944; }","duration":"102.647653ms","start":"2026-06-04T02:06:07.753481Z","end":"2026-06-04T02:06:07.856128Z","steps":["trace[1940145344] 'agreement among raft nodes before linearized reading' (duration: 102.417386ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:08:03.165229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10525} {"level":"info","ts":"2026-06-04T02:08:03.250042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10525,"took":"81.668108ms","hash":3251818887,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":26144768,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-04T02:08:03.250119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3251818887,"revision":10525,"compact-revision":8167} {"level":"info","ts":"2026-06-04T02:11:50.176037Z","caller":"traceutil/trace.go:171","msg":"trace[1664380077] transaction","detail":"{read_only:false; response_revision:14217; number_of_response:1; }","duration":"400.375998ms","start":"2026-06-04T02:11:49.775632Z","end":"2026-06-04T02:11:50.176008Z","steps":["trace[1664380077] 'process raft request' (duration: 400.215163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:50.176213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:49.775602Z","time spent":"400.520742ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9345,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:11:50.176255Z","caller":"traceutil/trace.go:171","msg":"trace[1185767701] linearizableReadLoop","detail":"{readStateIndex:14715; appliedIndex:14715; }","duration":"357.719498ms","start":"2026-06-04T02:11:49.818497Z","end":"2026-06-04T02:11:50.176217Z","steps":["trace[1185767701] 'read index received' (duration: 357.705837ms)","trace[1185767701] 'applied index is now lower than readState.Index' (duration: 10.941µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:11:50.176609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.470203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-04T02:11:50.176644Z","caller":"traceutil/trace.go:171","msg":"trace[379357671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14217; }","duration":"351.603337ms","start":"2026-06-04T02:11:49.825033Z","end":"2026-06-04T02:11:50.176637Z","steps":["trace[379357671] 'agreement among raft nodes before linearized reading' (duration: 351.33155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:50.176678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:49.825027Z","time spent":"351.643908ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T02:11:50.176958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.425697ms","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-06-04T02:11:50.177043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.452779ms","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-06-04T02:11:50.177048Z","caller":"traceutil/trace.go:171","msg":"trace[404671548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14217; }","duration":"358.598553ms","start":"2026-06-04T02:11:49.818428Z","end":"2026-06-04T02:11:50.177027Z","steps":["trace[404671548] 'agreement among raft nodes before linearized reading' (duration: 358.326334ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:11:50.177069Z","caller":"traceutil/trace.go:171","msg":"trace[1911613272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14217; }","duration":"306.542261ms","start":"2026-06-04T02:11:49.87052Z","end":"2026-06-04T02:11:50.177062Z","steps":["trace[1911613272] 'agreement among raft nodes before linearized reading' (duration: 306.473059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:50.177094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:49.87047Z","time spent":"306.620584ms","remote":"127.0.0.1:37982","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-06-04T02:11:50.177097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:49.818403Z","time spent":"358.684295ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T02:11:51.352731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993779198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:11:51.853857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056228756993779198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-04T02:11:51.972043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.158742601s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T02:11:51.975137Z","caller":"traceutil/trace.go:171","msg":"trace[1308298315] transaction","detail":"{read_only:false; response_revision:14224; number_of_response:1; }","duration":"1.161890439s","start":"2026-06-04T02:11:50.813164Z","end":"2026-06-04T02:11:51.974996Z","steps":["trace[1308298315] 'process raft request' (duration: 1.159088081s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.975577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:50.813143Z","time spent":"1.162331902s","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:11:51.977196Z","caller":"traceutil/trace.go:171","msg":"trace[1343999818] transaction","detail":"{read_only:false; response_revision:14225; number_of_response:1; }","duration":"789.302457ms","start":"2026-06-04T02:11:51.187862Z","end":"2026-06-04T02:11:51.977164Z","steps":["trace[1343999818] 'process raft request' (duration: 789.126682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.977349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:51.187838Z","time spent":"789.4331ms","remote":"127.0.0.1:37942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7527,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T02:11:51.979982Z","caller":"traceutil/trace.go:171","msg":"trace[1898548638] linearizableReadLoop","detail":"{readStateIndex:14724; appliedIndex:14721; }","duration":"1.128127698s","start":"2026-06-04T02:11:50.851837Z","end":"2026-06-04T02:11:51.979965Z","steps":["trace[1898548638] 'read index received' (duration: 1.120453424s)","trace[1898548638] 'applied index is now lower than readState.Index' (duration: 7.673044ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:11:51.980291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128416396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T02:11:51.980355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031599824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:11:51.980407Z","caller":"traceutil/trace.go:171","msg":"trace[731847303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14225; }","duration":"1.128583411s","start":"2026-06-04T02:11:50.851795Z","end":"2026-06-04T02:11:51.980379Z","steps":["trace[731847303] 'agreement among raft nodes before linearized reading' (duration: 1.128368355s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.980469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:50.851786Z","time spent":"1.128670963s","remote":"127.0.0.1:46282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-04T02:11:51.980525Z","caller":"traceutil/trace.go:171","msg":"trace[615292472] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:14225; }","duration":"1.031746359s","start":"2026-06-04T02:11:50.948673Z","end":"2026-06-04T02:11:51.98042Z","steps":["trace[615292472] 'agreement among raft nodes before linearized reading' (duration: 1.031540303s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.980556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028411366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7542"} {"level":"warn","ts":"2026-06-04T02:11:51.980622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:50.948657Z","time spent":"1.031912424s","remote":"127.0.0.1:60818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:11:51.9807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.411771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:11:51.980733Z","caller":"traceutil/trace.go:171","msg":"trace[93126742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14225; }","duration":"973.457333ms","start":"2026-06-04T02:11:51.007269Z","end":"2026-06-04T02:11:51.980726Z","steps":["trace[93126742] 'agreement among raft nodes before linearized reading' (duration: 973.404232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.980764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:51.00726Z","time spent":"973.498054ms","remote":"127.0.0.1:41958","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-06-04T02:11:51.980771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.713091ms","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-06-04T02:11:51.980851Z","caller":"traceutil/trace.go:171","msg":"trace[832898669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14225; }","duration":"265.819994ms","start":"2026-06-04T02:11:51.715002Z","end":"2026-06-04T02:11:51.980822Z","steps":["trace[832898669] 'agreement among raft nodes before linearized reading' (duration: 265.612158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.98089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.846355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T02:11:51.980954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030783711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T02:11:51.981032Z","caller":"traceutil/trace.go:171","msg":"trace[2039324693] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:0; response_revision:14225; }","duration":"1.030915776s","start":"2026-06-04T02:11:50.950098Z","end":"2026-06-04T02:11:51.981014Z","steps":["trace[2039324693] 'agreement among raft nodes before linearized reading' (duration: 1.030764002s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.981077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:50.950074Z","time spent":"1.03099472s","remote":"127.0.0.1:37942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"info","ts":"2026-06-04T02:11:51.980647Z","caller":"traceutil/trace.go:171","msg":"trace[2026742141] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:14225; }","duration":"1.028539199s","start":"2026-06-04T02:11:50.952086Z","end":"2026-06-04T02:11:51.980625Z","steps":["trace[2026742141] 'agreement among raft nodes before linearized reading' (duration: 1.028233551s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.98111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.787888ms","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-06-04T02:11:51.981145Z","caller":"traceutil/trace.go:171","msg":"trace[1912329761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14225; }","duration":"481.875791ms","start":"2026-06-04T02:11:51.499263Z","end":"2026-06-04T02:11:51.981138Z","steps":["trace[1912329761] 'agreement among raft nodes before linearized reading' (duration: 481.741887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.981161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:50.952075Z","time spent":"1.029056814s","remote":"127.0.0.1:37942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":7566,"request content":"key:\"/registry/jobs/openstack/placement-ks-user\" "} {"level":"warn","ts":"2026-06-04T02:11:51.98118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:51.499231Z","time spent":"481.942323ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-04T02:11:51.98098Z","caller":"traceutil/trace.go:171","msg":"trace[470356289] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:14225; }","duration":"336.974309ms","start":"2026-06-04T02:11:51.643993Z","end":"2026-06-04T02:11:51.980967Z","steps":["trace[470356289] 'agreement among raft nodes before linearized reading' (duration: 336.857506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.981296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:51.643965Z","time spent":"337.317849ms","remote":"127.0.0.1:38218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:11:51.980534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.754267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2hklx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-04T02:11:51.98145Z","caller":"traceutil/trace.go:171","msg":"trace[1241886883] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2hklx; range_end:; response_count:1; response_revision:14225; }","duration":"653.749636ms","start":"2026-06-04T02:11:51.327686Z","end":"2026-06-04T02:11:51.981436Z","steps":["trace[1241886883] 'agreement among raft nodes before linearized reading' (duration: 652.553592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:11:51.981492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:11:51.327662Z","time spent":"653.818478ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-2hklx\" "} {"level":"info","ts":"2026-06-04T02:12:22.592421Z","caller":"traceutil/trace.go:171","msg":"trace[1107599162] transaction","detail":"{read_only:false; response_revision:14457; number_of_response:1; }","duration":"128.847885ms","start":"2026-06-04T02:12:22.463541Z","end":"2026-06-04T02:12:22.592389Z","steps":["trace[1107599162] 'process raft request' (duration: 104.968018ms)","trace[1107599162] 'compare' (duration: 23.729162ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T02:13:00.391598Z","caller":"traceutil/trace.go:171","msg":"trace[1574538855] transaction","detail":"{read_only:false; response_revision:14671; number_of_response:1; }","duration":"109.182245ms","start":"2026-06-04T02:13:00.282383Z","end":"2026-06-04T02:13:00.391565Z","steps":["trace[1574538855] 'process raft request' (duration: 107.56671ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:13:00.510648Z","caller":"traceutil/trace.go:171","msg":"trace[2081497839] transaction","detail":"{read_only:false; response_revision:14672; number_of_response:1; }","duration":"104.837644ms","start":"2026-06-04T02:13:00.405761Z","end":"2026-06-04T02:13:00.510599Z","steps":["trace[2081497839] 'process raft request' (duration: 87.674716ms)","trace[2081497839] 'compare' (duration: 16.830379ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T02:13:03.171692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12713} {"level":"info","ts":"2026-06-04T02:13:03.268505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12713,"took":"94.131146ms","hash":3080400316,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":27369472,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-04T02:13:03.268579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3080400316,"revision":12713,"compact-revision":10525} {"level":"info","ts":"2026-06-04T02:13:10.362379Z","caller":"traceutil/trace.go:171","msg":"trace[518549919] transaction","detail":"{read_only:false; response_revision:14841; number_of_response:1; }","duration":"120.398268ms","start":"2026-06-04T02:13:10.241947Z","end":"2026-06-04T02:13:10.362345Z","steps":["trace[518549919] 'process raft request' (duration: 120.178382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:13:10.633666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.707738ms","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-06-04T02:13:10.633859Z","caller":"traceutil/trace.go:171","msg":"trace[591294877] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14841; }","duration":"148.940095ms","start":"2026-06-04T02:13:10.484886Z","end":"2026-06-04T02:13:10.633826Z","steps":["trace[591294877] 'range keys from in-memory index tree' (duration: 148.682897ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:13:10.63398Z","caller":"traceutil/trace.go:171","msg":"trace[606830722] transaction","detail":"{read_only:false; response_revision:14842; number_of_response:1; }","duration":"201.664245ms","start":"2026-06-04T02:13:10.432291Z","end":"2026-06-04T02:13:10.633955Z","steps":["trace[606830722] 'process raft request' (duration: 177.983724ms)","trace[606830722] 'compare' (duration: 23.015033ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:14:43.858638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.68621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2zk2s\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-06-04T02:14:43.85875Z","caller":"traceutil/trace.go:171","msg":"trace[466704145] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2zk2s; range_end:; response_count:1; response_revision:15902; }","duration":"137.870576ms","start":"2026-06-04T02:14:43.720858Z","end":"2026-06-04T02:14:43.858728Z","steps":["trace[466704145] 'range keys from in-memory index tree' (duration: 137.444124ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:18:03.178614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14705} {"level":"info","ts":"2026-06-04T02:18:03.258917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14705,"took":"77.3588ms","hash":2811870815,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":33816576,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T02:18:03.258984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2811870815,"revision":14705,"compact-revision":12713} {"level":"info","ts":"2026-06-04T02:20:33.103717Z","caller":"traceutil/trace.go:171","msg":"trace[546014193] transaction","detail":"{read_only:false; response_revision:18453; number_of_response:1; }","duration":"119.732502ms","start":"2026-06-04T02:20:32.98394Z","end":"2026-06-04T02:20:33.103673Z","steps":["trace[546014193] 'process raft request' (duration: 119.358221ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:20:33.510813Z","caller":"traceutil/trace.go:171","msg":"trace[2075635191] linearizableReadLoop","detail":"{readStateIndex:19095; appliedIndex:19094; }","duration":"407.298546ms","start":"2026-06-04T02:20:33.103469Z","end":"2026-06-04T02:20:33.510768Z","steps":["trace[2075635191] 'read index received' (duration: 326.98851ms)","trace[2075635191] 'applied index is now lower than readState.Index' (duration: 80.308366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T02:20:33.511355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.253843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-04T02:20:33.5114Z","caller":"traceutil/trace.go:171","msg":"trace[1687879683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18454; }","duration":"426.343305ms","start":"2026-06-04T02:20:33.085043Z","end":"2026-06-04T02:20:33.511386Z","steps":["trace[1687879683] 'agreement among raft nodes before linearized reading' (duration: 426.121569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.602685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-06-04T02:20:33.511443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.085028Z","time spent":"426.405667ms","remote":"127.0.0.1:37982","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-06-04T02:20:33.511425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.599819ms","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-06-04T02:20:33.511472Z","caller":"traceutil/trace.go:171","msg":"trace[1552421735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18454; }","duration":"425.724368ms","start":"2026-06-04T02:20:33.085719Z","end":"2026-06-04T02:20:33.511443Z","steps":["trace[1552421735] 'agreement among raft nodes before linearized reading' (duration: 425.402509ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:20:33.511512Z","caller":"traceutil/trace.go:171","msg":"trace[922457004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18454; }","duration":"423.737943ms","start":"2026-06-04T02:20:33.087756Z","end":"2026-06-04T02:20:33.511494Z","steps":["trace[922457004] 'agreement among raft nodes before linearized reading' (duration: 423.486936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.658217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-04T02:20:33.51153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.085689Z","time spent":"425.830031ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-04T02:20:33.511555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.087743Z","time spent":"423.801074ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T02:20:33.51159Z","caller":"traceutil/trace.go:171","msg":"trace[1568031296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18454; }","duration":"424.782942ms","start":"2026-06-04T02:20:33.086783Z","end":"2026-06-04T02:20:33.511566Z","steps":["trace[1568031296] 'agreement among raft nodes before linearized reading' (duration: 424.496254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.807664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-06-04T02:20:33.511563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.254007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T02:20:33.511648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.502784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-04T02:20:33.511652Z","caller":"traceutil/trace.go:171","msg":"trace[1782049452] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18454; }","duration":"294.959048ms","start":"2026-06-04T02:20:33.216674Z","end":"2026-06-04T02:20:33.511633Z","steps":["trace[1782049452] 'agreement among raft nodes before linearized reading' (duration: 294.673311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.569795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T02:20:33.511689Z","caller":"traceutil/trace.go:171","msg":"trace[800002357] transaction","detail":"{read_only:false; response_revision:18454; number_of_response:1; }","duration":"458.311565ms","start":"2026-06-04T02:20:33.053366Z","end":"2026-06-04T02:20:33.511678Z","steps":["trace[800002357] 'process raft request' (duration: 377.116465ms)","trace[800002357] 'compare' (duration: 79.993357ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T02:20:33.511707Z","caller":"traceutil/trace.go:171","msg":"trace[910427194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18454; }","duration":"392.586236ms","start":"2026-06-04T02:20:33.119098Z","end":"2026-06-04T02:20:33.511684Z","steps":["trace[910427194] 'agreement among raft nodes before linearized reading' (duration: 392.38588ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:20:33.511718Z","caller":"traceutil/trace.go:171","msg":"trace[1179100786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18454; }","duration":"406.902704ms","start":"2026-06-04T02:20:33.104803Z","end":"2026-06-04T02:20:33.511706Z","steps":["trace[1179100786] 'agreement among raft nodes before linearized reading' (duration: 406.524764ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T02:20:33.511722Z","caller":"traceutil/trace.go:171","msg":"trace[1877957284] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:18454; }","duration":"369.494903ms","start":"2026-06-04T02:20:33.142195Z","end":"2026-06-04T02:20:33.51169Z","steps":["trace[1877957284] 'agreement among raft nodes before linearized reading' (duration: 369.156144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.671635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-06-04T02:20:33.511755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.104792Z","time spent":"406.953185ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T02:20:33.511776Z","caller":"traceutil/trace.go:171","msg":"trace[1523517407] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18454; }","duration":"232.725916ms","start":"2026-06-04T02:20:33.279044Z","end":"2026-06-04T02:20:33.51177Z","steps":["trace[1523517407] 'agreement among raft nodes before linearized reading' (duration: 232.626533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T02:20:33.511776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.053333Z","time spent":"458.379407ms","remote":"127.0.0.1:37982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T02:20:33.511744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.119064Z","time spent":"392.673628ms","remote":"127.0.0.1:37982","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":"warn","ts":"2026-06-04T02:20:33.511813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.142173Z","time spent":"369.623077ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":30,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-04T02:20:33.51164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T02:20:33.086776Z","time spent":"424.856093ms","remote":"127.0.0.1:37982","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":"info","ts":"2026-06-04T02:22:59.503681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T02:22:59.511895Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-04T02:22:59.512036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-04T02:23:03.188257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17460} {"level":"info","ts":"2026-06-04T02:23:03.287295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17460,"took":"95.132308ms","hash":3265544282,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T02:23:03.287362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3265544282,"revision":17460,"compact-revision":14705} {"level":"info","ts":"2026-06-04T02:28:03.208707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19343} {"level":"info","ts":"2026-06-04T02:28:03.303965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19343,"took":"92.174557ms","hash":3346276004,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":24285184,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-04T02:28:03.304029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3346276004,"revision":19343,"compact-revision":17460}