{"level":"warn","ts":"2026-04-07T05:51:56.081222Z","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-07T05:51:56.081369Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-07T05:51:56.082094Z","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-07T05:51:56.082131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-07T05:51:56.082167Z","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-07T05:51:56.084168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-04-07T05:51:56.084395Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-07T05:51:56.08862Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.534533ms"} {"level":"info","ts":"2026-04-07T05:51:56.099454Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-04-07T05:51:56.099577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T05:51:56.099605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-04-07T05:51:56.099622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T05:51:56.099638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-04-07T05:51:56.099693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-04-07T05:51:56.105325Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T05:51:56.106778Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T05:51:56.107782Z","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-07T05:51:56.110503Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T05:51:56.110749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T05:51:56.110977Z","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-07T05:51:56.112864Z","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-07T05:51:56.113685Z","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-07T05:51:56.114748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-04-07T05:51:56.11496Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-07T05:51:56.117431Z","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-07T05:51:56.117625Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-07T05:51:56.117706Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-07T05:51:56.117964Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T05:51:56.118034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T05:51:56.900352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T05:51:56.900468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T05:51:56.900517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-04-07T05:51:56.900539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-04-07T05:51:56.900551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-07T05:51:56.900569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-04-07T05:51:56.900593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-07T05:51:56.902191Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:51:56.90486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T05:51:56.904899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:51:56.904998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:51:56.905472Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:51:56.905418Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T05:51:56.905552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T05:51:56.905618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:51:56.905669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:51:56.908589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-04-07T05:51:56.909126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T05:52:15.483002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.707781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T05:52:15.483493Z","caller":"traceutil/trace.go:171","msg":"trace[1287758057] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"164.568202ms","start":"2026-04-07T05:52:15.318761Z","end":"2026-04-07T05:52:15.48333Z","steps":["trace[1287758057] 'read index received' (duration: 107.903µs)","trace[1287758057] 'applied index is now lower than readState.Index' (duration: 164.448988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:52:15.483886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.986741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:52:15.484041Z","caller":"traceutil/trace.go:171","msg":"trace[1467405609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"165.294088ms","start":"2026-04-07T05:52:15.318695Z","end":"2026-04-07T05:52:15.483989Z","steps":["trace[1467405609] 'agreement among raft nodes before linearized reading' (duration: 164.851228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:21.062757Z","caller":"traceutil/trace.go:171","msg":"trace[134226953] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"140.112272ms","start":"2026-04-07T05:52:20.922613Z","end":"2026-04-07T05:52:21.062725Z","steps":["trace[134226953] 'process raft request' (duration: 139.950038ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:23.185729Z","caller":"traceutil/trace.go:171","msg":"trace[1639449095] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"114.685638ms","start":"2026-04-07T05:52:23.071012Z","end":"2026-04-07T05:52:23.185697Z","steps":["trace[1639449095] 'process raft request' (duration: 114.426192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:25.827475Z","caller":"traceutil/trace.go:171","msg":"trace[555263352] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"125.803433ms","start":"2026-04-07T05:52:25.701644Z","end":"2026-04-07T05:52:25.827448Z","steps":["trace[555263352] 'process raft request' (duration: 121.531902ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:25.828714Z","caller":"traceutil/trace.go:171","msg":"trace[626948078] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"121.587143ms","start":"2026-04-07T05:52:25.707099Z","end":"2026-04-07T05:52:25.828686Z","steps":["trace[626948078] 'process raft request' (duration: 121.445579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:25.828742Z","caller":"traceutil/trace.go:171","msg":"trace[1687340446] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"125.419744ms","start":"2026-04-07T05:52:25.703293Z","end":"2026-04-07T05:52:25.828713Z","steps":["trace[1687340446] 'process raft request' (duration: 124.913202ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:25.828904Z","caller":"traceutil/trace.go:171","msg":"trace[403848140] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"119.011732ms","start":"2026-04-07T05:52:25.709846Z","end":"2026-04-07T05:52:25.828858Z","steps":["trace[403848140] 'process raft request' (duration: 118.792306ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:26.106936Z","caller":"traceutil/trace.go:171","msg":"trace[160455472] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"139.148139ms","start":"2026-04-07T05:52:25.967684Z","end":"2026-04-07T05:52:26.106832Z","steps":["trace[160455472] 'process raft request' (duration: 133.951316ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:26.107418Z","caller":"traceutil/trace.go:171","msg":"trace[148325572] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.183775ms","start":"2026-04-07T05:52:25.999219Z","end":"2026-04-07T05:52:26.107402Z","steps":["trace[148325572] 'process raft request' (duration: 107.903688ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:26.447175Z","caller":"traceutil/trace.go:171","msg":"trace[1541828239] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"110.466318ms","start":"2026-04-07T05:52:26.336685Z","end":"2026-04-07T05:52:26.447152Z","steps":["trace[1541828239] 'process raft request' (duration: 110.311164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:32.012065Z","caller":"traceutil/trace.go:171","msg":"trace[2098496378] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"108.099773ms","start":"2026-04-07T05:52:31.90394Z","end":"2026-04-07T05:52:32.01204Z","steps":["trace[2098496378] 'process raft request' (duration: 107.96646ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:32.426219Z","caller":"traceutil/trace.go:171","msg":"trace[1740992528] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"175.756606ms","start":"2026-04-07T05:52:32.250432Z","end":"2026-04-07T05:52:32.426189Z","steps":["trace[1740992528] 'read index received' (duration: 174.866815ms)","trace[1740992528] 'applied index is now lower than readState.Index' (duration: 888.601µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:52:32.426306Z","caller":"traceutil/trace.go:171","msg":"trace[2040953752] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"197.41071ms","start":"2026-04-07T05:52:32.22886Z","end":"2026-04-07T05:52:32.426271Z","steps":["trace[2040953752] 'process raft request' (duration: 196.742494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:32.426457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.98495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:52:32.426534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.072013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T05:52:32.426546Z","caller":"traceutil/trace.go:171","msg":"trace[1714914570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"108.098202ms","start":"2026-04-07T05:52:32.318428Z","end":"2026-04-07T05:52:32.426526Z","steps":["trace[1714914570] 'agreement among raft nodes before linearized reading' (duration: 107.944408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:32.426582Z","caller":"traceutil/trace.go:171","msg":"trace[1083112730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"176.190457ms","start":"2026-04-07T05:52:32.25038Z","end":"2026-04-07T05:52:32.42657Z","steps":["trace[1083112730] 'agreement among raft nodes before linearized reading' (duration: 175.981892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:32.992191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537704148355086,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:52:33.080842Z","caller":"traceutil/trace.go:171","msg":"trace[467528494] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"590.055499ms","start":"2026-04-07T05:52:32.49076Z","end":"2026-04-07T05:52:33.080816Z","steps":["trace[467528494] 'process raft request' (duration: 509.301835ms)","trace[467528494] 'compare' (duration: 80.505209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:52:33.081251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:32.490728Z","time spent":"590.176183ms","remote":"127.0.0.1:46358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:52:33.082039Z","caller":"traceutil/trace.go:171","msg":"trace[706028793] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"455.120621ms","start":"2026-04-07T05:52:32.626895Z","end":"2026-04-07T05:52:33.082016Z","steps":["trace[706028793] 'process raft request' (duration: 454.744782ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:33.082018Z","caller":"traceutil/trace.go:171","msg":"trace[599340] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:640; }","duration":"590.08593ms","start":"2026-04-07T05:52:32.491901Z","end":"2026-04-07T05:52:33.081987Z","steps":["trace[599340] 'read index received' (duration: 508.171868ms)","trace[599340] 'applied index is now lower than readState.Index' (duration: 81.913062ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:52:33.08229Z","caller":"traceutil/trace.go:171","msg":"trace[1499242201] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"333.906527ms","start":"2026-04-07T05:52:32.748362Z","end":"2026-04-07T05:52:33.082269Z","steps":["trace[1499242201] 'process raft request' (duration: 333.579129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:33.082328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:32.626856Z","time spent":"455.246463ms","remote":"127.0.0.1:46296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:52:33.08236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.22441ms","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-07T05:52:33.082386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.457189ms","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-07T05:52:33.082428Z","caller":"traceutil/trace.go:171","msg":"trace[96854526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"403.359443ms","start":"2026-04-07T05:52:32.67905Z","end":"2026-04-07T05:52:33.08241Z","steps":["trace[96854526] 'agreement among raft nodes before linearized reading' (duration: 403.136218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:33.082553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:32.679027Z","time spent":"403.506906ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T05:52:33.082484Z","caller":"traceutil/trace.go:171","msg":"trace[1739418680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"590.596082ms","start":"2026-04-07T05:52:32.491864Z","end":"2026-04-07T05:52:33.08246Z","steps":["trace[1739418680] 'agreement among raft nodes before linearized reading' (duration: 590.316176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:33.082645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:32.491846Z","time spent":"590.778836ms","remote":"127.0.0.1:46380","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":"warn","ts":"2026-04-07T05:52:33.0824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:52:32.74833Z","time spent":"334.019359ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:52:49.089098Z","caller":"traceutil/trace.go:171","msg":"trace[1607720288] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"134.194183ms","start":"2026-04-07T05:52:48.954869Z","end":"2026-04-07T05:52:49.089063Z","steps":["trace[1607720288] 'process raft request' (duration: 133.867266ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:49.279793Z","caller":"traceutil/trace.go:171","msg":"trace[2145714863] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"106.363095ms","start":"2026-04-07T05:52:49.173401Z","end":"2026-04-07T05:52:49.279764Z","steps":["trace[2145714863] 'read index received' (duration: 105.462664ms)","trace[2145714863] 'applied index is now lower than readState.Index' (duration: 899.571µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:52:49.279846Z","caller":"traceutil/trace.go:171","msg":"trace[488638405] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"180.60168ms","start":"2026-04-07T05:52:49.099219Z","end":"2026-04-07T05:52:49.279821Z","steps":["trace[488638405] 'process raft request' (duration: 180.371785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:52:49.280159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.732674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T05:52:49.280259Z","caller":"traceutil/trace.go:171","msg":"trace[1689619963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"106.911128ms","start":"2026-04-07T05:52:49.173326Z","end":"2026-04-07T05:52:49.280237Z","steps":["trace[1689619963] 'agreement among raft nodes before linearized reading' (duration: 106.590901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:03.474677Z","caller":"traceutil/trace.go:171","msg":"trace[977867457] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"255.151102ms","start":"2026-04-07T05:54:03.219487Z","end":"2026-04-07T05:54:03.474638Z","steps":["trace[977867457] 'read index received' (duration: 254.879206ms)","trace[977867457] 'applied index is now lower than readState.Index' (duration: 270.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:03.474786Z","caller":"traceutil/trace.go:171","msg":"trace[147147007] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"336.658703ms","start":"2026-04-07T05:54:03.1381Z","end":"2026-04-07T05:54:03.474759Z","steps":["trace[147147007] 'process raft request' (duration: 336.308505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:03.474944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:03.138063Z","time spent":"336.797406ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:03.47495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.422918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:54:03.474998Z","caller":"traceutil/trace.go:171","msg":"trace[105374860] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1830; }","duration":"255.568431ms","start":"2026-04-07T05:54:03.219414Z","end":"2026-04-07T05:54:03.474983Z","steps":["trace[105374860] 'agreement among raft nodes before linearized reading' (duration: 255.439218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:03.475148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.75927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:54:03.475333Z","caller":"traceutil/trace.go:171","msg":"trace[438539360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"154.955845ms","start":"2026-04-07T05:54:03.320344Z","end":"2026-04-07T05:54:03.4753Z","steps":["trace[438539360] 'agreement among raft nodes before linearized reading' (duration: 154.616687ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:03.596635Z","caller":"traceutil/trace.go:171","msg":"trace[1790253106] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"101.534319ms","start":"2026-04-07T05:54:03.495069Z","end":"2026-04-07T05:54:03.596604Z","steps":["trace[1790253106] 'read index received' (duration: 93.150693ms)","trace[1790253106] 'applied index is now lower than readState.Index' (duration: 8.382056ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:03.596691Z","caller":"traceutil/trace.go:171","msg":"trace[865142011] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"114.093712ms","start":"2026-04-07T05:54:03.482567Z","end":"2026-04-07T05:54:03.596661Z","steps":["trace[865142011] 'process raft request' (duration: 105.656285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:03.596858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.768544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cf8b653-4869-4d92-a4e5-069fa1d7009d\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-04-07T05:54:03.596982Z","caller":"traceutil/trace.go:171","msg":"trace[1228769262] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cf8b653-4869-4d92-a4e5-069fa1d7009d; range_end:; response_count:1; response_revision:1831; }","duration":"101.873036ms","start":"2026-04-07T05:54:03.495054Z","end":"2026-04-07T05:54:03.596927Z","steps":["trace[1228769262] 'agreement among raft nodes before linearized reading' (duration: 101.680392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:03.913073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.658565ms","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-07T05:54:03.91316Z","caller":"traceutil/trace.go:171","msg":"trace[580663017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1831; }","duration":"189.777167ms","start":"2026-04-07T05:54:03.723364Z","end":"2026-04-07T05:54:03.913141Z","steps":["trace[580663017] 'range keys from in-memory index tree' (duration: 189.47883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:03.913165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.91028ms","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-07T05:54:03.913171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.280179ms","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-07T05:54:03.913298Z","caller":"traceutil/trace.go:171","msg":"trace[896004057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1831; }","duration":"190.128465ms","start":"2026-04-07T05:54:03.723147Z","end":"2026-04-07T05:54:03.913276Z","steps":["trace[896004057] 'range keys from in-memory index tree' (duration: 189.638644ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:03.913324Z","caller":"traceutil/trace.go:171","msg":"trace[1999630348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1831; }","duration":"190.499455ms","start":"2026-04-07T05:54:03.722803Z","end":"2026-04-07T05:54:03.913303Z","steps":["trace[1999630348] 'range keys from in-memory index tree' (duration: 190.063414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:12.75167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.857459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-07T05:54:12.751795Z","caller":"traceutil/trace.go:171","msg":"trace[1314259467] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1922; }","duration":"139.049973ms","start":"2026-04-07T05:54:12.612716Z","end":"2026-04-07T05:54:12.751766Z","steps":["trace[1314259467] 'range keys from in-memory index tree' (duration: 138.623214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:18.009493Z","caller":"traceutil/trace.go:171","msg":"trace[972712527] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"192.02634ms","start":"2026-04-07T05:54:17.817414Z","end":"2026-04-07T05:54:18.00944Z","steps":["trace[972712527] 'process raft request' (duration: 191.895857ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:16.818345Z","caller":"traceutil/trace.go:171","msg":"trace[1197080593] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"102.026355ms","start":"2026-04-07T05:56:16.716275Z","end":"2026-04-07T05:56:16.818302Z","steps":["trace[1197080593] 'process raft request' (duration: 101.844651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:17.092648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.488395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:56:17.092935Z","caller":"traceutil/trace.go:171","msg":"trace[339193746] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"268.81099ms","start":"2026-04-07T05:56:16.824096Z","end":"2026-04-07T05:56:17.092907Z","steps":["trace[339193746] 'process raft request' (duration: 268.692217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:17.092975Z","caller":"traceutil/trace.go:171","msg":"trace[1152186615] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"269.481336ms","start":"2026-04-07T05:56:16.823417Z","end":"2026-04-07T05:56:17.092898Z","steps":["trace[1152186615] 'process raft request' (duration: 51.604771ms)","trace[1152186615] 'compare' (duration: 217.216479ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:56:17.093045Z","caller":"traceutil/trace.go:171","msg":"trace[2075656126] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"268.882562ms","start":"2026-04-07T05:56:16.824135Z","end":"2026-04-07T05:56:17.093017Z","steps":["trace[2075656126] 'process raft request' (duration: 268.725868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:22.500508Z","caller":"traceutil/trace.go:171","msg":"trace[703109318] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"108.852795ms","start":"2026-04-07T05:56:22.391628Z","end":"2026-04-07T05:56:22.500481Z","steps":["trace[703109318] 'process raft request' (duration: 108.66203ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:00:20.238892Z","caller":"traceutil/trace.go:171","msg":"trace[1179034682] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5052; }","duration":"107.659935ms","start":"2026-04-07T06:00:20.1312Z","end":"2026-04-07T06:00:20.23886Z","steps":["trace[1179034682] 'read index received' (duration: 107.520852ms)","trace[1179034682] 'applied index is now lower than readState.Index' (duration: 134.763µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:00:20.239189Z","caller":"traceutil/trace.go:171","msg":"trace[783070920] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"190.825822ms","start":"2026-04-07T06:00:20.04835Z","end":"2026-04-07T06:00:20.239176Z","steps":["trace[783070920] 'process raft request' (duration: 190.368191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:00:20.240358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.131589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196036"} {"level":"info","ts":"2026-04-07T06:00:20.24041Z","caller":"traceutil/trace.go:171","msg":"trace[3213592] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4863; }","duration":"109.242912ms","start":"2026-04-07T06:00:20.131155Z","end":"2026-04-07T06:00:20.240398Z","steps":["trace[3213592] 'agreement among raft nodes before linearized reading' (duration: 108.227948ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:00:36.479804Z","caller":"traceutil/trace.go:171","msg":"trace[1512552624] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"159.402473ms","start":"2026-04-07T06:00:36.320373Z","end":"2026-04-07T06:00:36.479776Z","steps":["trace[1512552624] 'read index received' (duration: 159.124177ms)","trace[1512552624] 'applied index is now lower than readState.Index' (duration: 277.186µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:00:36.479855Z","caller":"traceutil/trace.go:171","msg":"trace[513110777] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"260.677717ms","start":"2026-04-07T06:00:36.219129Z","end":"2026-04-07T06:00:36.479807Z","steps":["trace[513110777] 'process raft request' (duration: 260.38016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:00:36.480052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.643179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T06:00:36.480153Z","caller":"traceutil/trace.go:171","msg":"trace[1000456949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5241; }","duration":"159.812273ms","start":"2026-04-07T06:00:36.320321Z","end":"2026-04-07T06:00:36.480133Z","steps":["trace[1000456949] 'agreement among raft nodes before linearized reading' (duration: 159.564487ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:00:36.487903Z","caller":"traceutil/trace.go:171","msg":"trace[1356170066] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"148.66405ms","start":"2026-04-07T06:00:36.339214Z","end":"2026-04-07T06:00:36.487878Z","steps":["trace[1356170066] 'process raft request' (duration: 148.585618ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:00:36.487943Z","caller":"traceutil/trace.go:171","msg":"trace[363530823] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"149.588401ms","start":"2026-04-07T06:00:36.338314Z","end":"2026-04-07T06:00:36.487902Z","steps":["trace[363530823] 'process raft request' (duration: 149.287404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:00:44.944038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.926654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T06:00:44.944177Z","caller":"traceutil/trace.go:171","msg":"trace[988319118] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5393; }","duration":"154.145459ms","start":"2026-04-07T06:00:44.790005Z","end":"2026-04-07T06:00:44.94415Z","steps":["trace[988319118] 'range keys from in-memory index tree' (duration: 153.581866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:00:44.944277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.964574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-04-07T06:00:44.944384Z","caller":"traceutil/trace.go:171","msg":"trace[1130474097] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5393; }","duration":"202.138429ms","start":"2026-04-07T06:00:44.742221Z","end":"2026-04-07T06:00:44.944359Z","steps":["trace[1130474097] 'range keys from in-memory index tree' (duration: 201.704418ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:00:45.923466Z","caller":"traceutil/trace.go:171","msg":"trace[1009939298] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"106.654491ms","start":"2026-04-07T06:00:45.816779Z","end":"2026-04-07T06:00:45.923433Z","steps":["trace[1009939298] 'process raft request' (duration: 106.425716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:41.3195Z","caller":"traceutil/trace.go:171","msg":"trace[656175848] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"157.430979ms","start":"2026-04-07T06:01:41.16205Z","end":"2026-04-07T06:01:41.319481Z","steps":["trace[656175848] 'process raft request' (duration: 157.226855ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:41.673941Z","caller":"traceutil/trace.go:171","msg":"trace[913442953] linearizableReadLoop","detail":"{readStateIndex:6069; appliedIndex:6068; }","duration":"288.334795ms","start":"2026-04-07T06:01:41.385584Z","end":"2026-04-07T06:01:41.673919Z","steps":["trace[913442953] 'read index received' (duration: 288.184262ms)","trace[913442953] 'applied index is now lower than readState.Index' (duration: 149.643µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:01:41.674369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.701634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-07T06:01:41.674405Z","caller":"traceutil/trace.go:171","msg":"trace[2017005511] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"346.079191ms","start":"2026-04-07T06:01:41.32831Z","end":"2026-04-07T06:01:41.674389Z","steps":["trace[2017005511] 'process raft request' (duration: 345.480447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:41.674452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.478511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T06:01:41.674469Z","caller":"traceutil/trace.go:171","msg":"trace[1988762792] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5846; }","duration":"288.909808ms","start":"2026-04-07T06:01:41.385529Z","end":"2026-04-07T06:01:41.674439Z","steps":["trace[1988762792] 'agreement among raft nodes before linearized reading' (duration: 288.582611ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:41.674516Z","caller":"traceutil/trace.go:171","msg":"trace[141896074] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5846; }","duration":"286.557643ms","start":"2026-04-07T06:01:41.387944Z","end":"2026-04-07T06:01:41.674502Z","steps":["trace[141896074] 'agreement among raft nodes before linearized reading' (duration: 286.459091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:41.674539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:01:41.328292Z","time spent":"346.159514ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8455,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T06:01:41.674545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.168163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp58q\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T06:01:41.674607Z","caller":"traceutil/trace.go:171","msg":"trace[855940966] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp58q; range_end:; response_count:1; response_revision:5846; }","duration":"243.242655ms","start":"2026-04-07T06:01:41.431351Z","end":"2026-04-07T06:01:41.674594Z","steps":["trace[855940966] 'agreement among raft nodes before linearized reading' (duration: 243.063881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:41.674729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.292939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T06:01:41.674848Z","caller":"traceutil/trace.go:171","msg":"trace[1503326010] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5846; }","duration":"175.476753ms","start":"2026-04-07T06:01:41.49932Z","end":"2026-04-07T06:01:41.674797Z","steps":["trace[1503326010] 'agreement among raft nodes before linearized reading' (duration: 175.093004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:41.674792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.202142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-07T06:01:41.675008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.093227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T06:01:41.675155Z","caller":"traceutil/trace.go:171","msg":"trace[718313151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5846; }","duration":"170.353273ms","start":"2026-04-07T06:01:41.504761Z","end":"2026-04-07T06:01:41.675114Z","steps":["trace[718313151] 'agreement among raft nodes before linearized reading' (duration: 169.640687ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:41.675209Z","caller":"traceutil/trace.go:171","msg":"trace[458069954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5846; }","duration":"168.465788ms","start":"2026-04-07T06:01:41.506515Z","end":"2026-04-07T06:01:41.674981Z","steps":["trace[458069954] 'agreement among raft nodes before linearized reading' (duration: 167.904805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:42.207922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537704148367300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T06:01:42.273964Z","caller":"traceutil/trace.go:171","msg":"trace[881211903] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"571.635222ms","start":"2026-04-07T06:01:41.702303Z","end":"2026-04-07T06:01:42.273939Z","steps":["trace[881211903] 'process raft request' (duration: 555.288417ms)","trace[881211903] 'compare' (duration: 16.069008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:01:42.274146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:01:41.70228Z","time spent":"571.756714ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:01:42.310944Z","caller":"traceutil/trace.go:171","msg":"trace[1384949625] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"342.022537ms","start":"2026-04-07T06:01:41.968899Z","end":"2026-04-07T06:01:42.310921Z","steps":["trace[1384949625] 'process raft request' (duration: 341.832682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:42.311097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:01:41.968862Z","time spent":"342.15681ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:01:42.311105Z","caller":"traceutil/trace.go:171","msg":"trace[545383749] linearizableReadLoop","detail":"{readStateIndex:6074; appliedIndex:6071; }","duration":"603.716425ms","start":"2026-04-07T06:01:41.707368Z","end":"2026-04-07T06:01:42.311085Z","steps":["trace[545383749] 'read index received' (duration: 550.242979ms)","trace[545383749] 'applied index is now lower than readState.Index' (duration: 53.471856ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:01:42.31121Z","caller":"traceutil/trace.go:171","msg":"trace[588889932] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"156.738423ms","start":"2026-04-07T06:01:42.154458Z","end":"2026-04-07T06:01:42.311197Z","steps":["trace[588889932] 'process raft request' (duration: 156.401945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:42.311521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.146125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-07T06:01:42.311607Z","caller":"traceutil/trace.go:171","msg":"trace[1484195154] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5851; }","duration":"604.259128ms","start":"2026-04-07T06:01:41.707327Z","end":"2026-04-07T06:01:42.311586Z","steps":["trace[1484195154] 'agreement among raft nodes before linearized reading' (duration: 604.061684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:42.311652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:01:41.707291Z","time spent":"604.35213ms","remote":"127.0.0.1:56146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5707,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T06:01:42.455067Z","caller":"traceutil/trace.go:171","msg":"trace[1706901951] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"102.941738ms","start":"2026-04-07T06:01:42.352088Z","end":"2026-04-07T06:01:42.45503Z","steps":["trace[1706901951] 'process raft request' (duration: 63.319297ms)","trace[1706901951] 'compare' (duration: 39.372305ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:01:42.47874Z","caller":"traceutil/trace.go:171","msg":"trace[5435678] transaction","detail":"{read_only:false; response_revision:5854; number_of_response:1; }","duration":"126.617185ms","start":"2026-04-07T06:01:42.352086Z","end":"2026-04-07T06:01:42.478703Z","steps":["trace[5435678] 'process raft request' (duration: 126.325148ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.47881Z","caller":"traceutil/trace.go:171","msg":"trace[1090423237] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"125.491309ms","start":"2026-04-07T06:01:42.353295Z","end":"2026-04-07T06:01:42.478786Z","steps":["trace[1090423237] 'process raft request' (duration: 125.358605ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.479073Z","caller":"traceutil/trace.go:171","msg":"trace[1383815496] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"125.709194ms","start":"2026-04-07T06:01:42.353342Z","end":"2026-04-07T06:01:42.479051Z","steps":["trace[1383815496] 'process raft request' (duration: 125.394896ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.479101Z","caller":"traceutil/trace.go:171","msg":"trace[486158244] linearizableReadLoop","detail":"{readStateIndex:6079; appliedIndex:6075; }","duration":"124.7432ms","start":"2026-04-07T06:01:42.354336Z","end":"2026-04-07T06:01:42.479079Z","steps":["trace[486158244] 'read index received' (duration: 61.103735ms)","trace[486158244] 'applied index is now lower than readState.Index' (duration: 63.638505ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:01:42.479179Z","caller":"traceutil/trace.go:171","msg":"trace[1546068149] transaction","detail":"{read_only:false; response_revision:5857; number_of_response:1; }","duration":"120.119792ms","start":"2026-04-07T06:01:42.359041Z","end":"2026-04-07T06:01:42.47916Z","steps":["trace[1546068149] 'process raft request' (duration: 119.930548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:01:42.479316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.964366ms","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-07T06:01:42.479363Z","caller":"traceutil/trace.go:171","msg":"trace[372855522] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5857; }","duration":"125.022518ms","start":"2026-04-07T06:01:42.354326Z","end":"2026-04-07T06:01:42.479349Z","steps":["trace[372855522] 'agreement among raft nodes before linearized reading' (duration: 124.862144ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.627783Z","caller":"traceutil/trace.go:171","msg":"trace[1511981407] transaction","detail":"{read_only:false; response_revision:5859; number_of_response:1; }","duration":"144.007004ms","start":"2026-04-07T06:01:42.483742Z","end":"2026-04-07T06:01:42.627749Z","steps":["trace[1511981407] 'process raft request' (duration: 107.535967ms)","trace[1511981407] 'compare' (duration: 36.19207ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:01:42.628077Z","caller":"traceutil/trace.go:171","msg":"trace[1140752120] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"144.232089ms","start":"2026-04-07T06:01:42.483819Z","end":"2026-04-07T06:01:42.628051Z","steps":["trace[1140752120] 'process raft request' (duration: 143.838989ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.628158Z","caller":"traceutil/trace.go:171","msg":"trace[197527330] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"143.81743ms","start":"2026-04-07T06:01:42.484313Z","end":"2026-04-07T06:01:42.62813Z","steps":["trace[197527330] 'process raft request' (duration: 143.637115ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.628288Z","caller":"traceutil/trace.go:171","msg":"trace[1584967953] transaction","detail":"{read_only:false; response_revision:5862; number_of_response:1; }","duration":"142.853137ms","start":"2026-04-07T06:01:42.485424Z","end":"2026-04-07T06:01:42.628277Z","steps":["trace[1584967953] 'process raft request' (duration: 142.637522ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:42.628324Z","caller":"traceutil/trace.go:171","msg":"trace[1309039245] transaction","detail":"{read_only:false; response_revision:5863; number_of_response:1; }","duration":"141.650018ms","start":"2026-04-07T06:01:42.486637Z","end":"2026-04-07T06:01:42.628287Z","steps":["trace[1309039245] 'process raft request' (duration: 141.489845ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:01:56.934513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-04-07T06:01:57.022154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"86.39054ms","hash":1564678341,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":30367744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T06:01:57.022226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1564678341,"revision":3023,"compact-revision":-1} {"level":"info","ts":"2026-04-07T06:06:56.941309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5970} {"level":"info","ts":"2026-04-07T06:06:57.021273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5970,"took":"78.110444ms","hash":3071984243,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":25231360,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T06:06:57.021345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3071984243,"revision":5970,"compact-revision":3023} {"level":"info","ts":"2026-04-07T06:07:29.79462Z","caller":"traceutil/trace.go:171","msg":"trace[659326435] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"354.486289ms","start":"2026-04-07T06:07:29.440097Z","end":"2026-04-07T06:07:29.794583Z","steps":["trace[659326435] 'process raft request' (duration: 354.292594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:29.794847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:07:29.440079Z","time spent":"354.656193ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:07:29.869399Z","caller":"traceutil/trace.go:171","msg":"trace[2077950610] linearizableReadLoop","detail":"{readStateIndex:8811; appliedIndex:8809; }","duration":"356.770692ms","start":"2026-04-07T06:07:29.512602Z","end":"2026-04-07T06:07:29.869373Z","steps":["trace[2077950610] 'read index received' (duration: 281.795192ms)","trace[2077950610] 'applied index is now lower than readState.Index' (duration: 74.97431ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:07:29.86952Z","caller":"traceutil/trace.go:171","msg":"trace[1136090863] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"215.016826ms","start":"2026-04-07T06:07:29.654494Z","end":"2026-04-07T06:07:29.869511Z","steps":["trace[1136090863] 'process raft request' (duration: 214.841691ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:07:29.869513Z","caller":"traceutil/trace.go:171","msg":"trace[69914075] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"428.139888ms","start":"2026-04-07T06:07:29.441339Z","end":"2026-04-07T06:07:29.869479Z","steps":["trace[69914075] 'process raft request' (duration: 427.866991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:29.869701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:07:29.441315Z","time spent":"428.2741ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T06:07:29.869797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.448003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T06:07:29.869859Z","caller":"traceutil/trace.go:171","msg":"trace[1585738844] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8505; }","duration":"156.571116ms","start":"2026-04-07T06:07:29.71327Z","end":"2026-04-07T06:07:29.869841Z","steps":["trace[1585738844] 'agreement among raft nodes before linearized reading' (duration: 156.449493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:29.869848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.869036ms","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-07T06:07:29.869885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.716654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp58q\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T06:07:29.869924Z","caller":"traceutil/trace.go:171","msg":"trace[794066179] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8505; }","duration":"226.977689ms","start":"2026-04-07T06:07:29.642927Z","end":"2026-04-07T06:07:29.869904Z","steps":["trace[794066179] 'agreement among raft nodes before linearized reading' (duration: 226.851916ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:07:29.869948Z","caller":"traceutil/trace.go:171","msg":"trace[678944704] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp58q; range_end:; response_count:1; response_revision:8505; }","duration":"243.782825ms","start":"2026-04-07T06:07:29.626149Z","end":"2026-04-07T06:07:29.869931Z","steps":["trace[678944704] 'agreement among raft nodes before linearized reading' (duration: 243.55604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:29.870026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.422598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414469"} {"level":"info","ts":"2026-04-07T06:07:29.870073Z","caller":"traceutil/trace.go:171","msg":"trace[1311763984] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8505; }","duration":"357.529391ms","start":"2026-04-07T06:07:29.512531Z","end":"2026-04-07T06:07:29.870061Z","steps":["trace[1311763984] 'agreement among raft nodes before linearized reading' (duration: 357.281395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:29.870101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:07:29.512501Z","time spent":"357.591302ms","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":414493,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-07T06:07:41.542868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.931316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-07T06:07:41.542953Z","caller":"traceutil/trace.go:171","msg":"trace[908879571] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8567; }","duration":"160.053088ms","start":"2026-04-07T06:07:41.382877Z","end":"2026-04-07T06:07:41.54293Z","steps":["trace[908879571] 'range keys from in-memory index tree' (duration: 159.727841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:07:41.54296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.007396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T06:07:41.543014Z","caller":"traceutil/trace.go:171","msg":"trace[1772232969] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8567; }","duration":"237.089468ms","start":"2026-04-07T06:07:41.305905Z","end":"2026-04-07T06:07:41.542995Z","steps":["trace[1772232969] 'count revisions from in-memory index tree' (duration: 236.948364ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:07:42.339145Z","caller":"traceutil/trace.go:171","msg":"trace[1938759514] linearizableReadLoop","detail":"{readStateIndex:8885; appliedIndex:8884; }","duration":"172.552794ms","start":"2026-04-07T06:07:42.166562Z","end":"2026-04-07T06:07:42.339115Z","steps":["trace[1938759514] 'read index received' (duration: 80.683225ms)","trace[1938759514] 'applied index is now lower than readState.Index' (duration: 91.867699ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:07:42.339322Z","caller":"traceutil/trace.go:171","msg":"trace[1812737245] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"223.728882ms","start":"2026-04-07T06:07:42.115566Z","end":"2026-04-07T06:07:42.339295Z","steps":["trace[1812737245] 'process raft request' (duration: 131.71955ms)","trace[1812737245] 'compare' (duration: 91.49242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:07:42.339551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.973973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T06:07:42.339601Z","caller":"traceutil/trace.go:171","msg":"trace[1596443318] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8574; }","duration":"173.094856ms","start":"2026-04-07T06:07:42.166494Z","end":"2026-04-07T06:07:42.339589Z","steps":["trace[1596443318] 'agreement among raft nodes before linearized reading' (duration: 172.979963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:07:43.088703Z","caller":"traceutil/trace.go:171","msg":"trace[823346096] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"202.301537ms","start":"2026-04-07T06:07:42.886367Z","end":"2026-04-07T06:07:43.088669Z","steps":["trace[823346096] 'process raft request' (duration: 169.037581ms)","trace[823346096] 'compare' (duration: 32.927038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:08:11.308828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.519971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T06:08:11.308974Z","caller":"traceutil/trace.go:171","msg":"trace[1977919063] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8778; }","duration":"438.699436ms","start":"2026-04-07T06:08:10.870226Z","end":"2026-04-07T06:08:11.308925Z","steps":["trace[1977919063] 'count revisions from in-memory index tree' (duration: 438.43464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:11.309025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:10.870186Z","time spent":"438.827269ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-04-07T06:08:11.404422Z","caller":"traceutil/trace.go:171","msg":"trace[1983186140] linearizableReadLoop","detail":"{readStateIndex:9098; appliedIndex:9097; }","duration":"394.416531ms","start":"2026-04-07T06:08:11.009984Z","end":"2026-04-07T06:08:11.404401Z","steps":["trace[1983186140] 'read index received' (duration: 394.204426ms)","trace[1983186140] 'applied index is now lower than readState.Index' (duration: 211.445µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:08:11.404511Z","caller":"traceutil/trace.go:171","msg":"trace[1665329521] transaction","detail":"{read_only:false; response_revision:8779; number_of_response:1; }","duration":"424.253975ms","start":"2026-04-07T06:08:10.980237Z","end":"2026-04-07T06:08:11.404491Z","steps":["trace[1665329521] 'process raft request' (duration: 424.004309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:11.404614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.629225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-07T06:08:11.404621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:10.980199Z","time spent":"424.355557ms","remote":"127.0.0.1:46296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19368,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:08:11.404636Z","caller":"traceutil/trace.go:171","msg":"trace[1494515634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8779; }","duration":"394.710798ms","start":"2026-04-07T06:08:11.009919Z","end":"2026-04-07T06:08:11.40463Z","steps":["trace[1494515634] 'agreement among raft nodes before linearized reading' (duration: 394.576895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:11.404658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:11.009894Z","time spent":"394.756049ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T06:08:11.404766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.28226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T06:08:11.404797Z","caller":"traceutil/trace.go:171","msg":"trace[482247725] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8779; }","duration":"331.348302ms","start":"2026-04-07T06:08:11.073441Z","end":"2026-04-07T06:08:11.40479Z","steps":["trace[482247725] 'agreement among raft nodes before linearized reading' (duration: 331.26936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:11.404822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:11.073419Z","time spent":"331.397943ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-07T06:08:11.404947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.567358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T06:08:11.404973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.493632ms","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-07T06:08:11.404991Z","caller":"traceutil/trace.go:171","msg":"trace[1404207971] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8779; }","duration":"204.65271ms","start":"2026-04-07T06:08:11.200332Z","end":"2026-04-07T06:08:11.404985Z","steps":["trace[1404207971] 'agreement among raft nodes before linearized reading' (duration: 204.584159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:11.405005Z","caller":"traceutil/trace.go:171","msg":"trace[1918469855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8779; }","duration":"189.568465ms","start":"2026-04-07T06:08:11.215427Z","end":"2026-04-07T06:08:11.404996Z","steps":["trace[1918469855] 'agreement among raft nodes before linearized reading' (duration: 189.470422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:23.444464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.603751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T06:08:23.444495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.8109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T06:08:23.444556Z","caller":"traceutil/trace.go:171","msg":"trace[2009466947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8881; }","duration":"124.752165ms","start":"2026-04-07T06:08:23.319783Z","end":"2026-04-07T06:08:23.444535Z","steps":["trace[2009466947] 'range keys from in-memory index tree' (duration: 124.51942ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:23.444587Z","caller":"traceutil/trace.go:171","msg":"trace[1612992312] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8881; }","duration":"197.976483ms","start":"2026-04-07T06:08:23.246586Z","end":"2026-04-07T06:08:23.444563Z","steps":["trace[1612992312] 'count revisions from in-memory index tree' (duration: 197.747218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:23.444921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.665233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-2cgqv\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-04-07T06:08:23.445003Z","caller":"traceutil/trace.go:171","msg":"trace[1225412848] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-2cgqv; range_end:; response_count:1; response_revision:8881; }","duration":"196.752075ms","start":"2026-04-07T06:08:23.248236Z","end":"2026-04-07T06:08:23.444988Z","steps":["trace[1225412848] 'range keys from in-memory index tree' (duration: 196.54398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:23.44522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.562521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2cgqv\" ","response":"range_response_count:1 size:4667"} {"level":"info","ts":"2026-04-07T06:08:23.445259Z","caller":"traceutil/trace.go:171","msg":"trace[2012259332] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2cgqv; range_end:; response_count:1; response_revision:8881; }","duration":"196.653183ms","start":"2026-04-07T06:08:23.248597Z","end":"2026-04-07T06:08:23.445251Z","steps":["trace[2012259332] 'range keys from in-memory index tree' (duration: 196.465278ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:23.651882Z","caller":"traceutil/trace.go:171","msg":"trace[1251829686] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"195.995617ms","start":"2026-04-07T06:08:23.455863Z","end":"2026-04-07T06:08:23.651859Z","steps":["trace[1251829686] 'process raft request' (duration: 195.832783ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:23.663347Z","caller":"traceutil/trace.go:171","msg":"trace[104734495] linearizableReadLoop","detail":"{readStateIndex:9207; appliedIndex:9205; }","duration":"169.251036ms","start":"2026-04-07T06:08:23.494075Z","end":"2026-04-07T06:08:23.663326Z","steps":["trace[104734495] 'read index received' (duration: 157.677013ms)","trace[104734495] 'applied index is now lower than readState.Index' (duration: 11.572843ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:08:23.663547Z","caller":"traceutil/trace.go:171","msg":"trace[678972245] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8883; }","duration":"207.254193ms","start":"2026-04-07T06:08:23.456284Z","end":"2026-04-07T06:08:23.663538Z","steps":["trace[678972245] 'process raft request' (duration: 206.865914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:23.663929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.841399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-07T06:08:23.663908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.679088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T06:08:23.66399Z","caller":"traceutil/trace.go:171","msg":"trace[780795253] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"123.117476ms","start":"2026-04-07T06:08:23.540866Z","end":"2026-04-07T06:08:23.663984Z","steps":["trace[780795253] 'process raft request' (duration: 122.396869ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:23.664205Z","caller":"traceutil/trace.go:171","msg":"trace[885806281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8884; }","duration":"164.981804ms","start":"2026-04-07T06:08:23.499165Z","end":"2026-04-07T06:08:23.664147Z","steps":["trace[885806281] 'agreement among raft nodes before linearized reading' (duration: 164.530684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:23.664227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.510259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2cgqv\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-04-07T06:08:23.664267Z","caller":"traceutil/trace.go:171","msg":"trace[15473416] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2cgqv; range_end:; response_count:1; response_revision:8884; }","duration":"124.571771ms","start":"2026-04-07T06:08:23.539688Z","end":"2026-04-07T06:08:23.66426Z","steps":["trace[15473416] 'agreement among raft nodes before linearized reading' (duration: 124.510829ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:23.663964Z","caller":"traceutil/trace.go:171","msg":"trace[328373809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8884; }","duration":"169.927332ms","start":"2026-04-07T06:08:23.494029Z","end":"2026-04-07T06:08:23.663956Z","steps":["trace[328373809] 'agreement among raft nodes before linearized reading' (duration: 169.757188ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:24.254609Z","caller":"traceutil/trace.go:171","msg":"trace[1382110274] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9211; }","duration":"130.226894ms","start":"2026-04-07T06:08:24.124339Z","end":"2026-04-07T06:08:24.254566Z","steps":["trace[1382110274] 'read index received' (duration: 130.010299ms)","trace[1382110274] 'applied index is now lower than readState.Index' (duration: 215.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:08:24.254776Z","caller":"traceutil/trace.go:171","msg":"trace[55680440] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"160.1193ms","start":"2026-04-07T06:08:24.094619Z","end":"2026-04-07T06:08:24.254738Z","steps":["trace[55680440] 'process raft request' (duration: 159.69133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.255966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.613543ms","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-07T06:08:24.256049Z","caller":"traceutil/trace.go:171","msg":"trace[464298842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8888; }","duration":"131.74342ms","start":"2026-04-07T06:08:24.124286Z","end":"2026-04-07T06:08:24.25603Z","steps":["trace[464298842] 'agreement among raft nodes before linearized reading' (duration: 130.47505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:24.350372Z","caller":"traceutil/trace.go:171","msg":"trace[1148607154] transaction","detail":"{read_only:false; response_revision:8889; number_of_response:1; }","duration":"113.50029ms","start":"2026-04-07T06:08:24.236827Z","end":"2026-04-07T06:08:24.350327Z","steps":["trace[1148607154] 'process raft request' (duration: 83.00868ms)","trace[1148607154] 'compare' (duration: 30.251084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:08:24.350467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.021675ms","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-07T06:08:24.350476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.300503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-07T06:08:24.350505Z","caller":"traceutil/trace.go:171","msg":"trace[790265416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8889; }","duration":"220.116786ms","start":"2026-04-07T06:08:24.130378Z","end":"2026-04-07T06:08:24.350495Z","steps":["trace[790265416] 'agreement among raft nodes before linearized reading' (duration: 219.956612ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:24.350535Z","caller":"traceutil/trace.go:171","msg":"trace[1142904] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8889; }","duration":"190.411715ms","start":"2026-04-07T06:08:24.160109Z","end":"2026-04-07T06:08:24.350521Z","steps":["trace[1142904] 'agreement among raft nodes before linearized reading' (duration: 190.223171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.350841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.87659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pp58q\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T06:08:24.350961Z","caller":"traceutil/trace.go:171","msg":"trace[837235249] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pp58q; range_end:; response_count:1; response_revision:8889; }","duration":"167.042403ms","start":"2026-04-07T06:08:24.183892Z","end":"2026-04-07T06:08:24.350934Z","steps":["trace[837235249] 'agreement among raft nodes before linearized reading' (duration: 166.46488ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:24.370951Z","caller":"traceutil/trace.go:171","msg":"trace[1938696072] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"110.013667ms","start":"2026-04-07T06:08:24.260902Z","end":"2026-04-07T06:08:24.370915Z","steps":["trace[1938696072] 'process raft request' (duration: 109.814003ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:08:24.67831Z","caller":"traceutil/trace.go:171","msg":"trace[1344794952] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"323.776534ms","start":"2026-04-07T06:08:24.354508Z","end":"2026-04-07T06:08:24.678285Z","steps":["trace[1344794952] 'process raft request' (duration: 323.579009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.678465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:24.354485Z","time spent":"323.896967ms","remote":"127.0.0.1:46380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:08:24.678875Z","caller":"traceutil/trace.go:171","msg":"trace[1456788691] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"317.094806ms","start":"2026-04-07T06:08:24.361741Z","end":"2026-04-07T06:08:24.678836Z","steps":["trace[1456788691] 'process raft request' (duration: 316.487471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.679054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:24.361729Z","time spent":"317.224539ms","remote":"127.0.0.1:40586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5857,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T06:08:24.67849Z","caller":"traceutil/trace.go:171","msg":"trace[2078737117] linearizableReadLoop","detail":"{readStateIndex:9217; appliedIndex:9215; }","duration":"307.584762ms","start":"2026-04-07T06:08:24.370878Z","end":"2026-04-07T06:08:24.678463Z","steps":["trace[2078737117] 'read index received' (duration: 307.308245ms)","trace[2078737117] 'applied index is now lower than readState.Index' (duration: 274.817µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T06:08:24.679265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.807438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228428"} {"level":"info","ts":"2026-04-07T06:08:24.679332Z","caller":"traceutil/trace.go:171","msg":"trace[1118528699] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8893; }","duration":"324.905131ms","start":"2026-04-07T06:08:24.354414Z","end":"2026-04-07T06:08:24.679319Z","steps":["trace[1118528699] 'agreement among raft nodes before linearized reading' (duration: 324.484471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.679369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T06:08:24.354392Z","time spent":"324.967972ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228452,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-07T06:08:24.702756Z","caller":"traceutil/trace.go:171","msg":"trace[1138285058] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"171.833866ms","start":"2026-04-07T06:08:24.530881Z","end":"2026-04-07T06:08:24.702715Z","steps":["trace[1138285058] 'process raft request' (duration: 171.655522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T06:08:24.702853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.606323ms","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-07T06:08:24.702921Z","caller":"traceutil/trace.go:171","msg":"trace[396983994] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8894; }","duration":"130.719106ms","start":"2026-04-07T06:08:24.572183Z","end":"2026-04-07T06:08:24.702902Z","steps":["trace[396983994] 'agreement among raft nodes before linearized reading' (duration: 130.518291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T06:09:12.137008Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000025b2.wal"} {"level":"info","ts":"2026-04-07T06:09:42.323Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T06:09:42.327332Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T06:09:42.327414Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T06:11:56.946765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8156} {"level":"info","ts":"2026-04-07T06:11:57.011055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8156,"took":"60.162835ms","hash":1964825698,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T06:11:57.011122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1964825698,"revision":8156,"compact-revision":5970} {"level":"info","ts":"2026-04-07T06:16:56.961663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10683} {"level":"info","ts":"2026-04-07T06:16:57.043143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10683,"took":"79.074985ms","hash":4099369265,"current-db-size-bytes":57028608,"current-db-size":"57 MB","current-db-size-in-use-bytes":21422080,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T06:16:57.043229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4099369265,"revision":10683,"compact-revision":8156}