{"level":"warn","ts":"2026-04-07T00:03:11.264708Z","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-07T00:03:11.264787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21: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.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-07T00:03:11.264856Z","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-07T00:03:11.264868Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-04-07T00:03:11.264885Z","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-07T00:03:11.265482Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-04-07T00:03:11.265552Z","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":8,"max-cpu-available":8,"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.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-07T00:03:11.271853Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.252652ms"} {"level":"info","ts":"2026-04-07T00:03:11.277299Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-04-07T00:03:11.277365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:03:11.277382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-04-07T00:03:11.277392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:03:11.277398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-04-07T00:03:11.277427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-04-07T00:03:11.299392Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:03:11.300638Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:03:11.302605Z","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-07T00:03:11.304522Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:03:11.304654Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:03:11.306794Z","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-07T00:03:11.307052Z","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-07T00:03:11.307093Z","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-07T00:03:11.31021Z","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-07T00:03:11.3104Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-04-07T00:03:11.31046Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:03:11.310485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-04-07T00:03:11.310514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:03:11.341772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-04-07T00:03:11.342119Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-04-07T00:03:12.278444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:03:12.27849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:03:12.278526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-04-07T00:03:12.278537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:03:12.278543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-04-07T00:03:12.278552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-04-07T00:03:12.278559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-04-07T00:03:12.294685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:03:12.294705Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:03:12.294713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:03:12.294903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:03:12.295062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:03:12.295138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:03:12.296393Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:03:12.2966Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:03:12.296685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:03:12.301365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-04-07T00:03:12.301887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:03:46.028644Z","caller":"traceutil/trace.go:171","msg":"trace[1031657143] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"154.99303ms","start":"2026-04-07T00:03:45.873616Z","end":"2026-04-07T00:03:46.028609Z","steps":["trace[1031657143] 'process raft request' (duration: 154.836078ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:46.444487Z","caller":"traceutil/trace.go:171","msg":"trace[283929020] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"211.113581ms","start":"2026-04-07T00:03:46.23334Z","end":"2026-04-07T00:03:46.444453Z","steps":["trace[283929020] 'process raft request' (duration: 210.558993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:46.932505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.323262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:03:46.932637Z","caller":"traceutil/trace.go:171","msg":"trace[1100483285] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"271.380749ms","start":"2026-04-07T00:03:46.661235Z","end":"2026-04-07T00:03:46.932616Z","steps":["trace[1100483285] 'read index received' (duration: 98.445588ms)","trace[1100483285] 'applied index is now lower than readState.Index' (duration: 172.931501ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:03:46.932733Z","caller":"traceutil/trace.go:171","msg":"trace[1335962940] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"428.741238ms","start":"2026-04-07T00:03:46.503981Z","end":"2026-04-07T00:03:46.932722Z","steps":["trace[1335962940] 'process raft request' (duration: 255.647855ms)","trace[1335962940] 'compare' (duration: 172.130809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:03:46.932776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:03:46.503958Z","time spent":"428.797608ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:03:46.932963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.471009ms","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-07T00:03:46.933066Z","caller":"traceutil/trace.go:171","msg":"trace[468927064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"163.624271ms","start":"2026-04-07T00:03:46.769406Z","end":"2026-04-07T00:03:46.93303Z","steps":["trace[468927064] 'agreement among raft nodes before linearized reading' (duration: 163.405838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:46.933134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.897938ms","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-07T00:03:46.933153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.514426ms","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-07T00:03:46.933195Z","caller":"traceutil/trace.go:171","msg":"trace[1233837700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"271.997888ms","start":"2026-04-07T00:03:46.661178Z","end":"2026-04-07T00:03:46.933176Z","steps":["trace[1233837700] 'agreement among raft nodes before linearized reading' (duration: 271.814776ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:46.933223Z","caller":"traceutil/trace.go:171","msg":"trace[1391682648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"156.631797ms","start":"2026-04-07T00:03:46.776573Z","end":"2026-04-07T00:03:46.933204Z","steps":["trace[1391682648] 'agreement among raft nodes before linearized reading' (duration: 156.472895ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:47.053711Z","caller":"traceutil/trace.go:171","msg":"trace[1275117346] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"115.721734ms","start":"2026-04-07T00:03:46.937964Z","end":"2026-04-07T00:03:47.053686Z","steps":["trace[1275117346] 'process raft request' (duration: 110.641148ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:47.053928Z","caller":"traceutil/trace.go:171","msg":"trace[2134515532] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:637; }","duration":"113.497981ms","start":"2026-04-07T00:03:46.9404Z","end":"2026-04-07T00:03:47.053898Z","steps":["trace[2134515532] 'read index received' (duration: 76.759885ms)","trace[2134515532] 'applied index is now lower than readState.Index' (duration: 36.735996ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:03:47.05399Z","caller":"traceutil/trace.go:171","msg":"trace[904801465] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"115.304907ms","start":"2026-04-07T00:03:46.938626Z","end":"2026-04-07T00:03:47.05393Z","steps":["trace[904801465] 'process raft request' (duration: 115.022353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:47.054142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.723904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T00:03:47.054193Z","caller":"traceutil/trace.go:171","msg":"trace[1226415213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:619; }","duration":"113.810205ms","start":"2026-04-07T00:03:46.940367Z","end":"2026-04-07T00:03:47.054177Z","steps":["trace[1226415213] 'agreement among raft nodes before linearized reading' (duration: 113.655252ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:49.261247Z","caller":"traceutil/trace.go:171","msg":"trace[1087018560] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"103.632702ms","start":"2026-04-07T00:04:49.15752Z","end":"2026-04-07T00:04:49.261152Z","steps":["trace[1087018560] 'process raft request' (duration: 103.375328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:20.801233Z","caller":"traceutil/trace.go:171","msg":"trace[225829501] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"140.666336ms","start":"2026-04-07T00:05:20.660538Z","end":"2026-04-07T00:05:20.801204Z","steps":["trace[225829501] 'process raft request' (duration: 140.528524ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:21.212924Z","caller":"traceutil/trace.go:171","msg":"trace[1186146077] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"213.940093ms","start":"2026-04-07T00:05:20.998953Z","end":"2026-04-07T00:05:21.212893Z","steps":["trace[1186146077] 'read index received' (duration: 213.74717ms)","trace[1186146077] 'applied index is now lower than readState.Index' (duration: 191.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:21.212968Z","caller":"traceutil/trace.go:171","msg":"trace[1977340764] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"223.833514ms","start":"2026-04-07T00:05:20.989094Z","end":"2026-04-07T00:05:21.212928Z","steps":["trace[1977340764] 'process raft request' (duration: 223.610221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:21.213118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.146605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:05:21.213174Z","caller":"traceutil/trace.go:171","msg":"trace[84318930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"214.250136ms","start":"2026-04-07T00:05:20.998909Z","end":"2026-04-07T00:05:21.213159Z","steps":["trace[84318930] 'agreement among raft nodes before linearized reading' (duration: 214.145235ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:21.31824Z","caller":"traceutil/trace.go:171","msg":"trace[816621779] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"105.19669ms","start":"2026-04-07T00:05:21.213021Z","end":"2026-04-07T00:05:21.318217Z","steps":["trace[816621779] 'read index received' (duration: 93.231654ms)","trace[816621779] 'applied index is now lower than readState.Index' (duration: 11.964156ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:21.31827Z","caller":"traceutil/trace.go:171","msg":"trace[562918973] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"223.393708ms","start":"2026-04-07T00:05:21.094853Z","end":"2026-04-07T00:05:21.318247Z","steps":["trace[562918973] 'process raft request' (duration: 211.469273ms)","trace[562918973] 'compare' (duration: 11.808744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:05:21.31855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.277348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:05:21.318585Z","caller":"traceutil/trace.go:171","msg":"trace[181830679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1852; }","duration":"104.351199ms","start":"2026-04-07T00:05:21.214224Z","end":"2026-04-07T00:05:21.318576Z","steps":["trace[181830679] 'agreement among raft nodes before linearized reading' (duration: 104.277428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:21.318711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.166749ms","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-07T00:05:21.319033Z","caller":"traceutil/trace.go:171","msg":"trace[30171151] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"238.665675ms","start":"2026-04-07T00:05:21.080355Z","end":"2026-04-07T00:05:21.31902Z","steps":["trace[30171151] 'agreement among raft nodes before linearized reading' (duration: 238.025308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:21.318873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.796726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a3ea529564b04e\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-07T00:05:21.319557Z","caller":"traceutil/trace.go:171","msg":"trace[584022821] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a3ea529564b04e; range_end:; response_count:1; response_revision:1852; }","duration":"314.534885ms","start":"2026-04-07T00:05:21.004991Z","end":"2026-04-07T00:05:21.319526Z","steps":["trace[584022821] 'agreement among raft nodes before linearized reading' (duration: 313.637585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:21.319632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:05:21.004963Z","time spent":"314.641476ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a3ea529564b04e\" "} {"level":"info","ts":"2026-04-07T00:05:36.286502Z","caller":"traceutil/trace.go:171","msg":"trace[262062670] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"140.810963ms","start":"2026-04-07T00:05:36.14566Z","end":"2026-04-07T00:05:36.286471Z","steps":["trace[262062670] 'process raft request' (duration: 140.50802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:54.401173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.672426ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:05:54.401383Z","caller":"traceutil/trace.go:171","msg":"trace[1881930820] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2084; }","duration":"129.902182ms","start":"2026-04-07T00:05:54.271457Z","end":"2026-04-07T00:05:54.40136Z","steps":["trace[1881930820] 'range keys from in-memory index tree' (duration: 129.637606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:05:59.90081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.009201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:05:59.901091Z","caller":"traceutil/trace.go:171","msg":"trace[418848498] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"210.976301ms","start":"2026-04-07T00:05:59.690087Z","end":"2026-04-07T00:05:59.901064Z","steps":["trace[418848498] 'process raft request' (duration: 210.887838ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:05:59.901223Z","caller":"traceutil/trace.go:171","msg":"trace[730130119] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"222.843018ms","start":"2026-04-07T00:05:59.678347Z","end":"2026-04-07T00:05:59.90119Z","steps":["trace[730130119] 'read index received' (duration: 90.129039ms)","trace[730130119] 'applied index is now lower than readState.Index' (duration: 132.709309ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:05:59.901365Z","caller":"traceutil/trace.go:171","msg":"trace[1277745021] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"268.21146ms","start":"2026-04-07T00:05:59.633133Z","end":"2026-04-07T00:05:59.901344Z","steps":["trace[1277745021] 'process raft request' (duration: 135.419879ms)","trace[1277745021] 'compare' (duration: 131.852757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:05:59.901649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.283229ms","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-07T00:05:59.901929Z","caller":"traceutil/trace.go:171","msg":"trace[903305392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2112; }","duration":"223.583137ms","start":"2026-04-07T00:05:59.678308Z","end":"2026-04-07T00:05:59.901891Z","steps":["trace[903305392] 'agreement among raft nodes before linearized reading' (duration: 223.114035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:00.572008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.928785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:06:00.572122Z","caller":"traceutil/trace.go:171","msg":"trace[1051702036] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"167.243503ms","start":"2026-04-07T00:06:00.404861Z","end":"2026-04-07T00:06:00.572105Z","steps":["trace[1051702036] 'read index received' (duration: 28.039812ms)","trace[1051702036] 'applied index is now lower than readState.Index' (duration: 139.202151ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:00.572203Z","caller":"traceutil/trace.go:171","msg":"trace[1277507532] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"295.998677ms","start":"2026-04-07T00:06:00.276195Z","end":"2026-04-07T00:06:00.572194Z","steps":["trace[1277507532] 'process raft request' (duration: 156.803175ms)","trace[1277507532] 'compare' (duration: 138.597206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:06:00.572372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.512502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:06:00.572406Z","caller":"traceutil/trace.go:171","msg":"trace[2133070847] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2117; }","duration":"167.596204ms","start":"2026-04-07T00:06:00.404798Z","end":"2026-04-07T00:06:00.572394Z","steps":["trace[2133070847] 'agreement among raft nodes before linearized reading' (duration: 167.529462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:00.644312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.450075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:06:00.644467Z","caller":"traceutil/trace.go:171","msg":"trace[1641902797] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2117; }","duration":"154.66814ms","start":"2026-04-07T00:06:00.489765Z","end":"2026-04-07T00:06:00.644433Z","steps":["trace[1641902797] 'agreement among raft nodes before linearized reading' (duration: 154.442404ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:01.107068Z","caller":"traceutil/trace.go:171","msg":"trace[504316680] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"115.142593ms","start":"2026-04-07T00:06:00.991885Z","end":"2026-04-07T00:06:01.107028Z","steps":["trace[504316680] 'process raft request' (duration: 114.844496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:01.435535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.478137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T00:06:01.435689Z","caller":"traceutil/trace.go:171","msg":"trace[1832394321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2118; }","duration":"251.710484ms","start":"2026-04-07T00:06:01.183954Z","end":"2026-04-07T00:06:01.435665Z","steps":["trace[1832394321] 'range keys from in-memory index tree' (duration: 251.147159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:01.559723Z","caller":"traceutil/trace.go:171","msg":"trace[683918219] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"117.044931ms","start":"2026-04-07T00:06:01.442646Z","end":"2026-04-07T00:06:01.559691Z","steps":["trace[683918219] 'process raft request' (duration: 96.637668ms)","trace[683918219] 'compare' (duration: 20.28873ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:01.740462Z","caller":"traceutil/trace.go:171","msg":"trace[78200443] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"170.003369ms","start":"2026-04-07T00:06:01.570424Z","end":"2026-04-07T00:06:01.740427Z","steps":["trace[78200443] 'process raft request' (duration: 169.534676ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:32.250169Z","caller":"traceutil/trace.go:171","msg":"trace[354621791] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"166.989197ms","start":"2026-04-07T00:07:32.08314Z","end":"2026-04-07T00:07:32.250129Z","steps":["trace[354621791] 'process raft request' (duration: 166.617689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:28.159966Z","caller":"traceutil/trace.go:171","msg":"trace[15292389] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"141.271562ms","start":"2026-04-07T00:11:28.018672Z","end":"2026-04-07T00:11:28.159943Z","steps":["trace[15292389] 'process raft request' (duration: 133.180397ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:28.160557Z","caller":"traceutil/trace.go:171","msg":"trace[403694034] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"133.828958ms","start":"2026-04-07T00:11:28.026698Z","end":"2026-04-07T00:11:28.160527Z","steps":["trace[403694034] 'process raft request' (duration: 133.736506ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:32.99097Z","caller":"traceutil/trace.go:171","msg":"trace[538386566] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4945; }","duration":"161.004347ms","start":"2026-04-07T00:11:32.829942Z","end":"2026-04-07T00:11:32.990946Z","steps":["trace[538386566] 'read index received' (duration: 160.776723ms)","trace[538386566] 'applied index is now lower than readState.Index' (duration: 226.764µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:32.991168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.212889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7dc7e350-a8ff-4732-818e-55b7ccac0733\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-07T00:11:32.991249Z","caller":"traceutil/trace.go:171","msg":"trace[1947136887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7dc7e350-a8ff-4732-818e-55b7ccac0733; range_end:; response_count:1; response_revision:4766; }","duration":"161.345922ms","start":"2026-04-07T00:11:32.829888Z","end":"2026-04-07T00:11:32.991234Z","steps":["trace[1947136887] 'agreement among raft nodes before linearized reading' (duration: 161.153619ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:32.991185Z","caller":"traceutil/trace.go:171","msg":"trace[678774208] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"199.483577ms","start":"2026-04-07T00:11:32.791648Z","end":"2026-04-07T00:11:32.991131Z","steps":["trace[678774208] 'process raft request' (duration: 199.114322ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:33.313086Z","caller":"traceutil/trace.go:171","msg":"trace[1063460179] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"106.916774ms","start":"2026-04-07T00:11:33.206145Z","end":"2026-04-07T00:11:33.313062Z","steps":["trace[1063460179] 'process raft request' (duration: 106.707361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:45.556631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.529574ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-07T00:11:45.556842Z","caller":"traceutil/trace.go:171","msg":"trace[1399164923] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"300.138708ms","start":"2026-04-07T00:11:45.256686Z","end":"2026-04-07T00:11:45.556825Z","steps":["trace[1399164923] 'process raft request' (duration: 300.032287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:45.55708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:45.256665Z","time spent":"300.215499ms","remote":"127.0.0.1:34404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:45.557184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:45.229417Z","time spent":"327.692772ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:11:45.568044Z","caller":"traceutil/trace.go:171","msg":"trace[467790480] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5115; }","duration":"175.481105ms","start":"2026-04-07T00:11:45.392546Z","end":"2026-04-07T00:11:45.568027Z","steps":["trace[467790480] 'read index received' (duration: 9.614144ms)","trace[467790480] 'applied index is now lower than readState.Index' (duration: 165.865941ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:45.568127Z","caller":"traceutil/trace.go:171","msg":"trace[761699728] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"204.119915ms","start":"2026-04-07T00:11:45.363985Z","end":"2026-04-07T00:11:45.568105Z","steps":["trace[761699728] 'process raft request' (duration: 203.925212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:45.568191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.624957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:45.568219Z","caller":"traceutil/trace.go:171","msg":"trace[1637790694] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4933; }","duration":"175.692767ms","start":"2026-04-07T00:11:45.392518Z","end":"2026-04-07T00:11:45.568211Z","steps":["trace[1637790694] 'agreement among raft nodes before linearized reading' (duration: 175.586716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:45.568287Z","caller":"traceutil/trace.go:171","msg":"trace[1618945251] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"203.325224ms","start":"2026-04-07T00:11:45.364914Z","end":"2026-04-07T00:11:45.568239Z","steps":["trace[1618945251] 'process raft request' (duration: 203.08163ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:15.308319Z","caller":"traceutil/trace.go:171","msg":"trace[1273481502] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"104.598314ms","start":"2026-04-07T00:12:15.203687Z","end":"2026-04-07T00:12:15.308285Z","steps":["trace[1273481502] 'process raft request' (duration: 104.413611ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.860108Z","caller":"traceutil/trace.go:171","msg":"trace[177945064] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"121.308374ms","start":"2026-04-07T00:12:33.738775Z","end":"2026-04-07T00:12:33.860083Z","steps":["trace[177945064] 'process raft request' (duration: 121.265773ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:33.860489Z","caller":"traceutil/trace.go:171","msg":"trace[583919487] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"123.483785ms","start":"2026-04-07T00:12:33.73694Z","end":"2026-04-07T00:12:33.860423Z","steps":["trace[583919487] 'process raft request' (duration: 123.021178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.08067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.063405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-07T00:12:34.081013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.017858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:12:34.081065Z","caller":"traceutil/trace.go:171","msg":"trace[1985952237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5364; }","duration":"176.516301ms","start":"2026-04-07T00:12:33.904534Z","end":"2026-04-07T00:12:34.081051Z","steps":["trace[1985952237] 'range keys from in-memory index tree' (duration: 175.862043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.08101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.189831ms","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-07T00:12:34.081226Z","caller":"traceutil/trace.go:171","msg":"trace[1124461284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5364; }","duration":"110.439524ms","start":"2026-04-07T00:12:33.970779Z","end":"2026-04-07T00:12:34.081219Z","steps":["trace[1124461284] 'range keys from in-memory index tree' (duration: 110.05223ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.081102Z","caller":"traceutil/trace.go:171","msg":"trace[1699289250] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5364; }","duration":"153.14325ms","start":"2026-04-07T00:12:33.927939Z","end":"2026-04-07T00:12:34.081082Z","steps":["trace[1699289250] 'range keys from in-memory index tree' (duration: 152.813555ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.353811Z","caller":"traceutil/trace.go:171","msg":"trace[1697615582] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"267.036133ms","start":"2026-04-07T00:12:34.086749Z","end":"2026-04-07T00:12:34.353785Z","steps":["trace[1697615582] 'process raft request' (duration: 266.80455ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.353945Z","caller":"traceutil/trace.go:171","msg":"trace[902726505] linearizableReadLoop","detail":"{readStateIndex:5565; appliedIndex:5562; }","duration":"192.620521ms","start":"2026-04-07T00:12:34.1613Z","end":"2026-04-07T00:12:34.35392Z","steps":["trace[902726505] 'read index received' (duration: 153.067189ms)","trace[902726505] 'applied index is now lower than readState.Index' (duration: 39.551482ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:34.353977Z","caller":"traceutil/trace.go:171","msg":"trace[414175218] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"267.800053ms","start":"2026-04-07T00:12:34.086165Z","end":"2026-04-07T00:12:34.353965Z","steps":["trace[414175218] 'process raft request' (duration: 228.13993ms)","trace[414175218] 'compare' (duration: 38.801491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:34.354073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.752813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:34.354146Z","caller":"traceutil/trace.go:171","msg":"trace[1945509476] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5369; }","duration":"192.873195ms","start":"2026-04-07T00:12:34.161256Z","end":"2026-04-07T00:12:34.35413Z","steps":["trace[1945509476] 'agreement among raft nodes before linearized reading' (duration: 192.760574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.354313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.948606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:12:34.354353Z","caller":"traceutil/trace.go:171","msg":"trace[2100722828] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5369; }","duration":"193.050207ms","start":"2026-04-07T00:12:34.161294Z","end":"2026-04-07T00:12:34.354344Z","steps":["trace[2100722828] 'agreement among raft nodes before linearized reading' (duration: 192.834184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:34.354383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.040717ms","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-07T00:12:34.354444Z","caller":"traceutil/trace.go:171","msg":"trace[731622832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5369; }","duration":"177.142099ms","start":"2026-04-07T00:12:34.177287Z","end":"2026-04-07T00:12:34.354429Z","steps":["trace[731622832] 'agreement among raft nodes before linearized reading' (duration: 177.010447ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:34.355346Z","caller":"traceutil/trace.go:171","msg":"trace[1613707003] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"268.083877ms","start":"2026-04-07T00:12:34.086178Z","end":"2026-04-07T00:12:34.354262Z","steps":["trace[1613707003] 'process raft request' (duration: 267.300747ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:12.318911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2926} {"level":"info","ts":"2026-04-07T00:13:12.410347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2926,"took":"90.232484ms","hash":1474653668,"current-db-size-bytes":53100544,"current-db-size":"53 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:13:12.41041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1474653668,"revision":2926,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T00:15:46.722327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.33831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107255"} {"level":"info","ts":"2026-04-07T00:15:46.722417Z","caller":"traceutil/trace.go:171","msg":"trace[254842553] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6707; }","duration":"137.476114ms","start":"2026-04-07T00:15:46.584922Z","end":"2026-04-07T00:15:46.722398Z","steps":["trace[254842553] 'range keys from in-memory index tree' (duration: 137.122535ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:46.756414Z","caller":"traceutil/trace.go:171","msg":"trace[1954663320] transaction","detail":"{read_only:false; response_revision:6708; number_of_response:1; }","duration":"132.146178ms","start":"2026-04-07T00:15:46.624247Z","end":"2026-04-07T00:15:46.756394Z","steps":["trace[1954663320] 'process raft request' (duration: 131.995814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:46.7796Z","caller":"traceutil/trace.go:171","msg":"trace[231606080] transaction","detail":"{read_only:false; response_revision:6709; number_of_response:1; }","duration":"144.569027ms","start":"2026-04-07T00:15:46.635002Z","end":"2026-04-07T00:15:46.779571Z","steps":["trace[231606080] 'process raft request' (duration: 144.426843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:46.988249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.40879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:15:46.988336Z","caller":"traceutil/trace.go:171","msg":"trace[1665984891] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6709; }","duration":"104.511133ms","start":"2026-04-07T00:15:46.883807Z","end":"2026-04-07T00:15:46.988318Z","steps":["trace[1665984891] 'count revisions from in-memory index tree' (duration: 104.330468ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:12.324275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5671} {"level":"info","ts":"2026-04-07T00:18:12.414862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5671,"took":"89.012541ms","hash":2574141614,"current-db-size-bytes":53100544,"current-db-size":"53 MB","current-db-size-in-use-bytes":18235392,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T00:18:12.414935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2574141614,"revision":5671,"compact-revision":2926}