{"level":"warn","ts":"2026-04-09T05:54:15.335639Z","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-09T05:54:15.335737Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.68:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.68:2380","--initial-cluster=instance=https://199.19.213.68:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.68:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.68: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-09T05:54:15.335824Z","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-09T05:54:15.33584Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-04-09T05:54:15.335867Z","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-09T05:54:15.336751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"]} {"level":"info","ts":"2026-04-09T05:54:15.336845Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.68: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-09T05:54:15.339518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.402094ms"} {"level":"info","ts":"2026-04-09T05:54:15.346423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"78a0acde880f698","cluster-id":"45d27515d88ba308"} {"level":"info","ts":"2026-04-09T05:54:15.346505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:54:15.346529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:54:15.346551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 78a0acde880f698 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:54:15.346564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:54:15.346607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"warn","ts":"2026-04-09T05:54:15.352882Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:54:15.354019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:54:15.355146Z","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-09T05:54:15.357262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"78a0acde880f698","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:54:15.357447Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"78a0acde880f698","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:54:15.357425Z","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-09T05:54:15.357564Z","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-09T05:54:15.357582Z","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-09T05:54:15.359435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 switched to configuration voters=(543258584536905368)"} {"level":"info","ts":"2026-04-09T05:54:15.359908Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","added-peer-id":"78a0acde880f698","added-peer-peer-urls":["https://199.19.213.68:2380"]} {"level":"info","ts":"2026-04-09T05:54:15.36139Z","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-09T05:54:15.361552Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-04-09T05:54:15.361721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.68:2380"} {"level":"info","ts":"2026-04-09T05:54:15.361611Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"78a0acde880f698","initial-advertise-peer-urls":["https://199.19.213.68:2380"],"listen-peer-urls":["https://199.19.213.68:2380"],"advertise-client-urls":["https://199.19.213.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:54:15.361635Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:54:16.049704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:54:16.04975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:54:16.049779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgPreVoteResp from 78a0acde880f698 at term 1"} {"level":"info","ts":"2026-04-09T05:54:16.049793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:54:16.049806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 received MsgVoteResp from 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-04-09T05:54:16.049819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"78a0acde880f698 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:54:16.049832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 78a0acde880f698 elected leader 78a0acde880f698 at term 2"} {"level":"info","ts":"2026-04-09T05:54:16.05132Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"78a0acde880f698","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.68:2379]}","request-path":"/0/members/78a0acde880f698/attributes","cluster-id":"45d27515d88ba308","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:54:16.051467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:54:16.051468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:54:16.051795Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:54:16.053641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.68:2379"} {"level":"info","ts":"2026-04-09T05:54:16.053973Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45d27515d88ba308","local-member-id":"78a0acde880f698","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:54:16.05411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:54:16.054146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:54:16.054338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:54:16.05436Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:54:16.054598Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:54:19.879081Z","caller":"traceutil/trace.go:171","msg":"trace[442019958] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"263.96258ms","start":"2026-04-09T05:54:19.615091Z","end":"2026-04-09T05:54:19.879054Z","steps":["trace[442019958] 'process raft request' (duration: 263.811207ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:20.037556Z","caller":"traceutil/trace.go:171","msg":"trace[1546650228] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"150.520146ms","start":"2026-04-09T05:54:19.887015Z","end":"2026-04-09T05:54:20.037535Z","steps":["trace[1546650228] 'process raft request' (duration: 140.835774ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:44.974933Z","caller":"traceutil/trace.go:171","msg":"trace[1354037285] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"139.203721ms","start":"2026-04-09T05:54:44.835696Z","end":"2026-04-09T05:54:44.9749Z","steps":["trace[1354037285] 'process raft request' (duration: 100.09901ms)","trace[1354037285] 'compare' (duration: 38.803658ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:44.975613Z","caller":"traceutil/trace.go:171","msg":"trace[1854924960] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"100.397514ms","start":"2026-04-09T05:54:44.875198Z","end":"2026-04-09T05:54:44.975596Z","steps":["trace[1854924960] 'read index received' (duration: 60.643356ms)","trace[1854924960] 'applied index is now lower than readState.Index' (duration: 39.753398ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:44.975718Z","caller":"traceutil/trace.go:171","msg":"trace[765561670] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"116.814637ms","start":"2026-04-09T05:54:44.85886Z","end":"2026-04-09T05:54:44.975675Z","steps":["trace[765561670] 'process raft request' (duration: 115.962048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:44.975971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.755068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-09T05:54:44.97608Z","caller":"traceutil/trace.go:171","msg":"trace[1213115664] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:551; }","duration":"100.92041ms","start":"2026-04-09T05:54:44.875141Z","end":"2026-04-09T05:54:44.976062Z","steps":["trace[1213115664] 'agreement among raft nodes before linearized reading' (duration: 100.569386ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:47.593743Z","caller":"traceutil/trace.go:171","msg":"trace[856854202] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"168.45758ms","start":"2026-04-09T05:54:47.425263Z","end":"2026-04-09T05:54:47.593721Z","steps":["trace[856854202] 'read index received' (duration: 168.307488ms)","trace[856854202] 'applied index is now lower than readState.Index' (duration: 149.121µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:47.593967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.680712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-09T05:54:47.594008Z","caller":"traceutil/trace.go:171","msg":"trace[869204888] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"168.761814ms","start":"2026-04-09T05:54:47.425235Z","end":"2026-04-09T05:54:47.593997Z","steps":["trace[869204888] 'agreement among raft nodes before linearized reading' (duration: 168.577972ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:47.594229Z","caller":"traceutil/trace.go:171","msg":"trace[1243065922] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"206.871438ms","start":"2026-04-09T05:54:47.387315Z","end":"2026-04-09T05:54:47.594187Z","steps":["trace[1243065922] 'process raft request' (duration: 206.308781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:53.259109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.052043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4rfdz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-09T05:54:53.259211Z","caller":"traceutil/trace.go:171","msg":"trace[569437861] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4rfdz; range_end:; response_count:1; response_revision:675; }","duration":"101.200094ms","start":"2026-04-09T05:54:53.157994Z","end":"2026-04-09T05:54:53.259194Z","steps":["trace[569437861] 'range keys from in-memory index tree' (duration: 100.87755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:53.807095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.402708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:54:53.807189Z","caller":"traceutil/trace.go:171","msg":"trace[2071012270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"145.528169ms","start":"2026-04-09T05:54:53.66164Z","end":"2026-04-09T05:54:53.807169Z","steps":["trace[2071012270] 'range keys from in-memory index tree' (duration: 145.328247ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:53.990771Z","caller":"traceutil/trace.go:171","msg":"trace[588020974] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"180.009948ms","start":"2026-04-09T05:54:53.810734Z","end":"2026-04-09T05:54:53.990744Z","steps":["trace[588020974] 'process raft request' (duration: 179.828176ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:53.990787Z","caller":"traceutil/trace.go:171","msg":"trace[1166413149] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:699; }","duration":"170.133922ms","start":"2026-04-09T05:54:53.820631Z","end":"2026-04-09T05:54:53.990764Z","steps":["trace[1166413149] 'read index received' (duration: 170.123042ms)","trace[1166413149] 'applied index is now lower than readState.Index' (duration: 9.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:53.991203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.547428ms","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-09T05:54:53.991268Z","caller":"traceutil/trace.go:171","msg":"trace[887967106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"170.644398ms","start":"2026-04-09T05:54:53.820606Z","end":"2026-04-09T05:54:53.99125Z","steps":["trace[887967106] 'agreement among raft nodes before linearized reading' (duration: 170.311545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:54:54.020706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.558963ms","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-09T05:54:54.02092Z","caller":"traceutil/trace.go:171","msg":"trace[510464464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"180.807775ms","start":"2026-04-09T05:54:53.840091Z","end":"2026-04-09T05:54:54.020899Z","steps":["trace[510464464] 'agreement among raft nodes before linearized reading' (duration: 180.441441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:55:17.523428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.173729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kgt7j\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-04-09T05:55:17.523428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.441825ms","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-09T05:55:17.523537Z","caller":"traceutil/trace.go:171","msg":"trace[1274577379] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kgt7j; range_end:; response_count:1; response_revision:810; }","duration":"256.322861ms","start":"2026-04-09T05:55:17.267192Z","end":"2026-04-09T05:55:17.523515Z","steps":["trace[1274577379] 'range keys from in-memory index tree' (duration: 256.000187ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:55:17.523571Z","caller":"traceutil/trace.go:171","msg":"trace[589364947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"156.651916ms","start":"2026-04-09T05:55:17.366897Z","end":"2026-04-09T05:55:17.523549Z","steps":["trace[589364947] 'range keys from in-memory index tree' (duration: 156.266093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:32.148889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.471702ms","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-09T05:56:32.148983Z","caller":"traceutil/trace.go:171","msg":"trace[2044618754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"157.623116ms","start":"2026-04-09T05:56:31.991341Z","end":"2026-04-09T05:56:32.148964Z","steps":["trace[2044618754] 'range keys from in-memory index tree' (duration: 157.287668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:32.14907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.023635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-09T05:56:32.149179Z","caller":"traceutil/trace.go:171","msg":"trace[805202741] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1861; }","duration":"250.2362ms","start":"2026-04-09T05:56:31.89892Z","end":"2026-04-09T05:56:32.149156Z","steps":["trace[805202741] 'range keys from in-memory index tree' (duration: 249.789889ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:37.769918Z","caller":"traceutil/trace.go:171","msg":"trace[140732759] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"120.023564ms","start":"2026-04-09T05:56:37.649872Z","end":"2026-04-09T05:56:37.769896Z","steps":["trace[140732759] 'read index received' (duration: 119.8753ms)","trace[140732759] 'applied index is now lower than readState.Index' (duration: 147.654µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:56:37.770132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.245669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10948"} {"level":"info","ts":"2026-04-09T05:56:37.770057Z","caller":"traceutil/trace.go:171","msg":"trace[1740724474] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"144.751559ms","start":"2026-04-09T05:56:37.625224Z","end":"2026-04-09T05:56:37.769976Z","steps":["trace[1740724474] 'process raft request' (duration: 144.538584ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:37.770165Z","caller":"traceutil/trace.go:171","msg":"trace[1665822479] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1933; }","duration":"120.334062ms","start":"2026-04-09T05:56:37.649822Z","end":"2026-04-09T05:56:37.770157Z","steps":["trace[1665822479] 'agreement among raft nodes before linearized reading' (duration: 120.189248ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:37.902146Z","caller":"traceutil/trace.go:171","msg":"trace[1956163635] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"119.469199ms","start":"2026-04-09T05:56:37.782626Z","end":"2026-04-09T05:56:37.902095Z","steps":["trace[1956163635] 'process raft request' (duration: 119.13429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:57:04.217665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.658122ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T05:57:04.217762Z","caller":"traceutil/trace.go:171","msg":"trace[1608786247] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"161.26077ms","start":"2026-04-09T05:57:04.056485Z","end":"2026-04-09T05:57:04.217745Z","steps":["trace[1608786247] 'read index received' (duration: 57.276561ms)","trace[1608786247] 'applied index is now lower than readState.Index' (duration: 103.982899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:04.217914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.430564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-09T05:57:04.217946Z","caller":"traceutil/trace.go:171","msg":"trace[675100613] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2130; }","duration":"161.513435ms","start":"2026-04-09T05:57:04.056424Z","end":"2026-04-09T05:57:04.217937Z","steps":["trace[675100613] 'agreement among raft nodes before linearized reading' (duration: 161.367812ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:04.343903Z","caller":"traceutil/trace.go:171","msg":"trace[1803030518] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"121.619324ms","start":"2026-04-09T05:57:04.222257Z","end":"2026-04-09T05:57:04.343876Z","steps":["trace[1803030518] 'process raft request' (duration: 121.358649ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:57.325942Z","caller":"traceutil/trace.go:171","msg":"trace[843774511] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"219.036466ms","start":"2026-04-09T05:57:57.106855Z","end":"2026-04-09T05:57:57.325892Z","steps":["trace[843774511] 'process raft request' (duration: 216.645017ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:24.920144Z","caller":"traceutil/trace.go:171","msg":"trace[33328074] linearizableReadLoop","detail":"{readStateIndex:2889; appliedIndex:2888; }","duration":"201.473947ms","start":"2026-04-09T05:58:24.718647Z","end":"2026-04-09T05:58:24.920121Z","steps":["trace[33328074] 'read index received' (duration: 201.263754ms)","trace[33328074] 'applied index is now lower than readState.Index' (duration: 209.163µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:24.920203Z","caller":"traceutil/trace.go:171","msg":"trace[121904421] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"233.639506ms","start":"2026-04-09T05:58:24.686524Z","end":"2026-04-09T05:58:24.920163Z","steps":["trace[121904421] 'process raft request' (duration: 233.405323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:24.920514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.985046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-09T05:58:24.920612Z","caller":"traceutil/trace.go:171","msg":"trace[1921229681] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2783; }","duration":"202.20781ms","start":"2026-04-09T05:58:24.718379Z","end":"2026-04-09T05:58:24.920587Z","steps":["trace[1921229681] 'agreement among raft nodes before linearized reading' (duration: 201.898975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:24.920871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.14152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-04-09T05:58:24.921034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.107373ms","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-09T05:58:24.921091Z","caller":"traceutil/trace.go:171","msg":"trace[1884953345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2783; }","duration":"156.301086ms","start":"2026-04-09T05:58:24.764776Z","end":"2026-04-09T05:58:24.921077Z","steps":["trace[1884953345] 'agreement among raft nodes before linearized reading' (duration: 156.036642ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:24.921062Z","caller":"traceutil/trace.go:171","msg":"trace[1677460650] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2783; }","duration":"192.404503ms","start":"2026-04-09T05:58:24.728636Z","end":"2026-04-09T05:58:24.92104Z","steps":["trace[1677460650] 'agreement among raft nodes before linearized reading' (duration: 192.106918ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.153324Z","caller":"traceutil/trace.go:171","msg":"trace[1228925131] transaction","detail":"{read_only:false; response_revision:4434; number_of_response:1; }","duration":"115.836584ms","start":"2026-04-09T06:00:50.037467Z","end":"2026-04-09T06:00:50.153304Z","steps":["trace[1228925131] 'process raft request' (duration: 84.091471ms)","trace[1228925131] 'compare' (duration: 31.445834ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:50.175763Z","caller":"traceutil/trace.go:171","msg":"trace[1045584716] linearizableReadLoop","detail":"{readStateIndex:4629; appliedIndex:4624; }","duration":"132.148278ms","start":"2026-04-09T06:00:50.043599Z","end":"2026-04-09T06:00:50.175748Z","steps":["trace[1045584716] 'read index received' (duration: 78.061268ms)","trace[1045584716] 'applied index is now lower than readState.Index' (duration: 54.08448ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:50.175779Z","caller":"traceutil/trace.go:171","msg":"trace[1318102712] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"137.979765ms","start":"2026-04-09T06:00:50.037778Z","end":"2026-04-09T06:00:50.175758Z","steps":["trace[1318102712] 'process raft request' (duration: 137.886832ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.175839Z","caller":"traceutil/trace.go:171","msg":"trace[1781993898] transaction","detail":"{read_only:false; response_revision:4438; number_of_response:1; }","duration":"133.634834ms","start":"2026-04-09T06:00:50.042198Z","end":"2026-04-09T06:00:50.175833Z","steps":["trace[1781993898] 'process raft request' (duration: 133.48852ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.175767Z","caller":"traceutil/trace.go:171","msg":"trace[1699026419] transaction","detail":"{read_only:false; response_revision:4435; number_of_response:1; }","duration":"138.14438ms","start":"2026-04-09T06:00:50.037586Z","end":"2026-04-09T06:00:50.175731Z","steps":["trace[1699026419] 'process raft request' (duration: 137.981475ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.176017Z","caller":"traceutil/trace.go:171","msg":"trace[1177769065] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"126.304731ms","start":"2026-04-09T06:00:50.049704Z","end":"2026-04-09T06:00:50.176008Z","steps":["trace[1177769065] 'process raft request' (duration: 126.012673ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.176588Z","caller":"traceutil/trace.go:171","msg":"trace[641530819] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"138.846291ms","start":"2026-04-09T06:00:50.037716Z","end":"2026-04-09T06:00:50.176562Z","steps":["trace[641530819] 'process raft request' (duration: 137.930843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:50.17764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.991414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-09T06:00:50.177729Z","caller":"traceutil/trace.go:171","msg":"trace[1804071496] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4439; }","duration":"134.143759ms","start":"2026-04-09T06:00:50.043564Z","end":"2026-04-09T06:00:50.177708Z","steps":["trace[1804071496] 'agreement among raft nodes before linearized reading' (duration: 132.302283ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.428794Z","caller":"traceutil/trace.go:171","msg":"trace[1124544370] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"158.904569ms","start":"2026-04-09T06:00:50.269866Z","end":"2026-04-09T06:00:50.42877Z","steps":["trace[1124544370] 'process raft request' (duration: 158.708313ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.436093Z","caller":"traceutil/trace.go:171","msg":"trace[1365690990] linearizableReadLoop","detail":"{readStateIndex:4637; appliedIndex:4635; }","duration":"161.789428ms","start":"2026-04-09T06:00:50.274287Z","end":"2026-04-09T06:00:50.436076Z","steps":["trace[1365690990] 'read index received' (duration: 154.305491ms)","trace[1365690990] 'applied index is now lower than readState.Index' (duration: 7.483297ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:50.436488Z","caller":"traceutil/trace.go:171","msg":"trace[1552583504] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4445; }","duration":"165.571892ms","start":"2026-04-09T06:00:50.270893Z","end":"2026-04-09T06:00:50.436465Z","steps":["trace[1552583504] 'process raft request' (duration: 165.058056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:50.437223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.902381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-09T06:00:50.437267Z","caller":"traceutil/trace.go:171","msg":"trace[955552136] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4446; }","duration":"162.989424ms","start":"2026-04-09T06:00:50.274266Z","end":"2026-04-09T06:00:50.437256Z","steps":["trace[955552136] 'agreement among raft nodes before linearized reading' (duration: 162.16543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:50.437447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.977758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-09T06:00:50.437539Z","caller":"traceutil/trace.go:171","msg":"trace[1427253576] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4446; }","duration":"148.407011ms","start":"2026-04-09T06:00:50.289113Z","end":"2026-04-09T06:00:50.43752Z","steps":["trace[1427253576] 'agreement among raft nodes before linearized reading' (duration: 147.308928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:54.836099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.936705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-09T06:00:54.836159Z","caller":"traceutil/trace.go:171","msg":"trace[1714299413] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4532; }","duration":"181.029988ms","start":"2026-04-09T06:00:54.655114Z","end":"2026-04-09T06:00:54.836144Z","steps":["trace[1714299413] 'range keys from in-memory index tree' (duration: 180.824533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:54.836162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.253143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-rnmvf-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-04-09T06:00:54.836238Z","caller":"traceutil/trace.go:171","msg":"trace[1779431046] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-rnmvf-tls; range_end:; response_count:1; response_revision:4532; }","duration":"189.384907ms","start":"2026-04-09T06:00:54.646837Z","end":"2026-04-09T06:00:54.836222Z","steps":["trace[1779431046] 'range keys from in-memory index tree' (duration: 189.073109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:54.836268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.06428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:54.836332Z","caller":"traceutil/trace.go:171","msg":"trace[1452448254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4532; }","duration":"173.147462ms","start":"2026-04-09T06:00:54.66317Z","end":"2026-04-09T06:00:54.836317Z","steps":["trace[1452448254] 'range keys from in-memory index tree' (duration: 173.032099ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:55.314956Z","caller":"traceutil/trace.go:171","msg":"trace[1264940309] linearizableReadLoop","detail":"{readStateIndex:4732; appliedIndex:4731; }","duration":"166.561548ms","start":"2026-04-09T06:00:55.148362Z","end":"2026-04-09T06:00:55.314924Z","steps":["trace[1264940309] 'read index received' (duration: 95.202583ms)","trace[1264940309] 'applied index is now lower than readState.Index' (duration: 71.357965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:55.315141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.763893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T06:00:55.315156Z","caller":"traceutil/trace.go:171","msg":"trace[1112473077] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"171.02899ms","start":"2026-04-09T06:00:55.144102Z","end":"2026-04-09T06:00:55.315131Z","steps":["trace[1112473077] 'process raft request' (duration: 99.560912ms)","trace[1112473077] 'compare' (duration: 71.058725ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:55.315171Z","caller":"traceutil/trace.go:171","msg":"trace[1025820660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4537; }","duration":"166.838916ms","start":"2026-04-09T06:00:55.148323Z","end":"2026-04-09T06:00:55.315162Z","steps":["trace[1025820660] 'agreement among raft nodes before linearized reading' (duration: 166.696131ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.500432Z","caller":"traceutil/trace.go:171","msg":"trace[1251192382] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"525.543673ms","start":"2026-04-09T06:01:06.974864Z","end":"2026-04-09T06:01:07.500407Z","steps":["trace[1251192382] 'process raft request' (duration: 525.390219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.501118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:06.974842Z","time spent":"525.691468ms","remote":"127.0.0.1:55016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10793,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:07.571452Z","caller":"traceutil/trace.go:171","msg":"trace[515573576] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4933; }","duration":"544.099479ms","start":"2026-04-09T06:01:07.027328Z","end":"2026-04-09T06:01:07.571428Z","steps":["trace[515573576] 'read index received' (duration: 473.029296ms)","trace[515573576] 'applied index is now lower than readState.Index' (duration: 71.069293ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:07.571559Z","caller":"traceutil/trace.go:171","msg":"trace[1870924488] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"589.549326ms","start":"2026-04-09T06:01:06.981994Z","end":"2026-04-09T06:01:07.571543Z","steps":["trace[1870924488] 'process raft request' (duration: 556.042692ms)","trace[1870924488] 'compare' (duration: 33.218436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:07.571673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:06.981979Z","time spent":"589.619397ms","remote":"127.0.0.1:55288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:07.571861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.516262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-09T06:01:07.57196Z","caller":"traceutil/trace.go:171","msg":"trace[2100807404] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4733; }","duration":"544.662996ms","start":"2026-04-09T06:01:07.027268Z","end":"2026-04-09T06:01:07.571931Z","steps":["trace[2100807404] 'agreement among raft nodes before linearized reading' (duration: 544.391198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.572022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.027246Z","time spent":"544.760098ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T06:01:07.847955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.353673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:01:07.848634Z","caller":"traceutil/trace.go:171","msg":"trace[179956278] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"133.113139ms","start":"2026-04-09T06:01:07.715498Z","end":"2026-04-09T06:01:07.848611Z","steps":["trace[179956278] 'process raft request' (duration: 133.033977ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.849084Z","caller":"traceutil/trace.go:171","msg":"trace[202946900] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"341.632976ms","start":"2026-04-09T06:01:07.507432Z","end":"2026-04-09T06:01:07.849065Z","steps":["trace[202946900] 'process raft request' (duration: 340.907005ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.849291Z","caller":"traceutil/trace.go:171","msg":"trace[438816814] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"342.742298ms","start":"2026-04-09T06:01:07.506509Z","end":"2026-04-09T06:01:07.849251Z","steps":["trace[438816814] 'process raft request' (duration: 172.995155ms)","trace[438816814] 'compare' (duration: 167.821378ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:07.849294Z","caller":"traceutil/trace.go:171","msg":"trace[1362081297] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"342.418407ms","start":"2026-04-09T06:01:07.50676Z","end":"2026-04-09T06:01:07.849178Z","steps":["trace[1362081297] 'process raft request' (duration: 341.303705ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.849529Z","caller":"traceutil/trace.go:171","msg":"trace[145085895] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4738; }","duration":"271.146499ms","start":"2026-04-09T06:01:07.578371Z","end":"2026-04-09T06:01:07.849517Z","steps":["trace[145085895] 'process raft request' (duration: 270.11496ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.849774Z","caller":"traceutil/trace.go:171","msg":"trace[441461335] linearizableReadLoop","detail":"{readStateIndex:4940; appliedIndex:4935; }","duration":"278.24036ms","start":"2026-04-09T06:01:07.571516Z","end":"2026-04-09T06:01:07.849756Z","steps":["trace[441461335] 'read index received' (duration: 108.005894ms)","trace[441461335] 'applied index is now lower than readState.Index' (duration: 170.230286ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:07.849745Z","caller":"traceutil/trace.go:171","msg":"trace[1143181523] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"312.786525ms","start":"2026-04-09T06:01:07.536734Z","end":"2026-04-09T06:01:07.84952Z","steps":["trace[1143181523] 'process raft request' (duration: 311.658913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.849988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.536717Z","time spent":"313.197256ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T06:01:07.850063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.59118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-04-09T06:01:07.850192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.803058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-09T06:01:07.850131Z","caller":"traceutil/trace.go:171","msg":"trace[2109568530] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4739; }","duration":"421.678553ms","start":"2026-04-09T06:01:07.42843Z","end":"2026-04-09T06:01:07.850109Z","steps":["trace[2109568530] 'agreement among raft nodes before linearized reading' (duration: 421.491728ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:07.85027Z","caller":"traceutil/trace.go:171","msg":"trace[635589543] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4739; }","duration":"812.961212ms","start":"2026-04-09T06:01:07.037288Z","end":"2026-04-09T06:01:07.850249Z","steps":["trace[635589543] 'agreement among raft nodes before linearized reading' (duration: 812.731926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.850328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.037275Z","time spent":"813.039355ms","remote":"127.0.0.1:54978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-09T06:01:07.850283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.428417Z","time spent":"421.852669ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T06:01:07.850787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.191952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T06:01:07.850822Z","caller":"traceutil/trace.go:171","msg":"trace[1022905422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4739; }","duration":"452.268274ms","start":"2026-04-09T06:01:07.398545Z","end":"2026-04-09T06:01:07.850814Z","steps":["trace[1022905422] 'agreement among raft nodes before linearized reading' (duration: 452.146291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.850848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.398524Z","time spent":"452.316606ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T06:01:07.849441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.506482Z","time spent":"342.872461ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:07.851038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.507419Z","time spent":"341.720128ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:07.849357Z","caller":"traceutil/trace.go:171","msg":"trace[382668213] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"342.245972ms","start":"2026-04-09T06:01:07.50708Z","end":"2026-04-09T06:01:07.849325Z","steps":["trace[382668213] 'process raft request' (duration: 341.190272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.851285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.50706Z","time spent":"344.151397ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:07.850003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.506749Z","time spent":"343.009344ms","remote":"127.0.0.1:54994","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-09T06:01:07.871507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.079741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:01:07.871586Z","caller":"traceutil/trace.go:171","msg":"trace[1247998657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4740; }","duration":"210.224634ms","start":"2026-04-09T06:01:07.661343Z","end":"2026-04-09T06:01:07.871568Z","steps":["trace[1247998657] 'agreement among raft nodes before linearized reading' (duration: 210.07545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:07.871738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.438022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-04-09T06:01:07.871796Z","caller":"traceutil/trace.go:171","msg":"trace[411408209] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"294.538774ms","start":"2026-04-09T06:01:07.577243Z","end":"2026-04-09T06:01:07.871782Z","steps":["trace[411408209] 'agreement among raft nodes before linearized reading' (duration: 294.36407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.222295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.88468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:01:08.222509Z","caller":"traceutil/trace.go:171","msg":"trace[1850714172] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"366.82581ms","start":"2026-04-09T06:01:07.855654Z","end":"2026-04-09T06:01:08.22248Z","steps":["trace[1850714172] 'process raft request' (duration: 135.650723ms)","trace[1850714172] 'compare' (duration: 230.582681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:08.222653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.855639Z","time spent":"366.934623ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:08.222764Z","caller":"traceutil/trace.go:171","msg":"trace[866349250] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4943; }","duration":"351.335599ms","start":"2026-04-09T06:01:07.871398Z","end":"2026-04-09T06:01:08.222733Z","steps":["trace[866349250] 'read index received' (duration: 119.890083ms)","trace[866349250] 'applied index is now lower than readState.Index' (duration: 231.442586ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:08.222918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.135883ms","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-09T06:01:08.222919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.809296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-09T06:01:08.222966Z","caller":"traceutil/trace.go:171","msg":"trace[2087008114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4742; }","duration":"364.205676ms","start":"2026-04-09T06:01:07.858749Z","end":"2026-04-09T06:01:08.222954Z","steps":["trace[2087008114] 'agreement among raft nodes before linearized reading' (duration: 364.059502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.223001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.858739Z","time spent":"364.251497ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T06:01:08.223002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.077944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-46dbddcd-527c-4bd2-b1c7-13cb06e690ae\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-09T06:01:08.223003Z","caller":"traceutil/trace.go:171","msg":"trace[310314351] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4742; }","duration":"368.894508ms","start":"2026-04-09T06:01:07.854069Z","end":"2026-04-09T06:01:08.222964Z","steps":["trace[310314351] 'agreement among raft nodes before linearized reading' (duration: 368.721063ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.22305Z","caller":"traceutil/trace.go:171","msg":"trace[484001175] range","detail":"{range_begin:/registry/persistentvolumes/pvc-46dbddcd-527c-4bd2-b1c7-13cb06e690ae; range_end:; response_count:1; response_revision:4742; }","duration":"369.166227ms","start":"2026-04-09T06:01:07.853867Z","end":"2026-04-09T06:01:08.223033Z","steps":["trace[484001175] 'agreement among raft nodes before linearized reading' (duration: 368.986542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.223053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.854059Z","time spent":"368.984371ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T06:01:08.223092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.853848Z","time spent":"369.236059ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-46dbddcd-527c-4bd2-b1c7-13cb06e690ae\" "} {"level":"warn","ts":"2026-04-09T06:01:08.223154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.841692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16547"} {"level":"info","ts":"2026-04-09T06:01:08.223182Z","caller":"traceutil/trace.go:171","msg":"trace[277561748] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4742; }","duration":"241.896624ms","start":"2026-04-09T06:01:07.981276Z","end":"2026-04-09T06:01:08.223172Z","steps":["trace[277561748] 'agreement among raft nodes before linearized reading' (duration: 241.809911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.22319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.808943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T06:01:08.223221Z","caller":"traceutil/trace.go:171","msg":"trace[1305214547] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4742; }","duration":"171.876605ms","start":"2026-04-09T06:01:08.051334Z","end":"2026-04-09T06:01:08.223211Z","steps":["trace[1305214547] 'agreement among raft nodes before linearized reading' (duration: 171.821693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.223331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.956718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:01:08.223635Z","caller":"traceutil/trace.go:171","msg":"trace[370778279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4742; }","duration":"351.285867ms","start":"2026-04-09T06:01:07.872332Z","end":"2026-04-09T06:01:08.223618Z","steps":["trace[370778279] 'agreement among raft nodes before linearized reading' (duration: 350.933847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.223773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.872319Z","time spent":"351.440862ms","remote":"127.0.0.1:54824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T06:01:08.223886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.78728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-04-09T06:01:08.223931Z","caller":"traceutil/trace.go:171","msg":"trace[1259334595] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4742; }","duration":"372.860483ms","start":"2026-04-09T06:01:07.851059Z","end":"2026-04-09T06:01:08.223919Z","steps":["trace[1259334595] 'agreement among raft nodes before linearized reading' (duration: 371.825403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.223961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.851031Z","time spent":"372.921885ms","remote":"127.0.0.1:55288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":13507,"request content":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" "} {"level":"warn","ts":"2026-04-09T06:01:08.223489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.61316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-09T06:01:08.224022Z","caller":"traceutil/trace.go:171","msg":"trace[1200138016] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4742; }","duration":"304.225028ms","start":"2026-04-09T06:01:07.919784Z","end":"2026-04-09T06:01:08.224009Z","steps":["trace[1200138016] 'agreement among raft nodes before linearized reading' (duration: 303.59129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.224075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.919764Z","time spent":"304.297361ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-09T06:01:08.224418Z","caller":"traceutil/trace.go:171","msg":"trace[50697474] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"345.593506ms","start":"2026-04-09T06:01:07.877078Z","end":"2026-04-09T06:01:08.222671Z","steps":["trace[50697474] 'process raft request' (duration: 345.417171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.22455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:07.877057Z","time spent":"347.449498ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:08.437274Z","caller":"traceutil/trace.go:171","msg":"trace[1189151112] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"206.005144ms","start":"2026-04-09T06:01:08.231246Z","end":"2026-04-09T06:01:08.437251Z","steps":["trace[1189151112] 'process raft request' (duration: 205.923951ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.437309Z","caller":"traceutil/trace.go:171","msg":"trace[1377517901] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"207.172937ms","start":"2026-04-09T06:01:08.230097Z","end":"2026-04-09T06:01:08.43727Z","steps":["trace[1377517901] 'process raft request' (duration: 206.93632ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.437658Z","caller":"traceutil/trace.go:171","msg":"trace[206952182] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"205.431486ms","start":"2026-04-09T06:01:08.232209Z","end":"2026-04-09T06:01:08.43764Z","steps":["trace[206952182] 'process raft request' (duration: 205.008105ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.437757Z","caller":"traceutil/trace.go:171","msg":"trace[1612780135] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"203.845822ms","start":"2026-04-09T06:01:08.233895Z","end":"2026-04-09T06:01:08.43774Z","steps":["trace[1612780135] 'process raft request' (duration: 203.577655ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.437982Z","caller":"traceutil/trace.go:171","msg":"trace[1708138914] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"202.279537ms","start":"2026-04-09T06:01:08.235684Z","end":"2026-04-09T06:01:08.437964Z","steps":["trace[1708138914] 'process raft request' (duration: 201.990739ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.437967Z","caller":"traceutil/trace.go:171","msg":"trace[386463155] linearizableReadLoop","detail":"{readStateIndex:4947; appliedIndex:4945; }","duration":"207.023123ms","start":"2026-04-09T06:01:08.230879Z","end":"2026-04-09T06:01:08.437903Z","steps":["trace[386463155] 'read index received' (duration: 7.822332ms)","trace[386463155] 'applied index is now lower than readState.Index' (duration: 199.199711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:08.438144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.242799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-90269375-68a4-49bb-899d-4f07e27d44a6\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-09T06:01:08.43819Z","caller":"traceutil/trace.go:171","msg":"trace[1679882825] range","detail":"{range_begin:/registry/persistentvolumes/pvc-90269375-68a4-49bb-899d-4f07e27d44a6; range_end:; response_count:1; response_revision:4748; }","duration":"207.31606ms","start":"2026-04-09T06:01:08.230862Z","end":"2026-04-09T06:01:08.438178Z","steps":["trace[1679882825] 'agreement among raft nodes before linearized reading' (duration: 207.155056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.438324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.696462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T06:01:08.438388Z","caller":"traceutil/trace.go:171","msg":"trace[331575880] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4748; }","duration":"139.822866ms","start":"2026-04-09T06:01:08.298552Z","end":"2026-04-09T06:01:08.438375Z","steps":["trace[331575880] 'agreement among raft nodes before linearized reading' (duration: 139.691992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.438398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.625313ms","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-09T06:01:08.438471Z","caller":"traceutil/trace.go:171","msg":"trace[1675460118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4748; }","duration":"186.764378ms","start":"2026-04-09T06:01:08.25169Z","end":"2026-04-09T06:01:08.438454Z","steps":["trace[1675460118] 'agreement among raft nodes before linearized reading' (duration: 186.537532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.438564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.343585ms","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-09T06:01:08.43861Z","caller":"traceutil/trace.go:171","msg":"trace[2104554568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4748; }","duration":"186.457118ms","start":"2026-04-09T06:01:08.252142Z","end":"2026-04-09T06:01:08.4386Z","steps":["trace[2104554568] 'agreement among raft nodes before linearized reading' (duration: 186.347575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:08.438641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.006654ms","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-09T06:01:08.438776Z","caller":"traceutil/trace.go:171","msg":"trace[1195866845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4748; }","duration":"181.169208ms","start":"2026-04-09T06:01:08.257517Z","end":"2026-04-09T06:01:08.438686Z","steps":["trace[1195866845] 'agreement among raft nodes before linearized reading' (duration: 180.792287ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:08.549055Z","caller":"traceutil/trace.go:171","msg":"trace[595028959] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"101.224762ms","start":"2026-04-09T06:01:08.447812Z","end":"2026-04-09T06:01:08.549037Z","steps":["trace[595028959] 'process raft request' (duration: 69.670916ms)","trace[595028959] 'compare' (duration: 31.306979ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:42.501144Z","caller":"traceutil/trace.go:171","msg":"trace[80719830] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"102.076715ms","start":"2026-04-09T06:01:42.399035Z","end":"2026-04-09T06:01:42.501111Z","steps":["trace[80719830] 'process raft request' (duration: 101.800308ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:04:16.077446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3225} {"level":"info","ts":"2026-04-09T06:04:16.166477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3225,"took":"87.607988ms","hash":2278034789,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-09T06:04:16.166533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278034789,"revision":3225,"compact-revision":-1}