{"level":"warn","ts":"2026-04-07T15:26:34.610063Z","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-07T15:26:34.610139Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-07T15:26:34.610208Z","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-07T15:26:34.61022Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-07T15:26:34.610237Z","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-07T15:26:34.610765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-04-07T15:26:34.610836Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-07T15:26:34.614357Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.292246ms"} {"level":"info","ts":"2026-04-07T15:26:34.623146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-04-07T15:26:34.623221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:26:34.623247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:26:34.623264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:26:34.623274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:26:34.623313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-04-07T15:26:34.630191Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:26:34.632162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:26:34.633809Z","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-07T15:26:34.636839Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:26:34.636995Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:26:34.637109Z","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-07T15:26:34.637292Z","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-07T15:26:34.637525Z","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-07T15:26:34.638046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-04-07T15:26:34.639356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-04-07T15:26:34.642525Z","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-07T15:26:34.642681Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-07T15:26:34.642769Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-04-07T15:26:34.642901Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:26:34.642929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:26:35.324179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:26:35.324236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:26:35.324261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-04-07T15:26:35.324278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:26:35.324285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-07T15:26:35.324297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:26:35.324314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-04-07T15:26:35.325082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:26:35.325873Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:26:35.325874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:26:35.325901Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:26:35.326193Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:26:35.326224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:26:35.326374Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:26:35.326462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:26:35.326503Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:26:35.328711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-04-07T15:26:35.329119Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:26:55.016199Z","caller":"traceutil/trace.go:171","msg":"trace[396934462] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"216.876475ms","start":"2026-04-07T15:26:54.799292Z","end":"2026-04-07T15:26:55.016168Z","steps":["trace[396934462] 'process raft request' (duration: 216.707071ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.130464Z","caller":"traceutil/trace.go:171","msg":"trace[2111857663] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"138.939877ms","start":"2026-04-07T15:27:03.991496Z","end":"2026-04-07T15:27:04.130436Z","steps":["trace[2111857663] 'process raft request' (duration: 106.857987ms)","trace[2111857663] 'compare' (duration: 31.951237ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:04.141804Z","caller":"traceutil/trace.go:171","msg":"trace[1768372054] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"146.079593ms","start":"2026-04-07T15:27:03.995706Z","end":"2026-04-07T15:27:04.141785Z","steps":["trace[1768372054] 'process raft request' (duration: 145.910328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.141909Z","caller":"traceutil/trace.go:171","msg":"trace[1474299361] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"146.016241ms","start":"2026-04-07T15:27:03.995869Z","end":"2026-04-07T15:27:04.141885Z","steps":["trace[1474299361] 'process raft request' (duration: 145.854017ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.141983Z","caller":"traceutil/trace.go:171","msg":"trace[1650836416] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"145.925209ms","start":"2026-04-07T15:27:03.99605Z","end":"2026-04-07T15:27:04.141975Z","steps":["trace[1650836416] 'process raft request' (duration: 145.787855ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.141927Z","caller":"traceutil/trace.go:171","msg":"trace[2076304946] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"145.9612ms","start":"2026-04-07T15:27:03.995949Z","end":"2026-04-07T15:27:04.14191Z","steps":["trace[2076304946] 'process raft request' (duration: 145.820796ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.14207Z","caller":"traceutil/trace.go:171","msg":"trace[1839959100] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"139.791498ms","start":"2026-04-07T15:27:04.002271Z","end":"2026-04-07T15:27:04.142063Z","steps":["trace[1839959100] 'process raft request' (duration: 139.631944ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.14214Z","caller":"traceutil/trace.go:171","msg":"trace[244588476] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"135.333852ms","start":"2026-04-07T15:27:04.006801Z","end":"2026-04-07T15:27:04.142135Z","steps":["trace[244588476] 'process raft request' (duration: 135.239059ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.142138Z","caller":"traceutil/trace.go:171","msg":"trace[694836013] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"136.460412ms","start":"2026-04-07T15:27:04.005631Z","end":"2026-04-07T15:27:04.142091Z","steps":["trace[694836013] 'process raft request' (duration: 136.354159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.142221Z","caller":"traceutil/trace.go:171","msg":"trace[212666277] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"129.706554ms","start":"2026-04-07T15:27:04.012506Z","end":"2026-04-07T15:27:04.142212Z","steps":["trace[212666277] 'process raft request' (duration: 129.586591ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.142279Z","caller":"traceutil/trace.go:171","msg":"trace[824166458] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"129.675803ms","start":"2026-04-07T15:27:04.012592Z","end":"2026-04-07T15:27:04.142268Z","steps":["trace[824166458] 'process raft request' (duration: 129.55301ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.142375Z","caller":"traceutil/trace.go:171","msg":"trace[1590532438] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"119.303722ms","start":"2026-04-07T15:27:04.023045Z","end":"2026-04-07T15:27:04.142348Z","steps":["trace[1590532438] 'process raft request' (duration: 119.158919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:04.142681Z","caller":"traceutil/trace.go:171","msg":"trace[1550911116] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"108.723476ms","start":"2026-04-07T15:27:04.033948Z","end":"2026-04-07T15:27:04.142671Z","steps":["trace[1550911116] 'process raft request' (duration: 108.526891ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:05.535928Z","caller":"traceutil/trace.go:171","msg":"trace[1205879411] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"109.594518ms","start":"2026-04-07T15:27:05.426261Z","end":"2026-04-07T15:27:05.535855Z","steps":["trace[1205879411] 'read index received' (duration: 106.490317ms)","trace[1205879411] 'applied index is now lower than readState.Index' (duration: 3.102861ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:05.535983Z","caller":"traceutil/trace.go:171","msg":"trace[1333375480] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"115.443121ms","start":"2026-04-07T15:27:05.420505Z","end":"2026-04-07T15:27:05.535949Z","steps":["trace[1333375480] 'process raft request' (duration: 112.239287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:05.536386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.084932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-04-07T15:27:05.536554Z","caller":"traceutil/trace.go:171","msg":"trace[274915154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"110.294936ms","start":"2026-04-07T15:27:05.426231Z","end":"2026-04-07T15:27:05.536526Z","steps":["trace[274915154] 'agreement among raft nodes before linearized reading' (duration: 109.829533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:10.207792Z","caller":"traceutil/trace.go:171","msg":"trace[1325260501] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"122.758302ms","start":"2026-04-07T15:27:10.084996Z","end":"2026-04-07T15:27:10.207755Z","steps":["trace[1325260501] 'process raft request' (duration: 122.513286ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:10.343235Z","caller":"traceutil/trace.go:171","msg":"trace[798157731] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"229.100215ms","start":"2026-04-07T15:27:10.114106Z","end":"2026-04-07T15:27:10.343206Z","steps":["trace[798157731] 'process raft request' (duration: 228.422758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:10.577249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.993468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:27:10.577383Z","caller":"traceutil/trace.go:171","msg":"trace[1563962933] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"163.754126ms","start":"2026-04-07T15:27:10.413602Z","end":"2026-04-07T15:27:10.577356Z","steps":["trace[1563962933] 'process raft request' (duration: 57.985587ms)","trace[1563962933] 'compare' (duration: 104.803174ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:10.997336Z","caller":"traceutil/trace.go:171","msg":"trace[166864748] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"241.360967ms","start":"2026-04-07T15:27:10.753381Z","end":"2026-04-07T15:27:10.994742Z","steps":["trace[166864748] 'process raft request' (duration: 240.566346ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:11.001628Z","caller":"traceutil/trace.go:171","msg":"trace[1457426236] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"123.867462ms","start":"2026-04-07T15:27:10.877727Z","end":"2026-04-07T15:27:11.001595Z","steps":["trace[1457426236] 'process raft request' (duration: 123.569604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:11.002131Z","caller":"traceutil/trace.go:171","msg":"trace[1408369544] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"123.239636ms","start":"2026-04-07T15:27:10.878847Z","end":"2026-04-07T15:27:11.002087Z","steps":["trace[1408369544] 'read index received' (duration: 115.203595ms)","trace[1408369544] 'applied index is now lower than readState.Index' (duration: 8.035331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:27:11.002251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.372689ms","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-07T15:27:11.00228Z","caller":"traceutil/trace.go:171","msg":"trace[1744992577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"123.464371ms","start":"2026-04-07T15:27:10.878806Z","end":"2026-04-07T15:27:11.00227Z","steps":["trace[1744992577] 'agreement among raft nodes before linearized reading' (duration: 123.375319ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:12.312812Z","caller":"traceutil/trace.go:171","msg":"trace[1736206834] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"109.704312ms","start":"2026-04-07T15:27:12.203032Z","end":"2026-04-07T15:27:12.312736Z","steps":["trace[1736206834] 'process raft request' (duration: 109.393183ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:12.312884Z","caller":"traceutil/trace.go:171","msg":"trace[1345570073] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"106.345373ms","start":"2026-04-07T15:27:12.206494Z","end":"2026-04-07T15:27:12.312839Z","steps":["trace[1345570073] 'process raft request' (duration: 106.071555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:51.493793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.41455ms","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-07T15:27:51.493876Z","caller":"traceutil/trace.go:171","msg":"trace[344152674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"178.548883ms","start":"2026-04-07T15:27:51.315309Z","end":"2026-04-07T15:27:51.493858Z","steps":["trace[344152674] 'range keys from in-memory index tree' (duration: 178.209394ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:51.503467Z","caller":"traceutil/trace.go:171","msg":"trace[238926136] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"113.748922ms","start":"2026-04-07T15:27:51.389689Z","end":"2026-04-07T15:27:51.503438Z","steps":["trace[238926136] 'read index received' (duration: 113.592948ms)","trace[238926136] 'applied index is now lower than readState.Index' (duration: 154.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:27:51.50365Z","caller":"traceutil/trace.go:171","msg":"trace[1713687130] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"156.290604ms","start":"2026-04-07T15:27:51.347332Z","end":"2026-04-07T15:27:51.503623Z","steps":["trace[1713687130] 'process raft request' (duration: 155.966466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:27:51.503891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.163853ms","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-07T15:27:51.50401Z","caller":"traceutil/trace.go:171","msg":"trace[610001008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"114.364538ms","start":"2026-04-07T15:27:51.389631Z","end":"2026-04-07T15:27:51.503996Z","steps":["trace[610001008] 'agreement among raft nodes before linearized reading' (duration: 113.960508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:28:35.271275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.613958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T15:28:35.271381Z","caller":"traceutil/trace.go:171","msg":"trace[542190024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1816; }","duration":"257.948057ms","start":"2026-04-07T15:28:35.013412Z","end":"2026-04-07T15:28:35.27136Z","steps":["trace[542190024] 'range keys from in-memory index tree' (duration: 257.426643ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:35.272517Z","caller":"traceutil/trace.go:171","msg":"trace[1189255530] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"146.934804ms","start":"2026-04-07T15:28:35.125566Z","end":"2026-04-07T15:28:35.272501Z","steps":["trace[1189255530] 'process raft request' (duration: 146.820731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:35.672286Z","caller":"traceutil/trace.go:171","msg":"trace[198924904] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"135.744968ms","start":"2026-04-07T15:28:35.536513Z","end":"2026-04-07T15:28:35.672258Z","steps":["trace[198924904] 'process raft request' (duration: 135.582564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:35.67641Z","caller":"traceutil/trace.go:171","msg":"trace[1499068853] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"136.96639ms","start":"2026-04-07T15:28:35.539421Z","end":"2026-04-07T15:28:35.676388Z","steps":["trace[1499068853] 'process raft request' (duration: 136.772584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:21.325771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.332693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:29:21.32597Z","caller":"traceutil/trace.go:171","msg":"trace[945511468] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"161.353027ms","start":"2026-04-07T15:29:21.164595Z","end":"2026-04-07T15:29:21.325948Z","steps":["trace[945511468] 'read index received' (duration: 21.5042ms)","trace[945511468] 'applied index is now lower than readState.Index' (duration: 139.846587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:21.326166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.573462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T15:29:21.326207Z","caller":"traceutil/trace.go:171","msg":"trace[203573362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2123; }","duration":"161.659455ms","start":"2026-04-07T15:29:21.164535Z","end":"2026-04-07T15:29:21.326195Z","steps":["trace[203573362] 'agreement among raft nodes before linearized reading' (duration: 161.509212ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:29:21.32618Z","caller":"traceutil/trace.go:171","msg":"trace[467624131] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"255.44376ms","start":"2026-04-07T15:29:21.070555Z","end":"2026-04-07T15:29:21.325999Z","steps":["trace[467624131] 'process raft request' (duration: 115.619594ms)","trace[467624131] 'compare' (duration: 139.221711ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:29:21.476247Z","caller":"traceutil/trace.go:171","msg":"trace[914757654] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"145.891657ms","start":"2026-04-07T15:29:21.330301Z","end":"2026-04-07T15:29:21.476192Z","steps":["trace[914757654] 'process raft request' (duration: 122.25607ms)","trace[914757654] 'compare' (duration: 23.37417ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:30:48.72622Z","caller":"traceutil/trace.go:171","msg":"trace[175880813] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"100.717598ms","start":"2026-04-07T15:30:48.625474Z","end":"2026-04-07T15:30:48.726191Z","steps":["trace[175880813] 'process raft request' (duration: 100.582485ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:30:54.464091Z","caller":"traceutil/trace.go:171","msg":"trace[2137436883] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"100.606916ms","start":"2026-04-07T15:30:54.363455Z","end":"2026-04-07T15:30:54.464062Z","steps":["trace[2137436883] 'process raft request' (duration: 83.810809ms)","trace[2137436883] 'compare' (duration: 16.690634ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:33:24.686288Z","caller":"traceutil/trace.go:171","msg":"trace[1103486566] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"126.237946ms","start":"2026-04-07T15:33:24.560016Z","end":"2026-04-07T15:33:24.686254Z","steps":["trace[1103486566] 'process raft request' (duration: 126.040751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:34:49.209616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.33229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:34:49.209781Z","caller":"traceutil/trace.go:171","msg":"trace[1291372995] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5187; }","duration":"160.8044ms","start":"2026-04-07T15:34:49.048952Z","end":"2026-04-07T15:34:49.209757Z","steps":["trace[1291372995] 'read index received' (duration: 60.132µs)","trace[1291372995] 'applied index is now lower than readState.Index' (duration: 160.742368ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:34:49.209863Z","caller":"traceutil/trace.go:171","msg":"trace[1141118911] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"198.611148ms","start":"2026-04-07T15:34:49.011204Z","end":"2026-04-07T15:34:49.209815Z","steps":["trace[1141118911] 'process raft request' (duration: 19.933959ms)","trace[1141118911] 'compare' (duration: 177.896148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:34:49.210047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.073358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f291066-4790-44f0-84ed-22c73ffa1bb9\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-07T15:34:49.21015Z","caller":"traceutil/trace.go:171","msg":"trace[219407073] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f291066-4790-44f0-84ed-22c73ffa1bb9; range_end:; response_count:1; response_revision:4996; }","duration":"161.220871ms","start":"2026-04-07T15:34:49.048906Z","end":"2026-04-07T15:34:49.210127Z","steps":["trace[219407073] 'agreement among raft nodes before linearized reading' (duration: 160.947813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:35:07.216585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.262073ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:35:07.217096Z","caller":"traceutil/trace.go:171","msg":"trace[553496410] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5366; }","duration":"292.757506ms","start":"2026-04-07T15:35:06.924274Z","end":"2026-04-07T15:35:07.217031Z","steps":["trace[553496410] 'range keys from in-memory index tree' (duration: 292.240932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:35:07.21678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.358207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:35:07.216942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.433376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fw8hj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T15:35:07.217474Z","caller":"traceutil/trace.go:171","msg":"trace[1902379718] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5366; }","duration":"287.066396ms","start":"2026-04-07T15:35:06.930354Z","end":"2026-04-07T15:35:07.21742Z","steps":["trace[1902379718] 'range keys from in-memory index tree' (duration: 286.292745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:07.217635Z","caller":"traceutil/trace.go:171","msg":"trace[1055201533] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fw8hj; range_end:; response_count:1; response_revision:5366; }","duration":"136.115585ms","start":"2026-04-07T15:35:07.081476Z","end":"2026-04-07T15:35:07.217592Z","steps":["trace[1055201533] 'range keys from in-memory index tree' (duration: 135.266451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:35:07.217792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.217146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-07T15:35:07.21788Z","caller":"traceutil/trace.go:171","msg":"trace[1389110014] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5366; }","duration":"137.356909ms","start":"2026-04-07T15:35:07.080502Z","end":"2026-04-07T15:35:07.217859Z","steps":["trace[1389110014] 'range keys from in-memory index tree' (duration: 136.213297ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:36:35.358207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3045} {"level":"info","ts":"2026-04-07T15:36:35.446573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3045,"took":"86.662497ms","hash":1952486187,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31858688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T15:36:35.446663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1952486187,"revision":3045,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:41:08.223946Z","caller":"traceutil/trace.go:171","msg":"trace[925542699] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"164.749539ms","start":"2026-04-07T15:41:08.059157Z","end":"2026-04-07T15:41:08.223906Z","steps":["trace[925542699] 'process raft request' (duration: 164.479921ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:19.831974Z","caller":"traceutil/trace.go:171","msg":"trace[481163330] linearizableReadLoop","detail":"{readStateIndex:8536; appliedIndex:8535; }","duration":"202.801016ms","start":"2026-04-07T15:41:19.629136Z","end":"2026-04-07T15:41:19.831937Z","steps":["trace[481163330] 'read index received' (duration: 202.494158ms)","trace[481163330] 'applied index is now lower than readState.Index' (duration: 305.018µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:41:19.832153Z","caller":"traceutil/trace.go:171","msg":"trace[125670036] transaction","detail":"{read_only:false; response_revision:8242; number_of_response:1; }","duration":"203.640998ms","start":"2026-04-07T15:41:19.62846Z","end":"2026-04-07T15:41:19.832101Z","steps":["trace[125670036] 'process raft request' (duration: 203.150525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:19.832289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.120424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-07T15:41:19.83235Z","caller":"traceutil/trace.go:171","msg":"trace[227852759] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8242; }","duration":"203.229188ms","start":"2026-04-07T15:41:19.629104Z","end":"2026-04-07T15:41:19.832333Z","steps":["trace[227852759] 'agreement among raft nodes before linearized reading' (duration: 202.987631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:22.124093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.951155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T15:41:22.124184Z","caller":"traceutil/trace.go:171","msg":"trace[1004294542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8253; }","duration":"154.106819ms","start":"2026-04-07T15:41:21.970055Z","end":"2026-04-07T15:41:22.124162Z","steps":["trace[1004294542] 'range keys from in-memory index tree' (duration: 153.74753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:22.124421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.331025ms","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-07T15:41:22.124446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.534616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:41:22.12453Z","caller":"traceutil/trace.go:171","msg":"trace[765689555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8253; }","duration":"123.64202ms","start":"2026-04-07T15:41:22.000869Z","end":"2026-04-07T15:41:22.124511Z","steps":["trace[765689555] 'count revisions from in-memory index tree' (duration: 123.481905ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:22.124529Z","caller":"traceutil/trace.go:171","msg":"trace[1123625339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8253; }","duration":"154.504012ms","start":"2026-04-07T15:41:21.97Z","end":"2026-04-07T15:41:22.124504Z","steps":["trace[1123625339] 'range keys from in-memory index tree' (duration: 154.069938ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:35.367476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6155} {"level":"info","ts":"2026-04-07T15:41:35.469859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6155,"took":"100.003879ms","hash":2756858587,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":23031808,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T15:41:35.469933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2756858587,"revision":6155,"compact-revision":3045} {"level":"info","ts":"2026-04-07T15:41:48.527607Z","caller":"traceutil/trace.go:171","msg":"trace[64233632] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"261.568775ms","start":"2026-04-07T15:41:48.266016Z","end":"2026-04-07T15:41:48.527585Z","steps":["trace[64233632] 'process raft request' (duration: 261.433751ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:48.6837Z","caller":"traceutil/trace.go:171","msg":"trace[1160968009] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8750; }","duration":"378.735741ms","start":"2026-04-07T15:41:48.304937Z","end":"2026-04-07T15:41:48.683673Z","steps":["trace[1160968009] 'read index received' (duration: 222.490841ms)","trace[1160968009] 'applied index is now lower than readState.Index' (duration: 156.24373ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:41:48.683749Z","caller":"traceutil/trace.go:171","msg":"trace[2005148692] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"417.589327ms","start":"2026-04-07T15:41:48.266113Z","end":"2026-04-07T15:41:48.683703Z","steps":["trace[2005148692] 'process raft request' (duration: 416.437587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:48.683955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.980987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-07T15:41:48.683995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:41:48.266093Z","time spent":"417.791353ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:41:48.684064Z","caller":"traceutil/trace.go:171","msg":"trace[1907911506] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:8450; }","duration":"379.141731ms","start":"2026-04-07T15:41:48.304898Z","end":"2026-04-07T15:41:48.68404Z","steps":["trace[1907911506] 'agreement among raft nodes before linearized reading' (duration: 378.861424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:48.684114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:41:48.304881Z","time spent":"379.222803ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-04-07T15:41:48.685292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.581466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T15:41:48.685364Z","caller":"traceutil/trace.go:171","msg":"trace[1388199460] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"143.896695ms","start":"2026-04-07T15:41:48.541451Z","end":"2026-04-07T15:41:48.685348Z","steps":["trace[1388199460] 'process raft request' (duration: 143.576316ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:48.685369Z","caller":"traceutil/trace.go:171","msg":"trace[1418177376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8451; }","duration":"271.69705ms","start":"2026-04-07T15:41:48.413654Z","end":"2026-04-07T15:41:48.685351Z","steps":["trace[1418177376] 'agreement among raft nodes before linearized reading' (duration: 271.480274ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:51.828792Z","caller":"traceutil/trace.go:171","msg":"trace[770172054] linearizableReadLoop","detail":"{readStateIndex:8769; appliedIndex:8768; }","duration":"172.44569ms","start":"2026-04-07T15:41:51.656318Z","end":"2026-04-07T15:41:51.828763Z","steps":["trace[770172054] 'read index received' (duration: 172.033869ms)","trace[770172054] 'applied index is now lower than readState.Index' (duration: 410.721µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:41:51.828837Z","caller":"traceutil/trace.go:171","msg":"trace[672111353] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"193.537494ms","start":"2026-04-07T15:41:51.635274Z","end":"2026-04-07T15:41:51.828811Z","steps":["trace[672111353] 'process raft request' (duration: 193.132873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:51.829081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.976474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fw8hj\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-07T15:41:51.829096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.756689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T15:41:51.829125Z","caller":"traceutil/trace.go:171","msg":"trace[584851623] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fw8hj; range_end:; response_count:1; response_revision:8466; }","duration":"159.054317ms","start":"2026-04-07T15:41:51.670058Z","end":"2026-04-07T15:41:51.829113Z","steps":["trace[584851623] 'agreement among raft nodes before linearized reading' (duration: 158.872132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:51.829178Z","caller":"traceutil/trace.go:171","msg":"trace[1223850341] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8466; }","duration":"172.897463ms","start":"2026-04-07T15:41:51.656256Z","end":"2026-04-07T15:41:51.829154Z","steps":["trace[1223850341] 'agreement among raft nodes before linearized reading' (duration: 172.623975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:41:52.268395Z","caller":"traceutil/trace.go:171","msg":"trace[1921236283] linearizableReadLoop","detail":"{readStateIndex:8771; appliedIndex:8770; }","duration":"169.721517ms","start":"2026-04-07T15:41:52.098645Z","end":"2026-04-07T15:41:52.268366Z","steps":["trace[1921236283] 'read index received' (duration: 168.489783ms)","trace[1921236283] 'applied index is now lower than readState.Index' (duration: 1.230134ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:41:52.268453Z","caller":"traceutil/trace.go:171","msg":"trace[1245072672] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"247.78803ms","start":"2026-04-07T15:41:52.020616Z","end":"2026-04-07T15:41:52.268404Z","steps":["trace[1245072672] 'process raft request' (duration: 246.573046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:41:52.269183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.418065ms","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-07T15:41:52.269229Z","caller":"traceutil/trace.go:171","msg":"trace[779104693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8468; }","duration":"170.611581ms","start":"2026-04-07T15:41:52.098609Z","end":"2026-04-07T15:41:52.26922Z","steps":["trace[779104693] 'agreement among raft nodes before linearized reading' (duration: 169.8553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:01.768368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.112853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:42:01.76853Z","caller":"traceutil/trace.go:171","msg":"trace[2038522910] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"183.176612ms","start":"2026-04-07T15:42:01.585322Z","end":"2026-04-07T15:42:01.768499Z","steps":["trace[2038522910] 'process raft request' (duration: 82.783641ms)","trace[2038522910] 'compare' (duration: 99.877417ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:42:02.327391Z","caller":"traceutil/trace.go:171","msg":"trace[444168458] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"132.419712ms","start":"2026-04-07T15:42:02.194947Z","end":"2026-04-07T15:42:02.327366Z","steps":["trace[444168458] 'process raft request' (duration: 132.271688ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:02.570276Z","caller":"traceutil/trace.go:171","msg":"trace[1587566860] linearizableReadLoop","detail":"{readStateIndex:8874; appliedIndex:8872; }","duration":"246.030352ms","start":"2026-04-07T15:42:02.324222Z","end":"2026-04-07T15:42:02.570252Z","steps":["trace[1587566860] 'read index received' (duration: 3.332631ms)","trace[1587566860] 'applied index is now lower than readState.Index' (duration: 242.69631ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:42:02.5703Z","caller":"traceutil/trace.go:171","msg":"trace[1477967540] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"367.254908ms","start":"2026-04-07T15:42:02.203014Z","end":"2026-04-07T15:42:02.570269Z","steps":["trace[1477967540] 'process raft request' (duration: 367.003901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:02.57035Z","caller":"traceutil/trace.go:171","msg":"trace[390059112] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"261.331227ms","start":"2026-04-07T15:42:02.308994Z","end":"2026-04-07T15:42:02.570326Z","steps":["trace[390059112] 'process raft request' (duration: 261.188104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:02.570544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.31226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T15:42:02.570591Z","caller":"traceutil/trace.go:171","msg":"trace[1698166339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8566; }","duration":"246.427412ms","start":"2026-04-07T15:42:02.324152Z","end":"2026-04-07T15:42:02.57058Z","steps":["trace[1698166339] 'agreement among raft nodes before linearized reading' (duration: 246.266698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:42:02.570841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:42:02.202996Z","time spent":"367.386932ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:42:03.161601Z","caller":"traceutil/trace.go:171","msg":"trace[654801756] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"149.250409ms","start":"2026-04-07T15:42:03.012319Z","end":"2026-04-07T15:42:03.16157Z","steps":["trace[654801756] 'process raft request' (duration: 149.026863ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:03.177693Z","caller":"traceutil/trace.go:171","msg":"trace[1617350582] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"131.510527ms","start":"2026-04-07T15:42:03.046153Z","end":"2026-04-07T15:42:03.177664Z","steps":["trace[1617350582] 'process raft request' (duration: 131.359733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:42:51.683502Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002466.wal"} {"level":"info","ts":"2026-04-07T15:43:29.720687Z","caller":"traceutil/trace.go:171","msg":"trace[2065873147] linearizableReadLoop","detail":"{readStateIndex:9706; appliedIndex:9705; }","duration":"193.313058ms","start":"2026-04-07T15:43:29.527346Z","end":"2026-04-07T15:43:29.720659Z","steps":["trace[2065873147] 'read index received' (duration: 193.106732ms)","trace[2065873147] 'applied index is now lower than readState.Index' (duration: 204.936µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:43:29.720747Z","caller":"traceutil/trace.go:171","msg":"trace[1048750669] transaction","detail":"{read_only:false; response_revision:9374; number_of_response:1; }","duration":"200.089832ms","start":"2026-04-07T15:43:29.520615Z","end":"2026-04-07T15:43:29.720705Z","steps":["trace[1048750669] 'process raft request' (duration: 199.846695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:43:29.720846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.484932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:43:29.720887Z","caller":"traceutil/trace.go:171","msg":"trace[2117269235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9374; }","duration":"193.595536ms","start":"2026-04-07T15:43:29.527281Z","end":"2026-04-07T15:43:29.720876Z","steps":["trace[2117269235] 'agreement among raft nodes before linearized reading' (duration: 193.504243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:44:08.533961Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"849a6ffb5e81c109","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T15:44:08.541632Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T15:44:08.541766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T15:46:35.375504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8328} {"level":"info","ts":"2026-04-07T15:46:35.457907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8328,"took":"79.461394ms","hash":1789715843,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":23801856,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T15:46:35.45798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1789715843,"revision":8328,"compact-revision":6155} {"level":"info","ts":"2026-04-07T15:47:29.819934Z","caller":"traceutil/trace.go:171","msg":"trace[196093276] transaction","detail":"{read_only:false; response_revision:10937; number_of_response:1; }","duration":"110.744631ms","start":"2026-04-07T15:47:29.70902Z","end":"2026-04-07T15:47:29.819764Z","steps":["trace[196093276] 'process raft request' (duration: 107.163194ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:47:29.849065Z","caller":"traceutil/trace.go:171","msg":"trace[1825236493] transaction","detail":"{read_only:false; response_revision:10938; number_of_response:1; }","duration":"134.68491ms","start":"2026-04-07T15:47:29.714361Z","end":"2026-04-07T15:47:29.849045Z","steps":["trace[1825236493] 'process raft request' (duration: 134.202947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:35.382869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10542} {"level":"info","ts":"2026-04-07T15:51:35.461934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10542,"took":"76.066234ms","hash":2057191533,"current-db-size-bytes":57688064,"current-db-size":"58 MB","current-db-size-in-use-bytes":20627456,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T15:51:35.462202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2057191533,"revision":10542,"compact-revision":8328} {"level":"warn","ts":"2026-04-07T15:51:55.560633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821996486031912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:51:56.172635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821996486031912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:51:56.673683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909821996486031912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:51:56.7039Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.751789459s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T15:51:56.704532Z","caller":"traceutil/trace.go:171","msg":"trace[1833717704] transaction","detail":"{read_only:false; response_revision:12362; number_of_response:1; }","duration":"1.752537929s","start":"2026-04-07T15:51:54.951958Z","end":"2026-04-07T15:51:56.704496Z","steps":["trace[1833717704] 'process raft request' (duration: 1.752354155s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.704812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:54.951926Z","time spent":"1.752678983s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:51:56.705522Z","caller":"traceutil/trace.go:171","msg":"trace[515719840] transaction","detail":"{read_only:false; response_revision:12364; number_of_response:1; }","duration":"1.019265791s","start":"2026-04-07T15:51:55.686234Z","end":"2026-04-07T15:51:56.7055Z","steps":["trace[515719840] 'process raft request' (duration: 1.019071995s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.705516Z","caller":"traceutil/trace.go:171","msg":"trace[613905316] transaction","detail":"{read_only:false; response_revision:12363; number_of_response:1; }","duration":"1.660885033s","start":"2026-04-07T15:51:55.044592Z","end":"2026-04-07T15:51:56.705477Z","steps":["trace[613905316] 'process raft request' (duration: 1.66045795s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.705658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.686203Z","time spent":"1.019374373s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:51:56.705744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64527971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:51:56.7058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.044558Z","time spent":"1.661041847s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:51:56.705818Z","caller":"traceutil/trace.go:171","msg":"trace[836589906] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:12364; }","duration":"1.645374882s","start":"2026-04-07T15:51:55.060423Z","end":"2026-04-07T15:51:56.705798Z","steps":["trace[836589906] 'agreement among raft nodes before linearized reading' (duration: 1.645220838s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.705875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.060255Z","time spent":"1.645602789s","remote":"127.0.0.1:34766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:51:56.706265Z","caller":"traceutil/trace.go:171","msg":"trace[1782291228] linearizableReadLoop","detail":"{readStateIndex:12806; appliedIndex:12803; }","duration":"1.644982911s","start":"2026-04-07T15:51:55.060444Z","end":"2026-04-07T15:51:56.705427Z","steps":["trace[1782291228] 'read index received' (duration: 1.64382581s)","trace[1782291228] 'applied index is now lower than readState.Index' (duration: 1.155551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:51:56.706549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.044636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:51:56.706586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.573803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:51:56.706611Z","caller":"traceutil/trace.go:171","msg":"trace[428092541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12364; }","duration":"180.153529ms","start":"2026-04-07T15:51:56.526444Z","end":"2026-04-07T15:51:56.706598Z","steps":["trace[428092541] 'agreement among raft nodes before linearized reading' (duration: 180.047976ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.706626Z","caller":"traceutil/trace.go:171","msg":"trace[1114163086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12364; }","duration":"804.681846ms","start":"2026-04-07T15:51:55.901933Z","end":"2026-04-07T15:51:56.706615Z","steps":["trace[1114163086] 'agreement among raft nodes before linearized reading' (duration: 804.533782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.706666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.901907Z","time spent":"804.751687ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:51:56.706686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463783085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T15:51:56.706733Z","caller":"traceutil/trace.go:171","msg":"trace[810363133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12364; }","duration":"1.463860877s","start":"2026-04-07T15:51:55.242857Z","end":"2026-04-07T15:51:56.706718Z","steps":["trace[810363133] 'agreement among raft nodes before linearized reading' (duration: 1.46361141s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.706731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179783227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:51:56.706775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.242781Z","time spent":"1.46398564s","remote":"127.0.0.1:35218","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":"info","ts":"2026-04-07T15:51:56.706785Z","caller":"traceutil/trace.go:171","msg":"trace[167479822] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:12364; }","duration":"1.17988274s","start":"2026-04-07T15:51:55.526884Z","end":"2026-04-07T15:51:56.706766Z","steps":["trace[167479822] 'agreement among raft nodes before linearized reading' (duration: 1.179805088s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.706742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.871378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T15:51:56.706836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.526852Z","time spent":"1.179970443s","remote":"127.0.0.1:39142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:51:56.706836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.274686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:51:56.706868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.730875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fw8hj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T15:51:56.706889Z","caller":"traceutil/trace.go:171","msg":"trace[476377756] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:12364; }","duration":"137.381398ms","start":"2026-04-07T15:51:56.569489Z","end":"2026-04-07T15:51:56.706871Z","steps":["trace[476377756] 'agreement among raft nodes before linearized reading' (duration: 137.302936ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.706882Z","caller":"traceutil/trace.go:171","msg":"trace[802992340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12364; }","duration":"999.052413ms","start":"2026-04-07T15:51:55.707801Z","end":"2026-04-07T15:51:56.706853Z","steps":["trace[802992340] 'agreement among raft nodes before linearized reading' (duration: 998.767705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.706653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181193546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:51:56.706992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.94596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-04-07T15:51:56.70702Z","caller":"traceutil/trace.go:171","msg":"trace[1329725299] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:12364; }","duration":"684.048103ms","start":"2026-04-07T15:51:56.022965Z","end":"2026-04-07T15:51:56.707013Z","steps":["trace[1329725299] 'agreement among raft nodes before linearized reading' (duration: 683.961351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.707053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:56.02286Z","time spent":"684.187527ms","remote":"127.0.0.1:35186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5153,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-07T15:51:56.707062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.739764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T15:51:56.707131Z","caller":"traceutil/trace.go:171","msg":"trace[1372214436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12364; }","duration":"991.876488ms","start":"2026-04-07T15:51:55.715238Z","end":"2026-04-07T15:51:56.707114Z","steps":["trace[1372214436] 'agreement among raft nodes before linearized reading' (duration: 991.671112ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.707086Z","caller":"traceutil/trace.go:171","msg":"trace[888232033] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:12364; }","duration":"1.181634218s","start":"2026-04-07T15:51:55.525379Z","end":"2026-04-07T15:51:56.707013Z","steps":["trace[888232033] 'agreement among raft nodes before linearized reading' (duration: 1.181201416s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.707173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.715214Z","time spent":"991.95133ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T15:51:56.707246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.52535Z","time spent":"1.181857704s","remote":"127.0.0.1:35162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:51:56.707246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.145235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T15:51:56.70727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.754508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:51:56.706904Z","caller":"traceutil/trace.go:171","msg":"trace[1293343176] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fw8hj; range_end:; response_count:1; response_revision:12364; }","duration":"442.819738ms","start":"2026-04-07T15:51:56.264076Z","end":"2026-04-07T15:51:56.706896Z","steps":["trace[1293343176] 'agreement among raft nodes before linearized reading' (duration: 442.719645ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.707348Z","caller":"traceutil/trace.go:171","msg":"trace[174628808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12364; }","duration":"813.336451ms","start":"2026-04-07T15:51:55.893966Z","end":"2026-04-07T15:51:56.707302Z","steps":["trace[174628808] 'agreement among raft nodes before linearized reading' (duration: 812.870248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.707408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:56.26404Z","time spent":"443.348772ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-fw8hj\" "} {"level":"warn","ts":"2026-04-07T15:51:56.707438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.893935Z","time spent":"813.491935ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:51:56.706962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.707775Z","time spent":"999.167976ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T15:51:56.707558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462782507s","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-07T15:51:56.707335Z","caller":"traceutil/trace.go:171","msg":"trace[1029099967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12364; }","duration":"137.851631ms","start":"2026-04-07T15:51:56.569464Z","end":"2026-04-07T15:51:56.707315Z","steps":["trace[1029099967] 'agreement among raft nodes before linearized reading' (duration: 137.727478ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:51:56.707618Z","caller":"traceutil/trace.go:171","msg":"trace[378638627] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12364; }","duration":"1.46290734s","start":"2026-04-07T15:51:55.244691Z","end":"2026-04-07T15:51:56.707599Z","steps":["trace[378638627] 'agreement among raft nodes before linearized reading' (duration: 1.462000625s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:51:56.707661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:51:55.244658Z","time spent":"1.462992972s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T15:52:10.033293Z","caller":"traceutil/trace.go:171","msg":"trace[211199768] linearizableReadLoop","detail":"{readStateIndex:12877; appliedIndex:12876; }","duration":"328.106495ms","start":"2026-04-07T15:52:09.705153Z","end":"2026-04-07T15:52:10.033259Z","steps":["trace[211199768] 'read index received' (duration: 327.872668ms)","trace[211199768] 'applied index is now lower than readState.Index' (duration: 232.527µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:52:10.033401Z","caller":"traceutil/trace.go:171","msg":"trace[1410721007] transaction","detail":"{read_only:false; response_revision:12432; number_of_response:1; }","duration":"472.781941ms","start":"2026-04-07T15:52:09.560575Z","end":"2026-04-07T15:52:10.033357Z","steps":["trace[1410721007] 'process raft request' (duration: 472.467253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:52:10.03355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.82262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:52:10.033552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.379281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:52:10.033597Z","caller":"traceutil/trace.go:171","msg":"trace[634203333] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:12432; }","duration":"165.960283ms","start":"2026-04-07T15:52:09.867622Z","end":"2026-04-07T15:52:10.033583Z","steps":["trace[634203333] 'agreement among raft nodes before linearized reading' (duration: 165.83675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:52:10.033598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:52:09.560553Z","time spent":"472.939665ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:52:10.03362Z","caller":"traceutil/trace.go:171","msg":"trace[636682135] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:12432; }","duration":"328.515605ms","start":"2026-04-07T15:52:09.705086Z","end":"2026-04-07T15:52:10.033601Z","steps":["trace[636682135] 'agreement among raft nodes before linearized reading' (duration: 328.363651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:52:10.033661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:52:09.705065Z","time spent":"328.585477ms","remote":"127.0.0.1:35366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "}