{"level":"warn","ts":"2026-09-11T00:42:30.245124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:42:30.245242Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.79: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.79:2380","--initial-cluster=instance=https://162.253.55.79:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.79:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.79:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-11T00:42:30.245369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:42:30.245392Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-09-11T00:42:30.245424Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-11T00:42:30.246504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"]} {"level":"info","ts":"2026-09-11T00:42:30.246631Z","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.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.79:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-11T00:42:30.251868Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.899112ms"} {"level":"info","ts":"2026-09-11T00:42:30.260145Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"401ca643156fc5da","cluster-id":"e9f568c3681bb0d0"} {"level":"info","ts":"2026-09-11T00:42:30.26024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T00:42:30.26027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 0"} {"level":"info","ts":"2026-09-11T00:42:30.260291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 401ca643156fc5da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T00:42:30.260307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 1"} {"level":"info","ts":"2026-09-11T00:42:30.260354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"warn","ts":"2026-09-11T00:42:30.266676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T00:42:30.268805Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T00:42:30.270632Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-11T00:42:30.273895Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"401ca643156fc5da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T00:42:30.274171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"401ca643156fc5da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T00:42:30.274271Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:30.274402Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:30.274419Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-11T00:42:30.27587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"info","ts":"2026-09-11T00:42:30.276482Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","added-peer-id":"401ca643156fc5da","added-peer-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-09-11T00:42:30.279483Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-11T00:42:30.279731Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-09-11T00:42:30.279814Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-09-11T00:42:30.279818Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"401ca643156fc5da","initial-advertise-peer-urls":["https://162.253.55.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T00:42:30.279882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T00:42:30.56536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T00:42:30.565427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T00:42:30.56546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgPreVoteResp from 401ca643156fc5da at term 1"} {"level":"info","ts":"2026-09-11T00:42:30.565478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became candidate at term 2"} {"level":"info","ts":"2026-09-11T00:42:30.565489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgVoteResp from 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-09-11T00:42:30.565504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became leader at term 2"} {"level":"info","ts":"2026-09-11T00:42:30.565521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 401ca643156fc5da elected leader 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-09-11T00:42:30.566594Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"401ca643156fc5da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.79:2379]}","request-path":"/0/members/401ca643156fc5da/attributes","cluster-id":"e9f568c3681bb0d0","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T00:42:30.566677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:30.566766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:42:30.566827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:42:30.567678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:30.567786Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:30.567819Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:42:30.569799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.79:2379"} {"level":"info","ts":"2026-09-11T00:42:30.56989Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T00:42:30.570018Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T00:42:30.570452Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T00:42:36.04221Z","caller":"traceutil/trace.go:171","msg":"trace[1815611000] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"108.293398ms","start":"2026-09-11T00:42:35.933894Z","end":"2026-09-11T00:42:36.042187Z","steps":["trace[1815611000] 'read index received' (duration: 90.328597ms)","trace[1815611000] 'applied index is now lower than readState.Index' (duration: 17.963171ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:42:36.04224Z","caller":"traceutil/trace.go:171","msg":"trace[1392843210] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"111.104432ms","start":"2026-09-11T00:42:35.931105Z","end":"2026-09-11T00:42:36.04221Z","steps":["trace[1392843210] 'process raft request' (duration: 93.1357ms)","trace[1392843210] 'compare' (duration: 17.782357ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:42:36.042417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.462622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-11T00:42:36.042446Z","caller":"traceutil/trace.go:171","msg":"trace[68386252] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"103.851846ms","start":"2026-09-11T00:42:35.938583Z","end":"2026-09-11T00:42:36.042435Z","steps":["trace[68386252] 'process raft request' (duration: 103.572709ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:36.042464Z","caller":"traceutil/trace.go:171","msg":"trace[623168947] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:286; }","duration":"108.580734ms","start":"2026-09-11T00:42:35.933872Z","end":"2026-09-11T00:42:36.042453Z","steps":["trace[623168947] 'agreement among raft nodes before linearized reading' (duration: 108.433381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:36.042609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.497882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"info","ts":"2026-09-11T00:42:36.042633Z","caller":"traceutil/trace.go:171","msg":"trace[2027799385] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:286; }","duration":"108.552624ms","start":"2026-09-11T00:42:35.934072Z","end":"2026-09-11T00:42:36.042625Z","steps":["trace[2027799385] 'agreement among raft nodes before linearized reading' (duration: 108.506053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:42:36.042655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.058283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-11T00:42:36.042685Z","caller":"traceutil/trace.go:171","msg":"trace[622010611] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"105.115695ms","start":"2026-09-11T00:42:35.937559Z","end":"2026-09-11T00:42:36.042675Z","steps":["trace[622010611] 'agreement among raft nodes before linearized reading' (duration: 105.061784ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:42:45.963255Z","caller":"traceutil/trace.go:171","msg":"trace[2093839069] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"286.627887ms","start":"2026-09-11T00:42:45.67658Z","end":"2026-09-11T00:42:45.963208Z","steps":["trace[2093839069] 'process raft request' (duration: 286.437723ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:23.992662Z","caller":"traceutil/trace.go:171","msg":"trace[2146947424] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"104.805824ms","start":"2026-09-11T00:43:23.887834Z","end":"2026-09-11T00:43:23.99264Z","steps":["trace[2146947424] 'read index received' (duration: 103.938165ms)","trace[2146947424] 'applied index is now lower than readState.Index' (duration: 866.699µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:43:23.992852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.993948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-11T00:43:23.992891Z","caller":"traceutil/trace.go:171","msg":"trace[132378129] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:656; }","duration":"105.079141ms","start":"2026-09-11T00:43:23.8878Z","end":"2026-09-11T00:43:23.992879Z","steps":["trace[132378129] 'agreement among raft nodes before linearized reading' (duration: 104.959178ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:23.993893Z","caller":"traceutil/trace.go:171","msg":"trace[605025519] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"203.392726ms","start":"2026-09-11T00:43:23.790451Z","end":"2026-09-11T00:43:23.993843Z","steps":["trace[605025519] 'process raft request' (duration: 201.722018ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:24.744329Z","caller":"traceutil/trace.go:171","msg":"trace[701307065] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"178.701447ms","start":"2026-09-11T00:43:24.565553Z","end":"2026-09-11T00:43:24.744254Z","steps":["trace[701307065] 'process raft request' (duration: 177.899588ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:25.191578Z","caller":"traceutil/trace.go:171","msg":"trace[52145400] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"357.871956ms","start":"2026-09-11T00:43:24.833677Z","end":"2026-09-11T00:43:25.191549Z","steps":["trace[52145400] 'process raft request' (duration: 357.712242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:25.19217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:43:24.833656Z","time spent":"358.006938ms","remote":"127.0.0.1:33910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:43:25.192473Z","caller":"traceutil/trace.go:171","msg":"trace[1524489359] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"355.833799ms","start":"2026-09-11T00:43:24.836585Z","end":"2026-09-11T00:43:25.192419Z","steps":["trace[1524489359] 'process raft request' (duration: 355.659675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:25.192891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:43:24.836577Z","time spent":"355.966472ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:43:42.417155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.837643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-11T00:43:42.417216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.232631ms","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-09-11T00:43:42.417251Z","caller":"traceutil/trace.go:171","msg":"trace[1551390089] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:831; }","duration":"258.963506ms","start":"2026-09-11T00:43:42.158268Z","end":"2026-09-11T00:43:42.417232Z","steps":["trace[1551390089] 'count revisions from in-memory index tree' (duration: 258.67418ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:42.417334Z","caller":"traceutil/trace.go:171","msg":"trace[1496965695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:831; }","duration":"150.376364ms","start":"2026-09-11T00:43:42.266906Z","end":"2026-09-11T00:43:42.417282Z","steps":["trace[1496965695] 'range keys from in-memory index tree' (duration: 149.973536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:42.417464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.967306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T00:43:42.417525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.378321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2wvjw\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-11T00:43:42.417571Z","caller":"traceutil/trace.go:171","msg":"trace[569671402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"104.110809ms","start":"2026-09-11T00:43:42.313423Z","end":"2026-09-11T00:43:42.417534Z","steps":["trace[569671402] 'range keys from in-memory index tree' (duration: 103.858333ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:43:42.417576Z","caller":"traceutil/trace.go:171","msg":"trace[715528111] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2wvjw; range_end:; response_count:1; response_revision:831; }","duration":"218.490735ms","start":"2026-09-11T00:43:42.199072Z","end":"2026-09-11T00:43:42.417563Z","steps":["trace[715528111] 'range keys from in-memory index tree' (duration: 218.183606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:43:42.417683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.80386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-11T00:43:42.417724Z","caller":"traceutil/trace.go:171","msg":"trace[76697643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"145.869711ms","start":"2026-09-11T00:43:42.271843Z","end":"2026-09-11T00:43:42.417713Z","steps":["trace[76697643] 'range keys from in-memory index tree' (duration: 145.664496ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.641375Z","caller":"traceutil/trace.go:171","msg":"trace[2066600894] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"138.302362ms","start":"2026-09-11T00:44:00.503029Z","end":"2026-09-11T00:44:00.641331Z","steps":["trace[2066600894] 'process raft request' (duration: 137.996785ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.641426Z","caller":"traceutil/trace.go:171","msg":"trace[843811763] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"134.149993ms","start":"2026-09-11T00:44:00.507243Z","end":"2026-09-11T00:44:00.641393Z","steps":["trace[843811763] 'process raft request' (duration: 133.972499ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.641642Z","caller":"traceutil/trace.go:171","msg":"trace[753179542] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"124.057696ms","start":"2026-09-11T00:44:00.517556Z","end":"2026-09-11T00:44:00.641614Z","steps":["trace[753179542] 'process raft request' (duration: 123.724198ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.6417Z","caller":"traceutil/trace.go:171","msg":"trace[1393923084] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"117.368809ms","start":"2026-09-11T00:44:00.524277Z","end":"2026-09-11T00:44:00.641645Z","steps":["trace[1393923084] 'process raft request' (duration: 117.254387ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.64185Z","caller":"traceutil/trace.go:171","msg":"trace[1598549706] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"118.409123ms","start":"2026-09-11T00:44:00.523372Z","end":"2026-09-11T00:44:00.641781Z","steps":["trace[1598549706] 'process raft request' (duration: 118.008804ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.735679Z","caller":"traceutil/trace.go:171","msg":"trace[405540731] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"148.799377ms","start":"2026-09-11T00:44:00.586848Z","end":"2026-09-11T00:44:00.735647Z","steps":["trace[405540731] 'process raft request' (duration: 108.92192ms)","trace[405540731] 'compare' (duration: 39.58656ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:44:00.735969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.758084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-11T00:44:00.736136Z","caller":"traceutil/trace.go:171","msg":"trace[420637809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"167.952548ms","start":"2026-09-11T00:44:00.568157Z","end":"2026-09-11T00:44:00.736109Z","steps":["trace[420637809] 'agreement among raft nodes before linearized reading' (duration: 167.509167ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.736175Z","caller":"traceutil/trace.go:171","msg":"trace[926482782] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"146.430392ms","start":"2026-09-11T00:44:00.589703Z","end":"2026-09-11T00:44:00.736133Z","steps":["trace[926482782] 'process raft request' (duration: 145.828397ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.763523Z","caller":"traceutil/trace.go:171","msg":"trace[1765229959] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"117.091882ms","start":"2026-09-11T00:44:00.646399Z","end":"2026-09-11T00:44:00.763491Z","steps":["trace[1765229959] 'process raft request' (duration: 116.885497ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.763702Z","caller":"traceutil/trace.go:171","msg":"trace[901101239] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"115.387982ms","start":"2026-09-11T00:44:00.648292Z","end":"2026-09-11T00:44:00.76368Z","steps":["trace[901101239] 'process raft request' (duration: 115.118196ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.76421Z","caller":"traceutil/trace.go:171","msg":"trace[580667979] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"101.22157ms","start":"2026-09-11T00:44:00.662967Z","end":"2026-09-11T00:44:00.764188Z","steps":["trace[580667979] 'process raft request' (duration: 100.697547ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.957465Z","caller":"traceutil/trace.go:171","msg":"trace[1301449906] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"132.950475ms","start":"2026-09-11T00:44:00.824478Z","end":"2026-09-11T00:44:00.957428Z","steps":["trace[1301449906] 'process raft request' (duration: 132.601266ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.95765Z","caller":"traceutil/trace.go:171","msg":"trace[82243997] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"133.045598ms","start":"2026-09-11T00:44:00.824582Z","end":"2026-09-11T00:44:00.957628Z","steps":["trace[82243997] 'process raft request' (duration: 132.761561ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.957724Z","caller":"traceutil/trace.go:171","msg":"trace[1545676000] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1103; }","duration":"128.07882ms","start":"2026-09-11T00:44:00.829618Z","end":"2026-09-11T00:44:00.957696Z","steps":["trace[1545676000] 'read index received' (duration: 65.920019ms)","trace[1545676000] 'applied index is now lower than readState.Index' (duration: 62.157581ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:44:00.958005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.366998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-09-11T00:44:00.958088Z","caller":"traceutil/trace.go:171","msg":"trace[50915608] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:1069; }","duration":"128.49503ms","start":"2026-09-11T00:44:00.829577Z","end":"2026-09-11T00:44:00.958072Z","steps":["trace[50915608] 'agreement among raft nodes before linearized reading' (duration: 128.227584ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.958103Z","caller":"traceutil/trace.go:171","msg":"trace[600767376] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"133.392945ms","start":"2026-09-11T00:44:00.824694Z","end":"2026-09-11T00:44:00.958087Z","steps":["trace[600767376] 'process raft request' (duration: 132.850643ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.958255Z","caller":"traceutil/trace.go:171","msg":"trace[1297710541] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"133.466627ms","start":"2026-09-11T00:44:00.824775Z","end":"2026-09-11T00:44:00.958242Z","steps":["trace[1297710541] 'process raft request' (duration: 132.809622ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:44:00.958286Z","caller":"traceutil/trace.go:171","msg":"trace[1956395582] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"128.143662ms","start":"2026-09-11T00:44:00.830113Z","end":"2026-09-11T00:44:00.958257Z","steps":["trace[1956395582] 'process raft request' (duration: 127.524208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:44:29.688565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.866741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-11T00:44:29.688666Z","caller":"traceutil/trace.go:171","msg":"trace[149368096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"118.018774ms","start":"2026-09-11T00:44:29.570621Z","end":"2026-09-11T00:44:29.688639Z","steps":["trace[149368096] 'range keys from in-memory index tree' (duration: 117.695586ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:03.847325Z","caller":"traceutil/trace.go:171","msg":"trace[263616679] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"224.891516ms","start":"2026-09-11T00:45:03.622408Z","end":"2026-09-11T00:45:03.8473Z","steps":["trace[263616679] 'process raft request' (duration: 224.746133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:04.373641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.322603ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-11T00:45:04.373945Z","caller":"traceutil/trace.go:171","msg":"trace[572177126] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"398.494317ms","start":"2026-09-11T00:45:03.975433Z","end":"2026-09-11T00:45:04.373928Z","steps":["trace[572177126] 'process raft request' (duration: 398.414925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:04.373945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:45:03.928941Z","time spent":"444.99679ms","remote":"127.0.0.1:33406","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-11T00:45:04.373994Z","caller":"traceutil/trace.go:171","msg":"trace[377319173] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"435.516617ms","start":"2026-09-11T00:45:03.938449Z","end":"2026-09-11T00:45:04.373965Z","steps":["trace[377319173] 'process raft request' (duration: 435.281812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:04.374042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:45:03.975407Z","time spent":"398.577879ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:45:04.374174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:45:03.938425Z","time spent":"435.640519ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:45:12.022323Z","caller":"traceutil/trace.go:171","msg":"trace[805241108] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"142.453408ms","start":"2026-09-11T00:45:11.879844Z","end":"2026-09-11T00:45:12.022297Z","steps":["trace[805241108] 'process raft request' (duration: 142.293794ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:12.039688Z","caller":"traceutil/trace.go:171","msg":"trace[566086719] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"116.464328ms","start":"2026-09-11T00:45:11.923192Z","end":"2026-09-11T00:45:12.039657Z","steps":["trace[566086719] 'process raft request' (duration: 116.302185ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:16.95255Z","caller":"traceutil/trace.go:171","msg":"trace[1350982504] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"230.457847ms","start":"2026-09-11T00:45:16.722059Z","end":"2026-09-11T00:45:16.952517Z","steps":["trace[1350982504] 'process raft request' (duration: 230.246852ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:16.961411Z","caller":"traceutil/trace.go:171","msg":"trace[284147262] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"190.608961ms","start":"2026-09-11T00:45:16.770758Z","end":"2026-09-11T00:45:16.961367Z","steps":["trace[284147262] 'process raft request' (duration: 190.247532ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:16.961466Z","caller":"traceutil/trace.go:171","msg":"trace[1952930668] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"117.918473ms","start":"2026-09-11T00:45:16.843517Z","end":"2026-09-11T00:45:16.961435Z","steps":["trace[1952930668] 'process raft request' (duration: 117.646235ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:48.289061Z","caller":"traceutil/trace.go:171","msg":"trace[132145777] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"115.227938ms","start":"2026-09-11T00:45:48.173807Z","end":"2026-09-11T00:45:48.289035Z","steps":["trace[132145777] 'process raft request' (duration: 115.026583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:54.028585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.503288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-11T00:45:54.028671Z","caller":"traceutil/trace.go:171","msg":"trace[523804018] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:2249; }","duration":"133.63ms","start":"2026-09-11T00:45:53.895021Z","end":"2026-09-11T00:45:54.028651Z","steps":["trace[523804018] 'range keys from in-memory index tree' (duration: 133.344134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:54.028775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.013625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-11T00:45:54.028862Z","caller":"traceutil/trace.go:171","msg":"trace[349847632] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2249; }","duration":"181.150238ms","start":"2026-09-11T00:45:53.84769Z","end":"2026-09-11T00:45:54.02884Z","steps":["trace[349847632] 'range keys from in-memory index tree' (duration: 180.80858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:45:54.815661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.198395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-11T00:45:54.815795Z","caller":"traceutil/trace.go:171","msg":"trace[594856803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2251; }","duration":"117.40786ms","start":"2026-09-11T00:45:54.69836Z","end":"2026-09-11T00:45:54.815768Z","steps":["trace[594856803] 'range keys from in-memory index tree' (duration: 116.891408ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:45:59.977613Z","caller":"traceutil/trace.go:171","msg":"trace[1263391629] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"247.746564ms","start":"2026-09-11T00:45:59.729835Z","end":"2026-09-11T00:45:59.977582Z","steps":["trace[1263391629] 'process raft request' (duration: 247.601821ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:20.576325Z","caller":"traceutil/trace.go:171","msg":"trace[1778514213] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"159.546441ms","start":"2026-09-11T00:47:20.416747Z","end":"2026-09-11T00:47:20.576293Z","steps":["trace[1778514213] 'process raft request' (duration: 159.344206ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:47:20.872301Z","caller":"traceutil/trace.go:171","msg":"trace[927232012] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"136.856848ms","start":"2026-09-11T00:47:20.735392Z","end":"2026-09-11T00:47:20.872249Z","steps":["trace[927232012] 'process raft request' (duration: 136.617062ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:50:48.709562Z","caller":"traceutil/trace.go:171","msg":"trace[222493135] linearizableReadLoop","detail":"{readStateIndex:4369; appliedIndex:4368; }","duration":"127.556726ms","start":"2026-09-11T00:50:48.581969Z","end":"2026-09-11T00:50:48.709526Z","steps":["trace[222493135] 'read index received' (duration: 71.901238ms)","trace[222493135] 'applied index is now lower than readState.Index' (duration: 55.653468ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:50:48.709592Z","caller":"traceutil/trace.go:171","msg":"trace[1547657158] transaction","detail":"{read_only:false; response_revision:4205; number_of_response:1; }","duration":"179.241933ms","start":"2026-09-11T00:50:48.530316Z","end":"2026-09-11T00:50:48.709558Z","steps":["trace[1547657158] 'process raft request' (duration: 123.559484ms)","trace[1547657158] 'compare' (duration: 55.384662ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:50:48.70981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.838172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-11T00:50:48.709998Z","caller":"traceutil/trace.go:171","msg":"trace[1225697752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4205; }","duration":"128.058767ms","start":"2026-09-11T00:50:48.581917Z","end":"2026-09-11T00:50:48.709976Z","steps":["trace[1225697752] 'agreement among raft nodes before linearized reading' (duration: 127.74389ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:19.124669Z","caller":"traceutil/trace.go:171","msg":"trace[1486398311] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5197; }","duration":"143.900068ms","start":"2026-09-11T00:52:18.980734Z","end":"2026-09-11T00:52:19.124634Z","steps":["trace[1486398311] 'process raft request' (duration: 76.38149ms)","trace[1486398311] 'compare' (duration: 67.202181ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:19.124915Z","caller":"traceutil/trace.go:171","msg":"trace[277438074] linearizableReadLoop","detail":"{readStateIndex:5400; appliedIndex:5399; }","duration":"138.104126ms","start":"2026-09-11T00:52:18.9868Z","end":"2026-09-11T00:52:19.124905Z","steps":["trace[277438074] 'read index received' (duration: 70.22002ms)","trace[277438074] 'applied index is now lower than readState.Index' (duration: 67.883026ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:19.12509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.24914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:52:19.125125Z","caller":"traceutil/trace.go:171","msg":"trace[880327544] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5198; }","duration":"138.336581ms","start":"2026-09-11T00:52:18.986778Z","end":"2026-09-11T00:52:19.125114Z","steps":["trace[880327544] 'agreement among raft nodes before linearized reading' (duration: 138.204928ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:19.819904Z","caller":"traceutil/trace.go:171","msg":"trace[1019240829] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"139.670642ms","start":"2026-09-11T00:52:19.680201Z","end":"2026-09-11T00:52:19.819872Z","steps":["trace[1019240829] 'read index received' (duration: 46.855028ms)","trace[1019240829] 'applied index is now lower than readState.Index' (duration: 92.814274ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:19.820165Z","caller":"traceutil/trace.go:171","msg":"trace[1351986973] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"185.979947ms","start":"2026-09-11T00:52:19.634128Z","end":"2026-09-11T00:52:19.820108Z","steps":["trace[1351986973] 'process raft request' (duration: 93.039679ms)","trace[1351986973] 'compare' (duration: 92.547919ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:19.82037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.146343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-11T00:52:19.82044Z","caller":"traceutil/trace.go:171","msg":"trace[1081375934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5213; }","duration":"140.306758ms","start":"2026-09-11T00:52:19.680118Z","end":"2026-09-11T00:52:19.820425Z","steps":["trace[1081375934] 'agreement among raft nodes before linearized reading' (duration: 140.01374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:19.820968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.40472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526853"} {"level":"info","ts":"2026-09-11T00:52:19.821006Z","caller":"traceutil/trace.go:171","msg":"trace[1270497878] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5213; }","duration":"126.46081ms","start":"2026-09-11T00:52:19.694534Z","end":"2026-09-11T00:52:19.820994Z","steps":["trace[1270497878] 'agreement among raft nodes before linearized reading' (duration: 125.908658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:19.8223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-09-11T00:52:19.822399Z","caller":"traceutil/trace.go:171","msg":"trace[982482093] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5213; }","duration":"131.24435ms","start":"2026-09-11T00:52:19.691131Z","end":"2026-09-11T00:52:19.822375Z","steps":["trace[982482093] 'agreement among raft nodes before linearized reading' (duration: 130.214177ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:28.034133Z","caller":"traceutil/trace.go:171","msg":"trace[576098426] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"280.409739ms","start":"2026-09-11T00:52:27.753661Z","end":"2026-09-11T00:52:28.034071Z","steps":["trace[576098426] 'process raft request' (duration: 209.892522ms)","trace[576098426] 'compare' (duration: 70.222899ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:28.034181Z","caller":"traceutil/trace.go:171","msg":"trace[725626150] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5661; }","duration":"217.186768ms","start":"2026-09-11T00:52:27.816963Z","end":"2026-09-11T00:52:28.03415Z","steps":["trace[725626150] 'read index received' (duration: 146.554808ms)","trace[725626150] 'applied index is now lower than readState.Index' (duration: 70.63051ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:28.034385Z","caller":"traceutil/trace.go:171","msg":"trace[1309965260] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"278.673819ms","start":"2026-09-11T00:52:27.75569Z","end":"2026-09-11T00:52:28.034363Z","steps":["trace[1309965260] 'process raft request' (duration: 278.396812ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:28.034341Z","caller":"traceutil/trace.go:171","msg":"trace[1655235743] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"280.44357ms","start":"2026-09-11T00:52:27.753854Z","end":"2026-09-11T00:52:28.034298Z","steps":["trace[1655235743] 'process raft request' (duration: 280.135372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.034837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.850743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-477d4bf9-d8f7-40b9-9c5b-7ee8e4a59d9f\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-11T00:52:28.034925Z","caller":"traceutil/trace.go:171","msg":"trace[1888691095] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-477d4bf9-d8f7-40b9-9c5b-7ee8e4a59d9f; range_end:; response_count:1; response_revision:5451; }","duration":"217.991587ms","start":"2026-09-11T00:52:27.816913Z","end":"2026-09-11T00:52:28.034904Z","steps":["trace[1888691095] 'agreement among raft nodes before linearized reading' (duration: 217.474335ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:28.243207Z","caller":"traceutil/trace.go:171","msg":"trace[895777071] linearizableReadLoop","detail":"{readStateIndex:5665; appliedIndex:5664; }","duration":"208.875188ms","start":"2026-09-11T00:52:28.034307Z","end":"2026-09-11T00:52:28.243182Z","steps":["trace[895777071] 'read index received' (duration: 138.226149ms)","trace[895777071] 'applied index is now lower than readState.Index' (duration: 70.647949ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:28.243548Z","caller":"traceutil/trace.go:171","msg":"trace[1339997655] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"222.56235ms","start":"2026-09-11T00:52:28.020971Z","end":"2026-09-11T00:52:28.243533Z","steps":["trace[1339997655] 'process raft request' (duration: 151.595684ms)","trace[1339997655] 'compare' (duration: 70.425004ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:28.244008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.028613ms","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-09-11T00:52:28.244073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.843108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-11T00:52:28.244121Z","caller":"traceutil/trace.go:171","msg":"trace[765368338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5452; }","duration":"386.226049ms","start":"2026-09-11T00:52:27.857872Z","end":"2026-09-11T00:52:28.244098Z","steps":["trace[765368338] 'agreement among raft nodes before linearized reading' (duration: 385.850919ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:28.244174Z","caller":"traceutil/trace.go:171","msg":"trace[1146594657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5452; }","duration":"364.007683ms","start":"2026-09-11T00:52:27.880132Z","end":"2026-09-11T00:52:28.24414Z","steps":["trace[1146594657] 'agreement among raft nodes before linearized reading' (duration: 363.700165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.244196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:27.857852Z","time spent":"386.331272ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-11T00:52:28.24423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:27.880103Z","time spent":"364.117884ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-11T00:52:28.244245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.689271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787113"} {"level":"info","ts":"2026-09-11T00:52:28.244292Z","caller":"traceutil/trace.go:171","msg":"trace[136273017] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5452; }","duration":"354.832754ms","start":"2026-09-11T00:52:27.889446Z","end":"2026-09-11T00:52:28.244279Z","steps":["trace[136273017] 'agreement among raft nodes before linearized reading' (duration: 354.308781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.244345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:27.889418Z","time spent":"354.918426ms","remote":"127.0.0.1:33478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":787136,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-09-11T00:52:28.257571Z","caller":"traceutil/trace.go:171","msg":"trace[944713907] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"217.201758ms","start":"2026-09-11T00:52:28.040355Z","end":"2026-09-11T00:52:28.257557Z","steps":["trace[944713907] 'process raft request' (duration: 217.111986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.267883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.772759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-jnjmc\" ","response":"range_response_count:1 size:4949"} {"level":"info","ts":"2026-09-11T00:52:28.268026Z","caller":"traceutil/trace.go:171","msg":"trace[1339500377] range","detail":"{range_begin:/registry/pods/monitoring/vector-jnjmc; range_end:; response_count:1; response_revision:5456; }","duration":"220.983344ms","start":"2026-09-11T00:52:28.04702Z","end":"2026-09-11T00:52:28.268003Z","steps":["trace[1339500377] 'agreement among raft nodes before linearized reading' (duration: 220.690577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.269515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.903652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:1 size:5083"} {"level":"info","ts":"2026-09-11T00:52:28.271179Z","caller":"traceutil/trace.go:171","msg":"trace[958230134] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:1; response_revision:5456; }","duration":"218.642791ms","start":"2026-09-11T00:52:28.052519Z","end":"2026-09-11T00:52:28.271161Z","steps":["trace[958230134] 'agreement among raft nodes before linearized reading' (duration: 215.618192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:28.61499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.257434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787113"} {"level":"info","ts":"2026-09-11T00:52:28.61509Z","caller":"traceutil/trace.go:171","msg":"trace[601688022] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5459; }","duration":"100.438179ms","start":"2026-09-11T00:52:28.514633Z","end":"2026-09-11T00:52:28.615071Z","steps":["trace[601688022] 'range keys from in-memory index tree' (duration: 99.678802ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:30.395532Z","caller":"traceutil/trace.go:171","msg":"trace[893100772] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"115.623554ms","start":"2026-09-11T00:52:30.279831Z","end":"2026-09-11T00:52:30.395454Z","steps":["trace[893100772] 'process raft request' (duration: 85.849376ms)","trace[893100772] 'compare' (duration: 29.271416ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:30.611173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-09-11T00:52:30.691304Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"77.504306ms","hash":1293095291,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":30212096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T00:52:30.691392Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1293095291,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-09-11T00:52:43.752261Z","caller":"traceutil/trace.go:171","msg":"trace[35150835] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"108.872339ms","start":"2026-09-11T00:52:43.643353Z","end":"2026-09-11T00:52:43.752226Z","steps":["trace[35150835] 'process raft request' (duration: 86.51118ms)","trace[35150835] 'compare' (duration: 22.130374ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:43.752816Z","caller":"traceutil/trace.go:171","msg":"trace[1374409500] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"103.527969ms","start":"2026-09-11T00:52:43.64926Z","end":"2026-09-11T00:52:43.752788Z","steps":["trace[1374409500] 'process raft request' (duration: 102.890734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.590338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.973562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:52:44.590677Z","caller":"traceutil/trace.go:171","msg":"trace[185187480] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"411.721449ms","start":"2026-09-11T00:52:44.178927Z","end":"2026-09-11T00:52:44.590649Z","steps":["trace[185187480] 'process raft request' (duration: 411.601087ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:44.590694Z","caller":"traceutil/trace.go:171","msg":"trace[900363429] linearizableReadLoop","detail":"{readStateIndex:5950; appliedIndex:5949; }","duration":"672.757556ms","start":"2026-09-11T00:52:43.917891Z","end":"2026-09-11T00:52:44.590648Z","steps":["trace[900363429] 'read index received' (duration: 67.386906ms)","trace[900363429] 'applied index is now lower than readState.Index' (duration: 605.3521ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:52:44.590676Z","caller":"traceutil/trace.go:171","msg":"trace[1173333377] transaction","detail":"{read_only:false; response_revision:5728; number_of_response:1; }","duration":"728.020306ms","start":"2026-09-11T00:52:43.862588Z","end":"2026-09-11T00:52:44.590608Z","steps":["trace[1173333377] 'process raft request' (duration: 122.659555ms)","trace[1173333377] 'compare' (duration: 604.821128ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:52:44.590811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:44.178894Z","time spent":"411.841762ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:52:44.59102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.089434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-11T00:52:44.591074Z","caller":"traceutil/trace.go:171","msg":"trace[643956725] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:5729; }","duration":"673.208257ms","start":"2026-09-11T00:52:43.91785Z","end":"2026-09-11T00:52:44.591058Z","steps":["trace[643956725] 'agreement among raft nodes before linearized reading' (duration: 672.938561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.591079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:43.86255Z","time spent":"728.436935ms","remote":"127.0.0.1:33468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T00:52:44.59113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:43.91782Z","time spent":"673.298678ms","remote":"127.0.0.1:33406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.79\" "} {"level":"info","ts":"2026-09-11T00:52:44.635913Z","caller":"traceutil/trace.go:171","msg":"trace[2091384776] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"271.68347ms","start":"2026-09-11T00:52:44.364201Z","end":"2026-09-11T00:52:44.635884Z","steps":["trace[2091384776] 'process raft request' (duration: 271.543457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.635988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.437266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:52:44.636045Z","caller":"traceutil/trace.go:171","msg":"trace[35130438] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:5730; }","duration":"498.575008ms","start":"2026-09-11T00:52:44.137454Z","end":"2026-09-11T00:52:44.636029Z","steps":["trace[35130438] 'agreement among raft nodes before linearized reading' (duration: 498.436635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.636081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:44.137425Z","time spent":"498.64485ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-09-11T00:52:44.636089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.065289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-11T00:52:44.636155Z","caller":"traceutil/trace.go:171","msg":"trace[1802215669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5730; }","duration":"150.184471ms","start":"2026-09-11T00:52:44.485951Z","end":"2026-09-11T00:52:44.636135Z","steps":["trace[1802215669] 'agreement among raft nodes before linearized reading' (duration: 149.973287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.636233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.350085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-11T00:52:44.636119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.123031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:52:44.636295Z","caller":"traceutil/trace.go:171","msg":"trace[408228974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5730; }","duration":"143.460568ms","start":"2026-09-11T00:52:44.492821Z","end":"2026-09-11T00:52:44.636281Z","steps":["trace[408228974] 'agreement among raft nodes before linearized reading' (duration: 143.295764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.63633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.440193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-dmf9k\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-09-11T00:52:44.636396Z","caller":"traceutil/trace.go:171","msg":"trace[1505144453] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-dmf9k; range_end:; response_count:1; response_revision:5730; }","duration":"339.549216ms","start":"2026-09-11T00:52:44.296826Z","end":"2026-09-11T00:52:44.636375Z","steps":["trace[1505144453] 'agreement among raft nodes before linearized reading' (duration: 339.353531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.636388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.359486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-11T00:52:44.636656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:44.296802Z","time spent":"339.634767ms","remote":"127.0.0.1:33566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7840,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-dmf9k\" "} {"level":"info","ts":"2026-09-11T00:52:44.63667Z","caller":"traceutil/trace.go:171","msg":"trace[640293475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5730; }","duration":"150.684333ms","start":"2026-09-11T00:52:44.485967Z","end":"2026-09-11T00:52:44.636651Z","steps":["trace[640293475] 'agreement among raft nodes before linearized reading' (duration: 150.292694ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:52:44.636361Z","caller":"traceutil/trace.go:171","msg":"trace[17518852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5730; }","duration":"323.401787ms","start":"2026-09-11T00:52:44.312894Z","end":"2026-09-11T00:52:44.636296Z","steps":["trace[17518852] 'agreement among raft nodes before linearized reading' (duration: 323.121391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:52:44.636864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:52:44.312862Z","time spent":"323.98273ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-11T00:53:07.416384Z","caller":"traceutil/trace.go:171","msg":"trace[497671586] transaction","detail":"{read_only:false; response_revision:6059; number_of_response:1; }","duration":"115.942922ms","start":"2026-09-11T00:53:07.300399Z","end":"2026-09-11T00:53:07.416342Z","steps":["trace[497671586] 'process raft request' (duration: 115.742197ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:57:30.618525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5485} {"level":"info","ts":"2026-09-11T00:57:30.703154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5485,"took":"83.024126ms","hash":3279437977,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T00:57:30.70322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3279437977,"revision":5485,"compact-revision":2965} {"level":"info","ts":"2026-09-11T00:59:13.867172Z","caller":"traceutil/trace.go:171","msg":"trace[2076858895] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"186.887904ms","start":"2026-09-11T00:59:13.680258Z","end":"2026-09-11T00:59:13.867146Z","steps":["trace[2076858895] 'process raft request' (duration: 186.736821ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:14.20954Z","caller":"traceutil/trace.go:171","msg":"trace[972381695] linearizableReadLoop","detail":"{readStateIndex:8969; appliedIndex:8968; }","duration":"212.308711ms","start":"2026-09-11T00:59:13.997204Z","end":"2026-09-11T00:59:14.209512Z","steps":["trace[972381695] 'read index received' (duration: 139.961838ms)","trace[972381695] 'applied index is now lower than readState.Index' (duration: 72.345683ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:14.20968Z","caller":"traceutil/trace.go:171","msg":"trace[1575574080] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"220.552999ms","start":"2026-09-11T00:59:13.989094Z","end":"2026-09-11T00:59:14.209647Z","steps":["trace[1575574080] 'process raft request' (duration: 148.159865ms)","trace[1575574080] 'compare' (duration: 72.115908ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:59:14.209832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.600818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-11T00:59:14.209911Z","caller":"traceutil/trace.go:171","msg":"trace[1913848310] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8650; }","duration":"212.715451ms","start":"2026-09-11T00:59:13.997179Z","end":"2026-09-11T00:59:14.209894Z","steps":["trace[1913848310] 'agreement among raft nodes before linearized reading' (duration: 212.475475ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:18.702836Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a2.wal"} {"level":"warn","ts":"2026-09-11T00:59:22.928506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.763905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:59:22.928878Z","caller":"traceutil/trace.go:171","msg":"trace[589350747] linearizableReadLoop","detail":"{readStateIndex:9153; appliedIndex:9149; }","duration":"168.080817ms","start":"2026-09-11T00:59:22.760776Z","end":"2026-09-11T00:59:22.928856Z","steps":["trace[589350747] 'read index received' (duration: 164.129317ms)","trace[589350747] 'applied index is now lower than readState.Index' (duration: 3.95029ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:22.928947Z","caller":"traceutil/trace.go:171","msg":"trace[101084101] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"600.498918ms","start":"2026-09-11T00:59:22.328408Z","end":"2026-09-11T00:59:22.928907Z","steps":["trace[101084101] 'process raft request' (duration: 600.241803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:22.929012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.22111ms","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-09-11T00:59:22.929056Z","caller":"traceutil/trace.go:171","msg":"trace[913195804] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8833; }","duration":"168.294522ms","start":"2026-09-11T00:59:22.760746Z","end":"2026-09-11T00:59:22.929041Z","steps":["trace[913195804] 'agreement among raft nodes before linearized reading' (duration: 168.18377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:22.929133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:59:22.328391Z","time spent":"600.645291ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:59:22.92919Z","caller":"traceutil/trace.go:171","msg":"trace[886042137] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"214.42681ms","start":"2026-09-11T00:59:22.714729Z","end":"2026-09-11T00:59:22.929156Z","steps":["trace[886042137] 'process raft request' (duration: 214.034301ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:22.929194Z","caller":"traceutil/trace.go:171","msg":"trace[987244738] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"606.170877ms","start":"2026-09-11T00:59:22.322976Z","end":"2026-09-11T00:59:22.929147Z","steps":["trace[987244738] 'process raft request' (duration: 354.606563ms)","trace[987244738] 'compare' (duration: 250.606181ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:22.929222Z","caller":"traceutil/trace.go:171","msg":"trace[1942840548] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"213.288474ms","start":"2026-09-11T00:59:22.715865Z","end":"2026-09-11T00:59:22.929153Z","steps":["trace[1942840548] 'process raft request' (duration: 212.950976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:22.929417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:59:22.322945Z","time spent":"606.339471ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:59:34.251638Z","caller":"traceutil/trace.go:171","msg":"trace[1928579810] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"165.609891ms","start":"2026-09-11T00:59:34.08595Z","end":"2026-09-11T00:59:34.25156Z","steps":["trace[1928579810] 'process raft request' (duration: 116.980967ms)","trace[1928579810] 'compare' (duration: 48.295607ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:34.252532Z","caller":"traceutil/trace.go:171","msg":"trace[437970035] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9210; }","duration":"144.111784ms","start":"2026-09-11T00:59:34.108396Z","end":"2026-09-11T00:59:34.252508Z","steps":["trace[437970035] 'read index received' (duration: 94.460916ms)","trace[437970035] 'applied index is now lower than readState.Index' (duration: 49.649317ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:34.252667Z","caller":"traceutil/trace.go:171","msg":"trace[998791456] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"163.650227ms","start":"2026-09-11T00:59:34.088987Z","end":"2026-09-11T00:59:34.252637Z","steps":["trace[998791456] 'process raft request' (duration: 163.370251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:34.252826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.40601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vr6k9\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-11T00:59:34.252876Z","caller":"traceutil/trace.go:171","msg":"trace[728876807] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vr6k9; range_end:; response_count:1; response_revision:8889; }","duration":"144.519782ms","start":"2026-09-11T00:59:34.108345Z","end":"2026-09-11T00:59:34.252865Z","steps":["trace[728876807] 'agreement among raft nodes before linearized reading' (duration: 144.245776ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:34.459101Z","caller":"traceutil/trace.go:171","msg":"trace[1416918917] linearizableReadLoop","detail":"{readStateIndex:9213; appliedIndex:9212; }","duration":"144.612715ms","start":"2026-09-11T00:59:34.314464Z","end":"2026-09-11T00:59:34.459076Z","steps":["trace[1416918917] 'read index received' (duration: 136.105992ms)","trace[1416918917] 'applied index is now lower than readState.Index' (duration: 8.505823ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:34.459201Z","caller":"traceutil/trace.go:171","msg":"trace[632726353] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"162.500941ms","start":"2026-09-11T00:59:34.296691Z","end":"2026-09-11T00:59:34.459192Z","steps":["trace[632726353] 'process raft request' (duration: 153.919286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:34.45942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.941442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:59:34.459452Z","caller":"traceutil/trace.go:171","msg":"trace[1841479288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8890; }","duration":"145.014373ms","start":"2026-09-11T00:59:34.314427Z","end":"2026-09-11T00:59:34.459441Z","steps":["trace[1841479288] 'agreement among raft nodes before linearized reading' (duration: 144.935492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:34.459743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.154709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-11T00:59:34.459851Z","caller":"traceutil/trace.go:171","msg":"trace[509117642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8890; }","duration":"135.319633ms","start":"2026-09-11T00:59:34.324508Z","end":"2026-09-11T00:59:34.459828Z","steps":["trace[509117642] 'agreement among raft nodes before linearized reading' (duration: 134.926774ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.252714Z","caller":"traceutil/trace.go:171","msg":"trace[163305434] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"281.699528ms","start":"2026-09-11T00:59:34.970994Z","end":"2026-09-11T00:59:35.252693Z","steps":["trace[163305434] 'process raft request' (duration: 281.546595ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.378539Z","caller":"traceutil/trace.go:171","msg":"trace[1153511943] linearizableReadLoop","detail":"{readStateIndex:9218; appliedIndex:9214; }","duration":"289.025064ms","start":"2026-09-11T00:59:35.089491Z","end":"2026-09-11T00:59:35.378516Z","steps":["trace[1153511943] 'read index received' (duration: 163.079853ms)","trace[1153511943] 'applied index is now lower than readState.Index' (duration: 125.944201ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:59:35.378574Z","caller":"traceutil/trace.go:171","msg":"trace[1718911112] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"402.307717ms","start":"2026-09-11T00:59:34.976234Z","end":"2026-09-11T00:59:35.378542Z","steps":["trace[1718911112] 'process raft request' (duration: 402.201735ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.378665Z","caller":"traceutil/trace.go:171","msg":"trace[1600117941] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"293.125528ms","start":"2026-09-11T00:59:35.08553Z","end":"2026-09-11T00:59:35.378655Z","steps":["trace[1600117941] 'process raft request' (duration: 292.946184ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.378666Z","caller":"traceutil/trace.go:171","msg":"trace[1098691065] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"404.590729ms","start":"2026-09-11T00:59:34.974047Z","end":"2026-09-11T00:59:35.378638Z","steps":["trace[1098691065] 'process raft request' (duration: 404.241092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:35.37874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:59:34.976225Z","time spent":"402.4402ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:59:35.378816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:59:34.974025Z","time spent":"404.695301ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:59:35.378918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.107098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-11T00:59:35.378951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.555483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12208"} {"level":"warn","ts":"2026-09-11T00:59:35.378933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.427653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-11T00:59:35.378991Z","caller":"traceutil/trace.go:171","msg":"trace[1224039793] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8895; }","duration":"100.619465ms","start":"2026-09-11T00:59:35.278361Z","end":"2026-09-11T00:59:35.37898Z","steps":["trace[1224039793] 'agreement among raft nodes before linearized reading' (duration: 100.504003ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.378995Z","caller":"traceutil/trace.go:171","msg":"trace[2123722736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8895; }","duration":"168.233841ms","start":"2026-09-11T00:59:35.210744Z","end":"2026-09-11T00:59:35.378978Z","steps":["trace[2123722736] 'agreement among raft nodes before linearized reading' (duration: 167.960425ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.37901Z","caller":"traceutil/trace.go:171","msg":"trace[827918092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8895; }","duration":"289.505944ms","start":"2026-09-11T00:59:35.089486Z","end":"2026-09-11T00:59:35.378992Z","steps":["trace[827918092] 'agreement among raft nodes before linearized reading' (duration: 289.29091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:59:35.379069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.553634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-11T00:59:35.379115Z","caller":"traceutil/trace.go:171","msg":"trace[1881158424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8895; }","duration":"148.642776ms","start":"2026-09-11T00:59:35.230458Z","end":"2026-09-11T00:59:35.379101Z","steps":["trace[1881158424] 'agreement among raft nodes before linearized reading' (duration: 148.479972ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:59:35.771673Z","caller":"traceutil/trace.go:171","msg":"trace[2057029852] transaction","detail":"{read_only:false; response_revision:8900; number_of_response:1; }","duration":"127.708391ms","start":"2026-09-11T00:59:35.643878Z","end":"2026-09-11T00:59:35.771586Z","steps":["trace[2057029852] 'process raft request' (duration: 127.469106ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:01.624383Z","caller":"traceutil/trace.go:171","msg":"trace[85441258] linearizableReadLoop","detail":"{readStateIndex:9416; appliedIndex:9415; }","duration":"204.382232ms","start":"2026-09-11T01:00:01.419968Z","end":"2026-09-11T01:00:01.62435Z","steps":["trace[85441258] 'read index received' (duration: 204.257809ms)","trace[85441258] 'applied index is now lower than readState.Index' (duration: 123.183µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:00:01.624527Z","caller":"traceutil/trace.go:171","msg":"trace[1181629274] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"269.225534ms","start":"2026-09-11T01:00:01.355293Z","end":"2026-09-11T01:00:01.624519Z","steps":["trace[1181629274] 'process raft request' (duration: 268.844506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:00:01.62484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.863923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-09-11T01:00:01.62488Z","caller":"traceutil/trace.go:171","msg":"trace[1810624710] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:9085; }","duration":"204.974075ms","start":"2026-09-11T01:00:01.419896Z","end":"2026-09-11T01:00:01.62487Z","steps":["trace[1810624710] 'agreement among raft nodes before linearized reading' (duration: 204.807871ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:01.633209Z","caller":"traceutil/trace.go:171","msg":"trace[790343240] transaction","detail":"{read_only:false; response_revision:9086; number_of_response:1; }","duration":"165.437117ms","start":"2026-09-11T01:00:01.467737Z","end":"2026-09-11T01:00:01.633174Z","steps":["trace[790343240] 'process raft request' (duration: 165.12843ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:01.63341Z","caller":"traceutil/trace.go:171","msg":"trace[12530001] transaction","detail":"{read_only:false; response_revision:9087; number_of_response:1; }","duration":"148.954842ms","start":"2026-09-11T01:00:01.484433Z","end":"2026-09-11T01:00:01.633388Z","steps":["trace[12530001] 'process raft request' (duration: 148.623175ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:01.633661Z","caller":"traceutil/trace.go:171","msg":"trace[1206306173] transaction","detail":"{read_only:false; response_revision:9088; number_of_response:1; }","duration":"146.21079ms","start":"2026-09-11T01:00:01.487343Z","end":"2026-09-11T01:00:01.633554Z","steps":["trace[1206306173] 'process raft request' (duration: 145.75449ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:08.969572Z","caller":"traceutil/trace.go:171","msg":"trace[401490976] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"315.68648ms","start":"2026-09-11T01:00:08.653849Z","end":"2026-09-11T01:00:08.969535Z","steps":["trace[401490976] 'process raft request' (duration: 315.467085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:00:08.969814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:00:08.653826Z","time spent":"315.868134ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:00:18.010551Z","caller":"traceutil/trace.go:171","msg":"trace[2124644914] transaction","detail":"{read_only:false; response_revision:9179; number_of_response:1; }","duration":"179.677657ms","start":"2026-09-11T01:00:17.830843Z","end":"2026-09-11T01:00:18.010521Z","steps":["trace[2124644914] 'process raft request' (duration: 132.111035ms)","trace[2124644914] 'compare' (duration: 47.431439ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:00:18.241202Z","caller":"traceutil/trace.go:171","msg":"trace[1744172832] linearizableReadLoop","detail":"{readStateIndex:9514; appliedIndex:9513; }","duration":"184.330542ms","start":"2026-09-11T01:00:18.05684Z","end":"2026-09-11T01:00:18.241171Z","steps":["trace[1744172832] 'read index received' (duration: 171.289726ms)","trace[1744172832] 'applied index is now lower than readState.Index' (duration: 13.039576ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:00:18.241626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.754731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T01:00:18.241676Z","caller":"traceutil/trace.go:171","msg":"trace[1540164232] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:9180; }","duration":"184.879425ms","start":"2026-09-11T01:00:18.056786Z","end":"2026-09-11T01:00:18.241666Z","steps":["trace[1540164232] 'agreement among raft nodes before linearized reading' (duration: 184.579988ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:18.242017Z","caller":"traceutil/trace.go:171","msg":"trace[1025287504] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"410.103428ms","start":"2026-09-11T01:00:17.831413Z","end":"2026-09-11T01:00:18.241517Z","steps":["trace[1025287504] 'process raft request' (duration: 396.826567ms)","trace[1025287504] 'compare' (duration: 12.78233ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:00:18.242137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:00:17.831396Z","time spent":"410.678282ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:00:18.533506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.000041ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T01:00:18.533894Z","caller":"traceutil/trace.go:171","msg":"trace[1562089470] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:9180; }","duration":"186.399921ms","start":"2026-09-11T01:00:18.347468Z","end":"2026-09-11T01:00:18.533868Z","steps":["trace[1562089470] 'range keys from in-memory index tree' (duration: 185.980641ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:00:19.122441Z","caller":"traceutil/trace.go:171","msg":"trace[985785890] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"124.227385ms","start":"2026-09-11T01:00:18.998185Z","end":"2026-09-11T01:00:19.122413Z","steps":["trace[985785890] 'process raft request' (duration: 124.053912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:00:19.382402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.458823ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-11T01:00:19.382531Z","caller":"traceutil/trace.go:171","msg":"trace[444992288] linearizableReadLoop","detail":"{readStateIndex:9517; appliedIndex:9515; }","duration":"332.298129ms","start":"2026-09-11T01:00:19.050211Z","end":"2026-09-11T01:00:19.382509Z","steps":["trace[444992288] 'read index received' (duration: 71.995538ms)","trace[444992288] 'applied index is now lower than readState.Index' (duration: 260.300961ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:00:19.382809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.574716ms","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-09-11T01:00:19.382902Z","caller":"traceutil/trace.go:171","msg":"trace[886160847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9182; }","duration":"332.723639ms","start":"2026-09-11T01:00:19.050159Z","end":"2026-09-11T01:00:19.382883Z","steps":["trace[886160847] 'agreement among raft nodes before linearized reading' (duration: 332.407111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:00:19.382946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:00:19.050141Z","time spent":"332.794521ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-11T01:01:10.905042Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:01:10.9109Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-11T01:01:10.91097Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-11T01:02:30.628199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7967} {"level":"info","ts":"2026-09-11T01:02:30.720721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7967,"took":"90.5506ms","hash":1431491811,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":26013696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T01:02:30.720783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1431491811,"revision":7967,"compact-revision":5485} {"level":"warn","ts":"2026-09-11T01:07:14.188464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.724539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-11T01:07:14.188578Z","caller":"traceutil/trace.go:171","msg":"trace[2059579213] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:12394; }","duration":"223.873773ms","start":"2026-09-11T01:07:13.964679Z","end":"2026-09-11T01:07:14.188553Z","steps":["trace[2059579213] 'range keys from in-memory index tree' (duration: 223.547496ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:30.635806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10342} {"level":"info","ts":"2026-09-11T01:07:30.729894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10342,"took":"91.555023ms","hash":1826946662,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":25702400,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-11T01:07:30.729969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1826946662,"revision":10342,"compact-revision":7967} {"level":"info","ts":"2026-09-11T01:07:34.07629Z","caller":"traceutil/trace.go:171","msg":"trace[1261523971] linearizableReadLoop","detail":"{readStateIndex:12950; appliedIndex:12949; }","duration":"134.332226ms","start":"2026-09-11T01:07:33.941934Z","end":"2026-09-11T01:07:34.076266Z","steps":["trace[1261523971] 'read index received' (duration: 134.128911ms)","trace[1261523971] 'applied index is now lower than readState.Index' (duration: 202.355µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:07:34.076422Z","caller":"traceutil/trace.go:171","msg":"trace[681631775] transaction","detail":"{read_only:false; response_revision:12509; number_of_response:1; }","duration":"203.406186ms","start":"2026-09-11T01:07:33.872997Z","end":"2026-09-11T01:07:34.076404Z","steps":["trace[681631775] 'process raft request' (duration: 203.121959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:07:34.076681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.728295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vr6k9\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-09-11T01:07:34.076684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.701239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.79\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-11T01:07:34.076738Z","caller":"traceutil/trace.go:171","msg":"trace[1460604612] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vr6k9; range_end:; response_count:1; response_revision:12509; }","duration":"134.840328ms","start":"2026-09-11T01:07:33.941884Z","end":"2026-09-11T01:07:34.076724Z","steps":["trace[1460604612] 'agreement among raft nodes before linearized reading' (duration: 134.656394ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:34.076762Z","caller":"traceutil/trace.go:171","msg":"trace[1214337177] range","detail":"{range_begin:/registry/masterleases/162.253.55.79; range_end:; response_count:1; response_revision:12509; }","duration":"110.825501ms","start":"2026-09-11T01:07:33.96592Z","end":"2026-09-11T01:07:34.076745Z","steps":["trace[1214337177] 'agreement among raft nodes before linearized reading' (duration: 110.602456ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:34.232943Z","caller":"traceutil/trace.go:171","msg":"trace[1474131557] transaction","detail":"{read_only:false; response_revision:12510; number_of_response:1; }","duration":"115.734072ms","start":"2026-09-11T01:07:34.117169Z","end":"2026-09-11T01:07:34.232903Z","steps":["trace[1474131557] 'process raft request' (duration: 51.87186ms)","trace[1474131557] 'compare' (duration: 63.695028ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:07:34.846412Z","caller":"traceutil/trace.go:171","msg":"trace[1255989402] transaction","detail":"{read_only:false; response_revision:12513; number_of_response:1; }","duration":"131.194144ms","start":"2026-09-11T01:07:34.715189Z","end":"2026-09-11T01:07:34.846383Z","steps":["trace[1255989402] 'process raft request' (duration: 131.03651ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:35.349269Z","caller":"traceutil/trace.go:171","msg":"trace[1905479924] linearizableReadLoop","detail":"{readStateIndex:12956; appliedIndex:12955; }","duration":"178.213954ms","start":"2026-09-11T01:07:35.171028Z","end":"2026-09-11T01:07:35.349242Z","steps":["trace[1905479924] 'read index received' (duration: 177.936038ms)","trace[1905479924] 'applied index is now lower than readState.Index' (duration: 277.016µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:07:35.349394Z","caller":"traceutil/trace.go:171","msg":"trace[1097533331] transaction","detail":"{read_only:false; response_revision:12514; number_of_response:1; }","duration":"193.672745ms","start":"2026-09-11T01:07:35.155672Z","end":"2026-09-11T01:07:35.349345Z","steps":["trace[1097533331] 'process raft request' (duration: 193.410219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:07:35.349555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.765358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:07:35.349498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.460969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:07:35.34963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.792799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-11T01:07:35.349545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.433639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-11T01:07:35.34972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.074375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T01:07:35.349846Z","caller":"traceutil/trace.go:171","msg":"trace[34462352] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12514; }","duration":"176.234799ms","start":"2026-09-11T01:07:35.173583Z","end":"2026-09-11T01:07:35.349818Z","steps":["trace[34462352] 'agreement among raft nodes before linearized reading' (duration: 175.983143ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:35.3497Z","caller":"traceutil/trace.go:171","msg":"trace[379087211] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12514; }","duration":"178.668014ms","start":"2026-09-11T01:07:35.171013Z","end":"2026-09-11T01:07:35.349681Z","steps":["trace[379087211] 'agreement among raft nodes before linearized reading' (duration: 178.405498ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:35.349762Z","caller":"traceutil/trace.go:171","msg":"trace[358812088] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12514; }","duration":"153.961822ms","start":"2026-09-11T01:07:35.195776Z","end":"2026-09-11T01:07:35.349738Z","steps":["trace[358812088] 'agreement among raft nodes before linearized reading' (duration: 153.682776ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:35.349607Z","caller":"traceutil/trace.go:171","msg":"trace[1104965067] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12514; }","duration":"175.8491ms","start":"2026-09-11T01:07:35.173745Z","end":"2026-09-11T01:07:35.349594Z","steps":["trace[1104965067] 'agreement among raft nodes before linearized reading' (duration: 175.752188ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:07:35.349764Z","caller":"traceutil/trace.go:171","msg":"trace[682259593] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12514; }","duration":"178.736626ms","start":"2026-09-11T01:07:35.171007Z","end":"2026-09-11T01:07:35.349743Z","steps":["trace[682259593] 'agreement among raft nodes before linearized reading' (duration: 178.423059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:07:35.349773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.389617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-11T01:07:35.35012Z","caller":"traceutil/trace.go:171","msg":"trace[1845755826] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12514; }","duration":"174.735104ms","start":"2026-09-11T01:07:35.175325Z","end":"2026-09-11T01:07:35.35006Z","steps":["trace[1845755826] 'agreement among raft nodes before linearized reading' (duration: 174.301925ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:12:30.643403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12488} {"level":"info","ts":"2026-09-11T01:12:30.729374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12488,"took":"83.090332ms","hash":2368408531,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":27918336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T01:12:30.729477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2368408531,"revision":12488,"compact-revision":10342} {"level":"info","ts":"2026-09-11T01:14:10.552552Z","caller":"traceutil/trace.go:171","msg":"trace[2128101101] linearizableReadLoop","detail":"{readStateIndex:15675; appliedIndex:15674; }","duration":"117.086446ms","start":"2026-09-11T01:14:10.435438Z","end":"2026-09-11T01:14:10.552525Z","steps":["trace[2128101101] 'read index received' (duration: 116.864931ms)","trace[2128101101] 'applied index is now lower than readState.Index' (duration: 220.715µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:14:10.552724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.251899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-11T01:14:10.552751Z","caller":"traceutil/trace.go:171","msg":"trace[2022222239] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:15142; }","duration":"117.351182ms","start":"2026-09-11T01:14:10.435392Z","end":"2026-09-11T01:14:10.552743Z","steps":["trace[2022222239] 'agreement among raft nodes before linearized reading' (duration: 117.237989ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:10.553104Z","caller":"traceutil/trace.go:171","msg":"trace[1064402490] transaction","detail":"{read_only:false; response_revision:15142; number_of_response:1; }","duration":"197.468187ms","start":"2026-09-11T01:14:10.35561Z","end":"2026-09-11T01:14:10.553079Z","steps":["trace[1064402490] 'process raft request' (duration: 196.764511ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:27.869186Z","caller":"traceutil/trace.go:171","msg":"trace[2095830358] transaction","detail":"{read_only:false; response_revision:15248; number_of_response:1; }","duration":"146.420224ms","start":"2026-09-11T01:14:27.722722Z","end":"2026-09-11T01:14:27.869142Z","steps":["trace[2095830358] 'process raft request' (duration: 146.159858ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:28.90764Z","caller":"traceutil/trace.go:171","msg":"trace[1114986340] transaction","detail":"{read_only:false; response_revision:15250; number_of_response:1; }","duration":"238.527721ms","start":"2026-09-11T01:14:28.669078Z","end":"2026-09-11T01:14:28.907606Z","steps":["trace[1114986340] 'process raft request' (duration: 238.328377ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:29.102637Z","caller":"traceutil/trace.go:171","msg":"trace[1597487853] transaction","detail":"{read_only:false; response_revision:15251; number_of_response:1; }","duration":"174.031653ms","start":"2026-09-11T01:14:28.928552Z","end":"2026-09-11T01:14:29.102584Z","steps":["trace[1597487853] 'process raft request' (duration: 137.043851ms)","trace[1597487853] 'compare' (duration: 36.634194ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:14:29.10268Z","caller":"traceutil/trace.go:171","msg":"trace[942872258] linearizableReadLoop","detail":"{readStateIndex:15789; appliedIndex:15788; }","duration":"147.752964ms","start":"2026-09-11T01:14:28.954852Z","end":"2026-09-11T01:14:29.102605Z","steps":["trace[942872258] 'read index received' (duration: 110.695181ms)","trace[942872258] 'applied index is now lower than readState.Index' (duration: 37.055203ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:14:29.113178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.287004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-11T01:14:29.113324Z","caller":"traceutil/trace.go:171","msg":"trace[191492163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15251; }","duration":"158.461658ms","start":"2026-09-11T01:14:28.954798Z","end":"2026-09-11T01:14:29.113259Z","steps":["trace[191492163] 'agreement among raft nodes before linearized reading' (duration: 147.952989ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:14:54.337871Z","caller":"traceutil/trace.go:171","msg":"trace[1122967096] transaction","detail":"{read_only:false; response_revision:15579; number_of_response:1; }","duration":"281.223144ms","start":"2026-09-11T01:14:54.056611Z","end":"2026-09-11T01:14:54.337834Z","steps":["trace[1122967096] 'process raft request' (duration: 224.979204ms)","trace[1122967096] 'compare' (duration: 56.030006ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:14:54.337832Z","caller":"traceutil/trace.go:171","msg":"trace[1555259699] linearizableReadLoop","detail":"{readStateIndex:16130; appliedIndex:16129; }","duration":"187.372847ms","start":"2026-09-11T01:14:54.150432Z","end":"2026-09-11T01:14:54.337804Z","steps":["trace[1555259699] 'read index received' (duration: 131.057545ms)","trace[1555259699] 'applied index is now lower than readState.Index' (duration: 56.314272ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:14:54.338109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.660913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-11T01:14:54.338156Z","caller":"traceutil/trace.go:171","msg":"trace[853002414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15579; }","duration":"187.772545ms","start":"2026-09-11T01:14:54.150368Z","end":"2026-09-11T01:14:54.338141Z","steps":["trace[853002414] 'agreement among raft nodes before linearized reading' (duration: 187.581471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:16:22.516165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.354348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-09-11T01:16:22.516293Z","caller":"traceutil/trace.go:171","msg":"trace[1134763056] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16400; }","duration":"137.518453ms","start":"2026-09-11T01:16:22.378746Z","end":"2026-09-11T01:16:22.516264Z","steps":["trace[1134763056] 'range keys from in-memory index tree' (duration: 136.898828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:16:22.516621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.67811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-09-11T01:16:22.516747Z","caller":"traceutil/trace.go:171","msg":"trace[395823560] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16400; }","duration":"124.844143ms","start":"2026-09-11T01:16:22.391876Z","end":"2026-09-11T01:16:22.51672Z","steps":["trace[395823560] 'range keys from in-memory index tree' (duration: 123.479171ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:16:23.186208Z","caller":"traceutil/trace.go:171","msg":"trace[1593262734] linearizableReadLoop","detail":"{readStateIndex:16974; appliedIndex:16973; }","duration":"193.274776ms","start":"2026-09-11T01:16:22.992904Z","end":"2026-09-11T01:16:23.186178Z","steps":["trace[1593262734] 'read index received' (duration: 192.99742ms)","trace[1593262734] 'applied index is now lower than readState.Index' (duration: 276.226µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:16:23.186282Z","caller":"traceutil/trace.go:171","msg":"trace[1280589391] transaction","detail":"{read_only:false; response_revision:16401; number_of_response:1; }","duration":"206.436048ms","start":"2026-09-11T01:16:22.979816Z","end":"2026-09-11T01:16:23.186252Z","steps":["trace[1280589391] 'process raft request' (duration: 206.186042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:16:23.186503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.574473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-x7q4s\" ","response":"range_response_count:1 size:20641"} {"level":"info","ts":"2026-09-11T01:16:23.186589Z","caller":"traceutil/trace.go:171","msg":"trace[1407871352] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-x7q4s; range_end:; response_count:1; response_revision:16401; }","duration":"193.718316ms","start":"2026-09-11T01:16:22.992846Z","end":"2026-09-11T01:16:23.186564Z","steps":["trace[1407871352] 'agreement among raft nodes before linearized reading' (duration: 193.434339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:16:23.186569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.4336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-x7vtm\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-09-11T01:16:23.186734Z","caller":"traceutil/trace.go:171","msg":"trace[888317814] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-x7vtm; range_end:; response_count:1; response_revision:16401; }","duration":"123.641135ms","start":"2026-09-11T01:16:23.063015Z","end":"2026-09-11T01:16:23.186656Z","steps":["trace[888317814] 'agreement among raft nodes before linearized reading' (duration: 123.321898ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:17:30.653874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14518} {"level":"info","ts":"2026-09-11T01:17:30.779886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14518,"took":"123.40374ms","hash":1983396596,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T01:17:30.780045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1983396596,"revision":14518,"compact-revision":12488} {"level":"info","ts":"2026-09-11T01:19:35.845555Z","caller":"traceutil/trace.go:171","msg":"trace[1035092592] transaction","detail":"{read_only:false; response_revision:17917; number_of_response:1; }","duration":"101.438288ms","start":"2026-09-11T01:19:35.744092Z","end":"2026-09-11T01:19:35.84553Z","steps":["trace[1035092592] 'process raft request' (duration: 101.276264ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:30.662103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16816} {"level":"info","ts":"2026-09-11T01:22:30.766738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16816,"took":"99.876522ms","hash":386772898,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-11T01:22:30.766833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":386772898,"revision":16816,"compact-revision":14518} {"level":"info","ts":"2026-09-11T01:22:48.561232Z","caller":"traceutil/trace.go:171","msg":"trace[1061853951] transaction","detail":"{read_only:false; response_revision:19155; number_of_response:1; }","duration":"162.124505ms","start":"2026-09-11T01:22:48.399065Z","end":"2026-09-11T01:22:48.561189Z","steps":["trace[1061853951] 'process raft request' (duration: 106.989295ms)","trace[1061853951] 'compare' (duration: 54.777722ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:22:56.545544Z","caller":"traceutil/trace.go:171","msg":"trace[775954523] linearizableReadLoop","detail":"{readStateIndex:19890; appliedIndex:19889; }","duration":"173.852543ms","start":"2026-09-11T01:22:56.371672Z","end":"2026-09-11T01:22:56.545525Z","steps":["trace[775954523] 'read index received' (duration: 173.606367ms)","trace[775954523] 'applied index is now lower than readState.Index' (duration: 245.566µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:22:56.545643Z","caller":"traceutil/trace.go:171","msg":"trace[469034123] transaction","detail":"{read_only:false; response_revision:19221; number_of_response:1; }","duration":"307.525417ms","start":"2026-09-11T01:22:56.238109Z","end":"2026-09-11T01:22:56.545635Z","steps":["trace[469034123] 'process raft request' (duration: 307.260311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:22:56.545755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:22:56.238035Z","time spent":"307.628559ms","remote":"127.0.0.1:33566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20860,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:22:56.545926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.268872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-11T01:22:56.545952Z","caller":"traceutil/trace.go:171","msg":"trace[692015892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19221; }","duration":"174.293963ms","start":"2026-09-11T01:22:56.37165Z","end":"2026-09-11T01:22:56.545944Z","steps":["trace[692015892] 'agreement among raft nodes before linearized reading' (duration: 174.203141ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:57.45443Z","caller":"traceutil/trace.go:171","msg":"trace[778987320] transaction","detail":"{read_only:false; response_revision:19223; number_of_response:1; }","duration":"101.12268ms","start":"2026-09-11T01:22:57.353272Z","end":"2026-09-11T01:22:57.454395Z","steps":["trace[778987320] 'process raft request' (duration: 100.301961ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:57.700668Z","caller":"traceutil/trace.go:171","msg":"trace[1027443130] linearizableReadLoop","detail":"{readStateIndex:19893; appliedIndex:19892; }","duration":"191.529007ms","start":"2026-09-11T01:22:57.509095Z","end":"2026-09-11T01:22:57.700624Z","steps":["trace[1027443130] 'read index received' (duration: 169.663397ms)","trace[1027443130] 'applied index is now lower than readState.Index' (duration: 21.8642ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:22:57.700749Z","caller":"traceutil/trace.go:171","msg":"trace[2034535372] transaction","detail":"{read_only:false; response_revision:19224; number_of_response:1; }","duration":"232.342479ms","start":"2026-09-11T01:22:57.468357Z","end":"2026-09-11T01:22:57.7007Z","steps":["trace[2034535372] 'process raft request' (duration: 210.384777ms)","trace[2034535372] 'compare' (duration: 21.753638ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:22:57.700797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.690881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-11T01:22:57.700824Z","caller":"traceutil/trace.go:171","msg":"trace[1542219979] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:19224; }","duration":"191.786743ms","start":"2026-09-11T01:22:57.509029Z","end":"2026-09-11T01:22:57.700816Z","steps":["trace[1542219979] 'agreement among raft nodes before linearized reading' (duration: 191.704751ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:57.77587Z","caller":"traceutil/trace.go:171","msg":"trace[1998448158] transaction","detail":"{read_only:false; response_revision:19225; number_of_response:1; }","duration":"165.959152ms","start":"2026-09-11T01:22:57.609876Z","end":"2026-09-11T01:22:57.775836Z","steps":["trace[1998448158] 'process raft request' (duration: 165.715067ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:57.776197Z","caller":"traceutil/trace.go:171","msg":"trace[1488385990] transaction","detail":"{read_only:false; response_revision:19226; number_of_response:1; }","duration":"162.500663ms","start":"2026-09-11T01:22:57.613682Z","end":"2026-09-11T01:22:57.776182Z","steps":["trace[1488385990] 'process raft request' (duration: 162.098393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:22:57.776348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.681592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-65cpf\" ","response":"range_response_count:1 size:22097"} {"level":"info","ts":"2026-09-11T01:22:57.776437Z","caller":"traceutil/trace.go:171","msg":"trace[597960367] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-65cpf; range_end:; response_count:1; response_revision:19227; }","duration":"139.825595ms","start":"2026-09-11T01:22:57.636594Z","end":"2026-09-11T01:22:57.776419Z","steps":["trace[597960367] 'agreement among raft nodes before linearized reading' (duration: 139.58995ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:22:57.776421Z","caller":"traceutil/trace.go:171","msg":"trace[1588937540] transaction","detail":"{read_only:false; response_revision:19227; number_of_response:1; }","duration":"153.139409ms","start":"2026-09-11T01:22:57.623138Z","end":"2026-09-11T01:22:57.776277Z","steps":["trace[1588937540] 'process raft request' (duration: 152.923144ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:23:04.034249Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:23:04.041338Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-11T01:23:04.041474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-11T01:23:57.942264Z","caller":"traceutil/trace.go:171","msg":"trace[786606461] transaction","detail":"{read_only:false; response_revision:19925; number_of_response:1; }","duration":"127.021604ms","start":"2026-09-11T01:23:57.815196Z","end":"2026-09-11T01:23:57.942218Z","steps":["trace[786606461] 'process raft request' (duration: 126.719388ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.133613Z","caller":"traceutil/trace.go:171","msg":"trace[1132807937] transaction","detail":"{read_only:false; response_revision:21357; number_of_response:1; }","duration":"110.959558ms","start":"2026-09-11T01:27:30.022623Z","end":"2026-09-11T01:27:30.133583Z","steps":["trace[1132807937] 'process raft request' (duration: 110.795715ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.133933Z","caller":"traceutil/trace.go:171","msg":"trace[431822157] transaction","detail":"{read_only:false; number_of_response:1; response_revision:21357; }","duration":"109.693041ms","start":"2026-09-11T01:27:30.024215Z","end":"2026-09-11T01:27:30.133909Z","steps":["trace[431822157] 'process raft request' (duration: 109.328372ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.532677Z","caller":"traceutil/trace.go:171","msg":"trace[555805970] transaction","detail":"{read_only:false; response_revision:21368; number_of_response:1; }","duration":"101.425061ms","start":"2026-09-11T01:27:30.431218Z","end":"2026-09-11T01:27:30.532643Z","steps":["trace[555805970] 'process raft request' (duration: 101.184576ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.5328Z","caller":"traceutil/trace.go:171","msg":"trace[1611882350] transaction","detail":"{read_only:false; response_revision:21369; number_of_response:1; }","duration":"101.475803ms","start":"2026-09-11T01:27:30.431248Z","end":"2026-09-11T01:27:30.532724Z","steps":["trace[1611882350] 'process raft request' (duration: 101.32316ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.532873Z","caller":"traceutil/trace.go:171","msg":"trace[975043647] transaction","detail":"{read_only:false; response_revision:21371; number_of_response:1; }","duration":"101.520024ms","start":"2026-09-11T01:27:30.431323Z","end":"2026-09-11T01:27:30.532843Z","steps":["trace[975043647] 'process raft request' (duration: 101.32162ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.532847Z","caller":"traceutil/trace.go:171","msg":"trace[68869298] transaction","detail":"{read_only:false; response_revision:21370; number_of_response:1; }","duration":"101.501214ms","start":"2026-09-11T01:27:30.431286Z","end":"2026-09-11T01:27:30.532788Z","steps":["trace[68869298] 'process raft request' (duration: 101.32557ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.532874Z","caller":"traceutil/trace.go:171","msg":"trace[1045367319] transaction","detail":"{read_only:false; response_revision:21372; number_of_response:1; }","duration":"101.487303ms","start":"2026-09-11T01:27:30.431349Z","end":"2026-09-11T01:27:30.532836Z","steps":["trace[1045367319] 'process raft request' (duration: 101.325099ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:30.730087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19048} {"level":"warn","ts":"2026-09-11T01:27:31.121344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.253418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-11T01:27:31.121372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.831368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:27:31.121421Z","caller":"traceutil/trace.go:171","msg":"trace[256966325] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:21383; }","duration":"169.386291ms","start":"2026-09-11T01:27:30.952017Z","end":"2026-09-11T01:27:31.121403Z","steps":["trace[256966325] 'count revisions from in-memory index tree' (duration: 169.152785ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.121457Z","caller":"traceutil/trace.go:171","msg":"trace[766863172] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:21383; }","duration":"297.978732ms","start":"2026-09-11T01:27:30.823454Z","end":"2026-09-11T01:27:31.121433Z","steps":["trace[766863172] 'range keys from in-memory index tree' (duration: 297.691575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.121905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.36219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-11T01:27:31.12201Z","caller":"traceutil/trace.go:171","msg":"trace[1315863033] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:21383; }","duration":"298.500183ms","start":"2026-09-11T01:27:30.823482Z","end":"2026-09-11T01:27:31.121982Z","steps":["trace[1315863033] 'range keys from in-memory index tree' (duration: 297.883079ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.179822Z","caller":"traceutil/trace.go:171","msg":"trace[706837337] linearizableReadLoop","detail":"{readStateIndex:22123; appliedIndex:22122; }","duration":"137.737029ms","start":"2026-09-11T01:27:31.042055Z","end":"2026-09-11T01:27:31.179792Z","steps":["trace[706837337] 'read index received' (duration: 92.321674ms)","trace[706837337] 'applied index is now lower than readState.Index' (duration: 45.414005ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:27:31.179855Z","caller":"traceutil/trace.go:171","msg":"trace[145505167] transaction","detail":"{read_only:false; response_revision:21384; number_of_response:1; }","duration":"149.59181ms","start":"2026-09-11T01:27:31.030221Z","end":"2026-09-11T01:27:31.179813Z","steps":["trace[145505167] 'process raft request' (duration: 104.138565ms)","trace[145505167] 'compare' (duration: 45.249381ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:27:31.180018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.928573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-11T01:27:31.180091Z","caller":"traceutil/trace.go:171","msg":"trace[1524947936] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:21384; }","duration":"138.069186ms","start":"2026-09-11T01:27:31.042003Z","end":"2026-09-11T01:27:31.180072Z","steps":["trace[1524947936] 'agreement among raft nodes before linearized reading' (duration: 137.837221ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.246905Z","caller":"traceutil/trace.go:171","msg":"trace[753062450] transaction","detail":"{read_only:false; response_revision:21386; number_of_response:1; }","duration":"144.553755ms","start":"2026-09-11T01:27:31.102322Z","end":"2026-09-11T01:27:31.246876Z","steps":["trace[753062450] 'process raft request' (duration: 144.418842ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.24692Z","caller":"traceutil/trace.go:171","msg":"trace[359633198] transaction","detail":"{read_only:false; response_revision:21385; number_of_response:1; }","duration":"147.531183ms","start":"2026-09-11T01:27:31.099332Z","end":"2026-09-11T01:27:31.246863Z","steps":["trace[359633198] 'process raft request' (duration: 106.219621ms)","trace[359633198] 'compare' (duration: 25.127273ms)","trace[359633198] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 15.876542ms)"],"step_count":3} {"level":"info","ts":"2026-09-11T01:27:31.247003Z","caller":"traceutil/trace.go:171","msg":"trace[304820979] transaction","detail":"{read_only:false; response_revision:21387; number_of_response:1; }","duration":"144.611436ms","start":"2026-09-11T01:27:31.102369Z","end":"2026-09-11T01:27:31.24698Z","steps":["trace[304820979] 'process raft request' (duration: 144.471333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.24717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.581028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-11T01:27:31.247247Z","caller":"traceutil/trace.go:171","msg":"trace[1026330463] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:21387; }","duration":"120.686051ms","start":"2026-09-11T01:27:31.126539Z","end":"2026-09-11T01:27:31.247225Z","steps":["trace[1026330463] 'agreement among raft nodes before linearized reading' (duration: 120.516577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:27:31.24717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.307635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-09-11T01:27:31.2474Z","caller":"traceutil/trace.go:171","msg":"trace[1609369465] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:21387; }","duration":"121.57012ms","start":"2026-09-11T01:27:31.125809Z","end":"2026-09-11T01:27:31.24738Z","steps":["trace[1609369465] 'agreement among raft nodes before linearized reading' (duration: 121.209212ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:27:31.416891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19048,"took":"680.490229ms","hash":2495909852,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":34082816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-11T01:27:31.416971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2495909852,"revision":19048,"compact-revision":16816} {"level":"info","ts":"2026-09-11T01:29:00.301772Z","caller":"traceutil/trace.go:171","msg":"trace[1890268335] transaction","detail":"{read_only:false; response_revision:22021; number_of_response:1; }","duration":"157.69326ms","start":"2026-09-11T01:29:00.144057Z","end":"2026-09-11T01:29:00.30175Z","steps":["trace[1890268335] 'process raft request' (duration: 157.543896ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:00.433375Z","caller":"traceutil/trace.go:171","msg":"trace[77801761] linearizableReadLoop","detail":"{readStateIndex:22784; appliedIndex:22782; }","duration":"180.71092ms","start":"2026-09-11T01:29:00.252629Z","end":"2026-09-11T01:29:00.43334Z","steps":["trace[77801761] 'read index received' (duration: 49.051746ms)","trace[77801761] 'applied index is now lower than readState.Index' (duration: 131.657324ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:29:00.433987Z","caller":"traceutil/trace.go:171","msg":"trace[816961104] transaction","detail":"{read_only:false; response_revision:22022; number_of_response:1; }","duration":"208.395005ms","start":"2026-09-11T01:29:00.225554Z","end":"2026-09-11T01:29:00.433949Z","steps":["trace[816961104] 'process raft request' (duration: 172.102474ms)","trace[816961104] 'compare' (duration: 35.489267ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:29:00.434029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.37605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-11T01:29:00.433971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.219888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-11T01:29:00.434159Z","caller":"traceutil/trace.go:171","msg":"trace[1823722589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22022; }","duration":"181.552215ms","start":"2026-09-11T01:29:00.252585Z","end":"2026-09-11T01:29:00.434137Z","steps":["trace[1823722589] 'agreement among raft nodes before linearized reading' (duration: 181.262847ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:00.434229Z","caller":"traceutil/trace.go:171","msg":"trace[1349578396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22022; }","duration":"164.535867ms","start":"2026-09-11T01:29:00.269669Z","end":"2026-09-11T01:29:00.434205Z","steps":["trace[1349578396] 'agreement among raft nodes before linearized reading' (duration: 164.120095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:29:16.803249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.000581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-11T01:29:16.803336Z","caller":"traceutil/trace.go:171","msg":"trace[2128829941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22107; }","duration":"277.153386ms","start":"2026-09-11T01:29:16.526158Z","end":"2026-09-11T01:29:16.803312Z","steps":["trace[2128829941] 'range keys from in-memory index tree' (duration: 276.798086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:29:16.803104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.78129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:29:16.803426Z","caller":"traceutil/trace.go:171","msg":"trace[1101530459] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:22107; }","duration":"168.15833ms","start":"2026-09-11T01:29:16.635245Z","end":"2026-09-11T01:29:16.803403Z","steps":["trace[1101530459] 'count revisions from in-memory index tree' (duration: 167.672846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:29:16.803546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.59486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-11T01:29:16.803641Z","caller":"traceutil/trace.go:171","msg":"trace[8820283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22107; }","duration":"276.741443ms","start":"2026-09-11T01:29:16.526876Z","end":"2026-09-11T01:29:16.803618Z","steps":["trace[8820283] 'range keys from in-memory index tree' (duration: 276.397224ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.019626Z","caller":"traceutil/trace.go:171","msg":"trace[797616814] transaction","detail":"{read_only:false; response_revision:22108; number_of_response:1; }","duration":"211.527764ms","start":"2026-09-11T01:29:16.808066Z","end":"2026-09-11T01:29:17.019594Z","steps":["trace[797616814] 'process raft request' (duration: 211.352509ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.156103Z","caller":"traceutil/trace.go:171","msg":"trace[1732981965] linearizableReadLoop","detail":"{readStateIndex:22874; appliedIndex:22872; }","duration":"286.282497ms","start":"2026-09-11T01:29:16.869787Z","end":"2026-09-11T01:29:17.15607Z","steps":["trace[1732981965] 'read index received' (duration: 149.610988ms)","trace[1732981965] 'applied index is now lower than readState.Index' (duration: 136.670429ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:29:17.156423Z","caller":"traceutil/trace.go:171","msg":"trace[1794012874] transaction","detail":"{read_only:false; response_revision:22109; number_of_response:1; }","duration":"347.541833ms","start":"2026-09-11T01:29:16.808819Z","end":"2026-09-11T01:29:17.156361Z","steps":["trace[1794012874] 'process raft request' (duration: 305.986171ms)","trace[1794012874] 'compare' (duration: 41.12775ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:29:17.156464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.651148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-11T01:29:17.156644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:29:16.808799Z","time spent":"347.721148ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T01:29:17.156647Z","caller":"traceutil/trace.go:171","msg":"trace[920598191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22109; }","duration":"286.887794ms","start":"2026-09-11T01:29:16.869732Z","end":"2026-09-11T01:29:17.15662Z","steps":["trace[920598191] 'agreement among raft nodes before linearized reading' (duration: 286.553395ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.477526Z","caller":"traceutil/trace.go:171","msg":"trace[810845318] linearizableReadLoop","detail":"{readStateIndex:22875; appliedIndex:22874; }","duration":"163.513898ms","start":"2026-09-11T01:29:17.313981Z","end":"2026-09-11T01:29:17.477495Z","steps":["trace[810845318] 'read index received' (duration: 162.668293ms)","trace[810845318] 'applied index is now lower than readState.Index' (duration: 843.935µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:29:17.477696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.699833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:29:17.477735Z","caller":"traceutil/trace.go:171","msg":"trace[622264258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22110; }","duration":"163.795106ms","start":"2026-09-11T01:29:17.313929Z","end":"2026-09-11T01:29:17.477724Z","steps":["trace[622264258] 'agreement among raft nodes before linearized reading' (duration: 163.669942ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.47796Z","caller":"traceutil/trace.go:171","msg":"trace[1535859149] transaction","detail":"{read_only:false; response_revision:22110; number_of_response:1; }","duration":"313.764615ms","start":"2026-09-11T01:29:17.164181Z","end":"2026-09-11T01:29:17.477946Z","steps":["trace[1535859149] 'process raft request' (duration: 312.475107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:29:17.478086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:29:17.164128Z","time spent":"313.861247ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T01:29:17.846454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.12771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-11T01:29:17.847663Z","caller":"traceutil/trace.go:171","msg":"trace[1271204912] linearizableReadLoop","detail":"{readStateIndex:22878; appliedIndex:22875; }","duration":"148.96947ms","start":"2026-09-11T01:29:17.698672Z","end":"2026-09-11T01:29:17.847642Z","steps":["trace[1271204912] 'read index received' (duration: 32.547712ms)","trace[1271204912] 'applied index is now lower than readState.Index' (duration: 116.420698ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:29:17.847718Z","caller":"traceutil/trace.go:171","msg":"trace[2104356123] transaction","detail":"{read_only:false; response_revision:22113; number_of_response:1; }","duration":"180.110774ms","start":"2026-09-11T01:29:17.667582Z","end":"2026-09-11T01:29:17.847693Z","steps":["trace[2104356123] 'process raft request' (duration: 179.96963ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.847799Z","caller":"traceutil/trace.go:171","msg":"trace[1085355011] transaction","detail":"{read_only:false; response_revision:22112; number_of_response:1; }","duration":"207.447517ms","start":"2026-09-11T01:29:17.640316Z","end":"2026-09-11T01:29:17.847763Z","steps":["trace[1085355011] 'process raft request' (duration: 207.146848ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.847807Z","caller":"traceutil/trace.go:171","msg":"trace[1316696167] transaction","detail":"{read_only:false; response_revision:22114; number_of_response:1; }","duration":"143.966367ms","start":"2026-09-11T01:29:17.703787Z","end":"2026-09-11T01:29:17.847754Z","steps":["trace[1316696167] 'process raft request' (duration: 143.809453ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.847843Z","caller":"traceutil/trace.go:171","msg":"trace[714091062] transaction","detail":"{read_only:false; response_revision:22111; number_of_response:1; }","duration":"240.815504ms","start":"2026-09-11T01:29:17.607Z","end":"2026-09-11T01:29:17.847815Z","steps":["trace[714091062] 'process raft request' (duration: 124.209261ms)","trace[714091062] 'compare' (duration: 114.981886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T01:29:17.84796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.863069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-11T01:29:17.847977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.695719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-11T01:29:17.848009Z","caller":"traceutil/trace.go:171","msg":"trace[1290356570] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:22114; }","duration":"125.953541ms","start":"2026-09-11T01:29:17.72204Z","end":"2026-09-11T01:29:17.847994Z","steps":["trace[1290356570] 'agreement among raft nodes before linearized reading' (duration: 125.862839ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:29:17.848041Z","caller":"traceutil/trace.go:171","msg":"trace[630897943] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:22114; }","duration":"114.811622ms","start":"2026-09-11T01:29:17.73321Z","end":"2026-09-11T01:29:17.848022Z","steps":["trace[630897943] 'agreement among raft nodes before linearized reading' (duration: 114.704419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:29:17.847957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.275039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-11T01:29:17.84814Z","caller":"traceutil/trace.go:171","msg":"trace[22446080] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:22114; }","duration":"149.501296ms","start":"2026-09-11T01:29:17.69862Z","end":"2026-09-11T01:29:17.848121Z","steps":["trace[22446080] 'agreement among raft nodes before linearized reading' (duration: 149.229998ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:32:30.740178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21379} {"level":"info","ts":"2026-09-11T01:32:30.867074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21379,"took":"123.010749ms","hash":2397172518,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":30093312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T01:32:30.867148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2397172518,"revision":21379,"compact-revision":19048} {"level":"info","ts":"2026-09-11T01:33:36.66999Z","caller":"traceutil/trace.go:171","msg":"trace[219192296] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"245.166759ms","start":"2026-09-11T01:33:36.424788Z","end":"2026-09-11T01:33:36.669955Z","steps":["trace[219192296] 'process raft request' (duration: 244.833661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:33:37.118525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.764408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-11T01:33:37.118601Z","caller":"traceutil/trace.go:171","msg":"trace[1067089357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24689; }","duration":"374.890411ms","start":"2026-09-11T01:33:36.743694Z","end":"2026-09-11T01:33:37.118584Z","steps":["trace[1067089357] 'range keys from in-memory index tree' (duration: 374.545564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:33:37.118637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T01:33:36.743666Z","time spent":"374.962463ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-11T01:33:37.141761Z","caller":"traceutil/trace.go:171","msg":"trace[880389009] linearizableReadLoop","detail":"{readStateIndex:25531; appliedIndex:25530; }","duration":"109.149011ms","start":"2026-09-11T01:33:37.032572Z","end":"2026-09-11T01:33:37.141721Z","steps":["trace[880389009] 'read index received' (duration: 108.893516ms)","trace[880389009] 'applied index is now lower than readState.Index' (duration: 253.535µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:33:37.141919Z","caller":"traceutil/trace.go:171","msg":"trace[455004460] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"121.73248ms","start":"2026-09-11T01:33:37.020138Z","end":"2026-09-11T01:33:37.141871Z","steps":["trace[455004460] 'process raft request' (duration: 121.29214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:33:37.14208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.468809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-11T01:33:37.14214Z","caller":"traceutil/trace.go:171","msg":"trace[783543892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24690; }","duration":"109.585162ms","start":"2026-09-11T01:33:37.032539Z","end":"2026-09-11T01:33:37.142125Z","steps":["trace[783543892] 'agreement among raft nodes before linearized reading' (duration: 109.328366ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:34:29.562827Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006629.wal"} {"level":"info","ts":"2026-09-11T01:34:47.648508Z","caller":"traceutil/trace.go:171","msg":"trace[1066927276] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"208.53763ms","start":"2026-09-11T01:34:47.439945Z","end":"2026-09-11T01:34:47.648483Z","steps":["trace[1066927276] 'process raft request' (duration: 165.947024ms)","trace[1066927276] 'compare' (duration: 42.368831ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:34:47.648882Z","caller":"traceutil/trace.go:171","msg":"trace[697646924] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"196.334039ms","start":"2026-09-11T01:34:47.452529Z","end":"2026-09-11T01:34:47.648863Z","steps":["trace[697646924] 'process raft request' (duration: 195.886689ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:34:47.648881Z","caller":"traceutil/trace.go:171","msg":"trace[1590544700] linearizableReadLoop","detail":"{readStateIndex:26351; appliedIndex:26348; }","duration":"107.747488ms","start":"2026-09-11T01:34:47.541107Z","end":"2026-09-11T01:34:47.648854Z","steps":["trace[1590544700] 'read index received' (duration: 64.880896ms)","trace[1590544700] 'applied index is now lower than readState.Index' (duration: 42.865422ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T01:34:47.648928Z","caller":"traceutil/trace.go:171","msg":"trace[634562702] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"195.03273ms","start":"2026-09-11T01:34:47.45386Z","end":"2026-09-11T01:34:47.648893Z","steps":["trace[634562702] 'process raft request' (duration: 194.890537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T01:34:47.649077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.951663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-11T01:34:47.649132Z","caller":"traceutil/trace.go:171","msg":"trace[1370223448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25491; }","duration":"108.052905ms","start":"2026-09-11T01:34:47.541067Z","end":"2026-09-11T01:34:47.649119Z","steps":["trace[1370223448] 'agreement among raft nodes before linearized reading' (duration: 107.861311ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T01:37:30.746901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23602} {"level":"info","ts":"2026-09-11T01:37:30.871825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23602,"took":"121.049224ms","hash":2877450952,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":42946560,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-11T01:37:30.871907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2877450952,"revision":23602,"compact-revision":21379} {"level":"info","ts":"2026-09-11T01:42:30.755346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26747} {"level":"info","ts":"2026-09-11T01:42:30.900668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26747,"took":"141.428571ms","hash":3628229647,"current-db-size-bytes":54837248,"current-db-size":"55 MB","current-db-size-in-use-bytes":30183424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-11T01:42:30.900724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3628229647,"revision":26747,"compact-revision":23602} {"level":"info","ts":"2026-09-11T01:42:39.243586Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-11T01:42:39.250271Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-11T01:42:39.250502Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}