{"level":"warn","ts":"2026-03-25T16:50:26.236178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:50:26.236313Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-03-25T16:50:26.236401Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T16:50:26.236412Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-03-25T16:50:26.236436Z","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-03-25T16:50:26.237164Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-03-25T16:50:26.237251Z","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://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-03-25T16:50:26.245049Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.558139ms"} {"level":"info","ts":"2026-03-25T16:50:26.259619Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-03-25T16:50:26.259824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T16:50:26.259872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-03-25T16:50:26.259899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T16:50:26.259921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-03-25T16:50:26.260076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-03-25T16:50:26.267753Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T16:50:26.270412Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T16:50:26.27159Z","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-03-25T16:50:26.2774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T16:50:26.277604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T16:50:26.277792Z","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-03-25T16:50:26.278029Z","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-03-25T16:50:26.278056Z","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-03-25T16:50:26.279901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-03-25T16:50:26.280142Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-03-25T16:50:26.282284Z","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-03-25T16:50:26.282373Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-03-25T16:50:26.282403Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-03-25T16:50:26.282695Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T16:50:26.282778Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T16:50:26.860625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T16:50:26.860695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T16:50:26.860746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-03-25T16:50:26.860767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-03-25T16:50:26.860778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-03-25T16:50:26.860802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-03-25T16:50:26.860817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-03-25T16:50:26.862453Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:50:26.863113Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T16:50:26.863321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:50:26.863383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T16:50:26.863621Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:50:26.863808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:50:26.863859Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T16:50:26.869006Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T16:50:26.869108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T16:50:26.87187Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T16:50:26.872171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-03-25T16:51:04.425908Z","caller":"traceutil/trace.go:171","msg":"trace[1441830427] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"158.534922ms","start":"2026-03-25T16:51:04.267341Z","end":"2026-03-25T16:51:04.425876Z","steps":["trace[1441830427] 'process raft request' (duration: 158.343778ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:04.508853Z","caller":"traceutil/trace.go:171","msg":"trace[1032879227] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"160.304174ms","start":"2026-03-25T16:51:04.348508Z","end":"2026-03-25T16:51:04.508812Z","steps":["trace[1032879227] 'read index received' (duration: 77.212633ms)","trace[1032879227] 'applied index is now lower than readState.Index' (duration: 83.090311ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:51:04.508958Z","caller":"traceutil/trace.go:171","msg":"trace[595336870] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"240.424064ms","start":"2026-03-25T16:51:04.268507Z","end":"2026-03-25T16:51:04.508931Z","steps":["trace[595336870] 'process raft request' (duration: 239.719948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:04.50923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.689102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T16:51:04.509471Z","caller":"traceutil/trace.go:171","msg":"trace[206610657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"160.98954ms","start":"2026-03-25T16:51:04.348438Z","end":"2026-03-25T16:51:04.509428Z","steps":["trace[206610657] 'agreement among raft nodes before linearized reading' (duration: 160.546049ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:04.727017Z","caller":"traceutil/trace.go:171","msg":"trace[172858981] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"206.528109ms","start":"2026-03-25T16:51:04.520451Z","end":"2026-03-25T16:51:04.726979Z","steps":["trace[172858981] 'process raft request' (duration: 206.269133ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:04.734419Z","caller":"traceutil/trace.go:171","msg":"trace[941435686] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"161.993233ms","start":"2026-03-25T16:51:04.5724Z","end":"2026-03-25T16:51:04.734393Z","steps":["trace[941435686] 'process raft request' (duration: 161.8583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:05.212634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.472034ms","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-03-25T16:51:05.212752Z","caller":"traceutil/trace.go:171","msg":"trace[570521895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"291.681138ms","start":"2026-03-25T16:51:04.921044Z","end":"2026-03-25T16:51:05.212725Z","steps":["trace[570521895] 'range keys from in-memory index tree' (duration: 291.255488ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:51:06.506282Z","caller":"traceutil/trace.go:171","msg":"trace[1292795789] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"100.733966ms","start":"2026-03-25T16:51:06.405516Z","end":"2026-03-25T16:51:06.50625Z","steps":["trace[1292795789] 'process raft request' (duration: 100.411358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:51:20.291679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.389644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T16:51:20.29182Z","caller":"traceutil/trace.go:171","msg":"trace[815419727] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:735; }","duration":"177.63592ms","start":"2026-03-25T16:51:20.114155Z","end":"2026-03-25T16:51:20.291791Z","steps":["trace[815419727] 'range keys from in-memory index tree' (duration: 177.026196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:10.795642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.610887ms","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-03-25T16:52:10.795794Z","caller":"traceutil/trace.go:171","msg":"trace[136742859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"106.810371ms","start":"2026-03-25T16:52:10.688956Z","end":"2026-03-25T16:52:10.795767Z","steps":["trace[136742859] 'range keys from in-memory index tree' (duration: 106.387014ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:34.878019Z","caller":"traceutil/trace.go:171","msg":"trace[646287190] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"153.736047ms","start":"2026-03-25T16:52:34.724251Z","end":"2026-03-25T16:52:34.877987Z","steps":["trace[646287190] 'process raft request' (duration: 111.813596ms)","trace[646287190] 'compare' (duration: 41.788799ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:52:34.926231Z","caller":"traceutil/trace.go:171","msg":"trace[1141488106] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1878; }","duration":"185.791141ms","start":"2026-03-25T16:52:34.740402Z","end":"2026-03-25T16:52:34.926193Z","steps":["trace[1141488106] 'read index received' (duration: 95.676239ms)","trace[1141488106] 'applied index is now lower than readState.Index' (duration: 90.113342ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:52:34.926333Z","caller":"traceutil/trace.go:171","msg":"trace[1313553574] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"199.344941ms","start":"2026-03-25T16:52:34.726952Z","end":"2026-03-25T16:52:34.926297Z","steps":["trace[1313553574] 'process raft request' (duration: 199.085386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:34.92656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.129517ms","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-03-25T16:52:34.92666Z","caller":"traceutil/trace.go:171","msg":"trace[2098331118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1808; }","duration":"186.26131ms","start":"2026-03-25T16:52:34.740375Z","end":"2026-03-25T16:52:34.926636Z","steps":["trace[2098331118] 'agreement among raft nodes before linearized reading' (duration: 186.008116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:34.926745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.06362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-650f0f30-8b26-47f8-90ad-bac385692787\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-25T16:52:34.926788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.562215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T16:52:34.926821Z","caller":"traceutil/trace.go:171","msg":"trace[1408905483] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-650f0f30-8b26-47f8-90ad-bac385692787; range_end:; response_count:1; response_revision:1808; }","duration":"181.170212ms","start":"2026-03-25T16:52:34.745631Z","end":"2026-03-25T16:52:34.926801Z","steps":["trace[1408905483] 'agreement among raft nodes before linearized reading' (duration: 180.976539ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:34.926862Z","caller":"traceutil/trace.go:171","msg":"trace[545614860] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1808; }","duration":"164.689147ms","start":"2026-03-25T16:52:34.762152Z","end":"2026-03-25T16:52:34.926841Z","steps":["trace[545614860] 'agreement among raft nodes before linearized reading' (duration: 164.545605ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:35.037327Z","caller":"traceutil/trace.go:171","msg":"trace[833547630] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"104.265693ms","start":"2026-03-25T16:52:34.933026Z","end":"2026-03-25T16:52:35.037291Z","steps":["trace[833547630] 'process raft request' (duration: 103.962027ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:35.037424Z","caller":"traceutil/trace.go:171","msg":"trace[1512674802] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1880; }","duration":"103.339076ms","start":"2026-03-25T16:52:34.934058Z","end":"2026-03-25T16:52:35.037397Z","steps":["trace[1512674802] 'read index received' (duration: 19.084695ms)","trace[1512674802] 'applied index is now lower than readState.Index' (duration: 84.251841ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:52:35.03751Z","caller":"traceutil/trace.go:171","msg":"trace[134201789] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"104.212731ms","start":"2026-03-25T16:52:34.933287Z","end":"2026-03-25T16:52:35.0375Z","steps":["trace[134201789] 'process raft request' (duration: 103.896296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:35.037755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.656301ms","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-03-25T16:52:35.037893Z","caller":"traceutil/trace.go:171","msg":"trace[962577588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1811; }","duration":"103.823924ms","start":"2026-03-25T16:52:34.934046Z","end":"2026-03-25T16:52:35.03787Z","steps":["trace[962577588] 'agreement among raft nodes before linearized reading' (duration: 103.483588ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:52:35.250919Z","caller":"traceutil/trace.go:171","msg":"trace[995646584] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"213.421219ms","start":"2026-03-25T16:52:35.03747Z","end":"2026-03-25T16:52:35.250892Z","steps":["trace[995646584] 'read index received' (duration: 187.29079ms)","trace[995646584] 'applied index is now lower than readState.Index' (duration: 26.129449ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:52:35.251046Z","caller":"traceutil/trace.go:171","msg":"trace[1578673760] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"234.753637ms","start":"2026-03-25T16:52:35.016244Z","end":"2026-03-25T16:52:35.250997Z","steps":["trace[1578673760] 'process raft request' (duration: 208.416104ms)","trace[1578673760] 'compare' (duration: 26.118799ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:52:35.251459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-650f0f30-8b26-47f8-90ad-bac385692787\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-03-25T16:52:35.251577Z","caller":"traceutil/trace.go:171","msg":"trace[1976726397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-650f0f30-8b26-47f8-90ad-bac385692787; range_end:; response_count:1; response_revision:1812; }","duration":"307.239142ms","start":"2026-03-25T16:52:34.944281Z","end":"2026-03-25T16:52:35.25152Z","steps":["trace[1976726397] 'agreement among raft nodes before linearized reading' (duration: 306.693242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:52:35.251678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:52:34.944234Z","time spent":"307.386515ms","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-650f0f30-8b26-47f8-90ad-bac385692787\" "} {"level":"info","ts":"2026-03-25T16:52:35.265038Z","caller":"traceutil/trace.go:171","msg":"trace[1195909211] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"222.37298ms","start":"2026-03-25T16:52:35.042643Z","end":"2026-03-25T16:52:35.265016Z","steps":["trace[1195909211] 'process raft request' (duration: 222.269628ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:53:19.621579Z","caller":"traceutil/trace.go:171","msg":"trace[436627978] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"107.40602ms","start":"2026-03-25T16:53:19.514145Z","end":"2026-03-25T16:53:19.621551Z","steps":["trace[436627978] 'process raft request' (duration: 107.243937ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:53:25.599954Z","caller":"traceutil/trace.go:171","msg":"trace[1971854288] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"121.525456ms","start":"2026-03-25T16:53:25.478389Z","end":"2026-03-25T16:53:25.599914Z","steps":["trace[1971854288] 'process raft request' (duration: 121.243671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:53:25.908201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.774088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-25T16:53:25.908535Z","caller":"traceutil/trace.go:171","msg":"trace[1286960250] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2222; }","duration":"358.339711ms","start":"2026-03-25T16:53:25.550175Z","end":"2026-03-25T16:53:25.908515Z","steps":["trace[1286960250] 'read index received' (duration: 49.437185ms)","trace[1286960250] 'applied index is now lower than readState.Index' (duration: 308.901596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:53:25.908679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.487804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T16:53:25.908718Z","caller":"traceutil/trace.go:171","msg":"trace[1453556713] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2142; }","duration":"358.574866ms","start":"2026-03-25T16:53:25.550134Z","end":"2026-03-25T16:53:25.908709Z","steps":["trace[1453556713] 'agreement among raft nodes before linearized reading' (duration: 358.482164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:53:25.908816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:53:25.550117Z","time spent":"358.678977ms","remote":"127.0.0.1:36322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-25T16:53:25.908862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.13487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-25T16:53:25.908913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.51537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T16:53:25.908939Z","caller":"traceutil/trace.go:171","msg":"trace[1902493845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2142; }","duration":"282.317955ms","start":"2026-03-25T16:53:25.626612Z","end":"2026-03-25T16:53:25.908929Z","steps":["trace[1902493845] 'agreement among raft nodes before linearized reading' (duration: 282.037468ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:53:25.909023Z","caller":"traceutil/trace.go:171","msg":"trace[519087562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"262.689884ms","start":"2026-03-25T16:53:25.646304Z","end":"2026-03-25T16:53:25.908994Z","steps":["trace[519087562] 'agreement among raft nodes before linearized reading' (duration: 262.400547ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:53:25.909783Z","caller":"traceutil/trace.go:171","msg":"trace[838634634] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"382.440476ms","start":"2026-03-25T16:53:25.526738Z","end":"2026-03-25T16:53:25.909178Z","steps":["trace[838634634] 'process raft request' (duration: 381.59628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:53:25.910311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T16:53:25.526696Z","time spent":"383.142559ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T16:53:31.336714Z","caller":"traceutil/trace.go:171","msg":"trace[471306450] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"121.364763ms","start":"2026-03-25T16:53:31.215313Z","end":"2026-03-25T16:53:31.336678Z","steps":["trace[471306450] 'process raft request' (duration: 121.149109ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:54:17.647149Z","caller":"traceutil/trace.go:171","msg":"trace[1551839696] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"147.192781ms","start":"2026-03-25T16:54:17.499932Z","end":"2026-03-25T16:54:17.647125Z","steps":["trace[1551839696] 'process raft request' (duration: 147.056539ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:54:17.659695Z","caller":"traceutil/trace.go:171","msg":"trace[598968936] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"113.81676ms","start":"2026-03-25T16:54:17.545824Z","end":"2026-03-25T16:54:17.659641Z","steps":["trace[598968936] 'process raft request' (duration: 113.355431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:54:51.155684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.559678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T16:54:51.155798Z","caller":"traceutil/trace.go:171","msg":"trace[806222513] linearizableReadLoop","detail":"{readStateIndex:2906; appliedIndex:2905; }","duration":"161.938189ms","start":"2026-03-25T16:54:50.993843Z","end":"2026-03-25T16:54:51.155782Z","steps":["trace[806222513] 'read index received' (duration: 39.701µs)","trace[806222513] 'applied index is now lower than readState.Index' (duration: 161.897158ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T16:54:51.155815Z","caller":"traceutil/trace.go:171","msg":"trace[1625625306] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"299.593461ms","start":"2026-03-25T16:54:50.8562Z","end":"2026-03-25T16:54:51.155793Z","steps":["trace[1625625306] 'process raft request' (duration: 115.791598ms)","trace[1625625306] 'compare' (duration: 183.360464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T16:54:51.155868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.018871ms","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":"info","ts":"2026-03-25T16:54:51.155908Z","caller":"traceutil/trace.go:171","msg":"trace[727701399] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2798; }","duration":"162.092862ms","start":"2026-03-25T16:54:50.993802Z","end":"2026-03-25T16:54:51.155894Z","steps":["trace[727701399] 'agreement among raft nodes before linearized reading' (duration: 162.026951ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:57:21.312866Z","caller":"traceutil/trace.go:171","msg":"trace[2143848905] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"116.492889ms","start":"2026-03-25T16:57:21.196344Z","end":"2026-03-25T16:57:21.312837Z","steps":["trace[2143848905] 'process raft request' (duration: 116.346726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T16:58:40.423793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.675248ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-03-25T16:58:41.906936Z","caller":"traceutil/trace.go:171","msg":"trace[822453733] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"160.714901ms","start":"2026-03-25T16:58:41.746187Z","end":"2026-03-25T16:58:41.906902Z","steps":["trace[822453733] 'process raft request' (duration: 160.579279ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:41.973401Z","caller":"traceutil/trace.go:171","msg":"trace[525878097] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"218.938862ms","start":"2026-03-25T16:58:41.754439Z","end":"2026-03-25T16:58:41.973378Z","steps":["trace[525878097] 'process raft request' (duration: 218.650277ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T16:58:59.942691Z","caller":"traceutil/trace.go:171","msg":"trace[708978855] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"157.072805ms","start":"2026-03-25T16:58:59.785596Z","end":"2026-03-25T16:58:59.942669Z","steps":["trace[708978855] 'process raft request' (duration: 156.938902ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:00:26.999106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-03-25T17:00:27.078929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"78.241104ms","hash":3466025981,"current-db-size-bytes":59101184,"current-db-size":"59 MB","current-db-size-in-use-bytes":34308096,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-25T17:00:27.078992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3466025981,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-03-25T17:05:05.66066Z","caller":"traceutil/trace.go:171","msg":"trace[1039555344] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"211.99009ms","start":"2026-03-25T17:05:05.448633Z","end":"2026-03-25T17:05:05.660623Z","steps":["trace[1039555344] 'process raft request' (duration: 211.803347ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:05:05.840427Z","caller":"traceutil/trace.go:171","msg":"trace[774100895] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"367.675009ms","start":"2026-03-25T17:05:05.472717Z","end":"2026-03-25T17:05:05.840392Z","steps":["trace[774100895] 'process raft request' (duration: 367.463155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:05:05.840642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:05:05.472687Z","time spent":"367.839572ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T17:05:18.266996Z","caller":"traceutil/trace.go:171","msg":"trace[78997709] linearizableReadLoop","detail":"{readStateIndex:8538; appliedIndex:8537; }","duration":"213.67238ms","start":"2026-03-25T17:05:18.053296Z","end":"2026-03-25T17:05:18.266969Z","steps":["trace[78997709] 'read index received' (duration: 213.606879ms)","trace[78997709] 'applied index is now lower than readState.Index' (duration: 64.431µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:05:18.267106Z","caller":"traceutil/trace.go:171","msg":"trace[965522972] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"241.630981ms","start":"2026-03-25T17:05:18.025437Z","end":"2026-03-25T17:05:18.267068Z","steps":["trace[965522972] 'process raft request' (duration: 241.369787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:05:18.267321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.002267ms","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-03-25T17:05:18.267418Z","caller":"traceutil/trace.go:171","msg":"trace[1072962585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8246; }","duration":"214.15871ms","start":"2026-03-25T17:05:18.053239Z","end":"2026-03-25T17:05:18.267397Z","steps":["trace[1072962585] 'agreement among raft nodes before linearized reading' (duration: 213.870125ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:05:18.419855Z","caller":"traceutil/trace.go:171","msg":"trace[788695053] linearizableReadLoop","detail":"{readStateIndex:8539; appliedIndex:8538; }","duration":"152.749037ms","start":"2026-03-25T17:05:18.267072Z","end":"2026-03-25T17:05:18.419821Z","steps":["trace[788695053] 'read index received' (duration: 118.07032ms)","trace[788695053] 'applied index is now lower than readState.Index' (duration: 34.675937ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:05:18.419882Z","caller":"traceutil/trace.go:171","msg":"trace[1102266706] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"147.451547ms","start":"2026-03-25T17:05:18.272404Z","end":"2026-03-25T17:05:18.419856Z","steps":["trace[1102266706] 'process raft request' (duration: 147.136741ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:05:18.419936Z","caller":"traceutil/trace.go:171","msg":"trace[569492218] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"290.391389ms","start":"2026-03-25T17:05:18.129293Z","end":"2026-03-25T17:05:18.419684Z","steps":["trace[569492218] 'process raft request' (duration: 255.732974ms)","trace[569492218] 'compare' (duration: 33.979893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T17:05:18.420106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.425453ms","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-03-25T17:05:18.420154Z","caller":"traceutil/trace.go:171","msg":"trace[89925425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8249; }","duration":"361.535046ms","start":"2026-03-25T17:05:18.058607Z","end":"2026-03-25T17:05:18.420142Z","steps":["trace[89925425] 'agreement among raft nodes before linearized reading' (duration: 361.339832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:05:18.420179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T17:05:18.058585Z","time spent":"361.587306ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T17:05:27.005822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6128} {"level":"info","ts":"2026-03-25T17:05:27.102513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6128,"took":"94.596464ms","hash":3757830173,"current-db-size-bytes":59101184,"current-db-size":"59 MB","current-db-size-in-use-bytes":23244800,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-25T17:05:27.102671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3757830173,"revision":6128,"compact-revision":2995} {"level":"info","ts":"2026-03-25T17:05:46.986857Z","caller":"traceutil/trace.go:171","msg":"trace[123180945] linearizableReadLoop","detail":"{readStateIndex:8759; appliedIndex:8758; }","duration":"237.508595ms","start":"2026-03-25T17:05:46.749322Z","end":"2026-03-25T17:05:46.986831Z","steps":["trace[123180945] 'read index received' (duration: 237.331542ms)","trace[123180945] 'applied index is now lower than readState.Index' (duration: 172.823µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:05:46.9874Z","caller":"traceutil/trace.go:171","msg":"trace[1868500481] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"247.939049ms","start":"2026-03-25T17:05:46.739447Z","end":"2026-03-25T17:05:46.987386Z","steps":["trace[1868500481] 'process raft request' (duration: 247.229856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:05:46.987944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.55506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-25T17:05:46.98795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.488029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dc7vc\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-25T17:05:46.988016Z","caller":"traceutil/trace.go:171","msg":"trace[1597260049] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8458; }","duration":"190.694363ms","start":"2026-03-25T17:05:46.797303Z","end":"2026-03-25T17:05:46.987997Z","steps":["trace[1597260049] 'agreement among raft nodes before linearized reading' (duration: 190.458619ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:05:46.988033Z","caller":"traceutil/trace.go:171","msg":"trace[1913660781] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dc7vc; range_end:; response_count:1; response_revision:8458; }","duration":"146.587961ms","start":"2026-03-25T17:05:46.841425Z","end":"2026-03-25T17:05:46.988013Z","steps":["trace[1913660781] 'agreement among raft nodes before linearized reading' (duration: 146.388807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:05:46.988054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.706547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-03-25T17:05:46.988111Z","caller":"traceutil/trace.go:171","msg":"trace[506942079] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8458; }","duration":"238.81084ms","start":"2026-03-25T17:05:46.749285Z","end":"2026-03-25T17:05:46.988096Z","steps":["trace[506942079] 'agreement among raft nodes before linearized reading' (duration: 238.485103ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:05:49.30504Z","caller":"traceutil/trace.go:171","msg":"trace[500108965] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"163.17764ms","start":"2026-03-25T17:05:49.14184Z","end":"2026-03-25T17:05:49.305018Z","steps":["trace[500108965] 'process raft request' (duration: 162.706781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:06:00.043387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.054594ms","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-03-25T17:06:00.043495Z","caller":"traceutil/trace.go:171","msg":"trace[1742426772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8566; }","duration":"144.220217ms","start":"2026-03-25T17:05:59.899254Z","end":"2026-03-25T17:06:00.043474Z","steps":["trace[1742426772] 'range keys from in-memory index tree' (duration: 143.85538ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:06:00.160556Z","caller":"traceutil/trace.go:171","msg":"trace[453022643] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"113.036986ms","start":"2026-03-25T17:06:00.047483Z","end":"2026-03-25T17:06:00.16052Z","steps":["trace[453022643] 'process raft request' (duration: 112.855571ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:06:00.189436Z","caller":"traceutil/trace.go:171","msg":"trace[1392460274] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"139.501898ms","start":"2026-03-25T17:06:00.04991Z","end":"2026-03-25T17:06:00.189412Z","steps":["trace[1392460274] 'process raft request' (duration: 139.327815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:06:00.359045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.608232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-25T17:06:00.359147Z","caller":"traceutil/trace.go:171","msg":"trace[636070224] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8569; }","duration":"101.786226ms","start":"2026-03-25T17:06:00.257339Z","end":"2026-03-25T17:06:00.359125Z","steps":["trace[636070224] 'range keys from in-memory index tree' (duration: 101.109433ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:06:00.797557Z","caller":"traceutil/trace.go:171","msg":"trace[480428057] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"114.083516ms","start":"2026-03-25T17:06:00.683443Z","end":"2026-03-25T17:06:00.797527Z","steps":["trace[480428057] 'process raft request' (duration: 32.446544ms)","trace[480428057] 'compare' (duration: 81.353856ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:06:21.589795Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023bb.wal"} {"level":"info","ts":"2026-03-25T17:06:59.147723Z","caller":"traceutil/trace.go:171","msg":"trace[653910065] transaction","detail":"{read_only:false; response_revision:9210; number_of_response:1; }","duration":"148.577938ms","start":"2026-03-25T17:06:58.999048Z","end":"2026-03-25T17:06:59.147626Z","steps":["trace[653910065] 'process raft request' (duration: 148.400885ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:08:01.020255Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T17:08:01.025833Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T17:08:01.025938Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T17:09:28.624646Z","caller":"traceutil/trace.go:171","msg":"trace[189508450] linearizableReadLoop","detail":"{readStateIndex:10695; appliedIndex:10694; }","duration":"142.319562ms","start":"2026-03-25T17:09:28.482311Z","end":"2026-03-25T17:09:28.624631Z","steps":["trace[189508450] 'read index received' (duration: 95.850916ms)","trace[189508450] 'applied index is now lower than readState.Index' (duration: 46.467726ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T17:09:28.624707Z","caller":"traceutil/trace.go:171","msg":"trace[1995457495] transaction","detail":"{read_only:false; response_revision:10336; number_of_response:1; }","duration":"159.625658ms","start":"2026-03-25T17:09:28.465044Z","end":"2026-03-25T17:09:28.62467Z","steps":["trace[1995457495] 'process raft request' (duration: 113.166332ms)","trace[1995457495] 'compare' (duration: 46.291533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T17:09:28.624859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.898548ms","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-03-25T17:09:28.624942Z","caller":"traceutil/trace.go:171","msg":"trace[1238675576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10336; }","duration":"163.013961ms","start":"2026-03-25T17:09:28.461909Z","end":"2026-03-25T17:09:28.624923Z","steps":["trace[1238675576] 'agreement among raft nodes before linearized reading' (duration: 162.789436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:09:28.625004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.560294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-25T17:09:28.625067Z","caller":"traceutil/trace.go:171","msg":"trace[1861874148] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:10336; }","duration":"105.667846ms","start":"2026-03-25T17:09:28.519383Z","end":"2026-03-25T17:09:28.62505Z","steps":["trace[1861874148] 'agreement among raft nodes before linearized reading' (duration: 105.508393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T17:09:28.62546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.421434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:40 size:368309"} {"level":"info","ts":"2026-03-25T17:09:28.626007Z","caller":"traceutil/trace.go:171","msg":"trace[107860198] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:40; response_revision:10336; }","duration":"137.994263ms","start":"2026-03-25T17:09:28.488002Z","end":"2026-03-25T17:09:28.625996Z","steps":["trace[107860198] 'agreement among raft nodes before linearized reading' (duration: 136.835513ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T17:10:27.011981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8301} {"level":"info","ts":"2026-03-25T17:10:27.089484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8301,"took":"75.203451ms","hash":2047914016,"current-db-size-bytes":59101184,"current-db-size":"59 MB","current-db-size-in-use-bytes":24875008,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-25T17:10:27.089549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2047914016,"revision":8301,"compact-revision":6128} {"level":"info","ts":"2026-03-25T17:15:27.018794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10688} {"level":"info","ts":"2026-03-25T17:15:27.097003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10688,"took":"76.021129ms","hash":3367566334,"current-db-size-bytes":59101184,"current-db-size":"59 MB","current-db-size-in-use-bytes":20496384,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-25T17:15:27.097087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3367566334,"revision":10688,"compact-revision":8301}