{"level":"warn","ts":"2026-02-28T06:00:55.418665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T06:00:55.418758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-02-28T06:00:55.418826Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T06:00:55.418843Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-28T06:00:55.418863Z","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-02-28T06:00:55.419512Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-28T06:00:55.419599Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-02-28T06:00:55.42318Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.332202ms"} {"level":"info","ts":"2026-02-28T06:00:55.433033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-28T06:00:55.433131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T06:00:55.43316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-28T06:00:55.433177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-28T06:00:55.43319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-28T06:00:55.433239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-28T06:00:55.435916Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T06:00:55.437442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T06:00:55.438618Z","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-02-28T06:00:55.441159Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-28T06:00:55.441749Z","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-02-28T06:00:55.441927Z","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-02-28T06:00:55.441955Z","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-02-28T06:00:55.441915Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-28T06:00:55.442483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-28T06:00:55.443511Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-28T06:00:55.446118Z","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-02-28T06:00:55.446213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-28T06:00:55.446558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T06:00:55.446468Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-28T06:00:55.446595Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-28T06:00:56.134191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T06:00:56.134264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T06:00:56.134322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-28T06:00:56.134341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-28T06:00:56.134352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-28T06:00:56.13437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-28T06:00:56.134383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-28T06:00:56.135707Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-28T06:00:56.135775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T06:00:56.135817Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T06:00:56.13586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T06:00:56.136128Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T06:00:56.136206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T06:00:56.136923Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T06:00:56.137069Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T06:00:56.13713Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T06:00:56.139827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-28T06:00:56.14032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-28T06:01:33.677948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.175992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:01:33.678076Z","caller":"traceutil/trace.go:171","msg":"trace[178921645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"109.426948ms","start":"2026-02-28T06:01:33.568627Z","end":"2026-02-28T06:01:33.678054Z","steps":["trace[178921645] 'range keys from in-memory index tree' (duration: 109.046048ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:01:35.666799Z","caller":"traceutil/trace.go:171","msg":"trace[1437322594] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"151.890893ms","start":"2026-02-28T06:01:35.514853Z","end":"2026-02-28T06:01:35.666744Z","steps":["trace[1437322594] 'process raft request' (duration: 123.657942ms)","trace[1437322594] 'compare' (duration: 27.881051ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:01:35.666913Z","caller":"traceutil/trace.go:171","msg":"trace[1528394727] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"149.347683ms","start":"2026-02-28T06:01:35.517531Z","end":"2026-02-28T06:01:35.666879Z","steps":["trace[1528394727] 'process raft request' (duration: 149.142637ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:01:35.672299Z","caller":"traceutil/trace.go:171","msg":"trace[1835068724] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:713; }","duration":"102.941499ms","start":"2026-02-28T06:01:35.569336Z","end":"2026-02-28T06:01:35.672278Z","steps":["trace[1835068724] 'read index received' (duration: 69.141324ms)","trace[1835068724] 'applied index is now lower than readState.Index' (duration: 33.798945ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:01:35.67243Z","caller":"traceutil/trace.go:171","msg":"trace[197216048] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"136.677822ms","start":"2026-02-28T06:01:35.535727Z","end":"2026-02-28T06:01:35.672405Z","steps":["trace[197216048] 'process raft request' (duration: 136.323343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:01:35.672696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.34215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:01:35.672842Z","caller":"traceutil/trace.go:171","msg":"trace[670212817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"103.544366ms","start":"2026-02-28T06:01:35.569275Z","end":"2026-02-28T06:01:35.67282Z","steps":["trace[670212817] 'agreement among raft nodes before linearized reading' (duration: 103.168706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:01:53.780817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.945065ms","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-02-28T06:01:53.781117Z","caller":"traceutil/trace.go:171","msg":"trace[1430324793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:945; }","duration":"165.267883ms","start":"2026-02-28T06:01:53.615821Z","end":"2026-02-28T06:01:53.781089Z","steps":["trace[1430324793] 'range keys from in-memory index tree' (duration: 164.910314ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:02:48.131376Z","caller":"traceutil/trace.go:171","msg":"trace[1352646317] linearizableReadLoop","detail":"{readStateIndex:1678; appliedIndex:1677; }","duration":"254.351944ms","start":"2026-02-28T06:02:47.876993Z","end":"2026-02-28T06:02:48.131345Z","steps":["trace[1352646317] 'read index received' (duration: 239.519292ms)","trace[1352646317] 'applied index is now lower than readState.Index' (duration: 14.831102ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:02:48.131521Z","caller":"traceutil/trace.go:171","msg":"trace[844278330] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"259.955377ms","start":"2026-02-28T06:02:47.87151Z","end":"2026-02-28T06:02:48.131465Z","steps":["trace[844278330] 'process raft request' (duration: 245.053242ms)","trace[844278330] 'compare' (duration: 14.634438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:02:48.131762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.725795ms","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-02-28T06:02:48.13189Z","caller":"traceutil/trace.go:171","msg":"trace[2055279383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1620; }","duration":"254.9103ms","start":"2026-02-28T06:02:47.876952Z","end":"2026-02-28T06:02:48.131862Z","steps":["trace[2055279383] 'agreement among raft nodes before linearized reading' (duration: 254.536449ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:02:48.546787Z","caller":"traceutil/trace.go:171","msg":"trace[1310051285] linearizableReadLoop","detail":"{readStateIndex:1685; appliedIndex:1684; }","duration":"212.579669ms","start":"2026-02-28T06:02:48.334179Z","end":"2026-02-28T06:02:48.546759Z","steps":["trace[1310051285] 'read index received' (duration: 212.256391ms)","trace[1310051285] 'applied index is now lower than readState.Index' (duration: 322.088µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:02:48.546856Z","caller":"traceutil/trace.go:171","msg":"trace[1047661058] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"249.691339ms","start":"2026-02-28T06:02:48.297128Z","end":"2026-02-28T06:02:48.546819Z","steps":["trace[1047661058] 'process raft request' (duration: 249.295688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:02:48.547066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.837786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:02:48.547197Z","caller":"traceutil/trace.go:171","msg":"trace[1960246527] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1627; }","duration":"213.048772ms","start":"2026-02-28T06:02:48.33412Z","end":"2026-02-28T06:02:48.547169Z","steps":["trace[1960246527] 'agreement among raft nodes before linearized reading' (duration: 212.811356ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:03:00.918902Z","caller":"traceutil/trace.go:171","msg":"trace[16936897] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"124.839764ms","start":"2026-02-28T06:03:00.794019Z","end":"2026-02-28T06:03:00.918858Z","steps":["trace[16936897] 'process raft request' (duration: 124.653359ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:03:32.525171Z","caller":"traceutil/trace.go:171","msg":"trace[1298493106] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"124.469043ms","start":"2026-02-28T06:03:32.400667Z","end":"2026-02-28T06:03:32.525136Z","steps":["trace[1298493106] 'process raft request' (duration: 124.268228ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:03:38.702469Z","caller":"traceutil/trace.go:171","msg":"trace[852983625] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"126.166346ms","start":"2026-02-28T06:03:38.576261Z","end":"2026-02-28T06:03:38.702427Z","steps":["trace[852983625] 'process raft request' (duration: 126.001382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:03:39.031204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.201563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T06:03:39.031499Z","caller":"traceutil/trace.go:171","msg":"trace[1220386496] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"111.856394ms","start":"2026-02-28T06:03:38.91956Z","end":"2026-02-28T06:03:39.031416Z","steps":["trace[1220386496] 'read index received' (duration: 38.351µs)","trace[1220386496] 'applied index is now lower than readState.Index' (duration: 111.814953ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:03:39.031712Z","caller":"traceutil/trace.go:171","msg":"trace[842150691] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"311.77557ms","start":"2026-02-28T06:03:38.719899Z","end":"2026-02-28T06:03:39.031675Z","steps":["trace[842150691] 'process raft request' (duration: 161.611497ms)","trace[842150691] 'compare' (duration: 146.964985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:03:39.031822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T06:03:38.719873Z","time spent":"311.887232ms","remote":"127.0.0.1:48244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T06:03:39.032406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.744988ms","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-02-28T06:03:39.0328Z","caller":"traceutil/trace.go:171","msg":"trace[768413651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"113.207941ms","start":"2026-02-28T06:03:38.919529Z","end":"2026-02-28T06:03:39.032737Z","steps":["trace[768413651] 'agreement among raft nodes before linearized reading' (duration: 112.415039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:03:39.032974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.404317ms","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-02-28T06:03:39.03305Z","caller":"traceutil/trace.go:171","msg":"trace[2142470501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1925; }","duration":"113.51213ms","start":"2026-02-28T06:03:38.919515Z","end":"2026-02-28T06:03:39.033027Z","steps":["trace[2142470501] 'agreement among raft nodes before linearized reading' (duration: 113.248921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:03:39.033285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.368726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T06:03:39.033351Z","caller":"traceutil/trace.go:171","msg":"trace[1301515915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"113.450328ms","start":"2026-02-28T06:03:38.919886Z","end":"2026-02-28T06:03:39.033336Z","steps":["trace[1301515915] 'agreement among raft nodes before linearized reading' (duration: 113.270223ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:03:39.150769Z","caller":"traceutil/trace.go:171","msg":"trace[397946297] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"112.317017ms","start":"2026-02-28T06:03:39.038416Z","end":"2026-02-28T06:03:39.150733Z","steps":["trace[397946297] 'process raft request' (duration: 108.917313ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:03:39.151054Z","caller":"traceutil/trace.go:171","msg":"trace[479980487] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"112.510861ms","start":"2026-02-28T06:03:39.038531Z","end":"2026-02-28T06:03:39.151042Z","steps":["trace[479980487] 'process raft request' (duration: 112.148091ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:05:07.779951Z","caller":"traceutil/trace.go:171","msg":"trace[950111481] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"159.915416ms","start":"2026-02-28T06:05:07.620012Z","end":"2026-02-28T06:05:07.779928Z","steps":["trace[950111481] 'process raft request' (duration: 159.855425ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:05:07.779993Z","caller":"traceutil/trace.go:171","msg":"trace[293475737] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"161.790978ms","start":"2026-02-28T06:05:07.618154Z","end":"2026-02-28T06:05:07.779945Z","steps":["trace[293475737] 'process raft request' (duration: 161.575332ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:05:07.780345Z","caller":"traceutil/trace.go:171","msg":"trace[2048616628] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"160.251895ms","start":"2026-02-28T06:05:07.62006Z","end":"2026-02-28T06:05:07.780311Z","steps":["trace[2048616628] 'process raft request' (duration: 159.836314ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:05:08.26346Z","caller":"traceutil/trace.go:171","msg":"trace[831793377] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"120.378957ms","start":"2026-02-28T06:05:08.143045Z","end":"2026-02-28T06:05:08.263424Z","steps":["trace[831793377] 'process raft request' (duration: 120.172861ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:14.630855Z","caller":"traceutil/trace.go:171","msg":"trace[67925617] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"160.564064ms","start":"2026-02-28T06:09:14.470273Z","end":"2026-02-28T06:09:14.630837Z","steps":["trace[67925617] 'process raft request' (duration: 160.44542ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:14.677805Z","caller":"traceutil/trace.go:171","msg":"trace[1791785230] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"177.68429ms","start":"2026-02-28T06:09:14.500075Z","end":"2026-02-28T06:09:14.677759Z","steps":["trace[1791785230] 'process raft request' (duration: 177.177776ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:14.703364Z","caller":"traceutil/trace.go:171","msg":"trace[1284875857] linearizableReadLoop","detail":"{readStateIndex:4843; appliedIndex:4841; }","duration":"133.54358ms","start":"2026-02-28T06:09:14.569796Z","end":"2026-02-28T06:09:14.703339Z","steps":["trace[1284875857] 'read index received' (duration: 61.049221ms)","trace[1284875857] 'applied index is now lower than readState.Index' (duration: 72.493279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:09:14.704058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.258501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:09:14.704087Z","caller":"traceutil/trace.go:171","msg":"trace[1937193102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4666; }","duration":"134.341123ms","start":"2026-02-28T06:09:14.569739Z","end":"2026-02-28T06:09:14.70408Z","steps":["trace[1937193102] 'agreement among raft nodes before linearized reading' (duration: 134.275041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:09:14.704286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.057973ms","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-02-28T06:09:14.704305Z","caller":"traceutil/trace.go:171","msg":"trace[102763689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4666; }","duration":"121.119824ms","start":"2026-02-28T06:09:14.583181Z","end":"2026-02-28T06:09:14.704301Z","steps":["trace[102763689] 'agreement among raft nodes before linearized reading' (duration: 121.042242ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:17.123516Z","caller":"traceutil/trace.go:171","msg":"trace[1410586726] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"101.100336ms","start":"2026-02-28T06:09:17.02239Z","end":"2026-02-28T06:09:17.123491Z","steps":["trace[1410586726] 'read index received' (duration: 51.31818ms)","trace[1410586726] 'applied index is now lower than readState.Index' (duration: 49.780496ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:09:17.123535Z","caller":"traceutil/trace.go:171","msg":"trace[685062506] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"107.194887ms","start":"2026-02-28T06:09:17.016309Z","end":"2026-02-28T06:09:17.123504Z","steps":["trace[685062506] 'process raft request' (duration: 57.199815ms)","trace[685062506] 'compare' (duration: 49.837647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:09:17.123769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.341273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/ipmi-exporter\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:09:17.123867Z","caller":"traceutil/trace.go:171","msg":"trace[398327397] range","detail":"{range_begin:/registry/daemonsets/monitoring/ipmi-exporter; range_end:; response_count:0; response_revision:4714; }","duration":"101.483008ms","start":"2026-02-28T06:09:17.022364Z","end":"2026-02-28T06:09:17.123847Z","steps":["trace[398327397] 'agreement among raft nodes before linearized reading' (duration: 101.2342ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:17.126756Z","caller":"traceutil/trace.go:171","msg":"trace[50249163] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"101.89837ms","start":"2026-02-28T06:09:17.02484Z","end":"2026-02-28T06:09:17.126738Z","steps":["trace[50249163] 'process raft request' (duration: 101.709073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:09:26.347757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.825962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T06:09:26.347914Z","caller":"traceutil/trace.go:171","msg":"trace[234712157] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"165.390338ms","start":"2026-02-28T06:09:26.182499Z","end":"2026-02-28T06:09:26.34789Z","steps":["trace[234712157] 'process raft request' (duration: 47.320829ms)","trace[234712157] 'compare' (duration: 117.662138ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:09:26.500979Z","caller":"traceutil/trace.go:171","msg":"trace[1787087712] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"137.47578ms","start":"2026-02-28T06:09:26.363478Z","end":"2026-02-28T06:09:26.500954Z","steps":["trace[1787087712] 'process raft request' (duration: 125.641221ms)","trace[1787087712] 'compare' (duration: 11.390447ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:09:26.501156Z","caller":"traceutil/trace.go:171","msg":"trace[944162934] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"137.647115ms","start":"2026-02-28T06:09:26.363479Z","end":"2026-02-28T06:09:26.501126Z","steps":["trace[944162934] 'process raft request' (duration: 137.353027ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:26.514023Z","caller":"traceutil/trace.go:171","msg":"trace[1247885325] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"150.4012ms","start":"2026-02-28T06:09:26.363586Z","end":"2026-02-28T06:09:26.513987Z","steps":["trace[1247885325] 'process raft request' (duration: 150.230045ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:09:34.537076Z","caller":"traceutil/trace.go:171","msg":"trace[1826000539] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"148.937319ms","start":"2026-02-28T06:09:34.388087Z","end":"2026-02-28T06:09:34.537024Z","steps":["trace[1826000539] 'process raft request' (duration: 92.61253ms)","trace[1826000539] 'compare' (duration: 56.086443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:09:34.734966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.18721ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-28T06:09:34.735096Z","caller":"traceutil/trace.go:171","msg":"trace[1746808847] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"165.691387ms","start":"2026-02-28T06:09:34.569383Z","end":"2026-02-28T06:09:34.735075Z","steps":["trace[1746808847] 'read index received' (duration: 22.473526ms)","trace[1746808847] 'applied index is now lower than readState.Index' (duration: 143.215871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:09:34.735281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.951336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:09:34.735366Z","caller":"traceutil/trace.go:171","msg":"trace[281501654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5052; }","duration":"165.143291ms","start":"2026-02-28T06:09:34.570203Z","end":"2026-02-28T06:09:34.735346Z","steps":["trace[281501654] 'agreement among raft nodes before linearized reading' (duration: 165.003107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:09:34.735368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.968024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6342f9bc-625a-45c0-861f-a1bb3859a3e8\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-02-28T06:09:34.73541Z","caller":"traceutil/trace.go:171","msg":"trace[1220740340] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6342f9bc-625a-45c0-861f-a1bb3859a3e8; range_end:; response_count:1; response_revision:5052; }","duration":"166.063367ms","start":"2026-02-28T06:09:34.569333Z","end":"2026-02-28T06:09:34.735396Z","steps":["trace[1220740340] 'agreement among raft nodes before linearized reading' (duration: 165.853651ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:10:56.167807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2795} {"level":"info","ts":"2026-02-28T06:10:56.232971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2795,"took":"62.919293ms","hash":3303966193,"current-db-size-bytes":41500672,"current-db-size":"42 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-28T06:10:56.233038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3303966193,"revision":2795,"compact-revision":-1} {"level":"info","ts":"2026-02-28T06:15:56.175776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5753} {"level":"info","ts":"2026-02-28T06:15:56.266298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5753,"took":"87.964274ms","hash":3091226002,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":22933504,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-28T06:15:56.266401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091226002,"revision":5753,"compact-revision":2795} {"level":"warn","ts":"2026-02-28T06:15:59.582064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.10816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T06:15:59.582562Z","caller":"traceutil/trace.go:171","msg":"trace[854446039] linearizableReadLoop","detail":"{readStateIndex:8061; appliedIndex:8059; }","duration":"182.128976ms","start":"2026-02-28T06:15:59.400406Z","end":"2026-02-28T06:15:59.582535Z","steps":["trace[854446039] 'read index received' (duration: 176.899513ms)","trace[854446039] 'applied index is now lower than readState.Index' (duration: 5.227663ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:15:59.58264Z","caller":"traceutil/trace.go:171","msg":"trace[1036888521] transaction","detail":"{read_only:false; response_revision:7781; number_of_response:1; }","duration":"230.619331ms","start":"2026-02-28T06:15:59.351983Z","end":"2026-02-28T06:15:59.582602Z","steps":["trace[1036888521] 'process raft request' (duration: 230.238041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:15:59.582845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.235951ms","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-02-28T06:15:59.582875Z","caller":"traceutil/trace.go:171","msg":"trace[1680761445] transaction","detail":"{read_only:false; response_revision:7780; number_of_response:1; }","duration":"260.950389ms","start":"2026-02-28T06:15:59.321903Z","end":"2026-02-28T06:15:59.582853Z","steps":["trace[1680761445] 'process raft request' (duration: 49.895652ms)","trace[1680761445] 'compare' (duration: 209.942116ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:15:59.582912Z","caller":"traceutil/trace.go:171","msg":"trace[1188822536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7781; }","duration":"170.370445ms","start":"2026-02-28T06:15:59.412524Z","end":"2026-02-28T06:15:59.582894Z","steps":["trace[1188822536] 'agreement among raft nodes before linearized reading' (duration: 170.17208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:15:59.582899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.458395ms","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-02-28T06:15:59.583021Z","caller":"traceutil/trace.go:171","msg":"trace[1771350795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7781; }","duration":"182.62591ms","start":"2026-02-28T06:15:59.400362Z","end":"2026-02-28T06:15:59.582988Z","steps":["trace[1771350795] 'agreement among raft nodes before linearized reading' (duration: 182.290531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:15:59.583081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.467617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-28T06:15:59.58317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.528734ms","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-02-28T06:15:59.583185Z","caller":"traceutil/trace.go:171","msg":"trace[110361114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7781; }","duration":"163.625891ms","start":"2026-02-28T06:15:59.419534Z","end":"2026-02-28T06:15:59.58316Z","steps":["trace[110361114] 'agreement among raft nodes before linearized reading' (duration: 163.243471ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:15:59.583246Z","caller":"traceutil/trace.go:171","msg":"trace[1918591448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7781; }","duration":"168.672408ms","start":"2026-02-28T06:15:59.414553Z","end":"2026-02-28T06:15:59.583225Z","steps":["trace[1918591448] 'agreement among raft nodes before linearized reading' (duration: 168.479313ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:16:00.937964Z","caller":"traceutil/trace.go:171","msg":"trace[1643836543] linearizableReadLoop","detail":"{readStateIndex:8069; appliedIndex:8068; }","duration":"120.823351ms","start":"2026-02-28T06:16:00.817113Z","end":"2026-02-28T06:16:00.937937Z","steps":["trace[1643836543] 'read index received' (duration: 82.471714ms)","trace[1643836543] 'applied index is now lower than readState.Index' (duration: 38.349947ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:16:00.938013Z","caller":"traceutil/trace.go:171","msg":"trace[784914130] transaction","detail":"{read_only:false; response_revision:7789; number_of_response:1; }","duration":"146.633526ms","start":"2026-02-28T06:16:00.791339Z","end":"2026-02-28T06:16:00.937973Z","steps":["trace[784914130] 'process raft request' (duration: 108.220247ms)","trace[784914130] 'compare' (duration: 38.238094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:16:00.938163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.015397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:16:00.938288Z","caller":"traceutil/trace.go:171","msg":"trace[302350261] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7789; }","duration":"121.222303ms","start":"2026-02-28T06:16:00.817034Z","end":"2026-02-28T06:16:00.938256Z","steps":["trace[302350261] 'agreement among raft nodes before linearized reading' (duration: 121.010796ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:16:31.91571Z","caller":"traceutil/trace.go:171","msg":"trace[2055634319] linearizableReadLoop","detail":"{readStateIndex:8282; appliedIndex:8281; }","duration":"126.307711ms","start":"2026-02-28T06:16:31.789376Z","end":"2026-02-28T06:16:31.915684Z","steps":["trace[2055634319] 'read index received' (duration: 126.118865ms)","trace[2055634319] 'applied index is now lower than readState.Index' (duration: 187.415µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:16:31.915761Z","caller":"traceutil/trace.go:171","msg":"trace[763894951] transaction","detail":"{read_only:false; response_revision:7993; number_of_response:1; }","duration":"128.012456ms","start":"2026-02-28T06:16:31.787707Z","end":"2026-02-28T06:16:31.915719Z","steps":["trace[763894951] 'process raft request' (duration: 127.78128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:16:31.916011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.61642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-28T06:16:31.916028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.333663ms","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-02-28T06:16:31.916072Z","caller":"traceutil/trace.go:171","msg":"trace[2113180748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7993; }","duration":"126.763844ms","start":"2026-02-28T06:16:31.789298Z","end":"2026-02-28T06:16:31.916062Z","steps":["trace[2113180748] 'agreement among raft nodes before linearized reading' (duration: 126.496547ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:16:31.916081Z","caller":"traceutil/trace.go:171","msg":"trace[291549974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7993; }","duration":"126.436045ms","start":"2026-02-28T06:16:31.789633Z","end":"2026-02-28T06:16:31.916069Z","steps":["trace[291549974] 'agreement among raft nodes before linearized reading' (duration: 126.229969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:16:31.916108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.865239ms","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-02-28T06:16:31.916181Z","caller":"traceutil/trace.go:171","msg":"trace[94582571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7993; }","duration":"125.963571ms","start":"2026-02-28T06:16:31.7902Z","end":"2026-02-28T06:16:31.916163Z","steps":["trace[94582571] 'agreement among raft nodes before linearized reading' (duration: 125.745906ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:16:32.028194Z","caller":"traceutil/trace.go:171","msg":"trace[1581369915] transaction","detail":"{read_only:false; response_revision:7994; number_of_response:1; }","duration":"107.420965ms","start":"2026-02-28T06:16:31.920732Z","end":"2026-02-28T06:16:32.028153Z","steps":["trace[1581369915] 'process raft request' (duration: 53.642055ms)","trace[1581369915] 'compare' (duration: 53.507153ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:16:32.028495Z","caller":"traceutil/trace.go:171","msg":"trace[1195784908] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"107.510297ms","start":"2026-02-28T06:16:31.920958Z","end":"2026-02-28T06:16:32.028468Z","steps":["trace[1195784908] 'process raft request' (duration: 107.413845ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:16:32.028697Z","caller":"traceutil/trace.go:171","msg":"trace[635223271] transaction","detail":"{read_only:false; response_revision:7995; number_of_response:1; }","duration":"107.830677ms","start":"2026-02-28T06:16:31.920835Z","end":"2026-02-28T06:16:32.028666Z","steps":["trace[635223271] 'process raft request' (duration: 107.253441ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:20:09.9185Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b11e7fc5714be64b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-28T06:20:09.92474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-28T06:20:09.92485Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-28T06:20:56.183628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7767} {"level":"info","ts":"2026-02-28T06:20:56.251867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7767,"took":"65.797204ms","hash":3421754022,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":26759168,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-02-28T06:20:56.251933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421754022,"revision":7767,"compact-revision":5753} {"level":"info","ts":"2026-02-28T06:21:39.992Z","caller":"traceutil/trace.go:171","msg":"trace[1951697662] transaction","detail":"{read_only:false; response_revision:10517; number_of_response:1; }","duration":"124.865031ms","start":"2026-02-28T06:21:39.867084Z","end":"2026-02-28T06:21:39.991949Z","steps":["trace[1951697662] 'process raft request' (duration: 124.585034ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:21:43.165377Z","caller":"traceutil/trace.go:171","msg":"trace[1750220991] transaction","detail":"{read_only:false; response_revision:10533; number_of_response:1; }","duration":"108.900462ms","start":"2026-02-28T06:21:43.056431Z","end":"2026-02-28T06:21:43.165332Z","steps":["trace[1750220991] 'process raft request' (duration: 108.618285ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:25:56.190858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10108} {"level":"info","ts":"2026-02-28T06:25:56.275103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10108,"took":"81.291673ms","hash":3490489805,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":23638016,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-28T06:25:56.275184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3490489805,"revision":10108,"compact-revision":7767} {"level":"info","ts":"2026-02-28T06:27:14.567421Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003305.wal"} {"level":"info","ts":"2026-02-28T06:28:13.671094Z","caller":"traceutil/trace.go:171","msg":"trace[1439190175] transaction","detail":"{read_only:false; response_revision:12966; number_of_response:1; }","duration":"231.844663ms","start":"2026-02-28T06:28:13.439215Z","end":"2026-02-28T06:28:13.67106Z","steps":["trace[1439190175] 'process raft request' (duration: 231.551825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:28:13.671218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.787764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:28:13.671305Z","caller":"traceutil/trace.go:171","msg":"trace[889245270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12966; }","duration":"101.936079ms","start":"2026-02-28T06:28:13.569348Z","end":"2026-02-28T06:28:13.671284Z","steps":["trace[889245270] 'agreement among raft nodes before linearized reading' (duration: 101.783845ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:28:13.671325Z","caller":"traceutil/trace.go:171","msg":"trace[2052395923] linearizableReadLoop","detail":"{readStateIndex:13426; appliedIndex:13425; }","duration":"101.560008ms","start":"2026-02-28T06:28:13.569407Z","end":"2026-02-28T06:28:13.670967Z","steps":["trace[2052395923] 'read index received' (duration: 101.306152ms)","trace[2052395923] 'applied index is now lower than readState.Index' (duration: 252.876µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:28:13.80291Z","caller":"traceutil/trace.go:171","msg":"trace[1231135871] transaction","detail":"{read_only:false; response_revision:12967; number_of_response:1; }","duration":"162.007483ms","start":"2026-02-28T06:28:13.640861Z","end":"2026-02-28T06:28:13.802869Z","steps":["trace[1231135871] 'process raft request' (duration: 161.608852ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:28:13.80327Z","caller":"traceutil/trace.go:171","msg":"trace[1524119066] transaction","detail":"{read_only:false; response_revision:12968; number_of_response:1; }","duration":"161.685294ms","start":"2026-02-28T06:28:13.641571Z","end":"2026-02-28T06:28:13.803256Z","steps":["trace[1524119066] 'process raft request' (duration: 161.167249ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:28:13.803352Z","caller":"traceutil/trace.go:171","msg":"trace[1958804603] linearizableReadLoop","detail":"{readStateIndex:13428; appliedIndex:13426; }","duration":"130.690517ms","start":"2026-02-28T06:28:13.672616Z","end":"2026-02-28T06:28:13.803306Z","steps":["trace[1958804603] 'read index received' (duration: 129.826362ms)","trace[1958804603] 'applied index is now lower than readState.Index' (duration: 860.715µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T06:28:13.803567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.916112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T06:28:13.803607Z","caller":"traceutil/trace.go:171","msg":"trace[1460377364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12969; }","duration":"131.079287ms","start":"2026-02-28T06:28:13.672515Z","end":"2026-02-28T06:28:13.803594Z","steps":["trace[1460377364] 'agreement among raft nodes before linearized reading' (duration: 130.948334ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:30:04.790891Z","caller":"traceutil/trace.go:171","msg":"trace[1811548480] transaction","detail":"{read_only:false; response_revision:14059; number_of_response:1; }","duration":"118.492982ms","start":"2026-02-28T06:30:04.672372Z","end":"2026-02-28T06:30:04.790865Z","steps":["trace[1811548480] 'process raft request' (duration: 118.303117ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:30:04.792069Z","caller":"traceutil/trace.go:171","msg":"trace[1082019440] linearizableReadLoop","detail":"{readStateIndex:14558; appliedIndex:14555; }","duration":"117.189576ms","start":"2026-02-28T06:30:04.674844Z","end":"2026-02-28T06:30:04.792033Z","steps":["trace[1082019440] 'read index received' (duration: 115.954612ms)","trace[1082019440] 'applied index is now lower than readState.Index' (duration: 1.233114ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T06:30:04.792089Z","caller":"traceutil/trace.go:171","msg":"trace[1260238046] transaction","detail":"{read_only:false; response_revision:14060; number_of_response:1; }","duration":"119.356807ms","start":"2026-02-28T06:30:04.672694Z","end":"2026-02-28T06:30:04.792051Z","steps":["trace[1260238046] 'process raft request' (duration: 119.037338ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:30:04.80173Z","caller":"traceutil/trace.go:171","msg":"trace[137141528] transaction","detail":"{read_only:false; response_revision:14061; number_of_response:1; }","duration":"128.937248ms","start":"2026-02-28T06:30:04.672753Z","end":"2026-02-28T06:30:04.801691Z","steps":["trace[137141528] 'process raft request' (duration: 119.222773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:30:04.802153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.277793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737873"} {"level":"info","ts":"2026-02-28T06:30:04.806826Z","caller":"traceutil/trace.go:171","msg":"trace[2058628033] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14061; }","duration":"131.977921ms","start":"2026-02-28T06:30:04.674825Z","end":"2026-02-28T06:30:04.806802Z","steps":["trace[2058628033] 'agreement among raft nodes before linearized reading' (duration: 117.352691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:30:04.944029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.376564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T06:30:04.944061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.749534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-02-28T06:30:04.944132Z","caller":"traceutil/trace.go:171","msg":"trace[1431280610] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14061; }","duration":"105.859946ms","start":"2026-02-28T06:30:04.838257Z","end":"2026-02-28T06:30:04.944117Z","steps":["trace[1431280610] 'range keys from in-memory index tree' (duration: 105.578139ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:30:04.944139Z","caller":"traceutil/trace.go:171","msg":"trace[1230082811] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14061; }","duration":"105.527397ms","start":"2026-02-28T06:30:04.838584Z","end":"2026-02-28T06:30:04.944112Z","steps":["trace[1230082811] 'range keys from in-memory index tree' (duration: 105.26885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:30:04.944408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.080154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-nks94\" ","response":"range_response_count:1 size:20750"} {"level":"info","ts":"2026-02-28T06:30:04.944451Z","caller":"traceutil/trace.go:171","msg":"trace[899846814] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-nks94; range_end:; response_count:1; response_revision:14061; }","duration":"117.167026ms","start":"2026-02-28T06:30:04.827272Z","end":"2026-02-28T06:30:04.944439Z","steps":["trace[899846814] 'range keys from in-memory index tree' (duration: 116.894519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T06:30:04.945047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.071144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737873"} {"level":"info","ts":"2026-02-28T06:30:04.945133Z","caller":"traceutil/trace.go:171","msg":"trace[626997449] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14061; }","duration":"132.172196ms","start":"2026-02-28T06:30:04.812942Z","end":"2026-02-28T06:30:04.945114Z","steps":["trace[626997449] 'range keys from in-memory index tree' (duration: 131.207371ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T06:30:56.204398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12059} {"level":"info","ts":"2026-02-28T06:30:56.287189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12059,"took":"78.702633ms","hash":2152498263,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":26660864,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-02-28T06:30:56.287284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2152498263,"revision":12059,"compact-revision":10108} {"level":"info","ts":"2026-02-28T06:35:56.219941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14364} {"level":"info","ts":"2026-02-28T06:35:56.343757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14364,"took":"119.753551ms","hash":2519979189,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":28270592,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-28T06:35:56.343834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2519979189,"revision":14364,"compact-revision":12059} {"level":"info","ts":"2026-02-28T06:40:56.225636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16445} {"level":"info","ts":"2026-02-28T06:40:56.331495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16445,"took":"101.407111ms","hash":2008739765,"current-db-size-bytes":43778048,"current-db-size":"44 MB","current-db-size-in-use-bytes":21843968,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-02-28T06:40:56.331597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2008739765,"revision":16445,"compact-revision":14364}