{"level":"warn","ts":"2026-04-17T15:10:35.559905Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T15:10:35.560071Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-04-17T15:10:35.560193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T15:10:35.560218Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-17T15:10:35.560252Z","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-04-17T15:10:35.561431Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-17T15:10:35.561577Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-04-17T15:10:35.566594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.454109ms"} {"level":"info","ts":"2026-04-17T15:10:35.573183Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-17T15:10:35.573318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T15:10:35.573359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-17T15:10:35.573401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T15:10:35.573435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-17T15:10:35.57349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-17T15:10:35.584814Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T15:10:35.586159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T15:10:35.587125Z","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-04-17T15:10:35.589464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T15:10:35.589684Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T15:10:35.589794Z","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-04-17T15:10:35.58985Z","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-04-17T15:10:35.589862Z","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-04-17T15:10:35.590457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-17T15:10:35.590721Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-17T15:10:35.597702Z","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-04-17T15:10:35.59778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-17T15:10:35.597839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-17T15:10:35.598027Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T15:10:35.598084Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T15:10:36.574818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T15:10:36.574913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T15:10:36.574941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-17T15:10:36.574961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-17T15:10:36.574973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-17T15:10:36.57499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-17T15:10:36.575005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-17T15:10:36.576193Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:10:36.57706Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T15:10:36.577064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T15:10:36.5771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T15:10:36.577517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T15:10:36.577626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T15:10:36.577766Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:10:36.577936Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:10:36.577987Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T15:10:36.580426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T15:10:36.581526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-17T15:10:52.352621Z","caller":"traceutil/trace.go:171","msg":"trace[260109617] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"141.799835ms","start":"2026-04-17T15:10:52.210795Z","end":"2026-04-17T15:10:52.352595Z","steps":["trace[260109617] 'process raft request' (duration: 141.180748ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:10:52.788811Z","caller":"traceutil/trace.go:171","msg":"trace[113728329] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:426; }","duration":"407.590545ms","start":"2026-04-17T15:10:52.38118Z","end":"2026-04-17T15:10:52.78877Z","steps":["trace[113728329] 'read index received' (duration: 68.169414ms)","trace[113728329] 'applied index is now lower than readState.Index' (duration: 339.419611ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:10:52.78885Z","caller":"traceutil/trace.go:171","msg":"trace[1365515134] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"417.900632ms","start":"2026-04-17T15:10:52.370911Z","end":"2026-04-17T15:10:52.788812Z","steps":["trace[1365515134] 'process raft request' (duration: 417.684176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:10:52.789003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.809171ms","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-04-17T15:10:52.789155Z","caller":"traceutil/trace.go:171","msg":"trace[1034116286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"407.983725ms","start":"2026-04-17T15:10:52.381131Z","end":"2026-04-17T15:10:52.789115Z","steps":["trace[1034116286] 'agreement among raft nodes before linearized reading' (duration: 407.752329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:10:52.789192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:10:52.381116Z","time spent":"408.068447ms","remote":"127.0.0.1:42042","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":"warn","ts":"2026-04-17T15:10:52.78944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:10:52.370882Z","time spent":"418.043356ms","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:10:52.959171Z","caller":"traceutil/trace.go:171","msg":"trace[1377409785] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"166.208222ms","start":"2026-04-17T15:10:52.792932Z","end":"2026-04-17T15:10:52.95914Z","steps":["trace[1377409785] 'process raft request' (duration: 166.049887ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:10:52.960101Z","caller":"traceutil/trace.go:171","msg":"trace[1681652870] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"164.535757ms","start":"2026-04-17T15:10:52.795536Z","end":"2026-04-17T15:10:52.960072Z","steps":["trace[1681652870] 'process raft request' (duration: 164.428874ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.173296Z","caller":"traceutil/trace.go:171","msg":"trace[1729560991] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"155.38906ms","start":"2026-04-17T15:11:06.017857Z","end":"2026-04-17T15:11:06.173246Z","steps":["trace[1729560991] 'process raft request' (duration: 155.128433ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180684Z","caller":"traceutil/trace.go:171","msg":"trace[1642519537] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"159.675856ms","start":"2026-04-17T15:11:06.020981Z","end":"2026-04-17T15:11:06.180657Z","steps":["trace[1642519537] 'process raft request' (duration: 159.524861ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180746Z","caller":"traceutil/trace.go:171","msg":"trace[110468798] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"159.579243ms","start":"2026-04-17T15:11:06.021136Z","end":"2026-04-17T15:11:06.180715Z","steps":["trace[110468798] 'process raft request' (duration: 159.432069ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180867Z","caller":"traceutil/trace.go:171","msg":"trace[1850401904] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"149.599445ms","start":"2026-04-17T15:11:06.031215Z","end":"2026-04-17T15:11:06.180815Z","steps":["trace[1850401904] 'process raft request' (duration: 149.42984ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180946Z","caller":"traceutil/trace.go:171","msg":"trace[1035979997] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"142.807532ms","start":"2026-04-17T15:11:06.038128Z","end":"2026-04-17T15:11:06.180936Z","steps":["trace[1035979997] 'process raft request' (duration: 142.71397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180958Z","caller":"traceutil/trace.go:171","msg":"trace[844645727] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"151.307361ms","start":"2026-04-17T15:11:06.029618Z","end":"2026-04-17T15:11:06.180926Z","steps":["trace[844645727] 'process raft request' (duration: 150.991662ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180878Z","caller":"traceutil/trace.go:171","msg":"trace[332091994] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"147.704824ms","start":"2026-04-17T15:11:06.033144Z","end":"2026-04-17T15:11:06.180849Z","steps":["trace[332091994] 'process raft request' (duration: 147.57326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.181023Z","caller":"traceutil/trace.go:171","msg":"trace[1385304438] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"133.020828ms","start":"2026-04-17T15:11:06.047995Z","end":"2026-04-17T15:11:06.181016Z","steps":["trace[1385304438] 'process raft request' (duration: 132.898705ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.180854Z","caller":"traceutil/trace.go:171","msg":"trace[5084401] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"149.198394ms","start":"2026-04-17T15:11:06.031619Z","end":"2026-04-17T15:11:06.180817Z","steps":["trace[5084401] 'process raft request' (duration: 149.06347ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.18105Z","caller":"traceutil/trace.go:171","msg":"trace[1748367056] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"102.412026ms","start":"2026-04-17T15:11:06.07862Z","end":"2026-04-17T15:11:06.181032Z","steps":["trace[1748367056] 'process raft request' (duration: 102.324193ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:06.181169Z","caller":"traceutil/trace.go:171","msg":"trace[317141759] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"147.626352ms","start":"2026-04-17T15:11:06.033294Z","end":"2026-04-17T15:11:06.18092Z","steps":["trace[317141759] 'process raft request' (duration: 147.458697ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:12.537188Z","caller":"traceutil/trace.go:171","msg":"trace[353230357] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"363.354834ms","start":"2026-04-17T15:11:12.173801Z","end":"2026-04-17T15:11:12.537156Z","steps":["trace[353230357] 'read index received' (duration: 363.19752ms)","trace[353230357] 'applied index is now lower than readState.Index' (duration: 156.064µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:11:12.537279Z","caller":"traceutil/trace.go:171","msg":"trace[1549917596] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"381.021349ms","start":"2026-04-17T15:11:12.156206Z","end":"2026-04-17T15:11:12.537227Z","steps":["trace[1549917596] 'process raft request' (duration: 380.759662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:12.537407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.595031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:11:12.537456Z","caller":"traceutil/trace.go:171","msg":"trace[793351962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"363.716684ms","start":"2026-04-17T15:11:12.173725Z","end":"2026-04-17T15:11:12.537442Z","steps":["trace[793351962] 'agreement among raft nodes before linearized reading' (duration: 363.597121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:12.537463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:12.156175Z","time spent":"381.193554ms","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T15:11:12.537474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.591562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5gsv2\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-17T15:11:12.537537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:12.173701Z","time spent":"363.825287ms","remote":"127.0.0.1:41752","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-04-17T15:11:12.537562Z","caller":"traceutil/trace.go:171","msg":"trace[1100263523] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5gsv2; range_end:; response_count:1; response_revision:615; }","duration":"298.722835ms","start":"2026-04-17T15:11:12.238815Z","end":"2026-04-17T15:11:12.537538Z","steps":["trace[1100263523] 'agreement among raft nodes before linearized reading' (duration: 298.543941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:12.537759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.287601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T15:11:12.537913Z","caller":"traceutil/trace.go:171","msg":"trace[1196647653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"231.554528ms","start":"2026-04-17T15:11:12.306322Z","end":"2026-04-17T15:11:12.537877Z","steps":["trace[1196647653] 'agreement among raft nodes before linearized reading' (duration: 231.179678ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:12.803581Z","caller":"traceutil/trace.go:171","msg":"trace[1339822938] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"236.05311ms","start":"2026-04-17T15:11:12.567497Z","end":"2026-04-17T15:11:12.803551Z","steps":["trace[1339822938] 'read index received' (duration: 228.345652ms)","trace[1339822938] 'applied index is now lower than readState.Index' (duration: 7.705738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:11:12.803749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.661208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:11:12.803818Z","caller":"traceutil/trace.go:171","msg":"trace[1666777263] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"255.780811ms","start":"2026-04-17T15:11:12.54802Z","end":"2026-04-17T15:11:12.8038Z","steps":["trace[1666777263] 'agreement among raft nodes before linearized reading' (duration: 255.623167ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:11:12.803638Z","caller":"traceutil/trace.go:171","msg":"trace[1549883106] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"251.215238ms","start":"2026-04-17T15:11:12.552382Z","end":"2026-04-17T15:11:12.803598Z","steps":["trace[1549883106] 'process raft request' (duration: 243.436159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:13.490945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.532302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:11:13.491067Z","caller":"traceutil/trace.go:171","msg":"trace[362154766] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"436.119043ms","start":"2026-04-17T15:11:13.054925Z","end":"2026-04-17T15:11:13.491044Z","steps":["trace[362154766] 'read index received' (duration: 61.032µs)","trace[362154766] 'applied index is now lower than readState.Index' (duration: 436.056441ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:11:13.491089Z","caller":"traceutil/trace.go:171","msg":"trace[1837116423] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"919.852856ms","start":"2026-04-17T15:11:12.571199Z","end":"2026-04-17T15:11:13.491052Z","steps":["trace[1837116423] 'process raft request' (duration: 420.952855ms)","trace[1837116423] 'compare' (duration: 498.402078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:11:13.491268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.344169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-17T15:11:13.491303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:12.571177Z","time spent":"919.984949ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:11:13.49133Z","caller":"traceutil/trace.go:171","msg":"trace[632695637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"436.440551ms","start":"2026-04-17T15:11:13.054871Z","end":"2026-04-17T15:11:13.491311Z","steps":["trace[632695637] 'agreement among raft nodes before linearized reading' (duration: 436.264847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:13.491379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:13.054848Z","time spent":"436.517464ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T15:11:13.559576Z","caller":"traceutil/trace.go:171","msg":"trace[465274035] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"349.78569ms","start":"2026-04-17T15:11:13.20976Z","end":"2026-04-17T15:11:13.559546Z","steps":["trace[465274035] 'process raft request' (duration: 349.095172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:13.55967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.413873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:11:13.559727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:13.209736Z","time spent":"349.916544ms","remote":"127.0.0.1:41876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:11:13.559731Z","caller":"traceutil/trace.go:171","msg":"trace[469825925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"384.495495ms","start":"2026-04-17T15:11:13.175219Z","end":"2026-04-17T15:11:13.559714Z","steps":["trace[469825925] 'agreement among raft nodes before linearized reading' (duration: 384.33262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:11:13.559792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:13.175143Z","time spent":"384.638709ms","remote":"127.0.0.1:41752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T15:11:30.225838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.285947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:11:30.225945Z","caller":"traceutil/trace.go:171","msg":"trace[154652161] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"585.532047ms","start":"2026-04-17T15:11:29.640386Z","end":"2026-04-17T15:11:30.225918Z","steps":["trace[154652161] 'process raft request' (duration: 466.90743ms)","trace[154652161] 'compare' (duration: 116.746756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:11:30.226155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:11:29.640354Z","time spent":"585.728832ms","remote":"127.0.0.1:42042","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-04-17T15:11:55.845157Z","caller":"traceutil/trace.go:171","msg":"trace[1115374174] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"134.910395ms","start":"2026-04-17T15:11:55.710218Z","end":"2026-04-17T15:11:55.845128Z","steps":["trace[1115374174] 'process raft request' (duration: 134.74353ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:17.835328Z","caller":"traceutil/trace.go:171","msg":"trace[1290917040] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"203.916324ms","start":"2026-04-17T15:12:17.631388Z","end":"2026-04-17T15:12:17.835305Z","steps":["trace[1290917040] 'process raft request' (duration: 203.787581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:42.676265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811323858437006,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T15:12:42.682096Z","caller":"traceutil/trace.go:171","msg":"trace[850627038] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"564.140796ms","start":"2026-04-17T15:12:42.117927Z","end":"2026-04-17T15:12:42.682068Z","steps":["trace[850627038] 'process raft request' (duration: 563.959391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:42.682261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:42.117908Z","time spent":"564.250578ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:12:42.689453Z","caller":"traceutil/trace.go:171","msg":"trace[414381135] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1917; }","duration":"514.18695ms","start":"2026-04-17T15:12:42.175249Z","end":"2026-04-17T15:12:42.689436Z","steps":["trace[414381135] 'read index received' (duration: 506.624566ms)","trace[414381135] 'applied index is now lower than readState.Index' (duration: 7.561984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:12:42.689577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.228825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:12:42.689637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.124089ms","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-04-17T15:12:42.68985Z","caller":"traceutil/trace.go:171","msg":"trace[2036816541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"331.369586ms","start":"2026-04-17T15:12:42.358461Z","end":"2026-04-17T15:12:42.689831Z","steps":["trace[2036816541] 'agreement among raft nodes before linearized reading' (duration: 331.061097ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:42.68985Z","caller":"traceutil/trace.go:171","msg":"trace[586221916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"516.456481ms","start":"2026-04-17T15:12:42.173288Z","end":"2026-04-17T15:12:42.689745Z","steps":["trace[586221916] 'agreement among raft nodes before linearized reading' (duration: 516.203485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:42.68987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.882668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T15:12:42.689899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:42.358447Z","time spent":"331.440638ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T15:12:42.689912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:42.173277Z","time spent":"516.620616ms","remote":"127.0.0.1:41752","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-04-17T15:12:42.689972Z","caller":"traceutil/trace.go:171","msg":"trace[1297930617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"117.012332ms","start":"2026-04-17T15:12:42.57291Z","end":"2026-04-17T15:12:42.689922Z","steps":["trace[1297930617] 'agreement among raft nodes before linearized reading' (duration: 116.694974ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:42.690076Z","caller":"traceutil/trace.go:171","msg":"trace[1295108698] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"470.703278ms","start":"2026-04-17T15:12:42.219331Z","end":"2026-04-17T15:12:42.690034Z","steps":["trace[1295108698] 'process raft request' (duration: 470.04232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:42.690404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:42.219281Z","time spent":"470.860833ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:12:42.892149Z","caller":"traceutil/trace.go:171","msg":"trace[2059086086] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"165.817006ms","start":"2026-04-17T15:12:42.726293Z","end":"2026-04-17T15:12:42.89211Z","steps":["trace[2059086086] 'process raft request' (duration: 165.630421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:43.416131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.450725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-17T15:12:43.416162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.192496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T15:12:43.416215Z","caller":"traceutil/trace.go:171","msg":"trace[1774648674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1853; }","duration":"433.587649ms","start":"2026-04-17T15:12:42.982608Z","end":"2026-04-17T15:12:43.416195Z","steps":["trace[1774648674] 'range keys from in-memory index tree' (duration: 433.2543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:43.416258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:42.982582Z","time spent":"433.667321ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T15:12:43.41624Z","caller":"traceutil/trace.go:171","msg":"trace[1948288451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"411.34164ms","start":"2026-04-17T15:12:43.004877Z","end":"2026-04-17T15:12:43.416219Z","steps":["trace[1948288451] 'range keys from in-memory index tree' (duration: 411.004201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:43.416309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:43.004852Z","time spent":"411.445942ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T15:12:43.423995Z","caller":"traceutil/trace.go:171","msg":"trace[1874760093] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"249.510981ms","start":"2026-04-17T15:12:43.174462Z","end":"2026-04-17T15:12:43.423973Z","steps":["trace[1874760093] 'read index received' (duration: 249.378048ms)","trace[1874760093] 'applied index is now lower than readState.Index' (duration: 131.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:12:43.424085Z","caller":"traceutil/trace.go:171","msg":"trace[1301807858] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"377.124428ms","start":"2026-04-17T15:12:43.046949Z","end":"2026-04-17T15:12:43.424074Z","steps":["trace[1301807858] 'process raft request' (duration: 376.893872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:43.424233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:43.046915Z","time spent":"377.19292ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:12:43.42428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.813289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:12:43.424347Z","caller":"traceutil/trace.go:171","msg":"trace[538017109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"249.932353ms","start":"2026-04-17T15:12:43.1744Z","end":"2026-04-17T15:12:43.424332Z","steps":["trace[538017109] 'agreement among raft nodes before linearized reading' (duration: 249.81703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:43.429517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.623134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82a926e7-9063-448d-9e56-6f2d3b6d3398\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-04-17T15:12:43.429615Z","caller":"traceutil/trace.go:171","msg":"trace[1516454366] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82a926e7-9063-448d-9e56-6f2d3b6d3398; range_end:; response_count:1; response_revision:1858; }","duration":"249.751367ms","start":"2026-04-17T15:12:43.179836Z","end":"2026-04-17T15:12:43.429588Z","steps":["trace[1516454366] 'agreement among raft nodes before linearized reading' (duration: 249.496761ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:50.791699Z","caller":"traceutil/trace.go:171","msg":"trace[566768048] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"113.353834ms","start":"2026-04-17T15:12:50.678314Z","end":"2026-04-17T15:12:50.791668Z","steps":["trace[566768048] 'process raft request' (duration: 113.164458ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:50.817702Z","caller":"traceutil/trace.go:171","msg":"trace[1978581101] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"111.966985ms","start":"2026-04-17T15:12:50.705699Z","end":"2026-04-17T15:12:50.817666Z","steps":["trace[1978581101] 'process raft request' (duration: 111.710299ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:50.817809Z","caller":"traceutil/trace.go:171","msg":"trace[1862181398] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"101.944206ms","start":"2026-04-17T15:12:50.715842Z","end":"2026-04-17T15:12:50.817786Z","steps":["trace[1862181398] 'process raft request' (duration: 101.73844ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:50.939056Z","caller":"traceutil/trace.go:171","msg":"trace[1186255768] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"116.317313ms","start":"2026-04-17T15:12:50.822705Z","end":"2026-04-17T15:12:50.939022Z","steps":["trace[1186255768] 'process raft request' (duration: 116.079097ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:12:56.02203Z","caller":"traceutil/trace.go:171","msg":"trace[1286681072] linearizableReadLoop","detail":"{readStateIndex:2028; appliedIndex:2028; }","duration":"490.653496ms","start":"2026-04-17T15:12:55.531356Z","end":"2026-04-17T15:12:56.022009Z","steps":["trace[1286681072] 'read index received' (duration: 490.645786ms)","trace[1286681072] 'applied index is now lower than readState.Index' (duration: 6.6µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:12:56.022029Z","caller":"traceutil/trace.go:171","msg":"trace[445955775] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"497.366816ms","start":"2026-04-17T15:12:55.524647Z","end":"2026-04-17T15:12:56.022013Z","steps":["trace[445955775] 'process raft request' (duration: 497.238333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:56.022227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:55.524615Z","time spent":"497.528361ms","remote":"127.0.0.1:42042","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-04-17T15:12:56.022365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.954284ms","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-04-17T15:12:56.022483Z","caller":"traceutil/trace.go:171","msg":"trace[1612415502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1956; }","duration":"491.147319ms","start":"2026-04-17T15:12:55.531308Z","end":"2026-04-17T15:12:56.022456Z","steps":["trace[1612415502] 'agreement among raft nodes before linearized reading' (duration: 490.81738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:56.022604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:55.531288Z","time spent":"491.287714ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T15:12:56.051921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.52552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T15:12:56.052Z","caller":"traceutil/trace.go:171","msg":"trace[1414700571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1957; }","duration":"520.636874ms","start":"2026-04-17T15:12:55.531345Z","end":"2026-04-17T15:12:56.051982Z","steps":["trace[1414700571] 'agreement among raft nodes before linearized reading' (duration: 520.374537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:56.052049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:55.531334Z","time spent":"520.706475ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T15:12:56.052075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.604043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T15:12:56.052178Z","caller":"traceutil/trace.go:171","msg":"trace[270375893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1957; }","duration":"520.763988ms","start":"2026-04-17T15:12:55.531392Z","end":"2026-04-17T15:12:56.052156Z","steps":["trace[270375893] 'agreement among raft nodes before linearized reading' (duration: 520.465479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:12:56.052231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:12:55.531369Z","time spent":"520.851949ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T15:13:27.265363Z","caller":"traceutil/trace.go:171","msg":"trace[1084638295] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"121.943103ms","start":"2026-04-17T15:13:27.143386Z","end":"2026-04-17T15:13:27.265329Z","steps":["trace[1084638295] 'process raft request' (duration: 121.639964ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:13:33.102096Z","caller":"traceutil/trace.go:171","msg":"trace[666691462] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"107.884594ms","start":"2026-04-17T15:13:32.99419Z","end":"2026-04-17T15:13:33.102075Z","steps":["trace[666691462] 'process raft request' (duration: 107.76528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:13:33.405723Z","caller":"traceutil/trace.go:171","msg":"trace[748693361] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"114.04107ms","start":"2026-04-17T15:13:33.291646Z","end":"2026-04-17T15:13:33.405687Z","steps":["trace[748693361] 'process raft request' (duration: 113.857445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:13:33.918329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.542996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-17T15:13:33.919539Z","caller":"traceutil/trace.go:171","msg":"trace[1060924149] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2175; }","duration":"218.834111ms","start":"2026-04-17T15:13:33.700672Z","end":"2026-04-17T15:13:33.919506Z","steps":["trace[1060924149] 'range keys from in-memory index tree' (duration: 217.191886ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:13:34.077295Z","caller":"traceutil/trace.go:171","msg":"trace[516251818] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"152.854604ms","start":"2026-04-17T15:13:33.924413Z","end":"2026-04-17T15:13:34.077267Z","steps":["trace[516251818] 'process raft request' (duration: 152.681779ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:14:58.837321Z","caller":"traceutil/trace.go:171","msg":"trace[1722011094] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"191.365802ms","start":"2026-04-17T15:14:58.645934Z","end":"2026-04-17T15:14:58.8373Z","steps":["trace[1722011094] 'process raft request' (duration: 169.498975ms)","trace[1722011094] 'compare' (duration: 21.768885ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:14:58.858021Z","caller":"traceutil/trace.go:171","msg":"trace[1223573473] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2935; }","duration":"208.335468ms","start":"2026-04-17T15:14:58.649667Z","end":"2026-04-17T15:14:58.858002Z","steps":["trace[1223573473] 'read index received' (duration: 165.741153ms)","trace[1223573473] 'applied index is now lower than readState.Index' (duration: 42.593495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:14:58.858169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.073696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:14:58.858128Z","caller":"traceutil/trace.go:171","msg":"trace[1323252062] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"209.54491ms","start":"2026-04-17T15:14:58.648556Z","end":"2026-04-17T15:14:58.858101Z","steps":["trace[1323252062] 'process raft request' (duration: 209.355505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:14:58.858217Z","caller":"traceutil/trace.go:171","msg":"trace[1620994063] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2831; }","duration":"165.134967ms","start":"2026-04-17T15:14:58.693071Z","end":"2026-04-17T15:14:58.858206Z","steps":["trace[1620994063] 'agreement among raft nodes before linearized reading' (duration: 165.052725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:14:58.858345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.658357ms","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-04-17T15:14:58.858426Z","caller":"traceutil/trace.go:171","msg":"trace[1811824808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2831; }","duration":"208.811861ms","start":"2026-04-17T15:14:58.649593Z","end":"2026-04-17T15:14:58.858405Z","steps":["trace[1811824808] 'agreement among raft nodes before linearized reading' (duration: 208.576584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:14:59.302773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.202425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:14:59.302863Z","caller":"traceutil/trace.go:171","msg":"trace[1053535833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2833; }","duration":"128.344308ms","start":"2026-04-17T15:14:59.174499Z","end":"2026-04-17T15:14:59.302843Z","steps":["trace[1053535833] 'range keys from in-memory index tree' (duration: 128.126333ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:14:59.514377Z","caller":"traceutil/trace.go:171","msg":"trace[1651516220] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"150.866924ms","start":"2026-04-17T15:14:59.363484Z","end":"2026-04-17T15:14:59.514351Z","steps":["trace[1651516220] 'process raft request' (duration: 150.709629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:14:59.515968Z","caller":"traceutil/trace.go:171","msg":"trace[280691459] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"145.101049ms","start":"2026-04-17T15:14:59.370809Z","end":"2026-04-17T15:14:59.51591Z","steps":["trace[280691459] 'process raft request' (duration: 144.801771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:44.564705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.529999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9e899f5c-ed3e-445c-b91c-f1e5121ee5fc\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-17T15:18:44.564792Z","caller":"traceutil/trace.go:171","msg":"trace[1623587391] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9e899f5c-ed3e-445c-b91c-f1e5121ee5fc; range_end:; response_count:1; response_revision:4768; }","duration":"119.657392ms","start":"2026-04-17T15:18:44.445117Z","end":"2026-04-17T15:18:44.564774Z","steps":["trace[1623587391] 'range keys from in-memory index tree' (duration: 119.369754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.505021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.647447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:18:50.505164Z","caller":"traceutil/trace.go:171","msg":"trace[143514826] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"213.549502ms","start":"2026-04-17T15:18:50.291594Z","end":"2026-04-17T15:18:50.505144Z","steps":["trace[143514826] 'process raft request' (duration: 14.70282ms)","trace[143514826] 'compare' (duration: 197.921117ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:18:50.538401Z","caller":"traceutil/trace.go:171","msg":"trace[610451037] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"230.994897ms","start":"2026-04-17T15:18:50.307378Z","end":"2026-04-17T15:18:50.538373Z","steps":["trace[610451037] 'process raft request' (duration: 230.790412ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:50.730277Z","caller":"traceutil/trace.go:171","msg":"trace[204869064] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"401.922688ms","start":"2026-04-17T15:18:50.328319Z","end":"2026-04-17T15:18:50.730241Z","steps":["trace[204869064] 'process raft request' (duration: 396.899971ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:50.730303Z","caller":"traceutil/trace.go:171","msg":"trace[603289036] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"362.918254ms","start":"2026-04-17T15:18:50.367356Z","end":"2026-04-17T15:18:50.730274Z","steps":["trace[603289036] 'process raft request' (duration: 362.777731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.730448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:18:50.328299Z","time spent":"402.061842ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:18:50.730491Z","caller":"traceutil/trace.go:171","msg":"trace[653301443] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5176; }","duration":"298.849137ms","start":"2026-04-17T15:18:50.431599Z","end":"2026-04-17T15:18:50.730449Z","steps":["trace[653301443] 'read index received' (duration: 106.577965ms)","trace[653301443] 'applied index is now lower than readState.Index' (duration: 192.268722ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:18:50.730501Z","caller":"traceutil/trace.go:171","msg":"trace[142595002] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"206.965223ms","start":"2026-04-17T15:18:50.5235Z","end":"2026-04-17T15:18:50.730465Z","steps":["trace[142595002] 'process raft request' (duration: 206.676745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.730488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:18:50.367331Z","time spent":"363.057238ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:18:50.730739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.112694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c80a35a8-156f-4a77-9609-7c6427ffeb2e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-17T15:18:50.730812Z","caller":"traceutil/trace.go:171","msg":"trace[923913537] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c80a35a8-156f-4a77-9609-7c6427ffeb2e; range_end:; response_count:1; response_revision:4992; }","duration":"299.221088ms","start":"2026-04-17T15:18:50.431572Z","end":"2026-04-17T15:18:50.730793Z","steps":["trace[923913537] 'agreement among raft nodes before linearized reading' (duration: 299.019622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.73082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.164514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c80a35a8-156f-4a77-9609-7c6427ffeb2e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-17T15:18:50.73088Z","caller":"traceutil/trace.go:171","msg":"trace[810291430] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c80a35a8-156f-4a77-9609-7c6427ffeb2e; range_end:; response_count:1; response_revision:4992; }","duration":"205.253057ms","start":"2026-04-17T15:18:50.525609Z","end":"2026-04-17T15:18:50.730862Z","steps":["trace[810291430] 'agreement among raft nodes before linearized reading' (duration: 205.053981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.730895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.863644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9d9d-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-04-17T15:18:50.730969Z","caller":"traceutil/trace.go:171","msg":"trace[709625410] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-s9d9d-tls; range_end:; response_count:1; response_revision:4992; }","duration":"190.947837ms","start":"2026-04-17T15:18:50.539993Z","end":"2026-04-17T15:18:50.730941Z","steps":["trace[709625410] 'agreement among raft nodes before linearized reading' (duration: 190.737151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:18:50.731339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.712238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777662"} {"level":"info","ts":"2026-04-17T15:18:50.731395Z","caller":"traceutil/trace.go:171","msg":"trace[322188559] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4992; }","duration":"132.7712ms","start":"2026-04-17T15:18:50.598611Z","end":"2026-04-17T15:18:50.731382Z","steps":["trace[322188559] 'agreement among raft nodes before linearized reading' (duration: 132.093341ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:52.741853Z","caller":"traceutil/trace.go:171","msg":"trace[115403139] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"147.754869ms","start":"2026-04-17T15:18:52.594026Z","end":"2026-04-17T15:18:52.741781Z","steps":["trace[115403139] 'process raft request' (duration: 147.694137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:52.741971Z","caller":"traceutil/trace.go:171","msg":"trace[118314262] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"147.966804ms","start":"2026-04-17T15:18:52.593827Z","end":"2026-04-17T15:18:52.741794Z","steps":["trace[118314262] 'process raft request' (duration: 114.575224ms)","trace[118314262] 'compare' (duration: 33.155844ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:18:52.760421Z","caller":"traceutil/trace.go:171","msg":"trace[2003238041] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"119.55697ms","start":"2026-04-17T15:18:52.640849Z","end":"2026-04-17T15:18:52.760406Z","steps":["trace[2003238041] 'process raft request' (duration: 119.431077ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:52.760671Z","caller":"traceutil/trace.go:171","msg":"trace[722624434] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"120.806154ms","start":"2026-04-17T15:18:52.639574Z","end":"2026-04-17T15:18:52.76038Z","steps":["trace[722624434] 'process raft request' (duration: 120.6413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:18:58.759051Z","caller":"traceutil/trace.go:171","msg":"trace[820369046] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"134.850587ms","start":"2026-04-17T15:18:58.624171Z","end":"2026-04-17T15:18:58.759021Z","steps":["trace[820369046] 'process raft request' (duration: 134.455566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:19:02.381472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.610962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:19:02.381734Z","caller":"traceutil/trace.go:171","msg":"trace[1309040355] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"435.498624ms","start":"2026-04-17T15:19:01.946219Z","end":"2026-04-17T15:19:02.381718Z","steps":["trace[1309040355] 'process raft request' (duration: 30.590764ms)","trace[1309040355] 'compare' (duration: 404.485498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:19:02.381791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:01.946193Z","time spent":"435.580596ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T15:19:02.382091Z","caller":"traceutil/trace.go:171","msg":"trace[404319624] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"435.288769ms","start":"2026-04-17T15:19:01.946765Z","end":"2026-04-17T15:19:02.382054Z","steps":["trace[404319624] 'process raft request' (duration: 434.797475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:19:02.382211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:01.946754Z","time spent":"435.405782ms","remote":"127.0.0.1:42052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T15:19:10.059004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.340192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:19:10.059115Z","caller":"traceutil/trace.go:171","msg":"trace[386608819] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"338.170271ms","start":"2026-04-17T15:19:09.720929Z","end":"2026-04-17T15:19:10.059099Z","steps":["trace[386608819] 'read index received' (duration: 199.703135ms)","trace[386608819] 'applied index is now lower than readState.Index' (duration: 138.466006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:19:10.059299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.335955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T15:19:10.059392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.212193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28cca9d7-660e-4f42-acbf-33c79f3305de\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-17T15:19:10.059413Z","caller":"traceutil/trace.go:171","msg":"trace[1837243671] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5387; }","duration":"338.464819ms","start":"2026-04-17T15:19:09.720902Z","end":"2026-04-17T15:19:10.059366Z","steps":["trace[1837243671] 'agreement among raft nodes before linearized reading' (duration: 338.257823ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:19:10.059447Z","caller":"traceutil/trace.go:171","msg":"trace[975860586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28cca9d7-660e-4f42-acbf-33c79f3305de; range_end:; response_count:1; response_revision:5387; }","duration":"330.310617ms","start":"2026-04-17T15:19:09.729118Z","end":"2026-04-17T15:19:10.059428Z","steps":["trace[975860586] 'agreement among raft nodes before linearized reading' (duration: 330.07347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:19:10.059456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:09.72089Z","time spent":"338.555691ms","remote":"127.0.0.1:57982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-17T15:19:10.0595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:09.729088Z","time spent":"330.398609ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28cca9d7-660e-4f42-acbf-33c79f3305de\" "} {"level":"warn","ts":"2026-04-17T15:19:10.059658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.958098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:19:10.059736Z","caller":"traceutil/trace.go:171","msg":"trace[1144027058] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5387; }","duration":"125.108512ms","start":"2026-04-17T15:19:09.934613Z","end":"2026-04-17T15:19:10.059721Z","steps":["trace[1144027058] 'agreement among raft nodes before linearized reading' (duration: 124.891616ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:19:10.059845Z","caller":"traceutil/trace.go:171","msg":"trace[1185006125] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"355.674077ms","start":"2026-04-17T15:19:09.703453Z","end":"2026-04-17T15:19:10.059127Z","steps":["trace[1185006125] 'process raft request' (duration: 217.113789ms)","trace[1185006125] 'compare' (duration: 138.222789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:19:10.060079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:09.70343Z","time spent":"356.453769ms","remote":"127.0.0.1:41788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:19:10.343623Z","caller":"traceutil/trace.go:171","msg":"trace[1598416378] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"274.072393ms","start":"2026-04-17T15:19:10.069521Z","end":"2026-04-17T15:19:10.343593Z","steps":["trace[1598416378] 'process raft request' (duration: 273.903928ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:19:10.343682Z","caller":"traceutil/trace.go:171","msg":"trace[1884567787] linearizableReadLoop","detail":"{readStateIndex:5585; appliedIndex:5585; }","duration":"273.769655ms","start":"2026-04-17T15:19:10.069872Z","end":"2026-04-17T15:19:10.343642Z","steps":["trace[1884567787] 'read index received' (duration: 273.756755ms)","trace[1884567787] 'applied index is now lower than readState.Index' (duration: 9.96µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:19:10.343821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.924879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T15:19:10.343895Z","caller":"traceutil/trace.go:171","msg":"trace[2145344893] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5388; }","duration":"274.039272ms","start":"2026-04-17T15:19:10.069839Z","end":"2026-04-17T15:19:10.343878Z","steps":["trace[2145344893] 'agreement among raft nodes before linearized reading' (duration: 273.861688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:19:10.343914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.554713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T15:19:10.343892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.15009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:19:10.343989Z","caller":"traceutil/trace.go:171","msg":"trace[254755163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5388; }","duration":"169.671706ms","start":"2026-04-17T15:19:10.174275Z","end":"2026-04-17T15:19:10.343947Z","steps":["trace[254755163] 'agreement among raft nodes before linearized reading' (duration: 169.553503ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:19:10.343988Z","caller":"traceutil/trace.go:171","msg":"trace[794432667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5388; }","duration":"243.263133ms","start":"2026-04-17T15:19:10.1007Z","end":"2026-04-17T15:19:10.343963Z","steps":["trace[794432667] 'agreement among raft nodes before linearized reading' (duration: 243.112119ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:19:12.243942Z","caller":"traceutil/trace.go:171","msg":"trace[568610008] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5599; }","duration":"112.628671ms","start":"2026-04-17T15:19:12.131287Z","end":"2026-04-17T15:19:12.243916Z","steps":["trace[568610008] 'read index received' (duration: 112.310682ms)","trace[568610008] 'applied index is now lower than readState.Index' (duration: 316.839µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:19:12.244071Z","caller":"traceutil/trace.go:171","msg":"trace[931353726] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"360.085038ms","start":"2026-04-17T15:19:11.883975Z","end":"2026-04-17T15:19:12.24406Z","steps":["trace[931353726] 'process raft request' (duration: 359.637055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:19:12.244173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:19:11.883949Z","time spent":"360.147349ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19435,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T15:19:12.244209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.900169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:19:12.244298Z","caller":"traceutil/trace.go:171","msg":"trace[253662783] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5403; }","duration":"113.049413ms","start":"2026-04-17T15:19:12.13123Z","end":"2026-04-17T15:19:12.244279Z","steps":["trace[253662783] 'agreement among raft nodes before linearized reading' (duration: 112.881878ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:20:36.603877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3037} {"level":"info","ts":"2026-04-17T15:20:36.695666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3037,"took":"90.302953ms","hash":3853767471,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T15:20:36.695738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3853767471,"revision":3037,"compact-revision":-1} {"level":"warn","ts":"2026-04-17T15:25:19.667351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.46674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-17T15:25:19.667466Z","caller":"traceutil/trace.go:171","msg":"trace[698302683] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8231; }","duration":"142.626584ms","start":"2026-04-17T15:25:19.524815Z","end":"2026-04-17T15:25:19.667442Z","steps":["trace[698302683] 'range keys from in-memory index tree' (duration: 142.295685ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:19.835534Z","caller":"traceutil/trace.go:171","msg":"trace[866865197] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"164.24822ms","start":"2026-04-17T15:25:19.671228Z","end":"2026-04-17T15:25:19.835476Z","steps":["trace[866865197] 'process raft request' (duration: 130.721061ms)","trace[866865197] 'compare' (duration: 33.230901ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:25:19.835575Z","caller":"traceutil/trace.go:171","msg":"trace[1014113084] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"163.552371ms","start":"2026-04-17T15:25:19.671986Z","end":"2026-04-17T15:25:19.835538Z","steps":["trace[1014113084] 'process raft request' (duration: 163.366326ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:31.19777Z","caller":"traceutil/trace.go:171","msg":"trace[425760086] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"515.082055ms","start":"2026-04-17T15:25:30.682663Z","end":"2026-04-17T15:25:31.197745Z","steps":["trace[425760086] 'process raft request' (duration: 514.933771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:25:31.197928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:25:30.682634Z","time spent":"515.207218ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T15:25:31.205646Z","caller":"traceutil/trace.go:171","msg":"trace[281094803] linearizableReadLoop","detail":"{readStateIndex:8585; appliedIndex:8584; }","duration":"347.042922ms","start":"2026-04-17T15:25:30.858583Z","end":"2026-04-17T15:25:31.205626Z","steps":["trace[281094803] 'read index received' (duration: 339.147859ms)","trace[281094803] 'applied index is now lower than readState.Index' (duration: 7.893473ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:25:31.205729Z","caller":"traceutil/trace.go:171","msg":"trace[511312230] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"521.58312ms","start":"2026-04-17T15:25:30.684117Z","end":"2026-04-17T15:25:31.2057Z","steps":["trace[511312230] 'process raft request' (duration: 521.378635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:25:31.205831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.238127ms","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-04-17T15:25:31.205861Z","caller":"traceutil/trace.go:171","msg":"trace[1679706831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8295; }","duration":"347.35177ms","start":"2026-04-17T15:25:30.858501Z","end":"2026-04-17T15:25:31.205853Z","steps":["trace[1679706831] 'agreement among raft nodes before linearized reading' (duration: 347.219757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:25:31.205839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.110178ms","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":"warn","ts":"2026-04-17T15:25:31.205889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:25:30.684092Z","time spent":"521.706914ms","remote":"127.0.0.1:42042","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-04-17T15:25:31.205894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:25:30.858479Z","time spent":"347.408251ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T15:25:31.205938Z","caller":"traceutil/trace.go:171","msg":"trace[29192859] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:8295; }","duration":"287.240892ms","start":"2026-04-17T15:25:30.918673Z","end":"2026-04-17T15:25:31.205913Z","steps":["trace[29192859] 'agreement among raft nodes before linearized reading' (duration: 287.091418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:25:31.206014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.966438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fb2hp\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-17T15:25:31.206085Z","caller":"traceutil/trace.go:171","msg":"trace[646132589] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fb2hp; range_end:; response_count:1; response_revision:8295; }","duration":"265.06183ms","start":"2026-04-17T15:25:30.941008Z","end":"2026-04-17T15:25:31.20607Z","steps":["trace[646132589] 'agreement among raft nodes before linearized reading' (duration: 264.880485ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:33.3899Z","caller":"traceutil/trace.go:171","msg":"trace[393589417] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"123.379022ms","start":"2026-04-17T15:25:33.266496Z","end":"2026-04-17T15:25:33.389875Z","steps":["trace[393589417] 'process raft request' (duration: 123.216468ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:33.390016Z","caller":"traceutil/trace.go:171","msg":"trace[1424514534] linearizableReadLoop","detail":"{readStateIndex:8595; appliedIndex:8595; }","duration":"122.195971ms","start":"2026-04-17T15:25:33.267777Z","end":"2026-04-17T15:25:33.389973Z","steps":["trace[1424514534] 'read index received' (duration: 122.17572ms)","trace[1424514534] 'applied index is now lower than readState.Index' (duration: 16.041µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:25:33.390475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.683694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224107"} {"level":"info","ts":"2026-04-17T15:25:33.390592Z","caller":"traceutil/trace.go:171","msg":"trace[1927857510] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"122.316994ms","start":"2026-04-17T15:25:33.268247Z","end":"2026-04-17T15:25:33.390564Z","steps":["trace[1927857510] 'process raft request' (duration: 122.142169ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:33.390616Z","caller":"traceutil/trace.go:171","msg":"trace[218033259] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8305; }","duration":"122.867149ms","start":"2026-04-17T15:25:33.267724Z","end":"2026-04-17T15:25:33.390591Z","steps":["trace[218033259] 'agreement among raft nodes before linearized reading' (duration: 122.376965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:25:33.685483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.333488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:25:33.685569Z","caller":"traceutil/trace.go:171","msg":"trace[1086627571] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8306; }","duration":"155.495853ms","start":"2026-04-17T15:25:33.530055Z","end":"2026-04-17T15:25:33.685551Z","steps":["trace[1086627571] 'count revisions from in-memory index tree' (duration: 155.222235ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:25:36.610239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6156} {"level":"info","ts":"2026-04-17T15:25:36.706043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6156,"took":"94.14809ms","hash":3059298559,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":23203840,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-17T15:25:36.706135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3059298559,"revision":6156,"compact-revision":3037} {"level":"info","ts":"2026-04-17T15:26:00.743534Z","caller":"traceutil/trace.go:171","msg":"trace[10308065] linearizableReadLoop","detail":"{readStateIndex:8805; appliedIndex:8804; }","duration":"144.801833ms","start":"2026-04-17T15:26:00.598709Z","end":"2026-04-17T15:26:00.74351Z","steps":["trace[10308065] 'read index received' (duration: 143.277952ms)","trace[10308065] 'applied index is now lower than readState.Index' (duration: 1.522881ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:26:00.743788Z","caller":"traceutil/trace.go:171","msg":"trace[547920416] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"221.768798ms","start":"2026-04-17T15:26:00.522008Z","end":"2026-04-17T15:26:00.743777Z","steps":["trace[547920416] 'process raft request' (duration: 219.930148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:00.744091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.373838ms","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-04-17T15:26:00.744137Z","caller":"traceutil/trace.go:171","msg":"trace[1844626513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8506; }","duration":"145.462931ms","start":"2026-04-17T15:26:00.598662Z","end":"2026-04-17T15:26:00.744125Z","steps":["trace[1844626513] 'agreement among raft nodes before linearized reading' (duration: 145.321297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:00.744332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.307033ms","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-04-17T15:26:00.744371Z","caller":"traceutil/trace.go:171","msg":"trace[85495992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8506; }","duration":"143.383575ms","start":"2026-04-17T15:26:00.600979Z","end":"2026-04-17T15:26:00.744362Z","steps":["trace[85495992] 'agreement among raft nodes before linearized reading' (duration: 143.289353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:00.744604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.332133ms","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-04-17T15:26:00.744646Z","caller":"traceutil/trace.go:171","msg":"trace[373491573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8506; }","duration":"143.385374ms","start":"2026-04-17T15:26:00.60125Z","end":"2026-04-17T15:26:00.744636Z","steps":["trace[373491573] 'agreement among raft nodes before linearized reading' (duration: 143.289082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:03.702944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.347182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fb2hp\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-17T15:26:03.702978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.807527ms","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-04-17T15:26:03.703039Z","caller":"traceutil/trace.go:171","msg":"trace[1698524864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8521; }","duration":"180.92723ms","start":"2026-04-17T15:26:03.522097Z","end":"2026-04-17T15:26:03.703025Z","steps":["trace[1698524864] 'range keys from in-memory index tree' (duration: 180.629052ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:03.703035Z","caller":"traceutil/trace.go:171","msg":"trace[1247160130] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fb2hp; range_end:; response_count:1; response_revision:8521; }","duration":"143.481696ms","start":"2026-04-17T15:26:03.559533Z","end":"2026-04-17T15:26:03.703015Z","steps":["trace[1247160130] 'range keys from in-memory index tree' (duration: 143.172028ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:03.951231Z","caller":"traceutil/trace.go:171","msg":"trace[1547462656] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"239.361234ms","start":"2026-04-17T15:26:03.711853Z","end":"2026-04-17T15:26:03.951214Z","steps":["trace[1547462656] 'process raft request' (duration: 239.251381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:13.536607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.108375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T15:26:13.536725Z","caller":"traceutil/trace.go:171","msg":"trace[727411270] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"267.493367ms","start":"2026-04-17T15:26:13.269211Z","end":"2026-04-17T15:26:13.536705Z","steps":["trace[727411270] 'process raft request' (duration: 113.201537ms)","trace[727411270] 'compare' (duration: 153.978182ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:26:13.560928Z","caller":"traceutil/trace.go:171","msg":"trace[1002815102] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"121.512941ms","start":"2026-04-17T15:26:13.439381Z","end":"2026-04-17T15:26:13.560894Z","steps":["trace[1002815102] 'process raft request' (duration: 121.358217ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:13.759203Z","caller":"traceutil/trace.go:171","msg":"trace[371108955] linearizableReadLoop","detail":"{readStateIndex:8922; appliedIndex:8921; }","duration":"112.018235ms","start":"2026-04-17T15:26:13.647149Z","end":"2026-04-17T15:26:13.759167Z","steps":["trace[371108955] 'read index received' (duration: 83.420601ms)","trace[371108955] 'applied index is now lower than readState.Index' (duration: 28.595744ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:26:13.759271Z","caller":"traceutil/trace.go:171","msg":"trace[1396370447] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"319.467775ms","start":"2026-04-17T15:26:13.439766Z","end":"2026-04-17T15:26:13.759234Z","steps":["trace[1396370447] 'process raft request' (duration: 290.820369ms)","trace[1396370447] 'compare' (duration: 28.328987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T15:26:13.75937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.20377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:26:13.759463Z","caller":"traceutil/trace.go:171","msg":"trace[1478548801] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8617; }","duration":"112.353173ms","start":"2026-04-17T15:26:13.647086Z","end":"2026-04-17T15:26:13.759439Z","steps":["trace[1478548801] 'agreement among raft nodes before linearized reading' (duration: 112.206659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:13.759468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T15:26:13.43973Z","time spent":"319.622869ms","remote":"127.0.0.1:42042","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-04-17T15:26:13.759478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.802888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T15:26:13.759544Z","caller":"traceutil/trace.go:171","msg":"trace[826147868] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8617; }","duration":"108.901251ms","start":"2026-04-17T15:26:13.650619Z","end":"2026-04-17T15:26:13.75952Z","steps":["trace[826147868] 'agreement among raft nodes before linearized reading' (duration: 108.778397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:14.348314Z","caller":"traceutil/trace.go:171","msg":"trace[1419159228] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"109.236739ms","start":"2026-04-17T15:26:14.239054Z","end":"2026-04-17T15:26:14.348291Z","steps":["trace[1419159228] 'read index received' (duration: 109.066404ms)","trace[1419159228] 'applied index is now lower than readState.Index' (duration: 169.645µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:26:14.348378Z","caller":"traceutil/trace.go:171","msg":"trace[1936763800] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"126.262219ms","start":"2026-04-17T15:26:14.222083Z","end":"2026-04-17T15:26:14.348346Z","steps":["trace[1936763800] 'process raft request' (duration: 126.056864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:26:14.348511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.434234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-17T15:26:14.348543Z","caller":"traceutil/trace.go:171","msg":"trace[79724471] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8622; }","duration":"109.518026ms","start":"2026-04-17T15:26:14.239016Z","end":"2026-04-17T15:26:14.348534Z","steps":["trace[79724471] 'agreement among raft nodes before linearized reading' (duration: 109.375652ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:14.552871Z","caller":"traceutil/trace.go:171","msg":"trace[1852775451] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"122.820167ms","start":"2026-04-17T15:26:14.430021Z","end":"2026-04-17T15:26:14.552841Z","steps":["trace[1852775451] 'process raft request' (duration: 122.610552ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:14.638986Z","caller":"traceutil/trace.go:171","msg":"trace[2024053859] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"115.082948ms","start":"2026-04-17T15:26:14.52387Z","end":"2026-04-17T15:26:14.638953Z","steps":["trace[2024053859] 'process raft request' (duration: 115.031887ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:26:14.639073Z","caller":"traceutil/trace.go:171","msg":"trace[1091017073] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"115.417257ms","start":"2026-04-17T15:26:14.52362Z","end":"2026-04-17T15:26:14.639038Z","steps":["trace[1091017073] 'process raft request' (duration: 115.079707ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:27:04.592673Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024cd.wal"} {"level":"info","ts":"2026-04-17T15:28:09.946398Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T15:28:09.956512Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T15:28:09.956627Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T15:29:45.445753Z","caller":"traceutil/trace.go:171","msg":"trace[645146196] linearizableReadLoop","detail":"{readStateIndex:10747; appliedIndex:10746; }","duration":"122.149216ms","start":"2026-04-17T15:29:45.323582Z","end":"2026-04-17T15:29:45.445731Z","steps":["trace[645146196] 'read index received' (duration: 122.012682ms)","trace[645146196] 'applied index is now lower than readState.Index' (duration: 135.614µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T15:29:45.445819Z","caller":"traceutil/trace.go:171","msg":"trace[1456920795] transaction","detail":"{read_only:false; response_revision:10388; number_of_response:1; }","duration":"242.213166ms","start":"2026-04-17T15:29:45.20357Z","end":"2026-04-17T15:29:45.445783Z","steps":["trace[1456920795] 'process raft request' (duration: 242.020121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T15:29:45.446023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.344381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T15:29:45.446121Z","caller":"traceutil/trace.go:171","msg":"trace[1085902065] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:10388; }","duration":"122.552187ms","start":"2026-04-17T15:29:45.323548Z","end":"2026-04-17T15:29:45.4461Z","steps":["trace[1085902065] 'agreement among raft nodes before linearized reading' (duration: 122.3026ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:30:36.617042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8331} {"level":"info","ts":"2026-04-17T15:30:36.696853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8331,"took":"78.118405ms","hash":632880532,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":24895488,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-17T15:30:36.696945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":632880532,"revision":8331,"compact-revision":6156} {"level":"warn","ts":"2026-04-17T15:30:48.412099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.514698ms","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-04-17T15:30:48.4121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.065893ms","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-04-17T15:30:48.412155Z","caller":"traceutil/trace.go:171","msg":"trace[990639073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10779; }","duration":"101.625471ms","start":"2026-04-17T15:30:48.310514Z","end":"2026-04-17T15:30:48.41214Z","steps":["trace[990639073] 'range keys from in-memory index tree' (duration: 101.413515ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:30:48.412168Z","caller":"traceutil/trace.go:171","msg":"trace[1928834358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10779; }","duration":"102.186386ms","start":"2026-04-17T15:30:48.309965Z","end":"2026-04-17T15:30:48.412151Z","steps":["trace[1928834358] 'range keys from in-memory index tree' (duration: 101.877408ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T15:35:36.626027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10719} {"level":"info","ts":"2026-04-17T15:35:36.70581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10719,"took":"77.956278ms","hash":153726615,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":20484096,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-17T15:35:36.705882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":153726615,"revision":10719,"compact-revision":8331}