{"level":"warn","ts":"2026-04-28T06:48:14.843758Z","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-28T06:48:14.843846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-28T06:48:14.843921Z","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-28T06:48:14.843938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-28T06:48:14.843962Z","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-28T06:48:14.844869Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-28T06:48:14.844962Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-28T06:48:14.84898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.772378ms"} {"level":"info","ts":"2026-04-28T06:48:14.855101Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-28T06:48:14.855235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:48:14.855273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-28T06:48:14.855298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:48:14.855316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-28T06:48:14.855386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-28T06:48:14.86058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:48:14.861923Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:48:14.862871Z","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-28T06:48:14.868635Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:48:14.868951Z","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-28T06:48:14.869038Z","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-28T06:48:14.869072Z","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-28T06:48:14.87028Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:48:14.870526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-28T06:48:14.870656Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-28T06:48:14.874136Z","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-28T06:48:14.874241Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-28T06:48:14.874304Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-28T06:48:14.8745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:48:14.874547Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:48:15.355624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:48:15.355666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:48:15.355686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-28T06:48:15.355698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:48:15.355704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-28T06:48:15.355717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-28T06:48:15.355724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-28T06:48:15.357867Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:48:15.360078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:48:15.360175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:48:15.360195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:48:15.361079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:48:15.361209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:48:15.361733Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-28T06:48:15.363144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:48:15.36319Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:48:15.364289Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:48:15.364305Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:48:17.014897Z","caller":"traceutil/trace.go:171","msg":"trace[1284694452] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"351.57688ms","start":"2026-04-28T06:48:16.663303Z","end":"2026-04-28T06:48:17.014879Z","steps":["trace[1284694452] 'read index received' (duration: 333.50397ms)","trace[1284694452] 'applied index is now lower than readState.Index' (duration: 18.07205ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:17.014944Z","caller":"traceutil/trace.go:171","msg":"trace[1181384608] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"355.922381ms","start":"2026-04-28T06:48:16.658982Z","end":"2026-04-28T06:48:17.014904Z","steps":["trace[1181384608] 'process raft request' (duration: 337.81114ms)","trace[1181384608] 'compare' (duration: 18.000279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:17.015037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.131176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.015075Z","caller":"traceutil/trace.go:171","msg":"trace[933697506] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"356.224499ms","start":"2026-04-28T06:48:16.658844Z","end":"2026-04-28T06:48:17.015068Z","steps":["trace[933697506] 'agreement among raft nodes before linearized reading' (duration: 356.115736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.015096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.658828Z","time spent":"356.262279ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-28T06:48:17.015112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.146197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.015212Z","caller":"traceutil/trace.go:171","msg":"trace[1933621689] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3; }","duration":"356.306961ms","start":"2026-04-28T06:48:16.658887Z","end":"2026-04-28T06:48:17.015194Z","steps":["trace[1933621689] 'agreement among raft nodes before linearized reading' (duration: 356.046855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.015355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.658839Z","time spent":"356.505925ms","remote":"127.0.0.1:43470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":26,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-28T06:48:17.015544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.658968Z","time spent":"356.062625ms","remote":"127.0.0.1:43460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:17.244202Z","caller":"traceutil/trace.go:171","msg":"trace[1253868396] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:6; }","duration":"229.210622ms","start":"2026-04-28T06:48:17.014944Z","end":"2026-04-28T06:48:17.244154Z","steps":["trace[1253868396] 'read index received' (duration: 200.042213ms)","trace[1253868396] 'applied index is now lower than readState.Index' (duration: 29.167289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:17.244451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.253123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.244547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.522195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.244564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.099019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.24461Z","caller":"traceutil/trace.go:171","msg":"trace[1051875922] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"538.178551ms","start":"2026-04-28T06:48:16.706419Z","end":"2026-04-28T06:48:17.244597Z","steps":["trace[1051875922] 'agreement among raft nodes before linearized reading' (duration: 538.106039ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.244618Z","caller":"traceutil/trace.go:171","msg":"trace[1144939340] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"537.600678ms","start":"2026-04-28T06:48:16.706994Z","end":"2026-04-28T06:48:17.244594Z","steps":["trace[1144939340] 'agreement among raft nodes before linearized reading' (duration: 537.500396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.244655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.706396Z","time spent":"538.249402ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:48:17.244661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.706982Z","time spent":"537.670979ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-28T06:48:17.244592Z","caller":"traceutil/trace.go:171","msg":"trace[260747713] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"507.435875ms","start":"2026-04-28T06:48:16.737115Z","end":"2026-04-28T06:48:17.244551Z","steps":["trace[260747713] 'process raft request' (duration: 506.900523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.244701Z","caller":"traceutil/trace.go:171","msg":"trace[1340621984] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"507.934787ms","start":"2026-04-28T06:48:16.736758Z","end":"2026-04-28T06:48:17.244693Z","steps":["trace[1340621984] 'process raft request' (duration: 507.216721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.244583Z","caller":"traceutil/trace.go:171","msg":"trace[979591070] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"535.406806ms","start":"2026-04-28T06:48:16.70916Z","end":"2026-04-28T06:48:17.244567Z","steps":["trace[979591070] 'agreement among raft nodes before linearized reading' (duration: 535.240722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.244813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.736749Z","time spent":"507.995588ms","remote":"127.0.0.1:56460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.244844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.999507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-28T06:48:17.244637Z","caller":"traceutil/trace.go:171","msg":"trace[1713966338] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"507.907816ms","start":"2026-04-28T06:48:16.736692Z","end":"2026-04-28T06:48:17.2446Z","steps":["trace[1713966338] 'process raft request' (duration: 478.286097ms)","trace[1713966338] 'compare' (duration: 28.889302ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:17.244886Z","caller":"traceutil/trace.go:171","msg":"trace[567363786] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"507.688721ms","start":"2026-04-28T06:48:16.737163Z","end":"2026-04-28T06:48:17.244852Z","steps":["trace[567363786] 'process raft request' (duration: 506.884592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.244839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.039557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.244924Z","caller":"traceutil/trace.go:171","msg":"trace[2100241754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"578.100479ms","start":"2026-04-28T06:48:16.666802Z","end":"2026-04-28T06:48:17.244903Z","steps":["trace[2100241754] 'agreement among raft nodes before linearized reading' (duration: 577.921415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.244964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.736678Z","time spent":"508.234743ms","remote":"127.0.0.1:56460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.244967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.666795Z","time spent":"578.163661ms","remote":"127.0.0.1:43460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T06:48:17.244817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.737097Z","time spent":"507.61345ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.24498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.737149Z","time spent":"507.790944ms","remote":"127.0.0.1:56460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.244882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.709153Z","time spent":"535.682172ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-28T06:48:17.245104Z","caller":"traceutil/trace.go:171","msg":"trace[1872654508] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"507.888705ms","start":"2026-04-28T06:48:16.737208Z","end":"2026-04-28T06:48:17.245097Z","steps":["trace[1872654508] 'process raft request' (duration: 506.859892ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.245112Z","caller":"traceutil/trace.go:171","msg":"trace[2087636268] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"538.545799ms","start":"2026-04-28T06:48:16.706458Z","end":"2026-04-28T06:48:17.245004Z","steps":["trace[2087636268] 'agreement among raft nodes before linearized reading' (duration: 538.013367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.245184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.737197Z","time spent":"507.939546ms","remote":"127.0.0.1:56460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.24521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.706442Z","time spent":"538.709503ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-28T06:48:17.245297Z","caller":"traceutil/trace.go:171","msg":"trace[783762041] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"497.212897ms","start":"2026-04-28T06:48:16.748076Z","end":"2026-04-28T06:48:17.245289Z","steps":["trace[783762041] 'process raft request' (duration: 496.0416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.24529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.895862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.245304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.214213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.245311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.854822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.245341Z","caller":"traceutil/trace.go:171","msg":"trace[849870357] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"457.977434ms","start":"2026-04-28T06:48:16.78735Z","end":"2026-04-28T06:48:17.245327Z","steps":["trace[849870357] 'agreement among raft nodes before linearized reading' (duration: 457.894802ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.245344Z","caller":"traceutil/trace.go:171","msg":"trace[506190976] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"508.273235ms","start":"2026-04-28T06:48:16.73706Z","end":"2026-04-28T06:48:17.245333Z","steps":["trace[506190976] 'agreement among raft nodes before linearized reading' (duration: 508.218244ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.245355Z","caller":"traceutil/trace.go:171","msg":"trace[1702878188] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"503.921684ms","start":"2026-04-28T06:48:16.74142Z","end":"2026-04-28T06:48:17.245342Z","steps":["trace[1702878188] 'agreement among raft nodes before linearized reading' (duration: 503.854542ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.24507Z","caller":"traceutil/trace.go:171","msg":"trace[1563632614] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"507.418394ms","start":"2026-04-28T06:48:16.737639Z","end":"2026-04-28T06:48:17.245057Z","steps":["trace[1563632614] 'process raft request' (duration: 506.451232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.245383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.737048Z","time spent":"508.328276ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:48:17.24539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.787325Z","time spent":"458.051736ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T06:48:17.245408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.737631Z","time spent":"507.758222ms","remote":"127.0.0.1:56460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:17.244433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.137179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.245391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.741404Z","time spent":"503.978525ms","remote":"127.0.0.1:43364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-28T06:48:17.245345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.748062Z","time spent":"497.263429ms","remote":"127.0.0.1:43550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:17.245677Z","caller":"traceutil/trace.go:171","msg":"trace[1204209708] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:0; response_revision:10; }","duration":"511.226014ms","start":"2026-04-28T06:48:16.734228Z","end":"2026-04-28T06:48:17.245454Z","steps":["trace[1204209708] 'agreement among raft nodes before linearized reading' (duration: 510.059707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.245717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:16.734202Z","time spent":"511.505989ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-04-28T06:48:17.249361Z","caller":"traceutil/trace.go:171","msg":"trace[1054734172] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"226.186332ms","start":"2026-04-28T06:48:17.023156Z","end":"2026-04-28T06:48:17.249342Z","steps":["trace[1054734172] 'process raft request' (duration: 226.070929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.249562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.527796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.249621Z","caller":"traceutil/trace.go:171","msg":"trace[312979146] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"231.620068ms","start":"2026-04-28T06:48:17.017989Z","end":"2026-04-28T06:48:17.249609Z","steps":["trace[312979146] 'agreement among raft nodes before linearized reading' (duration: 231.543196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:17.249728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.712583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:48:17.249772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.66002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:48:17.249774Z","caller":"traceutil/trace.go:171","msg":"trace[448167141] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:11; }","duration":"232.790905ms","start":"2026-04-28T06:48:17.016971Z","end":"2026-04-28T06:48:17.249762Z","steps":["trace[448167141] 'agreement among raft nodes before linearized reading' (duration: 232.713853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:17.249844Z","caller":"traceutil/trace.go:171","msg":"trace[2060142160] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"109.726252ms","start":"2026-04-28T06:48:17.140072Z","end":"2026-04-28T06:48:17.249798Z","steps":["trace[2060142160] 'agreement among raft nodes before linearized reading' (duration: 109.678321ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:19.566002Z","caller":"traceutil/trace.go:171","msg":"trace[1301688250] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"107.140022ms","start":"2026-04-28T06:48:19.458824Z","end":"2026-04-28T06:48:19.565964Z","steps":["trace[1301688250] 'process raft request' (duration: 67.049759ms)","trace[1301688250] 'compare' (duration: 39.815716ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:52.442675Z","caller":"traceutil/trace.go:171","msg":"trace[1771853372] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"103.43791ms","start":"2026-04-28T06:48:52.339213Z","end":"2026-04-28T06:48:52.442651Z","steps":["trace[1771853372] 'process raft request' (duration: 103.113202ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:52.443045Z","caller":"traceutil/trace.go:171","msg":"trace[1767825793] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"103.89264ms","start":"2026-04-28T06:48:52.339127Z","end":"2026-04-28T06:48:52.44302Z","steps":["trace[1767825793] 'process raft request' (duration: 102.784404ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:52.558352Z","caller":"traceutil/trace.go:171","msg":"trace[2123782156] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"104.554244ms","start":"2026-04-28T06:48:52.453773Z","end":"2026-04-28T06:48:52.558328Z","steps":["trace[2123782156] 'process raft request' (duration: 104.501853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:52.558376Z","caller":"traceutil/trace.go:171","msg":"trace[734007386] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"104.602536ms","start":"2026-04-28T06:48:52.45375Z","end":"2026-04-28T06:48:52.558352Z","steps":["trace[734007386] 'process raft request' (duration: 41.240802ms)","trace[734007386] 'compare' (duration: 63.140809ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:52.560015Z","caller":"traceutil/trace.go:171","msg":"trace[542744513] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"104.563666ms","start":"2026-04-28T06:48:52.455431Z","end":"2026-04-28T06:48:52.559995Z","steps":["trace[542744513] 'process raft request' (duration: 104.113495ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:52.560306Z","caller":"traceutil/trace.go:171","msg":"trace[574387061] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"104.722859ms","start":"2026-04-28T06:48:52.455578Z","end":"2026-04-28T06:48:52.560301Z","steps":["trace[574387061] 'process raft request' (duration: 104.090884ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:54.0856Z","caller":"traceutil/trace.go:171","msg":"trace[582527354] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"136.781069ms","start":"2026-04-28T06:48:53.948796Z","end":"2026-04-28T06:48:54.085577Z","steps":["trace[582527354] 'read index received' (duration: 79.928836ms)","trace[582527354] 'applied index is now lower than readState.Index' (duration: 56.851263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:54.085799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.983523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-28T06:48:54.085845Z","caller":"traceutil/trace.go:171","msg":"trace[1497139666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:603; }","duration":"137.065466ms","start":"2026-04-28T06:48:53.948768Z","end":"2026-04-28T06:48:54.085833Z","steps":["trace[1497139666] 'agreement among raft nodes before linearized reading' (duration: 136.904882ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:54.085976Z","caller":"traceutil/trace.go:171","msg":"trace[1114431878] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"225.720943ms","start":"2026-04-28T06:48:53.860243Z","end":"2026-04-28T06:48:54.085964Z","steps":["trace[1114431878] 'process raft request' (duration: 168.507072ms)","trace[1114431878] 'compare' (duration: 56.675948ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:59.032173Z","caller":"traceutil/trace.go:171","msg":"trace[1183386587] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"138.713613ms","start":"2026-04-28T06:48:58.893434Z","end":"2026-04-28T06:48:59.032147Z","steps":["trace[1183386587] 'read index received' (duration: 137.086835ms)","trace[1183386587] 'applied index is now lower than readState.Index' (duration: 1.625668ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:59.032263Z","caller":"traceutil/trace.go:171","msg":"trace[1549447374] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"156.78565ms","start":"2026-04-28T06:48:58.875435Z","end":"2026-04-28T06:48:59.032221Z","steps":["trace[1549447374] 'process raft request' (duration: 155.322015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:59.032355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.909237ms","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-28T06:48:59.03242Z","caller":"traceutil/trace.go:171","msg":"trace[1014948305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"139.0001ms","start":"2026-04-28T06:48:58.893387Z","end":"2026-04-28T06:48:59.032387Z","steps":["trace[1014948305] 'agreement among raft nodes before linearized reading' (duration: 138.859396ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:59.507821Z","caller":"traceutil/trace.go:171","msg":"trace[162968648] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"337.115173ms","start":"2026-04-28T06:48:59.170685Z","end":"2026-04-28T06:48:59.5078Z","steps":["trace[162968648] 'read index received' (duration: 336.99223ms)","trace[162968648] 'applied index is now lower than readState.Index' (duration: 122.102µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:59.507899Z","caller":"traceutil/trace.go:171","msg":"trace[543843545] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"422.574465ms","start":"2026-04-28T06:48:59.085291Z","end":"2026-04-28T06:48:59.507866Z","steps":["trace[543843545] 'process raft request' (duration: 422.399971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:59.508027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:59.085271Z","time spent":"422.697407ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:59.508071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.112819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:48:59.508126Z","caller":"traceutil/trace.go:171","msg":"trace[747876323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"294.224472ms","start":"2026-04-28T06:48:59.213885Z","end":"2026-04-28T06:48:59.50811Z","steps":["trace[747876323] 'agreement among raft nodes before linearized reading' (duration: 294.063668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:59.508137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.467841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fxfcl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T06:48:59.508193Z","caller":"traceutil/trace.go:171","msg":"trace[954434726] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fxfcl; range_end:; response_count:1; response_revision:636; }","duration":"337.592954ms","start":"2026-04-28T06:48:59.170586Z","end":"2026-04-28T06:48:59.508179Z","steps":["trace[954434726] 'agreement among raft nodes before linearized reading' (duration: 337.296567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:59.508225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:59.170564Z","time spent":"337.653355ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-fxfcl\" "} {"level":"info","ts":"2026-04-28T06:49:47.321492Z","caller":"traceutil/trace.go:171","msg":"trace[1749611758] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"168.173919ms","start":"2026-04-28T06:49:47.153293Z","end":"2026-04-28T06:49:47.321467Z","steps":["trace[1749611758] 'process raft request' (duration: 124.747975ms)","trace[1749611758] 'compare' (duration: 43.099728ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:25.628351Z","caller":"traceutil/trace.go:171","msg":"trace[1149589525] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"102.484073ms","start":"2026-04-28T06:50:25.525843Z","end":"2026-04-28T06:50:25.628327Z","steps":["trace[1149589525] 'process raft request' (duration: 102.336271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.058376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.647842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:50:33.058495Z","caller":"traceutil/trace.go:171","msg":"trace[1277967590] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"362.011977ms","start":"2026-04-28T06:50:32.696466Z","end":"2026-04-28T06:50:33.058478Z","steps":["trace[1277967590] 'read index received' (duration: 40.937037ms)","trace[1277967590] 'applied index is now lower than readState.Index' (duration: 321.07382ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:33.058528Z","caller":"traceutil/trace.go:171","msg":"trace[1890467501] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"365.007717ms","start":"2026-04-28T06:50:32.693488Z","end":"2026-04-28T06:50:33.058495Z","steps":["trace[1890467501] 'process raft request' (duration: 43.902326ms)","trace[1890467501] 'compare' (duration: 320.478288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:33.058726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.252832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-28T06:50:33.058738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.693464Z","time spent":"365.1794ms","remote":"127.0.0.1:43564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:33.058771Z","caller":"traceutil/trace.go:171","msg":"trace[943886586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d; range_end:; response_count:1; response_revision:1868; }","duration":"362.298843ms","start":"2026-04-28T06:50:32.696461Z","end":"2026-04-28T06:50:33.05876Z","steps":["trace[943886586] 'agreement among raft nodes before linearized reading' (duration: 362.089319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.05881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.696428Z","time spent":"362.373136ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d\" "} {"level":"info","ts":"2026-04-28T06:50:33.094848Z","caller":"traceutil/trace.go:171","msg":"trace[100267058] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"388.876808ms","start":"2026-04-28T06:50:32.705954Z","end":"2026-04-28T06:50:33.094831Z","steps":["trace[100267058] 'process raft request' (duration: 388.845457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:33.095002Z","caller":"traceutil/trace.go:171","msg":"trace[1589422159] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"389.590863ms","start":"2026-04-28T06:50:32.705387Z","end":"2026-04-28T06:50:33.094978Z","steps":["trace[1589422159] 'process raft request' (duration: 389.392859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.095142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.705368Z","time spent":"389.697114ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:33.095156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.705945Z","time spent":"388.953009ms","remote":"127.0.0.1:43564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:33.095434Z","caller":"traceutil/trace.go:171","msg":"trace[62318202] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"390.834117ms","start":"2026-04-28T06:50:32.70459Z","end":"2026-04-28T06:50:33.095424Z","steps":["trace[62318202] 'process raft request' (duration: 390.102682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.095495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.704574Z","time spent":"390.900948ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:33.455528Z","caller":"traceutil/trace.go:171","msg":"trace[80185890] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1946; }","duration":"396.973539ms","start":"2026-04-28T06:50:33.058521Z","end":"2026-04-28T06:50:33.455495Z","steps":["trace[80185890] 'read index received' (duration: 36.116263ms)","trace[80185890] 'applied index is now lower than readState.Index' (duration: 360.856286ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:33.455604Z","caller":"traceutil/trace.go:171","msg":"trace[370167390] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"378.656097ms","start":"2026-04-28T06:50:33.076922Z","end":"2026-04-28T06:50:33.455578Z","steps":["trace[370167390] 'process raft request' (duration: 378.457943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.45684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:33.076897Z","time spent":"379.843911ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:33.456914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.598137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:33.457027Z","caller":"traceutil/trace.go:171","msg":"trace[1036170126] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1873; }","duration":"296.77835ms","start":"2026-04-28T06:50:33.160223Z","end":"2026-04-28T06:50:33.457002Z","steps":["trace[1036170126] 'agreement among raft nodes before linearized reading' (duration: 296.553586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:33.455681Z","caller":"traceutil/trace.go:171","msg":"trace[1675526673] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"494.432522ms","start":"2026-04-28T06:50:32.961238Z","end":"2026-04-28T06:50:33.45567Z","steps":["trace[1675526673] 'process raft request' (duration: 449.230499ms)","trace[1675526673] 'compare' (duration: 44.56507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:33.45731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.961139Z","time spent":"496.129846ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:33.456021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.505316ms","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-28T06:50:33.457629Z","caller":"traceutil/trace.go:171","msg":"trace[882462179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"590.162229ms","start":"2026-04-28T06:50:32.867453Z","end":"2026-04-28T06:50:33.457615Z","steps":["trace[882462179] 'agreement among raft nodes before linearized reading' (duration: 588.434055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.457686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.867425Z","time spent":"590.24287ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:50:33.456321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.000041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:33.457881Z","caller":"traceutil/trace.go:171","msg":"trace[1781815620] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1873; }","duration":"674.631444ms","start":"2026-04-28T06:50:32.783236Z","end":"2026-04-28T06:50:33.457867Z","steps":["trace[1781815620] 'agreement among raft nodes before linearized reading' (duration: 672.93798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.457931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.783225Z","time spent":"674.690445ms","remote":"127.0.0.1:60740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:50:33.456376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.906389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-28T06:50:33.458088Z","caller":"traceutil/trace.go:171","msg":"trace[626457567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d; range_end:; response_count:1; response_revision:1873; }","duration":"713.658143ms","start":"2026-04-28T06:50:32.744409Z","end":"2026-04-28T06:50:33.458067Z","steps":["trace[626457567] 'agreement among raft nodes before linearized reading' (duration: 711.897778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:33.458176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:32.744399Z","time spent":"713.751806ms","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3766,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc57f3a2-5136-4d2e-80bc-33c0d83ae79d\" "} {"level":"info","ts":"2026-04-28T06:50:40.834227Z","caller":"traceutil/trace.go:171","msg":"trace[557704644] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"272.048276ms","start":"2026-04-28T06:50:40.562151Z","end":"2026-04-28T06:50:40.834199Z","steps":["trace[557704644] 'read index received' (duration: 271.898713ms)","trace[557704644] 'applied index is now lower than readState.Index' (duration: 148.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:40.834357Z","caller":"traceutil/trace.go:171","msg":"trace[677934024] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"316.589606ms","start":"2026-04-28T06:50:40.51772Z","end":"2026-04-28T06:50:40.83431Z","steps":["trace[677934024] 'process raft request' (duration: 316.28645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.834512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.346412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-28T06:50:40.834562Z","caller":"traceutil/trace.go:171","msg":"trace[1108668172] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1944; }","duration":"272.451944ms","start":"2026-04-28T06:50:40.562094Z","end":"2026-04-28T06:50:40.834546Z","steps":["trace[1108668172] 'agreement among raft nodes before linearized reading' (duration: 272.223329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.834586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.517687Z","time spent":"316.775629ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:40.947627Z","caller":"traceutil/trace.go:171","msg":"trace[1264062609] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"303.119158ms","start":"2026-04-28T06:50:40.644484Z","end":"2026-04-28T06:50:40.947603Z","steps":["trace[1264062609] 'process raft request' (duration: 303.057746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.947782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.64446Z","time spent":"303.240801ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:40.948101Z","caller":"traceutil/trace.go:171","msg":"trace[53993692] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"346.004794ms","start":"2026-04-28T06:50:40.602085Z","end":"2026-04-28T06:50:40.94809Z","steps":["trace[53993692] 'process raft request' (duration: 339.80194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.948159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.602055Z","time spent":"346.071056ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:45.12692Z","caller":"traceutil/trace.go:171","msg":"trace[1385579470] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"167.917579ms","start":"2026-04-28T06:50:44.95898Z","end":"2026-04-28T06:50:45.126898Z","steps":["trace[1385579470] 'process raft request' (duration: 167.783066ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:45.153151Z","caller":"traceutil/trace.go:171","msg":"trace[435372674] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"193.581916ms","start":"2026-04-28T06:50:44.959542Z","end":"2026-04-28T06:50:45.153124Z","steps":["trace[435372674] 'process raft request' (duration: 193.422763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:07.209667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.842964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:51:07.209751Z","caller":"traceutil/trace.go:171","msg":"trace[829999950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"111.940635ms","start":"2026-04-28T06:51:07.097791Z","end":"2026-04-28T06:51:07.209732Z","steps":["trace[829999950] 'range keys from in-memory index tree' (duration: 111.759181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:07.209996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.641081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-28T06:51:07.210096Z","caller":"traceutil/trace.go:171","msg":"trace[1064445705] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"189.823305ms","start":"2026-04-28T06:51:07.02024Z","end":"2026-04-28T06:51:07.210063Z","steps":["trace[1064445705] 'range keys from in-memory index tree' (duration: 189.422336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:07.8959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.469109ms","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-28T06:51:07.895995Z","caller":"traceutil/trace.go:171","msg":"trace[134459072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"145.607762ms","start":"2026-04-28T06:51:07.750366Z","end":"2026-04-28T06:51:07.895974Z","steps":["trace[134459072] 'range keys from in-memory index tree' (duration: 145.270155ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:51:08.311436Z","caller":"traceutil/trace.go:171","msg":"trace[845107885] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"132.601875ms","start":"2026-04-28T06:51:08.178786Z","end":"2026-04-28T06:51:08.311388Z","steps":["trace[845107885] 'process raft request' (duration: 132.213306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:08.621595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.363922ms","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-28T06:51:08.621681Z","caller":"traceutil/trace.go:171","msg":"trace[503467936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2113; }","duration":"223.496755ms","start":"2026-04-28T06:51:08.398165Z","end":"2026-04-28T06:51:08.621662Z","steps":["trace[503467936] 'agreement among raft nodes before linearized reading' (duration: 223.2523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:51:08.621908Z","caller":"traceutil/trace.go:171","msg":"trace[1599953357] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"222.954484ms","start":"2026-04-28T06:51:08.398211Z","end":"2026-04-28T06:51:08.621165Z","steps":["trace[1599953357] 'read index received' (duration: 174.603933ms)","trace[1599953357] 'applied index is now lower than readState.Index' (duration: 48.349131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:51:08.857368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.9497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:51:08.857966Z","caller":"traceutil/trace.go:171","msg":"trace[214593623] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"231.574615ms","start":"2026-04-28T06:51:08.626335Z","end":"2026-04-28T06:51:08.857909Z","steps":["trace[214593623] 'process raft request' (duration: 231.173627ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:51:08.857795Z","caller":"traceutil/trace.go:171","msg":"trace[1900207691] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"234.621328ms","start":"2026-04-28T06:51:08.6231Z","end":"2026-04-28T06:51:08.857722Z","steps":["trace[1900207691] 'process raft request' (duration: 133.166917ms)","trace[1900207691] 'compare' (duration: 100.720975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:51:09.092522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.136724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:51:09.09263Z","caller":"traceutil/trace.go:171","msg":"trace[288543213] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2115; }","duration":"100.313898ms","start":"2026-04-28T06:51:08.992292Z","end":"2026-04-28T06:51:09.092606Z","steps":["trace[288543213] 'count revisions from in-memory index tree' (duration: 100.034142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:51:09.092562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.009524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T06:51:09.092793Z","caller":"traceutil/trace.go:171","msg":"trace[1187743330] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2115; }","duration":"117.272781ms","start":"2026-04-28T06:51:08.975505Z","end":"2026-04-28T06:51:09.092777Z","steps":["trace[1187743330] 'range keys from in-memory index tree' (duration: 116.81943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:14.776667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.693366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:52:14.776776Z","caller":"traceutil/trace.go:171","msg":"trace[1753521555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2573; }","duration":"124.834489ms","start":"2026-04-28T06:52:14.651903Z","end":"2026-04-28T06:52:14.776737Z","steps":["trace[1753521555] 'range keys from in-memory index tree' (duration: 124.480472ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:39.582682Z","caller":"traceutil/trace.go:171","msg":"trace[1058428886] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"107.220617ms","start":"2026-04-28T06:55:39.475432Z","end":"2026-04-28T06:55:39.582652Z","steps":["trace[1058428886] 'process raft request' (duration: 106.494673ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:26.41574Z","caller":"traceutil/trace.go:171","msg":"trace[1803056944] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"102.6679ms","start":"2026-04-28T06:56:26.313048Z","end":"2026-04-28T06:56:26.415716Z","steps":["trace[1803056944] 'process raft request' (duration: 74.817488ms)","trace[1803056944] 'compare' (duration: 27.577427ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:34.842802Z","caller":"traceutil/trace.go:171","msg":"trace[881051300] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"104.939803ms","start":"2026-04-28T06:56:34.737827Z","end":"2026-04-28T06:56:34.842767Z","steps":["trace[881051300] 'process raft request' (duration: 104.785599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.292215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.344665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:35.292385Z","caller":"traceutil/trace.go:171","msg":"trace[1724643859] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4858; }","duration":"195.93786ms","start":"2026-04-28T06:56:35.09643Z","end":"2026-04-28T06:56:35.292368Z","steps":["trace[1724643859] 'read index received' (duration: 193.245414ms)","trace[1724643859] 'applied index is now lower than readState.Index' (duration: 2.691376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:35.292474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.040362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:35.292442Z","caller":"traceutil/trace.go:171","msg":"trace[1637657176] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"273.388008ms","start":"2026-04-28T06:56:35.019023Z","end":"2026-04-28T06:56:35.292411Z","steps":["trace[1637657176] 'process raft request' (duration: 33.754087ms)","trace[1637657176] 'compare' (duration: 239.200991ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:35.292511Z","caller":"traceutil/trace.go:171","msg":"trace[1998019121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4673; }","duration":"196.076893ms","start":"2026-04-28T06:56:35.096425Z","end":"2026-04-28T06:56:35.292501Z","steps":["trace[1998019121] 'agreement among raft nodes before linearized reading' (duration: 195.997181ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.292775Z","caller":"traceutil/trace.go:171","msg":"trace[1947414480] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"267.532617ms","start":"2026-04-28T06:56:35.025184Z","end":"2026-04-28T06:56:35.292717Z","steps":["trace[1947414480] 'process raft request' (duration: 267.128099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.436066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.102016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-04-28T06:56:35.436176Z","caller":"traceutil/trace.go:171","msg":"trace[1942149693] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4679; }","duration":"104.236589ms","start":"2026-04-28T06:56:35.331922Z","end":"2026-04-28T06:56:35.436158Z","steps":["trace[1942149693] 'agreement among raft nodes before linearized reading' (duration: 104.017895ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.436161Z","caller":"traceutil/trace.go:171","msg":"trace[1983861299] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"122.058927ms","start":"2026-04-28T06:56:35.314071Z","end":"2026-04-28T06:56:35.43613Z","steps":["trace[1983861299] 'process raft request' (duration: 120.954623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.436451Z","caller":"traceutil/trace.go:171","msg":"trace[849414259] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"122.097408ms","start":"2026-04-28T06:56:35.314343Z","end":"2026-04-28T06:56:35.43644Z","steps":["trace[849414259] 'process raft request' (duration: 121.502106ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.436475Z","caller":"traceutil/trace.go:171","msg":"trace[2062021557] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"121.525616ms","start":"2026-04-28T06:56:35.31494Z","end":"2026-04-28T06:56:35.436466Z","steps":["trace[2062021557] 'process raft request' (duration: 120.940354ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.436858Z","caller":"traceutil/trace.go:171","msg":"trace[1727855157] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"101.109384ms","start":"2026-04-28T06:56:35.335737Z","end":"2026-04-28T06:56:35.436847Z","steps":["trace[1727855157] 'process raft request' (duration: 100.170375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.437142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.38626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-28T06:56:35.437232Z","caller":"traceutil/trace.go:171","msg":"trace[1439284077] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4679; }","duration":"101.509973ms","start":"2026-04-28T06:56:35.335699Z","end":"2026-04-28T06:56:35.437209Z","steps":["trace[1439284077] 'agreement among raft nodes before linearized reading' (duration: 101.081684ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:47.536706Z","caller":"traceutil/trace.go:171","msg":"trace[171114350] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"117.114343ms","start":"2026-04-28T06:56:47.419567Z","end":"2026-04-28T06:56:47.536681Z","steps":["trace[171114350] 'process raft request' (duration: 116.95172ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:15.469576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2901} {"level":"info","ts":"2026-04-28T06:58:15.547276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2901,"took":"76.32683ms","hash":2114291444,"current-db-size-bytes":54296576,"current-db-size":"54 MB","current-db-size-in-use-bytes":29454336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-28T06:58:15.547382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2114291444,"revision":2901,"compact-revision":-1} {"level":"info","ts":"2026-04-28T07:03:15.474167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5628} {"level":"info","ts":"2026-04-28T07:03:15.545525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5628,"took":"69.844646ms","hash":339520225,"current-db-size-bytes":54296576,"current-db-size":"54 MB","current-db-size-in-use-bytes":18268160,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T07:03:15.545584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":339520225,"revision":5628,"compact-revision":2901}